builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1506538400.55 results: success (0) revision: c89417bb5ce904ef935563aa0e7ab9fc93f20f6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:20.553322) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:20.553779) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:20.554078) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:20.571488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:20.571815) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 11:53:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 89.8M=0s 2017-09-27 11:53:20 (89.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126619 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:20.715619) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:20.715935) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024769 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:20.765161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 11:53:20.765483) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c89417bb5ce904ef935563aa0e7ab9fc93f20f6f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c89417bb5ce904ef935563aa0e7ab9fc93f20f6f --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 11:53:20,847 truncating revision to first 12 chars 2017-09-27 11:53:20,847 Setting DEBUG logging. 2017-09-27 11:53:20,847 attempt 1/10 2017-09-27 11:53:20,847 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c89417bb5ce9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 11:53:21,664 unpacking tar archive at: mozilla-beta-c89417bb5ce9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.143707 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 11:53:21.925274) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:21.925600) ========= script_repo_revision: c89417bb5ce904ef935563aa0e7ab9fc93f20f6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:21.925970) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:21.926241) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 11:53:22.107793) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-09-27 11:53:22.108216) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:53:22 INFO - MultiFileLogger online at 20170927 11:53:22 in /builds/slave/test 11:53:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 11:53:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:53:22 INFO - {'append_to_log': False, 11:53:22 INFO - 'base_work_dir': '/builds/slave/test', 11:53:22 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 11:53:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:53:22 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 11:53:22 INFO - 'buildbot_json_path': 'buildprops.json', 11:53:22 INFO - 'code_coverage': False, 11:53:22 INFO - 'config_files': ('talos/linux_config.py',), 11:53:22 INFO - 'default_actions': ('clobber', 11:53:22 INFO - 'read-buildbot-config', 11:53:22 INFO - 'download-and-extract', 11:53:22 INFO - 'populate-webroot', 11:53:22 INFO - 'create-virtualenv', 11:53:22 INFO - 'install', 11:53:22 INFO - 'setup-mitmproxy', 11:53:22 INFO - 'run-tests'), 11:53:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:53:22 INFO - 'disable_ccov_upload': False, 11:53:22 INFO - 'disable_stylo': False, 11:53:22 INFO - 'download_minidump_stackwalk': True, 11:53:22 INFO - 'download_symbols': 'ondemand', 11:53:22 INFO - 'enable_stylo': False, 11:53:22 INFO - 'enable_webrender': False, 11:53:22 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:53:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:53:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:53:22 INFO - 'gecko_profile': False, 11:53:22 INFO - 'gecko_profile_interval': 0, 11:53:22 INFO - 'installer_path': 'installer.exe', 11:53:22 INFO - 'log_level': 'info', 11:53:22 INFO - 'log_name': 'talos', 11:53:22 INFO - 'log_to_console': True, 11:53:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:53:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:53:22 INFO - 'opt_config_files': (), 11:53:22 INFO - 'pip_index': False, 11:53:22 INFO - 'suite': 'dromaeojs-e10s', 11:53:22 INFO - 'system_bits': '32', 11:53:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:53:22 INFO - 'title': 'talos-linux64-ix-047', 11:53:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:53:22 INFO - 'use_talos_json': True, 11:53:22 INFO - 'verify': 'False', 11:53:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:53:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:53:22 INFO - 'work_dir': 'build'} 11:53:22 INFO - [mozharness: 2017-09-27 18:53:22.337273Z] Running clobber step. 11:53:22 INFO - Running pre-action listener: _resource_record_pre_action 11:53:22 INFO - Running main action method: clobber 11:53:22 INFO - rmtree: /builds/slave/test/build 11:53:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:53:23 INFO - Running post-action listener: _resource_record_post_action 11:53:23 INFO - [mozharness: 2017-09-27 18:53:23.356618Z] Finished clobber step (success) 11:53:23 INFO - [mozharness: 2017-09-27 18:53:23.356716Z] Running read-buildbot-config step. 11:53:23 INFO - Running pre-action listener: _resource_record_pre_action 11:53:23 INFO - Running main action method: read_buildbot_config 11:53:23 INFO - Using buildbot properties: 11:53:23 INFO - { 11:53:23 INFO - "project": "", 11:53:23 INFO - "product": "firefox", 11:53:23 INFO - "who": "ryanvm@gmail.com", 11:53:23 INFO - "installer_path": "public/build/target.tar.bz2", 11:53:23 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 11:53:23 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 11:53:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 11:53:23 INFO - "stage_platform": "linux64", 11:53:23 INFO - "basedir": "/builds/slave/test", 11:53:23 INFO - "buildnumber": 120, 11:53:23 INFO - "platform": "ubuntu64_hw", 11:53:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:53:23 INFO - "slavebuilddir": "test", 11:53:23 INFO - "taskId": "Z8uWTDTkSE6xJ4w73qv3jA", 11:53:23 INFO - "branch": "mozilla-beta", 11:53:23 INFO - "script_repo_revision": "production", 11:53:23 INFO - "revision": "c89417bb5ce904ef935563aa0e7ab9fc93f20f6f", 11:53:23 INFO - "slavename": "talos-linux64-ix-047", 11:53:23 INFO - "repo_path": "releases/mozilla-beta" 11:53:23 INFO - } 11:53:23 INFO - Finding installer, test and symbols from parent task. 11:53:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z8uWTDTkSE6xJ4w73qv3jA'}, attempt #1 11:53:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z8uWTDTkSE6xJ4w73qv3jA'}, attempt #1 11:53:24 INFO - Task dependencies: aipVw3bsRv2QZNMWT2ULyQ 11:53:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ'}, attempt #1 11:53:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.tar.bz2 11:53:25 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.test_packages.json 11:53:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.crashreporter-symbols.zip 11:53:25 INFO - Running post-action listener: _resource_record_post_action 11:53:25 INFO - [mozharness: 2017-09-27 18:53:25.056722Z] Finished read-buildbot-config step (success) 11:53:25 INFO - [mozharness: 2017-09-27 18:53:25.056939Z] Running download-and-extract step. 11:53:25 INFO - Running pre-action listener: _resource_record_pre_action 11:53:25 INFO - Running main action method: download_and_extract 11:53:25 INFO - mkdir: /builds/slave/test/build/tests 11:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:25 INFO - trying https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.test_packages.json 11:53:25 INFO - Downloading https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:53:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:53:25 INFO - Downloaded 989 bytes. 11:53:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:53:25 INFO - Using the following test package requirements: 11:53:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:53:25 INFO - u'common': [u'target.common.tests.zip'], 11:53:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:53:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:53:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:53:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:53:25 INFO - u'mozbase': [u'target.common.tests.zip'], 11:53:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:53:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:53:25 INFO - u'web-platform': [u'target.common.tests.zip', 11:53:25 INFO - u'target.web-platform.tests.tar.gz'], 11:53:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:53:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:53:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.common.tests.zip 11:53:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:53:25 INFO - Fetch https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.common.tests.zip into memory 11:53:26 INFO - Content-Length response header: 55176318 11:53:26 INFO - Bytes received: 55176318 11:53:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.talos.tests.zip 11:53:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:53:37 INFO - Fetch https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.talos.tests.zip into memory 11:53:38 INFO - Content-Length response header: 14509184 11:53:38 INFO - Bytes received: 14509184 11:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:38 INFO - trying https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.tar.bz2 11:53:38 INFO - Downloading https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:53:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:53:39 INFO - Downloaded 53522094 bytes. 11:53:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.tar.bz2 11:53:39 INFO - mkdir: /builds/slave/test/properties 11:53:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:53:39 INFO - Writing to file /builds/slave/test/properties/build_url 11:53:39 INFO - Contents: 11:53:39 INFO - build_url:https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.tar.bz2 11:53:39 INFO - Running post-action listener: _resource_record_post_action 11:53:39 INFO - Running post-action listener: find_tests_for_verification 11:53:39 INFO - Running post-action listener: set_extra_try_arguments 11:53:39 INFO - [mozharness: 2017-09-27 18:53:39.430723Z] Finished download-and-extract step (success) 11:53:39 INFO - [mozharness: 2017-09-27 18:53:39.430830Z] Running populate-webroot step. 11:53:39 INFO - Running pre-action listener: _resource_record_pre_action 11:53:39 INFO - Running main action method: populate_webroot 11:53:39 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:53:39 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['tresize', 11:53:39 INFO - 'tcanvasmark']}, 11:53:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:53:39 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['dromaeo_css', 11:53:39 INFO - 'kraken']}, 11:53:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:53:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:53:39 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:39 INFO - 'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:53:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:53:39 INFO - 'tests': ['damp', 'tps']}, 11:53:39 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:39 INFO - 'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['damp', 'tps']}, 11:53:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:53:39 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['dromaeo_dom']}, 11:53:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:53:39 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['basic_compositor_video', 11:53:39 INFO - 'glvideo']}, 11:53:39 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:53:39 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:53:39 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:39 INFO - 'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['ts_paint_webext', 11:53:39 INFO - 'tp5o_webext']}, 11:53:39 INFO - 'other-e10s': {'tests': ['a11yr', 11:53:39 INFO - 'ts_paint', 11:53:39 INFO - 'tpaint', 11:53:39 INFO - 'sessionrestore', 11:53:39 INFO - 'sessionrestore_many_windows', 11:53:39 INFO - 'sessionrestore_no_auto_restore', 11:53:39 INFO - 'tabpaint']}, 11:53:39 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['a11yr', 11:53:39 INFO - 'ts_paint', 11:53:39 INFO - 'tpaint', 11:53:39 INFO - 'sessionrestore', 11:53:39 INFO - 'sessionrestore_many_windows', 11:53:39 INFO - 'sessionrestore_no_auto_restore', 11:53:39 INFO - 'tabpaint']}, 11:53:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:53:39 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:53:39 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['perf_reftest_singletons']}, 11:53:39 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['bloom_basic']}, 11:53:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:53:39 INFO - 'tsvgr_opacity', 11:53:39 INFO - 'tart', 11:53:39 INFO - 'tscrollx', 11:53:39 INFO - 'tsvg_static']}, 11:53:39 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['tsvgx', 11:53:39 INFO - 'tsvgr_opacity', 11:53:39 INFO - 'tart', 11:53:39 INFO - 'tscrollx', 11:53:39 INFO - 'tsvg_static']}, 11:53:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:53:39 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:39 INFO - 'talos_options': ['--disable-stylo'], 11:53:39 INFO - 'tests': ['tp5o']}, 11:53:39 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:39 INFO - 'talos_options': ['--mitmproxy', 11:53:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:39 INFO - '--firstNonBlankPaint'], 11:53:39 INFO - 'tests': ['tp6_google', 11:53:39 INFO - 'tp6_youtube', 11:53:39 INFO - 'tp6_amazon', 11:53:39 INFO - 'tp6_facebook']}, 11:53:39 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:39 INFO - 'talos_options': ['--disable-stylo', 11:53:39 INFO - '--mitmproxy', 11:53:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:39 INFO - '--firstNonBlankPaint'], 11:53:39 INFO - 'tests': ['tp6_google', 11:53:39 INFO - 'tp6_youtube', 11:53:39 INFO - 'tp6_amazon', 11:53:39 INFO - 'tp6_facebook']}, 11:53:39 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:39 INFO - 'talos_options': ['--stylo-threads=1', 11:53:39 INFO - '--mitmproxy', 11:53:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:39 INFO - '--firstNonBlankPaint'], 11:53:39 INFO - 'tests': ['tp6_google', 11:53:39 INFO - 'tp6_youtube', 11:53:39 INFO - 'tp6_amazon', 11:53:39 INFO - 'tp6_facebook']}, 11:53:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:53:39 INFO - 'talos_options': ['--xperf_path', 11:53:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:39 INFO - 'tests': ['tp5n']}, 11:53:39 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:53:39 INFO - 'talos_options': ['--disable-stylo', 11:53:39 INFO - '--xperf_path', 11:53:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:39 INFO - 'tests': ['tp5n']}}} 11:53:39 INFO - Running post-action listener: _resource_record_post_action 11:53:39 INFO - [mozharness: 2017-09-27 18:53:39.445692Z] Finished populate-webroot step (success) 11:53:39 INFO - [mozharness: 2017-09-27 18:53:39.445781Z] Running create-virtualenv step. 11:53:39 INFO - Running pre-action listener: _resource_record_pre_action 11:53:39 INFO - Running main action method: create_virtualenv 11:53:39 INFO - Creating virtualenv /builds/slave/test/build/venv 11:53:39 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:53:39 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:53:39 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:53:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:53:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:53:40 INFO - Installing distribute.............................................................................................................................................................................................done. 11:53:40 INFO - Installing pip...............done. 11:53:40 INFO - Return code: 0 11:53:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:53:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:53:40 INFO - Reading from file tmpfile_stdout 11:53:40 INFO - Output received: 11:53:40 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:53:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e9df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:53:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:53:40 INFO - Using env: {'DISPLAY': ':0', 11:53:40 INFO - 'HOME': '/home/cltbld', 11:53:40 INFO - 'LANG': 'en_US.UTF-8', 11:53:40 INFO - 'LANGUAGE': 'en_US:en', 11:53:40 INFO - 'LOGNAME': 'cltbld', 11:53:40 INFO - 'MAIL': '/var/mail/cltbld', 11:53:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:40 INFO - 'MOZ_NO_REMOTE': '1', 11:53:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:40 INFO - 'NO_EM_RESTART': '1', 11:53:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:40 INFO - 'PWD': '/builds/slave/test', 11:53:40 INFO - 'SHELL': '/bin/bash', 11:53:40 INFO - 'SHLVL': '1', 11:53:40 INFO - 'TERM': 'linux', 11:53:40 INFO - 'TMOUT': '86400', 11:53:40 INFO - 'USER': 'cltbld', 11:53:40 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:53:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:40 INFO - '_': '/tools/buildbot/bin/python'} 11:53:41 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:53:41 INFO - Downloading/unpacking pip>=1.5 11:53:41 INFO - Running setup.py egg_info for package pip 11:53:41 INFO - warning: no files found matching 'pip/cacert.pem' 11:53:41 INFO - warning: no files found matching '*.html' under directory 'docs' 11:53:41 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:53:41 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:53:41 INFO - Installing collected packages: pip 11:53:41 INFO - Found existing installation: pip 1.1 11:53:41 INFO - Uninstalling pip: 11:53:41 INFO - Successfully uninstalled pip 11:53:41 INFO - Running setup.py install for pip 11:53:41 INFO - warning: no files found matching 'pip/cacert.pem' 11:53:41 INFO - warning: no files found matching '*.html' under directory 'docs' 11:53:41 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:53:41 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:53:41 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:53:41 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:53:41 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:53:41 INFO - Successfully installed pip 11:53:41 INFO - Cleaning up... 11:53:41 INFO - Return code: 0 11:53:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:53:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e9df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:53:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:53:41 INFO - Using env: {'DISPLAY': ':0', 11:53:41 INFO - 'HOME': '/home/cltbld', 11:53:41 INFO - 'LANG': 'en_US.UTF-8', 11:53:41 INFO - 'LANGUAGE': 'en_US:en', 11:53:41 INFO - 'LOGNAME': 'cltbld', 11:53:41 INFO - 'MAIL': '/var/mail/cltbld', 11:53:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:41 INFO - 'MOZ_NO_REMOTE': '1', 11:53:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:41 INFO - 'NO_EM_RESTART': '1', 11:53:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:41 INFO - 'PWD': '/builds/slave/test', 11:53:41 INFO - 'SHELL': '/bin/bash', 11:53:41 INFO - 'SHLVL': '1', 11:53:41 INFO - 'TERM': 'linux', 11:53:41 INFO - 'TMOUT': '86400', 11:53:41 INFO - 'USER': 'cltbld', 11:53:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:53:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:41 INFO - '_': '/tools/buildbot/bin/python'} 11:53:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:41 INFO - Downloading/unpacking psutil>=3.1.1 11:53:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:53:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:53:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:53:43 INFO - Installing collected packages: psutil 11:53:43 INFO - Running setup.py install for psutil 11:53:43 INFO - building 'psutil._psutil_linux' extension 11:53:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:53:43 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:53:43 INFO - building 'psutil._psutil_posix' extension 11:53:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:53:43 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:53:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:53:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:53:43 INFO - Successfully installed psutil 11:53:43 INFO - Cleaning up... 11:53:43 INFO - Return code: 0 11:53:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:53:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e9df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:53:43 INFO - Using env: {'DISPLAY': ':0', 11:53:43 INFO - 'HOME': '/home/cltbld', 11:53:43 INFO - 'LANG': 'en_US.UTF-8', 11:53:43 INFO - 'LANGUAGE': 'en_US:en', 11:53:43 INFO - 'LOGNAME': 'cltbld', 11:53:43 INFO - 'MAIL': '/var/mail/cltbld', 11:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:43 INFO - 'MOZ_NO_REMOTE': '1', 11:53:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:43 INFO - 'NO_EM_RESTART': '1', 11:53:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:43 INFO - 'PWD': '/builds/slave/test', 11:53:43 INFO - 'SHELL': '/bin/bash', 11:53:43 INFO - 'SHLVL': '1', 11:53:43 INFO - 'TERM': 'linux', 11:53:43 INFO - 'TMOUT': '86400', 11:53:43 INFO - 'USER': 'cltbld', 11:53:43 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:43 INFO - '_': '/tools/buildbot/bin/python'} 11:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:53:45 INFO - Installing collected packages: mozsystemmonitor 11:53:45 INFO - Running setup.py install for mozsystemmonitor 11:53:45 INFO - Successfully installed mozsystemmonitor 11:53:45 INFO - Cleaning up... 11:53:45 INFO - Return code: 0 11:53:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e9df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:53:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:53:45 INFO - Using env: {'DISPLAY': ':0', 11:53:45 INFO - 'HOME': '/home/cltbld', 11:53:45 INFO - 'LANG': 'en_US.UTF-8', 11:53:45 INFO - 'LANGUAGE': 'en_US:en', 11:53:45 INFO - 'LOGNAME': 'cltbld', 11:53:45 INFO - 'MAIL': '/var/mail/cltbld', 11:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:45 INFO - 'MOZ_NO_REMOTE': '1', 11:53:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:45 INFO - 'NO_EM_RESTART': '1', 11:53:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:45 INFO - 'PWD': '/builds/slave/test', 11:53:45 INFO - 'SHELL': '/bin/bash', 11:53:45 INFO - 'SHLVL': '1', 11:53:45 INFO - 'TERM': 'linux', 11:53:45 INFO - 'TMOUT': '86400', 11:53:45 INFO - 'USER': 'cltbld', 11:53:45 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:45 INFO - '_': '/tools/buildbot/bin/python'} 11:53:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:46 INFO - Downloading/unpacking jsonschema==2.5.1 11:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:53:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:48 INFO - Downloading functools32-3.2.3-2.tar.gz 11:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:53:48 INFO - warning: no files found matching '*.txt' 11:53:48 INFO - no previously-included directories found matching 'build' 11:53:48 INFO - no previously-included directories found matching 'dist' 11:53:48 INFO - no previously-included directories found matching '.git*' 11:53:48 INFO - Installing collected packages: jsonschema, functools32 11:53:48 INFO - Running setup.py install for functools32 11:53:49 INFO - warning: no files found matching '*.txt' 11:53:49 INFO - no previously-included directories found matching 'build' 11:53:49 INFO - no previously-included directories found matching 'dist' 11:53:49 INFO - no previously-included directories found matching '.git*' 11:53:49 INFO - Successfully installed jsonschema functools32 11:53:49 INFO - Cleaning up... 11:53:49 INFO - Return code: 0 11:53:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e9df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:53:49 INFO - Using env: {'DISPLAY': ':0', 11:53:49 INFO - 'HOME': '/home/cltbld', 11:53:49 INFO - 'LANG': 'en_US.UTF-8', 11:53:49 INFO - 'LANGUAGE': 'en_US:en', 11:53:49 INFO - 'LOGNAME': 'cltbld', 11:53:49 INFO - 'MAIL': '/var/mail/cltbld', 11:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:49 INFO - 'MOZ_NO_REMOTE': '1', 11:53:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:49 INFO - 'NO_EM_RESTART': '1', 11:53:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:49 INFO - 'PWD': '/builds/slave/test', 11:53:49 INFO - 'SHELL': '/bin/bash', 11:53:49 INFO - 'SHLVL': '1', 11:53:49 INFO - 'TERM': 'linux', 11:53:49 INFO - 'TMOUT': '86400', 11:53:49 INFO - 'USER': 'cltbld', 11:53:49 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:49 INFO - '_': '/tools/buildbot/bin/python'} 11:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:53:49 INFO - Cleaning up... 11:53:49 INFO - Return code: 0 11:53:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e9df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:53:49 INFO - Using env: {'DISPLAY': ':0', 11:53:49 INFO - 'HOME': '/home/cltbld', 11:53:49 INFO - 'LANG': 'en_US.UTF-8', 11:53:49 INFO - 'LANGUAGE': 'en_US:en', 11:53:49 INFO - 'LOGNAME': 'cltbld', 11:53:49 INFO - 'MAIL': '/var/mail/cltbld', 11:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:49 INFO - 'MOZ_NO_REMOTE': '1', 11:53:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:49 INFO - 'NO_EM_RESTART': '1', 11:53:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:49 INFO - 'PWD': '/builds/slave/test', 11:53:49 INFO - 'SHELL': '/bin/bash', 11:53:49 INFO - 'SHLVL': '1', 11:53:49 INFO - 'TERM': 'linux', 11:53:49 INFO - 'TMOUT': '86400', 11:53:49 INFO - 'USER': 'cltbld', 11:53:49 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:49 INFO - '_': '/tools/buildbot/bin/python'} 11:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:49 INFO - Downloading/unpacking blobuploader==1.2.4 11:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:50 INFO - Downloading blobuploader-1.2.4.tar.gz 11:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:53:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:53:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:51 INFO - Downloading docopt-0.6.1.tar.gz 11:53:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:53:51 INFO - Installing collected packages: blobuploader, requests, docopt 11:53:51 INFO - Running setup.py install for blobuploader 11:53:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:53:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:53:51 INFO - Running setup.py install for requests 11:53:51 INFO - Running setup.py install for docopt 11:53:51 INFO - Successfully installed blobuploader requests docopt 11:53:51 INFO - Cleaning up... 11:53:51 INFO - Return code: 0 11:53:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e9df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:53:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:53:51 INFO - Using env: {'DISPLAY': ':0', 11:53:51 INFO - 'HOME': '/home/cltbld', 11:53:51 INFO - 'LANG': 'en_US.UTF-8', 11:53:51 INFO - 'LANGUAGE': 'en_US:en', 11:53:51 INFO - 'LOGNAME': 'cltbld', 11:53:51 INFO - 'MAIL': '/var/mail/cltbld', 11:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:51 INFO - 'MOZ_NO_REMOTE': '1', 11:53:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:51 INFO - 'NO_EM_RESTART': '1', 11:53:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:51 INFO - 'PWD': '/builds/slave/test', 11:53:51 INFO - 'SHELL': '/bin/bash', 11:53:51 INFO - 'SHLVL': '1', 11:53:51 INFO - 'TERM': 'linux', 11:53:51 INFO - 'TMOUT': '86400', 11:53:51 INFO - 'USER': 'cltbld', 11:53:51 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:51 INFO - '_': '/tools/buildbot/bin/python'} 11:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:53:52 INFO - Running setup.py (path:/tmp/pip-XSLsK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:53:52 INFO - Running setup.py (path:/tmp/pip-zWVUFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:53:52 INFO - Running setup.py (path:/tmp/pip-oP9qdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:53:52 INFO - Running setup.py (path:/tmp/pip-JyWYOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:53:52 INFO - Running setup.py (path:/tmp/pip-sRzCU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:53:52 INFO - Running setup.py (path:/tmp/pip-znUyoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:53:52 INFO - Running setup.py (path:/tmp/pip-40GD2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:53:52 INFO - Running setup.py (path:/tmp/pip-Y8FX3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:53:52 INFO - Running setup.py (path:/tmp/pip-Tsm8MB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:53:52 INFO - Running setup.py (path:/tmp/pip-dGz6As-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:53:52 INFO - Running setup.py (path:/tmp/pip-DWXyZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:53:52 INFO - Running setup.py (path:/tmp/pip-Wx0L5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:53:52 INFO - Running setup.py (path:/tmp/pip-hvRwue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:53:52 INFO - Running setup.py (path:/tmp/pip-w7PBb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:53 INFO - Running setup.py (path:/tmp/pip-n_OX4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:53:53 INFO - Running setup.py (path:/tmp/pip-pDw7tD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:53:53 INFO - Running setup.py (path:/tmp/pip-lYu84W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:53:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:53:53 INFO - Running setup.py install for manifestparser 11:53:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:53:53 INFO - Running setup.py install for mozcrash 11:53:53 INFO - Running setup.py install for mozdebug 11:53:53 INFO - Running setup.py install for mozdevice 11:53:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:53:53 INFO - Running setup.py install for mozfile 11:53:53 INFO - Running setup.py install for mozhttpd 11:53:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:53:53 INFO - Running setup.py install for mozinfo 11:53:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:53:53 INFO - Running setup.py install for mozInstall 11:53:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:53:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:53:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:53:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:53:54 INFO - Running setup.py install for mozleak 11:53:54 INFO - Running setup.py install for mozlog 11:53:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:53:54 INFO - Running setup.py install for moznetwork 11:53:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:53:54 INFO - Running setup.py install for mozprocess 11:53:54 INFO - Running setup.py install for mozprofile 11:53:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:53:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:53:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:53:54 INFO - Running setup.py install for mozrunner 11:53:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:53:54 INFO - Running setup.py install for mozscreenshot 11:53:54 INFO - Running setup.py install for moztest 11:53:54 INFO - Running setup.py install for mozversion 11:53:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:53:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:53:54 INFO - Cleaning up... 11:53:55 INFO - Return code: 0 11:53:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e9df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:53:55 INFO - Using env: {'DISPLAY': ':0', 11:53:55 INFO - 'HOME': '/home/cltbld', 11:53:55 INFO - 'LANG': 'en_US.UTF-8', 11:53:55 INFO - 'LANGUAGE': 'en_US:en', 11:53:55 INFO - 'LOGNAME': 'cltbld', 11:53:55 INFO - 'MAIL': '/var/mail/cltbld', 11:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:55 INFO - 'MOZ_NO_REMOTE': '1', 11:53:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:55 INFO - 'NO_EM_RESTART': '1', 11:53:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:55 INFO - 'PWD': '/builds/slave/test', 11:53:55 INFO - 'SHELL': '/bin/bash', 11:53:55 INFO - 'SHLVL': '1', 11:53:55 INFO - 'TERM': 'linux', 11:53:55 INFO - 'TMOUT': '86400', 11:53:55 INFO - 'USER': 'cltbld', 11:53:55 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:55 INFO - '_': '/tools/buildbot/bin/python'} 11:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:53:55 INFO - Running setup.py (path:/tmp/pip-ffc_W4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:53:55 INFO - Running setup.py (path:/tmp/pip-HSIUS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:53:55 INFO - Running setup.py (path:/tmp/pip-SAYB5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:53:55 INFO - Running setup.py (path:/tmp/pip-HmpwJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:53:55 INFO - Running setup.py (path:/tmp/pip-cjRJOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:53:55 INFO - Running setup.py (path:/tmp/pip-pA_acx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:53:55 INFO - Running setup.py (path:/tmp/pip-gewN_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:53:55 INFO - Running setup.py (path:/tmp/pip-dB0tIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:53:55 INFO - Running setup.py (path:/tmp/pip-2on4Z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:53:55 INFO - Running setup.py (path:/tmp/pip-Pbm73G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:53:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:53:55 INFO - Running setup.py (path:/tmp/pip-XD3Voi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:53:56 INFO - Running setup.py (path:/tmp/pip-CvkZNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:53:56 INFO - Running setup.py (path:/tmp/pip-FrYbXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:53:56 INFO - Running setup.py (path:/tmp/pip-QfivX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:56 INFO - Running setup.py (path:/tmp/pip-xmMPNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:53:56 INFO - Running setup.py (path:/tmp/pip-n3irGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:53:56 INFO - Running setup.py (path:/tmp/pip-By7MN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:53:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:57 INFO - Downloading blessings-1.6.tar.gz 11:53:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:53:57 INFO - Installing collected packages: blessings 11:53:57 INFO - Running setup.py install for blessings 11:53:57 INFO - Successfully installed blessings 11:53:57 INFO - Cleaning up... 11:53:57 INFO - Return code: 0 11:53:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:53:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:53:57 INFO - Reading from file tmpfile_stdout 11:53:57 INFO - Current package versions: 11:53:57 INFO - argparse == 1.2.1 11:53:57 INFO - blessings == 1.6 11:53:57 INFO - blobuploader == 1.2.4 11:53:57 INFO - docopt == 0.6.1 11:53:57 INFO - functools32 == 3.2.3-2 11:53:57 INFO - jsonschema == 2.5.1 11:53:57 INFO - manifestparser == 1.1 11:53:57 INFO - mozInstall == 1.13 11:53:57 INFO - mozcrash == 1.0 11:53:57 INFO - mozdebug == 0.1 11:53:57 INFO - mozdevice == 0.51 11:53:57 INFO - mozfile == 1.2 11:53:57 INFO - mozhttpd == 0.7 11:53:57 INFO - mozinfo == 0.10 11:53:57 INFO - mozleak == 0.1 11:53:57 INFO - mozlog == 3.5 11:53:57 INFO - moznetwork == 0.27 11:53:57 INFO - mozprocess == 0.25 11:53:57 INFO - mozprofile == 0.28 11:53:57 INFO - mozrunner == 6.13 11:53:57 INFO - mozscreenshot == 0.1 11:53:57 INFO - mozsystemmonitor == 0.3 11:53:57 INFO - moztest == 0.8 11:53:57 INFO - mozversion == 1.4 11:53:57 INFO - psutil == 3.1.1 11:53:57 INFO - requests == 1.2.3 11:53:57 INFO - wsgiref == 0.1.2 11:53:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e9df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:53:58 INFO - Using env: {'DISPLAY': ':0', 11:53:58 INFO - 'HOME': '/home/cltbld', 11:53:58 INFO - 'LANG': 'en_US.UTF-8', 11:53:58 INFO - 'LANGUAGE': 'en_US:en', 11:53:58 INFO - 'LOGNAME': 'cltbld', 11:53:58 INFO - 'MAIL': '/var/mail/cltbld', 11:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:58 INFO - 'MOZ_NO_REMOTE': '1', 11:53:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:58 INFO - 'NO_EM_RESTART': '1', 11:53:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:58 INFO - 'PWD': '/builds/slave/test', 11:53:58 INFO - 'SHELL': '/bin/bash', 11:53:58 INFO - 'SHLVL': '1', 11:53:58 INFO - 'TERM': 'linux', 11:53:58 INFO - 'TMOUT': '86400', 11:53:58 INFO - 'USER': 'cltbld', 11:53:58 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:58 INFO - '_': '/tools/buildbot/bin/python'} 11:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:53:58 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:53:59 INFO - Installing collected packages: simplejson 11:53:59 INFO - Running setup.py install for simplejson 11:53:59 INFO - building 'simplejson._speedups' extension 11:53:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 11:54:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 11:54:00 INFO - Successfully installed simplejson 11:54:00 INFO - Cleaning up... 11:54:00 INFO - Return code: 0 11:54:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e9df90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:54:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:54:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:54:00 INFO - Using env: {'DISPLAY': ':0', 11:54:00 INFO - 'HOME': '/home/cltbld', 11:54:00 INFO - 'LANG': 'en_US.UTF-8', 11:54:00 INFO - 'LANGUAGE': 'en_US:en', 11:54:00 INFO - 'LOGNAME': 'cltbld', 11:54:00 INFO - 'MAIL': '/var/mail/cltbld', 11:54:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:00 INFO - 'MOZ_NO_REMOTE': '1', 11:54:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:00 INFO - 'NO_EM_RESTART': '1', 11:54:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:00 INFO - 'PWD': '/builds/slave/test', 11:54:00 INFO - 'SHELL': '/bin/bash', 11:54:00 INFO - 'SHLVL': '1', 11:54:00 INFO - 'TERM': 'linux', 11:54:00 INFO - 'TMOUT': '86400', 11:54:00 INFO - 'USER': 'cltbld', 11:54:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:54:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:00 INFO - '_': '/tools/buildbot/bin/python'} 11:54:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:54:00 INFO - Cleaning up... 11:54:00 INFO - Return code: 0 11:54:00 INFO - Running post-action listener: _resource_record_post_action 11:54:00 INFO - Running post-action listener: _start_resource_monitoring 11:54:00 INFO - Starting resource monitoring. 11:54:00 INFO - [mozharness: 2017-09-27 18:54:00.715820Z] Finished create-virtualenv step (success) 11:54:00 INFO - [mozharness: 2017-09-27 18:54:00.716285Z] Running install step. 11:54:00 INFO - Running pre-action listener: _resource_record_pre_action 11:54:00 INFO - Running main action method: install 11:54:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:54:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:54:00 INFO - Reading from file tmpfile_stdout 11:54:00 INFO - Detecting whether we're running mozinstall >=1.0... 11:54:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:54:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:54:01 INFO - Reading from file tmpfile_stdout 11:54:01 INFO - Output received: 11:54:01 INFO - Usage: mozinstall [options] installer 11:54:01 INFO - Options: 11:54:01 INFO - -h, --help show this help message and exit 11:54:01 INFO - -d DEST, --destination=DEST 11:54:01 INFO - Directory to install application into. [default: 11:54:01 INFO - "/builds/slave/test"] 11:54:01 INFO - --app=APP Application being installed. [default: firefox] 11:54:01 INFO - mkdir: /builds/slave/test/build/application 11:54:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:54:15 INFO - Reading from file tmpfile_stdout 11:54:15 INFO - Output received: 11:54:15 INFO - /builds/slave/test/build/application/firefox/firefox 11:54:15 INFO - Running post-action listener: _resource_record_post_action 11:54:15 INFO - [mozharness: 2017-09-27 18:54:15.665042Z] Finished install step (success) 11:54:15 INFO - [mozharness: 2017-09-27 18:54:15.665292Z] Running setup-mitmproxy step. 11:54:15 INFO - Running pre-action listener: _resource_record_pre_action 11:54:15 INFO - Running main action method: setup_mitmproxy 11:54:15 INFO - Skipping: mitmproxy is not required 11:54:15 INFO - Running post-action listener: _resource_record_post_action 11:54:15 INFO - [mozharness: 2017-09-27 18:54:15.666414Z] Finished setup-mitmproxy step (success) 11:54:15 INFO - [mozharness: 2017-09-27 18:54:15.666633Z] Running run-tests step. 11:54:15 INFO - Running pre-action listener: _resource_record_pre_action 11:54:15 INFO - Running pre-action listener: _set_gcov_prefix 11:54:15 INFO - Running main action method: run_tests 11:54:15 WARNING - Try message not found. 11:54:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:54:15 INFO - Python 2.7.3 11:54:15 INFO - Return code: 0 11:54:15 INFO - grabbing minidump binary from tooltool 11:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:15 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ea3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e9df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:54:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:54:15 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:54:15 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:54:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:54:15 INFO - Return code: 0 11:54:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:54:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:54:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:54:15 INFO - ENV: RUST_BACKTRACE is now full 11:54:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:54:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:54:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:54:15 INFO - Using env: {'DISPLAY': ':0', 11:54:15 INFO - 'HOME': '/home/cltbld', 11:54:15 INFO - 'LANG': 'en_US.UTF-8', 11:54:15 INFO - 'LANGUAGE': 'en_US:en', 11:54:15 INFO - 'LOGNAME': 'cltbld', 11:54:15 INFO - 'MAIL': '/var/mail/cltbld', 11:54:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:54:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:54:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:15 INFO - 'MOZ_NO_REMOTE': '1', 11:54:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:54:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:15 INFO - 'NO_EM_RESTART': '1', 11:54:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:15 INFO - 'PWD': '/builds/slave/test', 11:54:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:54:15 INFO - 'RUST_BACKTRACE': 'full', 11:54:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:54:15 INFO - 'SHELL': '/bin/bash', 11:54:15 INFO - 'SHLVL': '1', 11:54:15 INFO - 'TERM': 'linux', 11:54:15 INFO - 'TMOUT': '86400', 11:54:15 INFO - 'USER': 'cltbld', 11:54:15 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:54:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:15 INFO - '_': '/tools/buildbot/bin/python'} 11:54:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:54:16 INFO - mozversion application_buildid: 20170927173756 11:54:16 INFO - mozversion application_changeset: c89417bb5ce904ef935563aa0e7ab9fc93f20f6f 11:54:16 INFO - mozversion application_display_name: Firefox 11:54:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:54:16 INFO - mozversion application_name: Firefox 11:54:16 INFO - mozversion application_remotingname: firefox 11:54:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:54:16 INFO - mozversion application_vendor: Mozilla 11:54:16 INFO - mozversion application_version: 57.0 11:54:16 INFO - mozversion platform_buildid: 20170927173756 11:54:16 INFO - mozversion platform_changeset: c89417bb5ce904ef935563aa0e7ab9fc93f20f6f 11:54:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:54:16 INFO - mozversion platform_version: 57.0 11:54:16 INFO - using testdate: 1506538456 11:54:16 INFO - actual date: 1506538456 11:54:16 INFO - starting webserver on 'localhost:50900' 11:54:16 INFO - SUITE-START | Running 2 tests 11:54:16 INFO - TEST-START | dromaeo_css 11:54:16 INFO - Initialising browser for dromaeo_css test... 11:54:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50900/getInfo.html -profile /tmp/tmpa2wqzz/profile 11:54:16 INFO - TEST-INFO | started process 7476 (/builds/slave/test/build/application/firefox/firefox http://localhost:50900/getInfo.html) 11:54:21 INFO - TEST-INFO | 7476: exit 0 11:54:21 INFO - Browser initialized. 11:54:21 INFO - Running cycle 1/1 for dromaeo_css test... 11:54:21 INFO - Using env: {'DISPLAY': ':0', 11:54:21 INFO - 'HOME': '/home/cltbld', 11:54:21 INFO - 'JSGC_DISABLE_POISONING': '1', 11:54:21 INFO - 'LANG': 'en_US.UTF-8', 11:54:21 INFO - 'LANGUAGE': 'en_US:en', 11:54:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:54:21 INFO - 'LOGNAME': 'cltbld', 11:54:21 INFO - 'MAIL': '/var/mail/cltbld', 11:54:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:54:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:54:21 INFO - 'MOZ_CRASHREPORTER': '1', 11:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:54:21 INFO - 'MOZ_NO_REMOTE': '1', 11:54:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:54:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:21 INFO - 'NO_EM_RESTART': '1', 11:54:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:21 INFO - 'PWD': '/builds/slave/test', 11:54:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:54:21 INFO - 'RUST_BACKTRACE': 'full', 11:54:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:54:21 INFO - 'SHELL': '/bin/bash', 11:54:21 INFO - 'SHLVL': '1', 11:54:21 INFO - 'TERM': 'linux', 11:54:21 INFO - 'TMOUT': '86400', 11:54:21 INFO - 'USER': 'cltbld', 11:54:21 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 11:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:21 INFO - '_': '/tools/buildbot/bin/python'} 11:54:21 INFO - TEST-INFO | started process 7664 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa2wqzz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:54:22 INFO - PID 7664 | 11:54:22 INFO - PID 7664 | (/builds/slave/test/build/application/firefox/firefox:7712): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:54:22 INFO - PID 7664 | 11:54:32 INFO - PID 7664 | RSS: Main: 141070336 11:54:32 INFO - PID 7664 | 11:57:29 INFO - PID 7664 | Cycle 1(1): loaded http://localhost:50900/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50900/tests/dromaeo/cssquery-ext.html) 11:57:30 INFO - PID 7664 | RSS: Main: 144953344 11:57:30 INFO - PID 7664 | 12:00:32 INFO - PID 7664 | Cycle 1(1): loaded http://localhost:50900/tests/dromaeo/cssquery-ext.html (next: http://localhost:50900/tests/dromaeo/cssquery-jquery.html) 12:00:32 INFO - PID 7664 | RSS: Main: 143572992 12:00:32 INFO - PID 7664 | 12:03:34 INFO - PID 7664 | Cycle 1(1): loaded http://localhost:50900/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50900/tests/dromaeo/cssquery-mootools.html) 12:03:34 INFO - PID 7664 | RSS: Main: 145190912 12:03:34 INFO - PID 7664 | 12:06:36 INFO - PID 7664 | Cycle 1(1): loaded http://localhost:50900/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50900/tests/dromaeo/cssquery-prototype.html) 12:06:36 INFO - PID 7664 | RSS: Main: 144609280 12:06:36 INFO - PID 7664 | 12:09:39 INFO - PID 7664 | Cycle 1(1): loaded http://localhost:50900/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50900/tests/dromaeo/cssquery-yui.html) 12:09:40 INFO - PID 7664 | RSS: Main: 146395136 12:09:40 INFO - PID 7664 | 12:12:41 INFO - PID 7664 | Cycle 1(1): loaded http://localhost:50900/tests/dromaeo/cssquery-yui.html (next: http://localhost:50900/tests/dromaeo/cssquery-dojo.html) 12:12:42 INFO - PID 7664 | RSS: Main: 147021824 12:12:42 INFO - PID 7664 | 12:12:42 INFO - PID 7664 | __start_tp_report 12:12:42 INFO - PID 7664 | _x_x_mozilla_page_load 12:12:42 INFO - PID 7664 | _x_x_mozilla_page_load_details 12:12:42 INFO - PID 7664 | |i|pagename|runs| 12:12:42 INFO - PID 7664 | |0;dojo.html;3945.9210815783686;3963.4451176835264;3977.3636218205093;3980.2238563480123;4020.93968590471;6320.083587879754;6332.683365831707;6349.80950571483;6412.294647588773;6423.19710036246;6335.493160547157;6363.032479435011;6409.038644203371;6422.293547709748;6425.228972523304;5589.413111623282;5675.262215911936;5689.573282003867;5731.054376027951;5762.942370576304;5698.487136157745;5704.600677952545;5717.3139223293265;5727.284089488814;5793;5779.508741756956;5792.623479473821;5807.535397168238;5816.33112192097;5819.82540523785;5748.109043098327;5767.682777447239;5807.245058142456;5820.417958204188;5857.531397488191;5791.218185544982;5846.9707651461895;5857.121431785224;5860.150278209679;5860.238169038041;6353.809385718389;6437.0988061671405;6471.741130354781;6494.318096599836;6517.087607734921;6752.729890804362;6793.796186114425;6871.209810871764;6906.171259448898;6990.54561453509;6946.72213111475;7000.579965202104;7020.964895175543;7034.753783617549;7035.6482175891;6334.1765570475545;6381.649009304487;6457.61254324742;6509.218893732735;6510.316416776218;7074.610896400747;7094.219635840053;7136.215016348197;7148.142222933281;7166.247544007909;6795.728170873209;6809.965950170316;6867.244603093655;6874.896876546856;6876.415504682058;6864.862702745917;6889.379955804002;6913.965430172817;6982.162140543167;6986.93013069873;6905.620190889449;6906.447484201251;6910.516263861481;6920.446364290845;6922.653867306614;6906.620135892474;6939.097917270722;6973.825654358681;6987.650617469208;6988.755393561201;5066.316047333637;5073.315102461121;5091.465396133427;5145.4597267287145;5156.04613146569;4563.06457176278;4661.044485880454;4667.603253723471;4678;4699.201135806921;6503.089567460559;6516.2506311773805;6579.407853293131;6583.703733332011;6597.406233439014;6810.250872404032;6892.620905850226;6958.617276049866;6979.965100174467;6981.476389270723;6445.645489498029;6509.772157974448;6524.054012168209;6580.473562115019;6598.406143447017;5776.682282474504;5802.88394232113;5837.6205546640185;5842.211301474332;5843.94156058434;7196.96401517989;7224.494285399972;7294.817629559304;7296.854062918712;7316.853662926712;6663.633500157538;6794.93205067943;6880.174379074543;6897.9655101725175;6977.1976222733;7140.036095127091;7155.713771449084;7156.212816590067;7157.141143062658;7157.856842863218;6764.425023872829;6858.279880612464;6859.9314006859295;6878.140232470942;6880.58716477013;6856.41720453767;6914.4468442525485;6918.5502942308585;6929;7003.684834182576;6581.308962558863;6615.966920165369;6619.4042536170045;6627.403533681992;6643.103181070687;6625.337466253529;6650.933490665225;6694.564853284521;6717.764878229238;6725.764598239038;6561.311062338576;6587.63767992765;6646.667666616553;6690.76582319636;6696.263411024881;6627.6354800484505;6751.324867513209;6802.693878775566;6832.829179270558;6869.690863911036;6392.744290228525;6475.384838440341;6526.151600291932;6532.281449040697;6608.43828274602 12:12:42 INFO - PID 7664 | |1;ext.html;966.1690945786622;970.0784254957792;995.8605795188672;1030.9278350515467;1036.0468369100424;42369.36445953309;42868;43177;43245.13509729807;43281.78359108208;41867.16265674692;41987.37018944718;42164.57835421645;42175.3673694894;42249;7979.920200797976;8168.795780105485;8171.346292296632;8172.305354044884;8173.877391839097;8052.5973701315;8147.348212143044;8153.30696890765;8159.347252219808;8165.714200003001;8598.312135029213;8831.205191532761;8841.513716745576;8842.159994800484;8872.11278872114;8710.259627931633;8753.868691969625;8766.167214114617;8767.956160219159;8795.076516965722;8604.440711353805;8680.56597170139;8686.609102590335;8689.739307820711;8705.869411958825;40426.19147617031;40567.18865622671;40724.18551628951;40808.79595602032;40865.79567102146;3784.0539865033743;3817.828197731109;3825.196708691178;3828.712846536521;3835.8657446989223;33232.1691957703;33355.33223338823;33485.16287092818;33497;33533.832330838435;5451.918221226685;5547.583931205176;5615.971920140414;5624.634398764068;5652.34997975234;226703;229012.99999999834;229203;229376;229536;52209.47790522046;52548;52612.47387526152;52728.836269823936;52823;55679.164812527844;55912;55970.720146399006;56083.43916560782;56149;18199.81800181981;18655.533611659546;18658.720119198224;18659.160337784833;18663.346782862536;15439.382424703112;15443;15451.61370965735;15459.458918937784;15501.922490387702;15287.235638218268;15597;15597.376104955674;15633.218339083;15682.294296756674;6185.319614842364;6238.937610623836;6243.43809057178;6324.588901721373;6347.0479428086155;1060.6340812419703;1080.1250986700798;1097.319661809683;1104.5140138339104;1113.4544073404136;25569;25899.482010360065;25960.480790384463;25981.22056338313;25994.87002564975;1194.0507296699145;1212.6059030814845;1221.7067903703048;1227.1226073357593;1249.7063190150307;13555.390007449689;13707.657308567366;13719.039667223398;13744.312784360742;13759.312034398441;278746;282417;284272;287253;289917;56911;59375;59444;59524.702376488705;59530.404695952486;1553.4252326639216;1581.8339074397252;1588.8411158884019;1595.6888406760802;1603.3987254779458;30435.391292174474;30888.227794305323;30932.845335773178;31040.534391983685;31181.688183118786;41548.79225603853;41574.376384354866;41583;41628.79185604052;41694.16611667689;245091;245955;246450;246747;246775;35301.47047794337;36257.81871090628;36563.268734624624;36687.63312366842;36699.816500917324;47930.76034619805;48019.03961920672;48413.757931210115;48611.75694121507;48637.51362486471;35003.649963501055;35349.1162720934;35560.64439355573;35759.64240357563;36137.27725445424;35562.28875422425;35676.82161589279;35789.747358842316;35920.820395898896;36289;40653.796731016155;41093.17813643651;41198.38202426906;41256.381154283314;41328.38007429831;34669.826650866584;34975.82512087423;35092.473612895315;35130.64869351274;35384.292314153055;26279.605805912543;26290.4741905157;26370.4725905477;26590.867045664647;26689.599656005565 12:12:42 INFO - PID 7664 | |2;jquery.html;632.0147149930601;641.0695584939269;642.8225486886712;645.0346080212065;655.5123307049746;24169.91235394407;25057.12300069498;25117.49765004699;25159.245222643305;25206.621900671504;26726.331841703966;26801.19596412106;26977;27046.323841903963;27057.72942270577;6486.870262594745;6665.300143484937;6886.931130688704;6938.41023513002;6938.653067346638;6805.149356330458;6919.377256046955;6920.06579111821;6925.30746925306;6930.272321406255;6714.597124172541;6786.151731033621;6831.2144103428;7008.439324853999;7009.299070093001;6968.338007889268;7016.543924644907;7021.332973367522;7024.1571011478945;7036.226015138305;6549.410553050201;6555.770548030844;6567.835804104888;6572.14562106923;6575.178102737158;29202;29400;29827.701722982925;29913.850430747923;30299.848500757354;2060.32009436887;2325.732540757818;2348.7416384197722;2360.256519196465;2364.6807680963193;20647.58704825895;20886.164553417846;21041.789582104135;21100.5779884403;21129.47176320589;2527.9241622751347;2614.16346769034;2645.470905818844;2664.9733502664917;2687.516247075526;144567;148024;157193;164976;166138.1693091539;16357.754633680504;16868;16948.74576881348;16955.830441695423;17210.139493025297;27032.864835675697;27104.32239194036;27207.727922720915;27483.587746183824;27485.17544473669;11283.887161128283;11482.540698372139;11636.476358563885;11673.474693638807;11938.22401543897;10570.788584228427;10732.195085368628;10803.675889723321;10877.292975956538;11034.558617655364;9987.500624968723;10116.583678285046;10212.795744085077;10425.895741042492;10458.581656733797;3014.3217776000224;3025.8940937067537;3055.663876973531;3057;3129.890453834105;1057.8571892794528;1065.5524679634573;1080.1844607321423;1084.6420681175193;1087.994560027195;21511.247106351202;21623.459413514473;21642.891785541287;21681;22121.77878221197;1138.1805100327751;1150;1157.484919210943;1162.8023236049892;1166.4284500594647;11640.592579259684;11684.415779211175;11709.824352634718;11789.351585662871;11954.282743035446;632797;639197;648212;654357;670643;136841;140154;142731.2863435697;143096.28451857882;143116.28441857724;1259.9055070869722;1273.6242808371553;1292.5476083370745;1303.6610481274747;1309.9345032748324;18579.349722759638;19015.619687606446;19062.428127156207;19365.32221372245;19734.60530789405;62784.68607656933;62823.37176628175;62873.37126628858;63636;63639;77186;77663;77863.61068194623;78311;81399;16011.839881601034;16183.919080404523;16232.675346492768;16394.508164755076;16405;24414.51170976535;24497.3875653103;24695.876520617283;24744.752552474245;25102.748972510773;19647.508812279808;19779.406617801487;19913;20077.899610502438;20163.59672806565;16750;17185.14074296255;17746.91126544359;17817;18046.278148874422;20852.37442876716;20987.265445709327;20988.47528811792;21361.35915922525;21371.14515419366;16001.359945602511;16005.35978560891;16071.11608861524;16086.195690215209;16093.75859362134;20058.59882802365;20328.79671203269;20449.488762780453;20496.69254961147;20695.79304206999 12:12:42 INFO - PID 7664 | |3;mootools.html;1427.7572812621856;1473.4548217159652;1486.4202960845266;1491.79860718803;1498.8725958293544;17420.303187872487;18112.275508979626;18142.546436339097;18153.092345382727;18220.08899555022;17613;17724.556886077855;17736.46790596281;17840.64318713625;17850.375236866712;2916.4167166566667;2943.58789769433;2996.5654980027884;2998.0855838969105;3006.5339872319823;2963.9851800740967;3023.833689147096;3035.2260173655686;3044.4824379855368;3048.908532744021;2929.604503392047;2991.087718245934;3067.7699172562034;3080.0451859923382;3086.66046734859;3036.1347016100417;3036.6507851597016;3046.040497243361;3051.4812481878134;3071.1247294521067;2887.0328439972636;2898.7970842041063;2901.50674385355;2918.620579324674;2921.3426978929783;16992.49022529314;17034.914825425916;17216.139193040424;17219.65560688779;17245.22396492161;8592.183742544448;8777.385583009192;8795.780105497413;8804.911950880472;8854.158854908774;5559.16612508127;5654.095344744821;5673.347565030033;5760.3951585083805;5835.4748072673665;2176.5864485747657;2185.715856938604;2201.933941981743;2247.202243203672;2259.9887000565054;32583.348333033442;32818;32856;32881.177970550925;32899.3420131596;7753.185915478906;7841.5687137208;7868.449208555347;8033.758987230392;8063.354931605458;20639.380818575468;20675.276365327143;20685.793142068385;20704.275350362666;20742.688859667116;8881;9121.133492318218;9134.680286189952;9150.725478235663;9196;8233.629486672995;8295.792605184797;8318.001839779265;8358.749237522885;8358.791030224293;5975.07386355107;6150.876982460326;6225.346338634468;6277.591956522811;6290.276618188876;1660.219696742529;1716.605180808396;1768.4252617898976;1785.2234278089072;1827.45176447068;954.4941181173989;1017.6082208349839;1023.4933707814531;1025.2823023883311;1030.1707125763683;9609.279304052085;9989.950050249703;10077.294589378673;10098.646547370807;10122.240831937634;826.574314228173;831.4055450353027;837.409626213518;853.3983541603155;898.9325800564984;6059.7273122709585;6074.939250607437;6177.938220617826;6325.778597749057;6375.330590288046;26348.20955371342;27084.1874743758;27108.45783084367;27484.312892177455;27527.17418477449;25519.36201594975;26700.465990680466;27216.31959200996;27250.31874203121;27332.59001114985;12553.623391298275;12582.43379047945;12634.431450584745;12677.683057923625;12702.237865728086;9689;10047.698569042941;10170.288079834341;10210.387376757419;10284.897151028394;21823.781762182174;21993.780062199814;22308;23089.076436942334;23187.188448404224;22975.195868144536;23345.766542335037;23600.763992359858;23782.40543986345;24028;12898.935505322414;13069.019823513465;13152.671183220113;13160.73678526405;13168.144070635379;15569.377224910877;16227.918860405622;16304.429344972868;16430.178491075636;16471.176441178133;13041.934790325988;13205.603831885059;13460.528881489101;13603.455861765418;13615.863841361459;13001.869981300066;13039.217646941213;13207;13223.66940826487;13241.404136814059;11174.608888688857;11197.496112674786;11320.26418282842;11335.206535542433;11340.432978350884;8426.368022398223;8436.662533498591;8479;8586.65653373858;8640.22237998558;8899.421537600036;8920.821583568422;8951.060138685345;8955.641774328953;8994.280457563511 12:12:42 INFO - PID 7664 | |4;prototype.html;562.6061756770262;567.6991194666826;582.4263100845669;590.4449817171858;596.9432903874132;6373.553851230416;6374.968125159374;6376.8724625507575;6381.9999999999945;6393.680315984206;6269.592476489025;6280.560360774748;6300.936990630093;6308.71610777515;6322.367763223675;5159.432462429129;5193.922091168635;5199.298094757197;5208.8437346879655;5222.9477705223035;5123.154679477901;5130.2040387988445;5185.974070129644;5200.661956972804;5230.320058392404;4878.438979517347;4972.875678108058;4976.776045077962;5002.1496345621335;5061.139606266925;5018.749062546858;5022.698638081727;5048.268001139851;5048.671836330645;5052.722100284483;4546.65900057497;4591.747453890035;4601.516840731709;4624.190766615829;4631.282151266559;6141.539384546177;6164.907526387108;6169.259688837368;6202.62784232943;6242.313345531988;5911.852203694899;5931.139984702238;5951.940480595182;5964;5991.221141251653;6563.803085907431;6579.868402631921;6587.967060164717;6618.338166183343;6658.667066646697;4891.633127515415;5048.419431765321;5087.465816089331;5119.334486516766;5125.897482050375;6669.730912579057;6876.140482439695;6887;6904.965475172642;7039.577625342496;5246.685198888079;5254.712595414072;5259.76331065103;5274.472552744694;5314.70769107695;5355.357357117093;5380.811671591476;5381.892362152735;5384.946150538444;5386.272853164773;5888.911666325009;5930.970345148246;5940.198073260054;5948.524118070544;5956.85107872298;6631.900521492182;6661;6696.229933557605;6730.7307707692125;6740.797776066725;1843.1889968413855;1848.8890666560048;1853.7404763333145;1858.6375656747075;1870.4108205915093;1269.3780047776522;1302.9153105048142;1317.2886641213638;1338.8259526261525;1341.47011930287;6236.656983865931;6266.561340706106;6311.400416960381;6356.904646430307;6366.108744775735;6588.077669126326;6648;6668.86662266752;6720.899186512206;6737.427318677869;6245.313765283348;6285.622862628257;6292.119103325538;6301.4328710416285;6377.489800815923;3541.787492750443;3541.8937431877084;3545.7517973742106;3560.2701446203464;3592.604813470516;7063.2583578724525;7084.397826184832;7131;7134.857302853914;7174.282571742784;6903.378695917291;6932.514723969374;6967.616781077089;6989.33601307875;6990.335918087773;6940.618265995418;6992.3357281058215;7048.682809273492;7063.540869843444;7092.680829362691;6580.276169621434;6644.33556644351;6688.799336019927;6696.263411024881;6703.296153903965;6559.508036897157;6611.636360000246;6651.235107962452;6728.259891411843;6742.93257067423;6915.446764258747;6950.131233595801;6960.721571137098;6965.582065076112;6972.58164510131;5147.253648221062;5229.50319719626;5265.763040663102;5273.973630131824;5287.074761916726;6505.60966342021;6506.674666266573;6685.565438246498;6723.966380168068;6747.392734653905;6321.683915804099;6432.742690292336;6503.642559485062;6519.445847102859;6574.539782215199;6421.293657697556;6422.916999840009;6491.253505846699;6504.9101676703185;6582.637954912526;6492.902606460812;6506.967465162644;6510.7721229756735;6556.2788093308745;6589.670516474253;6652.866942661217;6667.933320666732;6701.698423571049;6733.158355205599;6734.629595372302;6454;6460.967695161494;6471.449926756279;6506.60960342381;6518 12:12:42 INFO - PID 7664 | |5;yui.html;965.6041023180497;1002.3484734922301;1030.963916262931;1037.6627596031287;1049.6693541534416;19076.141573629186;19338.419847404584;19339.516512087204;19355.709664355047;19366.90316548417;20743.585128297425;20868.269610563635;21090.367288981353;21450.463738406546;21596.784032159674;1067.1302888146174;1087.0705546757526;1087.7552550676114;1088.352430303972;1088.5536929858758;1096.583298346627;1104.6741211342633;1109.7724966381872;1146.7305183281921;1154.1862986594472;1183.556166437586;1185.25921299188;1188.43549314076;1189.3934093612277;1190.5356910804794;1186.1044911092156;1189.4766302826729;1189.8334233207358;1190.7023244188954;1192.7554851255472;866.7313132928749;868.6221493650282;870.8606622940363;874.152072489684;876.2201640539924;19561.608767824564;19792.70310945337;19928.40214793544;20052.1979120835;22150.88924555383;23904.16335428252;24086.15698450546;24320.513589728107;24819.379515512257;24836;19088.809111908842;19226.903865480723;19336.70994935077;19394.418167454856;19394.418167455;4226.154769046172;4276.2302785498905;4283.164782867342;4283.807228674717;4286.42133312002;164965;168269;169582;170467.99999999875;171565;18324.175412106488;18539.165737541844;18615.906920465583;18624.53438664017;18696.25214991385;35158.472622910675;35620.82189589035;36189.0952726184;36278.81860590733;36287.81856090703;8431.57842107892;8501.404901656824;8532.744017679355;8570;8579.35654825878;4528.184926713157;4575.7483338416705;4587.9541204588195;4600.056988317387;4607;6649.4347980421235;6667;6690.929451287868;6745.561538499982;6758.695858686371;1107.1640911112058;1121.899029087386;1133.5919069135105;1137.8008848451652;1145.799485090106;765.8200322924108;773.5668025905511;786.9567173805495;790.675822912603;799.8240387114826;13855.445782168801;14222.288885555889;14442;14785.63035924089;14928.776068358984;782.5734974438831;786.6695987685072;787.4330482052912;790.4387884601882;799.3006119645311;6694.665266736643;6784.559003664845;6801.285864984205;6808.7276508939085;6823.38589526945;151636;153275;154151;159086;161933;133704;134291;135150;136811.31594341964;143989;116914;117877;121207;121380;122740.38629806794;14538;15534.922325388301;15630.140342280829;15815.68368632644;15962.60093497672;58324.70837645785;58440.707796460745;59240.407595925215;59364.70317648384;59452.40547594469;74846.62576687081;76383.23616763762;76465.23534764581;77067.61466192633;77387.22612774026;21460.463488412915;22124.11503539905;22165.66751498761;22198.223062192745;22217.333479995625;24167.39581510406;24184.51630967406;24226.636600450656;24443.633345499475;24492.755072449047;22664;22763.772362276166;22847.771522284565;22872.771272287064;23043.88478057666;19816;20197;20257.392278231677;20389;20599.38201853947;19603.509912252306;19708.704369434756;19895.403137905887;19927.900360498104;20152.798472015093;15225.923870380577;15517.301721422336;15520.37918483248;15576.688466230386;15662.530124096294;16042.759358609397;16067.372540797409;16098.919505402398;16266.268017938984;16590.50228493147 12:12:42 INFO - PID 7664 | __end_tp_report 12:12:42 INFO - PID 7664 | __start_cc_report 12:12:42 INFO - PID 7664 | _x_x_mozilla_cycle_collect,261 12:12:42 INFO - PID 7664 | __end_cc_report 12:12:42 INFO - PID 7664 | __startTimestamp1506539562101__endTimestamp 12:12:42 INFO - PID 7664 | 12:12:42 INFO - PID 7664 | ------- Summary: start ------- 12:12:42 INFO - PID 7664 | Number of tests: 6 12:12:42 INFO - PID 7664 | 12:12:42 INFO - PID 7664 | [#0] dojo.html Cycles:165 Average:6393.39 Median:6608.44 stddev:736.58 (11.1%) stddev-sans-first:713.38 12:12:42 INFO - PID 7664 | Values: 3945.9 3963.4 3977.4 3980.2 4020.9 6320.1 6332.7 6349.8 6412.3 6423.2 6335.5 6363.0 6409.0 6422.3 6425.2 5589.4 5675.3 5689.6 5731.1 5762.9 5698.5 5704.6 5717.3 5727.3 5793.0 5779.5 5792.6 5807.5 5816.3 5819.8 5748.1 5767.7 5807.2 5820.4 5857.5 5791.2 5847.0 5857.1 5860.2 5860.2 6353.8 6437.1 6471.7 6494.3 6517.1 6752.7 6793.8 6871.2 6906.2 6990.5 6946.7 7000.6 7021.0 7034.8 7035.6 6334.2 6381.6 6457.6 6509.2 6510.3 7074.6 7094.2 7136.2 7148.1 7166.2 6795.7 6810.0 6867.2 6874.9 6876.4 6864.9 6889.4 6914.0 6982.2 6986.9 6905.6 6906.4 6910.5 6920.4 6922.7 6906.6 6939.1 6973.8 6987.7 6988.8 5066.3 5073.3 5091.5 5145.5 5156.0 4563.1 4661.0 4667.6 4678.0 4699.2 6503.1 6516.3 6579.4 6583.7 6597.4 6810.3 6892.6 6958.6 6980.0 6981.5 6445.6 6509.8 6524.1 6580.5 6598.4 5776.7 5802.9 5837.6 5842.2 5843.9 7197.0 7224.5 7294.8 7296.9 7316.9 6663.6 6794.9 6880.2 6898.0 6977.2 7140.0 7155.7 7156.2 7157.1 7157.9 6764.4 6858.3 6859.9 6878.1 6880.6 6856.4 6914.4 6918.6 6929.0 7003.7 6581.3 6616.0 6619.4 6627.4 6643.1 6625.3 6650.9 6694.6 6717.8 6725.8 6561.3 6587.6 6646.7 6690.8 6696.3 6627.6 6751.3 6802.7 6832.8 6869.7 6392.7 6475.4 6526.2 6532.3 6608.4 12:12:42 INFO - PID 7664 | 12:12:42 INFO - PID 7664 | [#1] ext.html Cycles:180 Average:43597.98 Median:36693.72 stddev:65990.13 (179.8%) stddev-sans-first:66097.61 12:12:42 INFO - PID 7664 | Values: 966.2 970.1 995.9 1030.9 1036.0 42369.4 42868.0 43177.0 43245.1 43281.8 41867.2 41987.4 42164.6 42175.4 42249.0 7979.9 8168.8 8171.3 8172.3 8173.9 8052.6 8147.3 8153.3 8159.3 8165.7 8598.3 8831.2 8841.5 8842.2 8872.1 8710.3 8753.9 8766.2 8768.0 8795.1 8604.4 8680.6 8686.6 8689.7 8705.9 40426.2 40567.2 40724.2 40808.8 40865.8 3784.1 3817.8 3825.2 3828.7 3835.9 33232.2 33355.3 33485.2 33497.0 33533.8 5451.9 5547.6 5616.0 5624.6 5652.3 226703.0 229013.0 229203.0 229376.0 229536.0 52209.5 52548.0 52612.5 52728.8 52823.0 55679.2 55912.0 55970.7 56083.4 56149.0 18199.8 18655.5 18658.7 18659.2 18663.3 15439.4 15443.0 15451.6 15459.5 15501.9 15287.2 15597.0 15597.4 15633.2 15682.3 6185.3 6238.9 6243.4 6324.6 6347.0 1060.6 1080.1 1097.3 1104.5 1113.5 25569.0 25899.5 25960.5 25981.2 25994.9 1194.1 1212.6 1221.7 1227.1 1249.7 13555.4 13707.7 13719.0 13744.3 13759.3 278746.0 282417.0 284272.0 287253.0 289917.0 56911.0 59375.0 59444.0 59524.7 59530.4 1553.4 1581.8 1588.8 1595.7 1603.4 30435.4 30888.2 30932.8 31040.5 31181.7 41548.8 41574.4 41583.0 41628.8 41694.2 245091.0 245955.0 246450.0 246747.0 246775.0 35301.5 36257.8 36563.3 36687.6 36699.8 47930.8 48019.0 48413.8 48611.8 48637.5 35003.6 35349.1 35560.6 35759.6 36137.3 35562.3 35676.8 35789.7 35920.8 36289.0 40653.8 41093.2 41198.4 41256.4 41328.4 34669.8 34975.8 35092.5 35130.6 35384.3 26279.6 26290.5 26370.5 26590.9 26689.6 12:12:42 INFO - PID 7664 | 12:12:42 INFO - PID 7664 | [#2] jquery.html Cycles:180 Average:41727.75 Median:21567.35 stddev:108479.60 (503.0%) stddev-sans-first:108740.03 12:12:42 INFO - PID 7664 | Values: 632.0 641.1 642.8 645.0 655.5 24169.9 25057.1 25117.5 25159.2 25206.6 26726.3 26801.2 26977.0 27046.3 27057.7 6486.9 6665.3 6886.9 6938.4 6938.7 6805.1 6919.4 6920.1 6925.3 6930.3 6714.6 6786.2 6831.2 7008.4 7009.3 6968.3 7016.5 7021.3 7024.2 7036.2 6549.4 6555.8 6567.8 6572.1 6575.2 29202.0 29400.0 29827.7 29913.9 30299.8 2060.3 2325.7 2348.7 2360.3 2364.7 20647.6 20886.2 21041.8 21100.6 21129.5 2527.9 2614.2 2645.5 2665.0 2687.5 144567.0 148024.0 157193.0 164976.0 166138.2 16357.8 16868.0 16948.7 16955.8 17210.1 27032.9 27104.3 27207.7 27483.6 27485.2 11283.9 11482.5 11636.5 11673.5 11938.2 10570.8 10732.2 10803.7 10877.3 11034.6 9987.5 10116.6 10212.8 10425.9 10458.6 3014.3 3025.9 3055.7 3057.0 3129.9 1057.9 1065.6 1080.2 1084.6 1088.0 21511.2 21623.5 21642.9 21681.0 22121.8 1138.2 1150.0 1157.5 1162.8 1166.4 11640.6 11684.4 11709.8 11789.4 11954.3 632797.0 639197.0 648212.0 654357.0 670643.0 136841.0 140154.0 142731.3 143096.3 143116.3 1259.9 1273.6 1292.5 1303.7 1309.9 18579.3 19015.6 19062.4 19365.3 19734.6 62784.7 62823.4 62873.4 63636.0 63639.0 77186.0 77663.0 77863.6 78311.0 81399.0 16011.8 16183.9 16232.7 16394.5 16405.0 24414.5 24497.4 24695.9 24744.8 25102.7 19647.5 19779.4 19913.0 20077.9 20163.6 16750.0 17185.1 17746.9 17817.0 18046.3 20852.4 20987.3 20988.5 21361.4 21371.1 16001.4 16005.4 16071.1 16086.2 16093.8 20058.6 20328.8 20449.5 20496.7 20695.8 12:12:42 INFO - PID 7664 | 12:12:42 INFO - PID 7664 | [#3] mootools.html Cycles:180 Average:11056.50 Median:27162.39 stddev:8247.08 (30.4%) stddev-sans-first:8238.49 12:12:42 INFO - PID 7664 | Values: 1427.8 1473.5 1486.4 1491.8 1498.9 17420.3 18112.3 18142.5 18153.1 18220.1 17613.0 17724.6 17736.5 17840.6 17850.4 2916.4 2943.6 2996.6 2998.1 3006.5 2964.0 3023.8 3035.2 3044.5 3048.9 2929.6 2991.1 3067.8 3080.0 3086.7 3036.1 3036.7 3046.0 3051.5 3071.1 2887.0 2898.8 2901.5 2918.6 2921.3 16992.5 17034.9 17216.1 17219.7 17245.2 8592.2 8777.4 8795.8 8804.9 8854.2 5559.2 5654.1 5673.3 5760.4 5835.5 2176.6 2185.7 2201.9 2247.2 2260.0 32583.3 32818.0 32856.0 32881.2 32899.3 7753.2 7841.6 7868.4 8033.8 8063.4 20639.4 20675.3 20685.8 20704.3 20742.7 8881.0 9121.1 9134.7 9150.7 9196.0 8233.6 8295.8 8318.0 8358.7 8358.8 5975.1 6150.9 6225.3 6277.6 6290.3 1660.2 1716.6 1768.4 1785.2 1827.5 954.5 1017.6 1023.5 1025.3 1030.2 9609.3 9990.0 10077.3 10098.6 10122.2 826.6 831.4 837.4 853.4 898.9 6059.7 6074.9 6177.9 6325.8 6375.3 26348.2 27084.2 27108.5 27484.3 27527.2 25519.4 26700.5 27216.3 27250.3 27332.6 12553.6 12582.4 12634.4 12677.7 12702.2 9689.0 10047.7 10170.3 10210.4 10284.9 21823.8 21993.8 22308.0 23089.1 23187.2 22975.2 23345.8 23600.8 23782.4 24028.0 12898.9 13069.0 13152.7 13160.7 13168.1 15569.4 16227.9 16304.4 16430.2 16471.2 13041.9 13205.6 13460.5 13603.5 13615.9 13001.9 13039.2 13207.0 13223.7 13241.4 11174.6 11197.5 11320.3 11335.2 11340.4 8426.4 8436.7 8479.0 8586.7 8640.2 8899.4 8920.8 8951.1 8955.6 8994.3 12:12:42 INFO - PID 7664 | 12:12:42 INFO - PID 7664 | [#4] prototype.html Cycles:180 Average:5669.59 Median:6316.54 stddev:1564.88 (24.8%) stddev-sans-first:1521.60 12:12:42 INFO - PID 7664 | Values: 562.6 567.7 582.4 590.4 596.9 6373.6 6375.0 6376.9 6382.0 6393.7 6269.6 6280.6 6300.9 6308.7 6322.4 5159.4 5193.9 5199.3 5208.8 5222.9 5123.2 5130.2 5186.0 5200.7 5230.3 4878.4 4972.9 4976.8 5002.1 5061.1 5018.7 5022.7 5048.3 5048.7 5052.7 4546.7 4591.7 4601.5 4624.2 4631.3 6141.5 6164.9 6169.3 6202.6 6242.3 5911.9 5931.1 5951.9 5964.0 5991.2 6563.8 6579.9 6588.0 6618.3 6658.7 4891.6 5048.4 5087.5 5119.3 5125.9 6669.7 6876.1 6887.0 6905.0 7039.6 5246.7 5254.7 5259.8 5274.5 5314.7 5355.4 5380.8 5381.9 5384.9 5386.3 5888.9 5931.0 5940.2 5948.5 5956.9 6631.9 6661.0 6696.2 6730.7 6740.8 1843.2 1848.9 1853.7 1858.6 1870.4 1269.4 1302.9 1317.3 1338.8 1341.5 6236.7 6266.6 6311.4 6356.9 6366.1 6588.1 6648.0 6668.9 6720.9 6737.4 6245.3 6285.6 6292.1 6301.4 6377.5 3541.8 3541.9 3545.8 3560.3 3592.6 7063.3 7084.4 7131.0 7134.9 7174.3 6903.4 6932.5 6967.6 6989.3 6990.3 6940.6 6992.3 7048.7 7063.5 7092.7 6580.3 6644.3 6688.8 6696.3 6703.3 6559.5 6611.6 6651.2 6728.3 6742.9 6915.4 6950.1 6960.7 6965.6 6972.6 5147.3 5229.5 5265.8 5274.0 5287.1 6505.6 6506.7 6685.6 6724.0 6747.4 6321.7 6432.7 6503.6 6519.4 6574.5 6421.3 6422.9 6491.3 6504.9 6582.6 6492.9 6507.0 6510.8 6556.3 6589.7 6652.9 6667.9 6701.7 6733.2 6734.6 6454.0 6461.0 6471.4 6506.6 6518.0 12:12:42 INFO - PID 7664 | 12:12:42 INFO - PID 7664 | [#5] yui.html Cycles:180 Average:30196.05 Median:19928.15 stddev:44200.99 (221.8%) stddev-sans-first:44270.49 12:12:42 INFO - PID 7664 | Values: 965.6 1002.3 1031.0 1037.7 1049.7 19076.1 19338.4 19339.5 19355.7 19366.9 20743.6 20868.3 21090.4 21450.5 21596.8 1067.1 1087.1 1087.8 1088.4 1088.6 1096.6 1104.7 1109.8 1146.7 1154.2 1183.6 1185.3 1188.4 1189.4 1190.5 1186.1 1189.5 1189.8 1190.7 1192.8 866.7 868.6 870.9 874.2 876.2 19561.6 19792.7 19928.4 20052.2 22150.9 23904.2 24086.2 24320.5 24819.4 24836.0 19088.8 19226.9 19336.7 19394.4 19394.4 4226.2 4276.2 4283.2 4283.8 4286.4 164965.0 168269.0 169582.0 170468.0 171565.0 18324.2 18539.2 18615.9 18624.5 18696.3 35158.5 35620.8 36189.1 36278.8 36287.8 8431.6 8501.4 8532.7 8570.0 8579.4 4528.2 4575.7 4588.0 4600.1 4607.0 6649.4 6667.0 6690.9 6745.6 6758.7 1107.2 1121.9 1133.6 1137.8 1145.8 765.8 773.6 787.0 790.7 799.8 13855.4 14222.3 14442.0 14785.6 14928.8 782.6 786.7 787.4 790.4 799.3 6694.7 6784.6 6801.3 6808.7 6823.4 151636.0 153275.0 154151.0 159086.0 161933.0 133704.0 134291.0 135150.0 136811.3 143989.0 116914.0 117877.0 121207.0 121380.0 122740.4 14538.0 15534.9 15630.1 15815.7 15962.6 58324.7 58440.7 59240.4 59364.7 59452.4 74846.6 76383.2 76465.2 77067.6 77387.2 21460.5 22124.1 22165.7 22198.2 22217.3 24167.4 24184.5 24226.6 24443.6 24492.8 22664.0 22763.8 22847.8 22872.8 23043.9 19816.0 20197.0 20257.4 20389.0 20599.4 19603.5 19708.7 19895.4 19927.9 20152.8 15225.9 15517.3 15520.4 15576.7 15662.5 16042.8 16067.4 16098.9 16266.3 16590.5 12:12:42 INFO - PID 7664 | -------- Summary: end -------- 12:12:42 INFO - PID 7664 | 12:12:42 INFO - TEST-INFO | 7664: exit 0 12:12:42 INFO - TEST-OK | dromaeo_css | took 1106609ms 12:12:42 INFO - TEST-START | kraken 12:12:42 INFO - Initialising browser for kraken test... 12:12:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50900/getInfo.html -profile /tmp/tmprQxlCq/profile 12:12:42 INFO - TEST-INFO | started process 8027 (/builds/slave/test/build/application/firefox/firefox http://localhost:50900/getInfo.html) 12:12:47 INFO - TEST-INFO | 8027: exit 0 12:12:47 INFO - Browser initialized. 12:12:47 INFO - Running cycle 1/1 for kraken test... 12:12:47 INFO - Using env: {'DISPLAY': ':0', 12:12:47 INFO - 'HOME': '/home/cltbld', 12:12:47 INFO - 'JSGC_DISABLE_POISONING': '1', 12:12:47 INFO - 'LANG': 'en_US.UTF-8', 12:12:47 INFO - 'LANGUAGE': 'en_US:en', 12:12:47 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:12:47 INFO - 'LOGNAME': 'cltbld', 12:12:47 INFO - 'MAIL': '/var/mail/cltbld', 12:12:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:12:47 INFO - 'MOZ_CRASHREPORTER': '1', 12:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:47 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:12:47 INFO - 'MOZ_NO_REMOTE': '1', 12:12:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:47 INFO - 'NO_EM_RESTART': '1', 12:12:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:47 INFO - 'PWD': '/builds/slave/test', 12:12:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:47 INFO - 'RUST_BACKTRACE': 'full', 12:12:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:12:47 INFO - 'SHELL': '/bin/bash', 12:12:47 INFO - 'SHLVL': '1', 12:12:47 INFO - 'TERM': 'linux', 12:12:47 INFO - 'TMOUT': '86400', 12:12:47 INFO - 'USER': 'cltbld', 12:12:47 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295', 12:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:47 INFO - '_': '/tools/buildbot/bin/python'} 12:12:47 INFO - TEST-INFO | started process 8214 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprQxlCq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:48 INFO - PID 8214 | 12:12:48 INFO - PID 8214 | (/builds/slave/test/build/application/firefox/firefox:8261): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:12:48 INFO - PID 8214 | 12:12:58 INFO - PID 8214 | RSS: Main: 143802368 12:12:58 INFO - PID 8214 | 12:13:01 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50900/tests/kraken/driver.html?testName=audio-beat-detection) 12:13:01 INFO - PID 8214 | RSS: Main: 150749184 12:13:01 INFO - PID 8214 | 12:13:04 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50900/tests/kraken/driver.html?testName=audio-dft) 12:13:05 INFO - PID 8214 | RSS: Main: 151457792 12:13:05 INFO - PID 8214 | 12:13:07 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50900/tests/kraken/driver.html?testName=audio-fft) 12:13:08 INFO - PID 8214 | RSS: Main: 152625152 12:13:08 INFO - PID 8214 | 12:13:10 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50900/tests/kraken/driver.html?testName=audio-oscillator) 12:13:11 INFO - PID 8214 | RSS: Main: 152621056 12:13:11 INFO - PID 8214 | 12:13:13 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50900/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:13:13 INFO - PID 8214 | RSS: Main: 152616960 12:13:13 INFO - PID 8214 | 12:13:16 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50900/tests/kraken/driver.html?testName=imaging-darkroom) 12:13:17 INFO - PID 8214 | RSS: Main: 152461312 12:13:17 INFO - PID 8214 | 12:13:21 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50900/tests/kraken/driver.html?testName=imaging-desaturate) 12:13:21 INFO - PID 8214 | RSS: Main: 154923008 12:13:21 INFO - PID 8214 | 12:13:24 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50900/tests/kraken/driver.html?testName=json-parse-financial) 12:13:25 INFO - PID 8214 | RSS: Main: 155414528 12:13:25 INFO - PID 8214 | 12:13:26 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50900/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:13:27 INFO - PID 8214 | RSS: Main: 155193344 12:13:27 INFO - PID 8214 | 12:13:28 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50900/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:13:29 INFO - PID 8214 | RSS: Main: 155066368 12:13:29 INFO - PID 8214 | 12:13:31 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50900/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:13:31 INFO - PID 8214 | RSS: Main: 151576576 12:13:31 INFO - PID 8214 | 12:13:34 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50900/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:13:34 INFO - PID 8214 | RSS: Main: 151982080 12:13:34 INFO - PID 8214 | 12:13:37 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50900/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:13:38 INFO - PID 8214 | RSS: Main: 152190976 12:13:38 INFO - PID 8214 | 12:13:39 INFO - PID 8214 | Cycle 1(1): loaded http://localhost:50900/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50900/tests/kraken/driver.html?testName=ai-astar) 12:13:40 INFO - PID 8214 | RSS: Main: 154054656 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | __start_tp_report 12:13:40 INFO - PID 8214 | _x_x_mozilla_page_load 12:13:40 INFO - PID 8214 | _x_x_mozilla_page_load_details 12:13:40 INFO - PID 8214 | |i|pagename|runs| 12:13:40 INFO - PID 8214 | |0;ai-astar;106;105;105;104;105;109;106;106;105;106 12:13:40 INFO - PID 8214 | |1;audio-beat-detection;113;112;112;110;110;116;111;116;140;112 12:13:40 INFO - PID 8214 | |2;audio-dft;162;162;161;163;163;160;162;163;163;161 12:13:40 INFO - PID 8214 | |3;audio-fft;72;72;75;73;72;73;73;74;73;75 12:13:40 INFO - PID 8214 | |4;audio-oscillator;78;77;76;75;78;76;126;78;79;78 12:13:40 INFO - PID 8214 | |5;imaging-gaussian-blur;114;113;117;117;114;113;115;114;115;114 12:13:40 INFO - PID 8214 | |6;imaging-darkroom;149;150;149;148;149;148;149;149;149;148 12:13:40 INFO - PID 8214 | |7;imaging-desaturate;94;94;94;94;95;94;94;94;96;94 12:13:40 INFO - PID 8214 | |8;json-parse-financial;44;42;44;45;43;44;44;44;42;42 12:13:40 INFO - PID 8214 | |9;json-stringify-tinderbox;37;38;38;38;38;38;38;37;38;38 12:13:40 INFO - PID 8214 | |10;stanford-crypto-aes;71;71;69;69;72;69;70;70;74;73 12:13:40 INFO - PID 8214 | |11;stanford-crypto-ccm;104;100;106;102;107;103;109;108;104;101 12:13:40 INFO - PID 8214 | |12;stanford-crypto-pbkdf2;150;147;147;152;151;147;154;148;148;153 12:13:40 INFO - PID 8214 | |13;stanford-crypto-sha256-iterative;62;62;60;58;59;57;59;58;60;61 12:13:40 INFO - PID 8214 | __end_tp_report 12:13:40 INFO - PID 8214 | __start_cc_report 12:13:40 INFO - PID 8214 | _x_x_mozilla_cycle_collect,713 12:13:40 INFO - PID 8214 | __end_cc_report 12:13:40 INFO - PID 8214 | __startTimestamp1506539620022__endTimestamp 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | ------- Summary: start ------- 12:13:40 INFO - PID 8214 | Number of tests: 14 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#0] ai-astar Cycles:10 Average:105.70 Median:106.00 stddev:1.34 (1.3%) stddev-sans-first:1.41 12:13:40 INFO - PID 8214 | Values: 106.0 105.0 105.0 104.0 105.0 109.0 106.0 106.0 105.0 106.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#1] audio-beat-detection Cycles:10 Average:115.20 Median:112.50 stddev:8.97 (8.0%) stddev-sans-first:9.48 12:13:40 INFO - PID 8214 | Values: 113.0 112.0 112.0 110.0 110.0 116.0 111.0 116.0 140.0 112.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.50 stddev:1.05 (0.6%) stddev-sans-first:1.12 12:13:40 INFO - PID 8214 | Values: 162.0 162.0 161.0 163.0 163.0 160.0 162.0 163.0 163.0 161.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#3] audio-fft Cycles:10 Average:73.20 Median:73.00 stddev:1.14 (1.6%) stddev-sans-first:1.12 12:13:40 INFO - PID 8214 | Values: 72.0 72.0 75.0 73.0 72.0 73.0 73.0 74.0 73.0 75.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:78.00 stddev:15.47 (19.8%) stddev-sans-first:16.34 12:13:40 INFO - PID 8214 | Values: 78.0 77.0 76.0 75.0 78.0 76.0 126.0 78.0 79.0 78.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:114.50 stddev:1.43 (1.2%) stddev-sans-first:1.50 12:13:40 INFO - PID 8214 | Values: 114.0 113.0 117.0 117.0 114.0 113.0 115.0 114.0 115.0 114.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:149.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 12:13:40 INFO - PID 8214 | Values: 149.0 150.0 149.0 148.0 149.0 148.0 149.0 149.0 149.0 148.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 12:13:40 INFO - PID 8214 | Values: 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 96.0 94.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#8] json-parse-financial Cycles:10 Average:43.40 Median:44.00 stddev:1.07 (2.4%) stddev-sans-first:1.12 12:13:40 INFO - PID 8214 | Values: 44.0 42.0 44.0 45.0 43.0 44.0 44.0 44.0 42.0 42.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.33 12:13:40 INFO - PID 8214 | Values: 37.0 38.0 38.0 38.0 38.0 38.0 38.0 37.0 38.0 38.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#10] stanford-crypto-aes Cycles:10 Average:70.80 Median:71.00 stddev:1.75 (2.5%) stddev-sans-first:1.86 12:13:40 INFO - PID 8214 | Values: 71.0 71.0 69.0 69.0 72.0 69.0 70.0 70.0 74.0 73.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#11] stanford-crypto-ccm Cycles:10 Average:104.40 Median:105.00 stddev:3.03 (2.9%) stddev-sans-first:3.21 12:13:40 INFO - PID 8214 | Values: 104.0 100.0 106.0 102.0 107.0 103.0 109.0 108.0 104.0 101.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.70 Median:150.50 stddev:2.67 (1.8%) stddev-sans-first:2.83 12:13:40 INFO - PID 8214 | Values: 150.0 147.0 147.0 152.0 151.0 147.0 154.0 148.0 148.0 153.0 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.60 Median:60.00 stddev:1.71 (2.9%) stddev-sans-first:1.58 12:13:40 INFO - PID 8214 | Values: 62.0 62.0 60.0 58.0 59.0 57.0 59.0 58.0 60.0 61.0 12:13:40 INFO - PID 8214 | -------- Summary: end -------- 12:13:40 INFO - PID 8214 | 12:13:40 INFO - PID 8214 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:13:40 INFO - TEST-INFO | 8214: exit 0 12:13:40 INFO - TEST-OK | kraken | took 58017ms 12:13:40 INFO - SUITE-END | took 1164s 12:13:40 INFO - Completed test suite (00:19:24) 12:13:40 INFO - javascript benchmark 12:13:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9403.328012377468, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3945.9210815783686, 3963.4451176835264, 3977.3636218205093, 3980.2238563480123, 4020.93968590471, 6320.083587879754, 6332.683365831707, 6349.80950571483, 6412.294647588773, 6423.19710036246, 6335.493160547157, 6363.032479435011, 6409.038644203371, 6422.293547709748, 6425.228972523304, 5589.413111623282, 5675.262215911936, 5689.573282003867, 5731.054376027951, 5762.942370576304, 5698.487136157745, 5704.600677952545, 5717.3139223293265, 5727.284089488814, 5793.0, 5779.508741756956, 5792.623479473821, 5807.535397168238, 5816.33112192097, 5819.82540523785, 5748.109043098327, 5767.682777447239, 5807.245058142456, 5820.417958204188, 5857.531397488191, 5791.218185544982, 5846.9707651461895, 5857.121431785224, 5860.150278209679, 5860.238169038041, 6353.809385718389, 6437.0988061671405, 6471.741130354781, 6494.318096599836, 6517.087607734921, 6752.729890804362, 6793.796186114425, 6871.209810871764, 6906.171259448898, 6990.54561453509, 6946.72213111475, 7000.579965202104, 7020.964895175543, 7034.753783617549, 7035.6482175891, 6334.1765570475545, 6381.649009304487, 6457.61254324742, 6509.218893732735, 6510.316416776218, 7074.610896400747, 7094.219635840053, 7136.215016348197, 7148.142222933281, 7166.247544007909, 6795.728170873209, 6809.965950170316, 6867.244603093655, 6874.896876546856, 6876.415504682058, 6864.862702745917, 6889.379955804002, 6913.965430172817, 6982.162140543167, 6986.93013069873, 6905.620190889449, 6906.447484201251, 6910.516263861481, 6920.446364290845, 6922.653867306614, 6906.620135892474, 6939.097917270722, 6973.825654358681, 6987.650617469208, 6988.755393561201, 5066.316047333637, 5073.315102461121, 5091.465396133427, 5145.4597267287145, 5156.04613146569, 4563.06457176278, 4661.044485880454, 4667.603253723471, 4678.0, 4699.201135806921, 6503.089567460559, 6516.2506311773805, 6579.407853293131, 6583.703733332011, 6597.406233439014, 6810.250872404032, 6892.620905850226, 6958.617276049866, 6979.965100174467, 6981.476389270723, 6445.645489498029, 6509.772157974448, 6524.054012168209, 6580.473562115019, 6598.406143447017, 5776.682282474504, 5802.88394232113, 5837.6205546640185, 5842.211301474332, 5843.94156058434, 7196.96401517989, 7224.494285399972, 7294.817629559304, 7296.854062918712, 7316.853662926712, 6663.633500157538, 6794.93205067943, 6880.174379074543, 6897.9655101725175, 6977.1976222733, 7140.036095127091, 7155.713771449084, 7156.212816590067, 7157.141143062658, 7157.856842863218, 6764.425023872829, 6858.279880612464, 6859.9314006859295, 6878.140232470942, 6880.58716477013, 6856.41720453767, 6914.4468442525485, 6918.5502942308585, 6929.0, 7003.684834182576, 6581.308962558863, 6615.966920165369, 6619.4042536170045, 6627.403533681992, 6643.103181070687, 6625.337466253529, 6650.933490665225, 6694.564853284521, 6717.764878229238, 6725.764598239038, 6561.311062338576, 6587.63767992765, 6646.667666616553, 6690.76582319636, 6696.263411024881, 6627.6354800484505, 6751.324867513209, 6802.693878775566, 6832.829179270558, 6869.690863911036, 6392.744290228525, 6475.384838440341, 6526.151600291932, 6532.281449040697, 6608.43828274602], "value": 6344.738082481049, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [966.1690945786622, 970.0784254957792, 995.8605795188672, 1030.9278350515467, 1036.0468369100424, 42369.36445953309, 42868.0, 43177.0, 43245.13509729807, 43281.78359108208, 41867.16265674692, 41987.37018944718, 42164.57835421645, 42175.3673694894, 42249.0, 7979.920200797976, 8168.795780105485, 8171.346292296632, 8172.305354044884, 8173.877391839097, 8052.5973701315, 8147.348212143044, 8153.30696890765, 8159.347252219808, 8165.714200003001, 8598.312135029213, 8831.205191532761, 8841.513716745576, 8842.159994800484, 8872.11278872114, 8710.259627931633, 8753.868691969625, 8766.167214114617, 8767.956160219159, 8795.076516965722, 8604.440711353805, 8680.56597170139, 8686.609102590335, 8689.739307820711, 8705.869411958825, 40426.19147617031, 40567.18865622671, 40724.18551628951, 40808.79595602032, 40865.79567102146, 3784.0539865033743, 3817.828197731109, 3825.196708691178, 3828.712846536521, 3835.8657446989223, 33232.1691957703, 33355.33223338823, 33485.16287092818, 33497.0, 33533.832330838435, 5451.918221226685, 5547.583931205176, 5615.971920140414, 5624.634398764068, 5652.34997975234, 226703.0, 229012.99999999834, 229203.0, 229376.0, 229536.0, 52209.47790522046, 52548.0, 52612.47387526152, 52728.836269823936, 52823.0, 55679.164812527844, 55912.0, 55970.720146399006, 56083.43916560782, 56149.0, 18199.81800181981, 18655.533611659546, 18658.720119198224, 18659.160337784833, 18663.346782862536, 15439.382424703112, 15443.0, 15451.61370965735, 15459.458918937784, 15501.922490387702, 15287.235638218268, 15597.0, 15597.376104955674, 15633.218339083, 15682.294296756674, 6185.319614842364, 6238.937610623836, 6243.43809057178, 6324.588901721373, 6347.0479428086155, 1060.6340812419703, 1080.1250986700798, 1097.319661809683, 1104.5140138339104, 1113.4544073404136, 25569.0, 25899.482010360065, 25960.480790384463, 25981.22056338313, 25994.87002564975, 1194.0507296699145, 1212.6059030814845, 1221.7067903703048, 1227.1226073357593, 1249.7063190150307, 13555.390007449689, 13707.657308567366, 13719.039667223398, 13744.312784360742, 13759.312034398441, 278746.0, 282417.0, 284272.0, 287253.0, 289917.0, 56911.0, 59375.0, 59444.0, 59524.702376488705, 59530.404695952486, 1553.4252326639216, 1581.8339074397252, 1588.8411158884019, 1595.6888406760802, 1603.3987254779458, 30435.391292174474, 30888.227794305323, 30932.845335773178, 31040.534391983685, 31181.688183118786, 41548.79225603853, 41574.376384354866, 41583.0, 41628.79185604052, 41694.16611667689, 245091.0, 245955.0, 246450.0, 246747.0, 246775.0, 35301.47047794337, 36257.81871090628, 36563.268734624624, 36687.63312366842, 36699.816500917324, 47930.76034619805, 48019.03961920672, 48413.757931210115, 48611.75694121507, 48637.51362486471, 35003.649963501055, 35349.1162720934, 35560.64439355573, 35759.64240357563, 36137.27725445424, 35562.28875422425, 35676.82161589279, 35789.747358842316, 35920.820395898896, 36289.0, 40653.796731016155, 41093.17813643651, 41198.38202426906, 41256.381154283314, 41328.38007429831, 34669.826650866584, 34975.82512087423, 35092.473612895315, 35130.64869351274, 35384.292314153055, 26279.605805912543, 26290.4741905157, 26370.4725905477, 26590.867045664647, 26689.599656005565], "value": 19165.961133339737, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.0147149930601, 641.0695584939269, 642.8225486886712, 645.0346080212065, 655.5123307049746, 24169.91235394407, 25057.12300069498, 25117.49765004699, 25159.245222643305, 25206.621900671504, 26726.331841703966, 26801.19596412106, 26977.0, 27046.323841903963, 27057.72942270577, 6486.870262594745, 6665.300143484937, 6886.931130688704, 6938.41023513002, 6938.653067346638, 6805.149356330458, 6919.377256046955, 6920.06579111821, 6925.30746925306, 6930.272321406255, 6714.597124172541, 6786.151731033621, 6831.2144103428, 7008.439324853999, 7009.299070093001, 6968.338007889268, 7016.543924644907, 7021.332973367522, 7024.1571011478945, 7036.226015138305, 6549.410553050201, 6555.770548030844, 6567.835804104888, 6572.14562106923, 6575.178102737158, 29202.0, 29400.0, 29827.701722982925, 29913.850430747923, 30299.848500757354, 2060.32009436887, 2325.732540757818, 2348.7416384197722, 2360.256519196465, 2364.6807680963193, 20647.58704825895, 20886.164553417846, 21041.789582104135, 21100.5779884403, 21129.47176320589, 2527.9241622751347, 2614.16346769034, 2645.470905818844, 2664.9733502664917, 2687.516247075526, 144567.0, 148024.0, 157193.0, 164976.0, 166138.1693091539, 16357.754633680504, 16868.0, 16948.74576881348, 16955.830441695423, 17210.139493025297, 27032.864835675697, 27104.32239194036, 27207.727922720915, 27483.587746183824, 27485.17544473669, 11283.887161128283, 11482.540698372139, 11636.476358563885, 11673.474693638807, 11938.22401543897, 10570.788584228427, 10732.195085368628, 10803.675889723321, 10877.292975956538, 11034.558617655364, 9987.500624968723, 10116.583678285046, 10212.795744085077, 10425.895741042492, 10458.581656733797, 3014.3217776000224, 3025.8940937067537, 3055.663876973531, 3057.0, 3129.890453834105, 1057.8571892794528, 1065.5524679634573, 1080.1844607321423, 1084.6420681175193, 1087.994560027195, 21511.247106351202, 21623.459413514473, 21642.891785541287, 21681.0, 22121.77878221197, 1138.1805100327751, 1150.0, 1157.484919210943, 1162.8023236049892, 1166.4284500594647, 11640.592579259684, 11684.415779211175, 11709.824352634718, 11789.351585662871, 11954.282743035446, 632797.0, 639197.0, 648212.0, 654357.0, 670643.0, 136841.0, 140154.0, 142731.2863435697, 143096.28451857882, 143116.28441857724, 1259.9055070869722, 1273.6242808371553, 1292.5476083370745, 1303.6610481274747, 1309.9345032748324, 18579.349722759638, 19015.619687606446, 19062.428127156207, 19365.32221372245, 19734.60530789405, 62784.68607656933, 62823.37176628175, 62873.37126628858, 63636.0, 63639.0, 77186.0, 77663.0, 77863.61068194623, 78311.0, 81399.0, 16011.839881601034, 16183.919080404523, 16232.675346492768, 16394.508164755076, 16405.0, 24414.51170976535, 24497.3875653103, 24695.876520617283, 24744.752552474245, 25102.748972510773, 19647.508812279808, 19779.406617801487, 19913.0, 20077.899610502438, 20163.59672806565, 16750.0, 17185.14074296255, 17746.91126544359, 17817.0, 18046.278148874422, 20852.37442876716, 20987.265445709327, 20988.47528811792, 21361.35915922525, 21371.14515419366, 16001.359945602511, 16005.35978560891, 16071.11608861524, 16086.195690215209, 16093.75859362134, 20058.59882802365, 20328.79671203269, 20449.488762780453, 20496.69254961147, 20695.79304206999], "value": 13223.14532161562, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.7572812621856, 1473.4548217159652, 1486.4202960845266, 1491.79860718803, 1498.8725958293544, 17420.303187872487, 18112.275508979626, 18142.546436339097, 18153.092345382727, 18220.08899555022, 17613.0, 17724.556886077855, 17736.46790596281, 17840.64318713625, 17850.375236866712, 2916.4167166566667, 2943.58789769433, 2996.5654980027884, 2998.0855838969105, 3006.5339872319823, 2963.9851800740967, 3023.833689147096, 3035.2260173655686, 3044.4824379855368, 3048.908532744021, 2929.604503392047, 2991.087718245934, 3067.7699172562034, 3080.0451859923382, 3086.66046734859, 3036.1347016100417, 3036.6507851597016, 3046.040497243361, 3051.4812481878134, 3071.1247294521067, 2887.0328439972636, 2898.7970842041063, 2901.50674385355, 2918.620579324674, 2921.3426978929783, 16992.49022529314, 17034.914825425916, 17216.139193040424, 17219.65560688779, 17245.22396492161, 8592.183742544448, 8777.385583009192, 8795.780105497413, 8804.911950880472, 8854.158854908774, 5559.16612508127, 5654.095344744821, 5673.347565030033, 5760.3951585083805, 5835.4748072673665, 2176.5864485747657, 2185.715856938604, 2201.933941981743, 2247.202243203672, 2259.9887000565054, 32583.348333033442, 32818.0, 32856.0, 32881.177970550925, 32899.3420131596, 7753.185915478906, 7841.5687137208, 7868.449208555347, 8033.758987230392, 8063.354931605458, 20639.380818575468, 20675.276365327143, 20685.793142068385, 20704.275350362666, 20742.688859667116, 8881.0, 9121.133492318218, 9134.680286189952, 9150.725478235663, 9196.0, 8233.629486672995, 8295.792605184797, 8318.001839779265, 8358.749237522885, 8358.791030224293, 5975.07386355107, 6150.876982460326, 6225.346338634468, 6277.591956522811, 6290.276618188876, 1660.219696742529, 1716.605180808396, 1768.4252617898976, 1785.2234278089072, 1827.45176447068, 954.4941181173989, 1017.6082208349839, 1023.4933707814531, 1025.2823023883311, 1030.1707125763683, 9609.279304052085, 9989.950050249703, 10077.294589378673, 10098.646547370807, 10122.240831937634, 826.574314228173, 831.4055450353027, 837.409626213518, 853.3983541603155, 898.9325800564984, 6059.7273122709585, 6074.939250607437, 6177.938220617826, 6325.778597749057, 6375.330590288046, 26348.20955371342, 27084.1874743758, 27108.45783084367, 27484.312892177455, 27527.17418477449, 25519.36201594975, 26700.465990680466, 27216.31959200996, 27250.31874203121, 27332.59001114985, 12553.623391298275, 12582.43379047945, 12634.431450584745, 12677.683057923625, 12702.237865728086, 9689.0, 10047.698569042941, 10170.288079834341, 10210.387376757419, 10284.897151028394, 21823.781762182174, 21993.780062199814, 22308.0, 23089.076436942334, 23187.188448404224, 22975.195868144536, 23345.766542335037, 23600.763992359858, 23782.40543986345, 24028.0, 12898.935505322414, 13069.019823513465, 13152.671183220113, 13160.73678526405, 13168.144070635379, 15569.377224910877, 16227.918860405622, 16304.429344972868, 16430.178491075636, 16471.176441178133, 13041.934790325988, 13205.603831885059, 13460.528881489101, 13603.455861765418, 13615.863841361459, 13001.869981300066, 13039.217646941213, 13207.0, 13223.66940826487, 13241.404136814059, 11174.608888688857, 11197.496112674786, 11320.26418282842, 11335.206535542433, 11340.432978350884, 8426.368022398223, 8436.662533498591, 8479.0, 8586.65653373858, 8640.22237998558, 8899.421537600036, 8920.821583568422, 8951.060138685345, 8955.641774328953, 8994.280457563511], "value": 7692.917704993969, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.6061756770262, 567.6991194666826, 582.4263100845669, 590.4449817171858, 596.9432903874132, 6373.553851230416, 6374.968125159374, 6376.8724625507575, 6381.9999999999945, 6393.680315984206, 6269.592476489025, 6280.560360774748, 6300.936990630093, 6308.71610777515, 6322.367763223675, 5159.432462429129, 5193.922091168635, 5199.298094757197, 5208.8437346879655, 5222.9477705223035, 5123.154679477901, 5130.2040387988445, 5185.974070129644, 5200.661956972804, 5230.320058392404, 4878.438979517347, 4972.875678108058, 4976.776045077962, 5002.1496345621335, 5061.139606266925, 5018.749062546858, 5022.698638081727, 5048.268001139851, 5048.671836330645, 5052.722100284483, 4546.65900057497, 4591.747453890035, 4601.516840731709, 4624.190766615829, 4631.282151266559, 6141.539384546177, 6164.907526387108, 6169.259688837368, 6202.62784232943, 6242.313345531988, 5911.852203694899, 5931.139984702238, 5951.940480595182, 5964.0, 5991.221141251653, 6563.803085907431, 6579.868402631921, 6587.967060164717, 6618.338166183343, 6658.667066646697, 4891.633127515415, 5048.419431765321, 5087.465816089331, 5119.334486516766, 5125.897482050375, 6669.730912579057, 6876.140482439695, 6887.0, 6904.965475172642, 7039.577625342496, 5246.685198888079, 5254.712595414072, 5259.76331065103, 5274.472552744694, 5314.70769107695, 5355.357357117093, 5380.811671591476, 5381.892362152735, 5384.946150538444, 5386.272853164773, 5888.911666325009, 5930.970345148246, 5940.198073260054, 5948.524118070544, 5956.85107872298, 6631.900521492182, 6661.0, 6696.229933557605, 6730.7307707692125, 6740.797776066725, 1843.1889968413855, 1848.8890666560048, 1853.7404763333145, 1858.6375656747075, 1870.4108205915093, 1269.3780047776522, 1302.9153105048142, 1317.2886641213638, 1338.8259526261525, 1341.47011930287, 6236.656983865931, 6266.561340706106, 6311.400416960381, 6356.904646430307, 6366.108744775735, 6588.077669126326, 6648.0, 6668.86662266752, 6720.899186512206, 6737.427318677869, 6245.313765283348, 6285.622862628257, 6292.119103325538, 6301.4328710416285, 6377.489800815923, 3541.787492750443, 3541.8937431877084, 3545.7517973742106, 3560.2701446203464, 3592.604813470516, 7063.2583578724525, 7084.397826184832, 7131.0, 7134.857302853914, 7174.282571742784, 6903.378695917291, 6932.514723969374, 6967.616781077089, 6989.33601307875, 6990.335918087773, 6940.618265995418, 6992.3357281058215, 7048.682809273492, 7063.540869843444, 7092.680829362691, 6580.276169621434, 6644.33556644351, 6688.799336019927, 6696.263411024881, 6703.296153903965, 6559.508036897157, 6611.636360000246, 6651.235107962452, 6728.259891411843, 6742.93257067423, 6915.446764258747, 6950.131233595801, 6960.721571137098, 6965.582065076112, 6972.58164510131, 5147.253648221062, 5229.50319719626, 5265.763040663102, 5273.973630131824, 5287.074761916726, 6505.60966342021, 6506.674666266573, 6685.565438246498, 6723.966380168068, 6747.392734653905, 6321.683915804099, 6432.742690292336, 6503.642559485062, 6519.445847102859, 6574.539782215199, 6421.293657697556, 6422.916999840009, 6491.253505846699, 6504.9101676703185, 6582.637954912526, 6492.902606460812, 6506.967465162644, 6510.7721229756735, 6556.2788093308745, 6589.670516474253, 6652.866942661217, 6667.933320666732, 6701.698423571049, 6733.158355205599, 6734.629595372302, 6454.0, 6460.967695161494, 6471.449926756279, 6506.60960342381, 6518.0], "value": 5223.384635244471, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [965.6041023180497, 1002.3484734922301, 1030.963916262931, 1037.6627596031287, 1049.6693541534416, 19076.141573629186, 19338.419847404584, 19339.516512087204, 19355.709664355047, 19366.90316548417, 20743.585128297425, 20868.269610563635, 21090.367288981353, 21450.463738406546, 21596.784032159674, 1067.1302888146174, 1087.0705546757526, 1087.7552550676114, 1088.352430303972, 1088.5536929858758, 1096.583298346627, 1104.6741211342633, 1109.7724966381872, 1146.7305183281921, 1154.1862986594472, 1183.556166437586, 1185.25921299188, 1188.43549314076, 1189.3934093612277, 1190.5356910804794, 1186.1044911092156, 1189.4766302826729, 1189.8334233207358, 1190.7023244188954, 1192.7554851255472, 866.7313132928749, 868.6221493650282, 870.8606622940363, 874.152072489684, 876.2201640539924, 19561.608767824564, 19792.70310945337, 19928.40214793544, 20052.1979120835, 22150.88924555383, 23904.16335428252, 24086.15698450546, 24320.513589728107, 24819.379515512257, 24836.0, 19088.809111908842, 19226.903865480723, 19336.70994935077, 19394.418167454856, 19394.418167455, 4226.154769046172, 4276.2302785498905, 4283.164782867342, 4283.807228674717, 4286.42133312002, 164965.0, 168269.0, 169582.0, 170467.99999999875, 171565.0, 18324.175412106488, 18539.165737541844, 18615.906920465583, 18624.53438664017, 18696.25214991385, 35158.472622910675, 35620.82189589035, 36189.0952726184, 36278.81860590733, 36287.81856090703, 8431.57842107892, 8501.404901656824, 8532.744017679355, 8570.0, 8579.35654825878, 4528.184926713157, 4575.7483338416705, 4587.9541204588195, 4600.056988317387, 4607.0, 6649.4347980421235, 6667.0, 6690.929451287868, 6745.561538499982, 6758.695858686371, 1107.1640911112058, 1121.899029087386, 1133.5919069135105, 1137.8008848451652, 1145.799485090106, 765.8200322924108, 773.5668025905511, 786.9567173805495, 790.675822912603, 799.8240387114826, 13855.445782168801, 14222.288885555889, 14442.0, 14785.63035924089, 14928.776068358984, 782.5734974438831, 786.6695987685072, 787.4330482052912, 790.4387884601882, 799.3006119645311, 6694.665266736643, 6784.559003664845, 6801.285864984205, 6808.7276508939085, 6823.38589526945, 151636.0, 153275.0, 154151.0, 159086.0, 161933.0, 133704.0, 134291.0, 135150.0, 136811.31594341964, 143989.0, 116914.0, 117877.0, 121207.0, 121380.0, 122740.38629806794, 14538.0, 15534.922325388301, 15630.140342280829, 15815.68368632644, 15962.60093497672, 58324.70837645785, 58440.707796460745, 59240.407595925215, 59364.70317648384, 59452.40547594469, 74846.62576687081, 76383.23616763762, 76465.23534764581, 77067.61466192633, 77387.22612774026, 21460.463488412915, 22124.11503539905, 22165.66751498761, 22198.223062192745, 22217.333479995625, 24167.39581510406, 24184.51630967406, 24226.636600450656, 24443.633345499475, 24492.755072449047, 22664.0, 22763.772362276166, 22847.771522284565, 22872.771272287064, 23043.88478057666, 19816.0, 20197.0, 20257.392278231677, 20389.0, 20599.38201853947, 19603.509912252306, 19708.704369434756, 19895.403137905887, 19927.900360498104, 20152.798472015093, 15225.923870380577, 15517.301721422336, 15520.37918483248, 15576.688466230386, 15662.530124096294, 16042.759358609397, 16067.372540797409, 16098.919505402398, 16266.268017938984, 16590.50228493147], "value": 10698.929399715455, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1361.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 105.0, 104.0, 105.0, 109.0, 106.0, 106.0, 105.0, 106.0], "value": 105.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 112.0, 110.0, 110.0, 116.0, 111.0, 116.0, 140.0, 112.0], "value": 115.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 163.0, 163.0, 160.0, 162.0, 163.0, 163.0, 161.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 75.0, 73.0, 72.0, 73.0, 73.0, 74.0, 73.0, 75.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 76.0, 75.0, 78.0, 76.0, 126.0, 78.0, 79.0, 78.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 117.0, 117.0, 114.0, 113.0, 115.0, 114.0, 115.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 148.0, 149.0, 148.0, 149.0, 149.0, 149.0, 148.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 96.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 42.0, 44.0, 45.0, 43.0, 44.0, 44.0, 44.0, 42.0, 42.0], "value": 43.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 37.0, 38.0, 38.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 69.0, 69.0, 72.0, 69.0, 70.0, 70.0, 74.0, 73.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 100.0, 106.0, 102.0, 107.0, 103.0, 109.0, 108.0, 104.0, 101.0], "value": 104.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 147.0, 147.0, 152.0, 151.0, 147.0, 154.0, 148.0, 148.0, 153.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 60.0, 58.0, 59.0, 57.0, 59.0, 58.0, 60.0, 61.0], "value": 59.6, "unit": "score"}]}]} 12:13:40 INFO - Return code: 0 12:13:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:13:40 INFO - # TBPL SUCCESS # 12:13:40 INFO - Running post-action listener: _package_coverage_data 12:13:40 INFO - Running post-action listener: _resource_record_post_action 12:13:40 INFO - [mozharness: 2017-09-27 19:13:40.806953Z] Finished run-tests step (success) 12:13:40 INFO - Running post-run listener: _resource_record_post_run 12:13:40 INFO - Total resource usage - Wall time: 1180s; CPU: 13.0%; Read bytes: 12288; Write bytes: 290590720; Read time: 248; Write time: 170768 12:13:40 INFO - TinderboxPrint: CPU usage
13.0% 12:13:40 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 248 12:13:40 INFO - TinderboxPrint: I/O write bytes / time
290,590,720 / 170,768 12:13:40 INFO - TinderboxPrint: CPU idle
8,180.2 (86.9%) 12:13:40 INFO - TinderboxPrint: CPU user
1,178.5 (12.5%) 12:13:40 INFO - TinderboxPrint: Swap in / out
0 / 0 12:13:40 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 9449472; Read time: 0; Write time: 1496 12:13:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:13:40 INFO - run-tests - Wall time: 1165s; CPU: 13.0%; Read bytes: 12288; Write bytes: 281141248; Read time: 248; Write time: 169272 12:13:41 INFO - Running post-run listener: _upload_blobber_files 12:13:41 INFO - Blob upload gear active. 12:13:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:13:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:13:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:13:41 INFO - (blobuploader) - INFO - Open directory for files ... 12:13:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:13:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:13:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:13:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:13:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:13:49 INFO - (blobuploader) - INFO - Done attempting. 12:13:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:13:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:13:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:13:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:13:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:13:50 INFO - (blobuploader) - INFO - Done attempting. 12:13:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:13:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:13:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:13:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:13:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:13:51 INFO - (blobuploader) - INFO - Done attempting. 12:13:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:13:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:13:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:13:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:13:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:13:52 INFO - (blobuploader) - INFO - Done attempting. 12:13:52 INFO - (blobuploader) - INFO - Iteration through files over. 12:13:52 INFO - Return code: 0 12:13:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:13:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:13:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4d7ec9894a312b2609510a8ccdcdbc21da06b3c76c458e2ff4504344933722178b7834431a20425f9a390b4c40859de6394ee50c225d8afdc837c342e0f8aea2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/689501b2d4543368c1e4f6e46ad00a60b433718b3225b2d08042dc76b663d6352e615b580d3a3e210e83680ab546ac10160c481e95427c7a45807965817a074a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/305c3d7f79dbe896d05ae4a6d0068d0349dd011083cfd6fa3ba4f4421fe3183c40fff7a215567a780e50aabfb81de2574f630ca0cade4e952df010f5f98bc4f4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f1e0112fc52e99b696c8fa2b8d05b76aa1c2bf1d3e86b62b60dde72fa4f7f3bc48a8aef4400713af895396bb89f3544fdf4f543d13306661bbd603d6961cb9be"} 12:13:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:13:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:13:52 INFO - Contents: 12:13:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4d7ec9894a312b2609510a8ccdcdbc21da06b3c76c458e2ff4504344933722178b7834431a20425f9a390b4c40859de6394ee50c225d8afdc837c342e0f8aea2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/689501b2d4543368c1e4f6e46ad00a60b433718b3225b2d08042dc76b663d6352e615b580d3a3e210e83680ab546ac10160c481e95427c7a45807965817a074a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/305c3d7f79dbe896d05ae4a6d0068d0349dd011083cfd6fa3ba4f4421fe3183c40fff7a215567a780e50aabfb81de2574f630ca0cade4e952df010f5f98bc4f4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f1e0112fc52e99b696c8fa2b8d05b76aa1c2bf1d3e86b62b60dde72fa4f7f3bc48a8aef4400713af895396bb89f3544fdf4f543d13306661bbd603d6961cb9be"} 12:13:52 INFO - Running post-run listener: copy_logs_to_upload_dir 12:13:52 INFO - Copying logs to upload dir... 12:13:52 INFO - mkdir: /builds/slave/test/build/upload/logs 12:13:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.623438 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-09-27 12:13:52.758295) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 12:13:52.762099) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4d7ec9894a312b2609510a8ccdcdbc21da06b3c76c458e2ff4504344933722178b7834431a20425f9a390b4c40859de6394ee50c225d8afdc837c342e0f8aea2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/689501b2d4543368c1e4f6e46ad00a60b433718b3225b2d08042dc76b663d6352e615b580d3a3e210e83680ab546ac10160c481e95427c7a45807965817a074a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/305c3d7f79dbe896d05ae4a6d0068d0349dd011083cfd6fa3ba4f4421fe3183c40fff7a215567a780e50aabfb81de2574f630ca0cade4e952df010f5f98bc4f4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f1e0112fc52e99b696c8fa2b8d05b76aa1c2bf1d3e86b62b60dde72fa4f7f3bc48a8aef4400713af895396bb89f3544fdf4f543d13306661bbd603d6961cb9be"} build_url:https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011538 build_url: 'https://queue.taskcluster.net/v1/task/aipVw3bsRv2QZNMWT2ULyQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4d7ec9894a312b2609510a8ccdcdbc21da06b3c76c458e2ff4504344933722178b7834431a20425f9a390b4c40859de6394ee50c225d8afdc837c342e0f8aea2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/689501b2d4543368c1e4f6e46ad00a60b433718b3225b2d08042dc76b663d6352e615b580d3a3e210e83680ab546ac10160c481e95427c7a45807965817a074a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/305c3d7f79dbe896d05ae4a6d0068d0349dd011083cfd6fa3ba4f4421fe3183c40fff7a215567a780e50aabfb81de2574f630ca0cade4e952df010f5f98bc4f4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f1e0112fc52e99b696c8fa2b8d05b76aa1c2bf1d3e86b62b60dde72fa4f7f3bc48a8aef4400713af895396bb89f3544fdf4f543d13306661bbd603d6961cb9be"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 12:13:52.791836) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 12:13:52.792166) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506538399.707205-651082295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 12:13:52.877531) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 12:13:52.877852) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 12:13:52.878169) ========= ========= Total master_lag: 0.18 =========