builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1474562176.24 results: success (0) buildid: 20160922064859 builduid: f9fc8d00b4ba47fd90c7cf8a9f10bd76 revision: aee0f090712afdefc44ddf6198515d31d3053a1b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:16.237052) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:16.237500) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:16.237852) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:16.253276) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:16.253556) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-22 09:36:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.7M=0s 2016-09-22 09:36:18 (45.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114496 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:16.381918) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:16.382258) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015671 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:16.431748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:16.432075) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev aee0f090712afdefc44ddf6198515d31d3053a1b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev aee0f090712afdefc44ddf6198515d31d3053a1b --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=82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-22 09:36:18,181 truncating revision to first 12 chars 2016-09-22 09:36:18,181 Setting DEBUG logging. 2016-09-22 09:36:18,182 attempt 1/10 2016-09-22 09:36:18,182 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/aee0f090712a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-22 09:36:18,470 unpacking tar archive at: mozilla-beta-aee0f090712a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.644970 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:17.090526) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:17.090854) ========= script_repo_revision: aee0f090712afdefc44ddf6198515d31d3053a1b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:17.092251) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:17.092557) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 09:36:17.102912) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 34 secs) (at 2016-09-22 09:36:17.103259) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:36:18 INFO - MultiFileLogger online at 20160922 09:36:18 in /builds/slave/test 09:36:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:36:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:18 INFO - {'append_to_log': False, 09:36:18 INFO - 'base_work_dir': '/builds/slave/test', 09:36:18 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:36:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:18 INFO - 'branch': 'Mozilla-Beta', 09:36:18 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:18 INFO - 'config_files': ('talos/linux_config.py',), 09:36:18 INFO - 'default_actions': ('clobber', 09:36:18 INFO - 'read-buildbot-config', 09:36:18 INFO - 'download-and-extract', 09:36:18 INFO - 'populate-webroot', 09:36:18 INFO - 'create-virtualenv', 09:36:18 INFO - 'install', 09:36:18 INFO - 'run-tests'), 09:36:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:18 INFO - 'download_minidump_stackwalk': True, 09:36:18 INFO - 'download_symbols': 'ondemand', 09:36:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:18 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:18 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:18 INFO - 'installer_path': 'installer.exe', 09:36:18 INFO - 'log_level': 'info', 09:36:18 INFO - 'log_name': 'talos', 09:36:18 INFO - 'log_to_console': True, 09:36:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:36:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:36:18 INFO - 'opt_config_files': (), 09:36:18 INFO - 'pip_index': False, 09:36:18 INFO - 'sps_profile': False, 09:36:18 INFO - 'sps_profile_interval': 0, 09:36:18 INFO - 'suite': 'dromaeojs', 09:36:18 INFO - 'system_bits': '32', 09:36:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:18 INFO - 'title': 'talos-linux64-ix-066', 09:36:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:18 INFO - 'use_talos_json': True, 09:36:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:18 INFO - 'work_dir': 'build'} 09:36:18 INFO - [mozharness: 2016-09-22 16:36:18.936987Z] Running clobber step. 09:36:18 INFO - Running pre-action listener: _resource_record_pre_action 09:36:18 INFO - Running main action method: clobber 09:36:18 INFO - rmtree: /builds/slave/test/build 09:36:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:19 INFO - Running post-action listener: _resource_record_post_action 09:36:19 INFO - [mozharness: 2016-09-22 16:36:19.948227Z] Finished clobber step (success) 09:36:19 INFO - [mozharness: 2016-09-22 16:36:19.948330Z] Running read-buildbot-config step. 09:36:19 INFO - Running pre-action listener: _resource_record_pre_action 09:36:19 INFO - Running main action method: read_buildbot_config 09:36:19 INFO - Using buildbot properties: 09:36:19 INFO - { 09:36:19 INFO - "project": "", 09:36:19 INFO - "product": "firefox", 09:36:19 INFO - "script_repo_revision": "production", 09:36:19 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:36:19 INFO - "repository": "", 09:36:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:36:19 INFO - "buildid": "20160922064859", 09:36:19 INFO - "pgo_build": "True", 09:36:19 INFO - "basedir": "/builds/slave/test", 09:36:19 INFO - "buildnumber": 167, 09:36:19 INFO - "slavename": "talos-linux64-ix-066", 09:36:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:36:19 INFO - "platform": "ubuntu64_hw", 09:36:19 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:36:19 INFO - "branch": "mozilla-beta", 09:36:19 INFO - "repo_path": "releases/mozilla-beta", 09:36:19 INFO - "revision": "aee0f090712afdefc44ddf6198515d31d3053a1b", 09:36:19 INFO - "stage_platform": "linux64-pgo", 09:36:19 INFO - "builduid": "f9fc8d00b4ba47fd90c7cf8a9f10bd76", 09:36:19 INFO - "slavebuilddir": "test" 09:36:19 INFO - } 09:36:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 09:36:19 INFO - Running post-action listener: _resource_record_post_action 09:36:19 INFO - [mozharness: 2016-09-22 16:36:19.950778Z] Finished read-buildbot-config step (success) 09:36:19 INFO - [mozharness: 2016-09-22 16:36:19.950868Z] Running download-and-extract step. 09:36:19 INFO - Running pre-action listener: _resource_record_pre_action 09:36:19 INFO - Running main action method: download_and_extract 09:36:19 INFO - mkdir: /builds/slave/test/build/tests 09:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:19 INFO - https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:36:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:36:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:36:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:36:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:36:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:36:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:36:19 INFO - retry: Failed, sleeping 30 seconds before retrying 09:36:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 09:36:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:36:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:36:49 INFO - retry: Failed, sleeping 60 seconds before retrying 09:37:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 09:37:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:37:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:37:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 09:37:50 INFO - Caught exception: HTTP Error 404: Not Found 09:37:50 INFO - Caught exception: HTTP Error 404: Not Found 09:37:50 INFO - Caught exception: HTTP Error 404: Not Found 09:37:50 INFO - trying https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:37:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:37:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:37:50 INFO - Downloaded 1327 bytes. 09:37:50 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 09:37:50 INFO - Using the following test package requirements: 09:37:50 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 09:37:50 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:37:50 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:37:50 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:37:50 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 09:37:50 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:37:50 INFO - u'jsshell-linux-x86_64.zip'], 09:37:50 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:37:50 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:37:50 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 09:37:50 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:37:50 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 09:37:50 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:37:50 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 09:37:50 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:37:50 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:37:50 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 09:37:50 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:37:50 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:50 INFO - https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:37:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:37:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:37:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:37:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:37:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:37:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:37:50 INFO - retry: Failed, sleeping 30 seconds before retrying 09:38:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 09:38:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:38:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:38:20 INFO - retry: Failed, sleeping 60 seconds before retrying 09:39:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 09:39:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:39:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:39:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 09:39:20 INFO - Caught exception: HTTP Error 404: Not Found 09:39:20 INFO - Caught exception: HTTP Error 404: Not Found 09:39:20 INFO - Caught exception: HTTP Error 404: Not Found 09:39:20 INFO - trying https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:39:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:39:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:39:22 INFO - Downloaded 21771342 bytes. 09:39:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:39:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:39:23 INFO - Return code: 0 09:39:23 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:23 INFO - https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:39:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:39:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:39:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:39:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:39:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:39:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:39:23 INFO - retry: Failed, sleeping 30 seconds before retrying 09:39:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 09:39:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:39:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:39:53 INFO - retry: Failed, sleeping 60 seconds before retrying 09:40:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 09:40:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:40:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:40:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 09:40:53 INFO - Caught exception: HTTP Error 404: Not Found 09:40:53 INFO - Caught exception: HTTP Error 404: Not Found 09:40:53 INFO - Caught exception: HTTP Error 404: Not Found 09:40:53 INFO - trying https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:40:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 09:40:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:40:54 INFO - Downloaded 21771342 bytes. 09:40:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:40:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:40:55 INFO - Return code: 0 09:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:55 INFO - https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:40:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:40:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:40:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:40:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:40:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:40:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:40:55 INFO - retry: Failed, sleeping 30 seconds before retrying 09:41:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 09:41:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:41:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:41:25 INFO - retry: Failed, sleeping 60 seconds before retrying 09:42:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 09:42:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:42:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:42:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 09:42:26 INFO - Caught exception: HTTP Error 404: Not Found 09:42:26 INFO - Caught exception: HTTP Error 404: Not Found 09:42:26 INFO - Caught exception: HTTP Error 404: Not Found 09:42:26 INFO - trying https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:42:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 09:42:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:42:27 INFO - Downloaded 14413740 bytes. 09:42:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:42:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:42:27 INFO - Return code: 0 09:42:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:27 INFO - https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:42:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:42:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:42:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:42:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:42:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:42:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:42:27 INFO - retry: Failed, sleeping 30 seconds before retrying 09:42:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 09:42:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:42:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:42:57 INFO - retry: Failed, sleeping 60 seconds before retrying 09:43:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 09:43:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:43:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:43:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 09:43:58 INFO - Caught exception: HTTP Error 404: Not Found 09:43:58 INFO - Caught exception: HTTP Error 404: Not Found 09:43:58 INFO - Caught exception: HTTP Error 404: Not Found 09:43:58 INFO - trying https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:43:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:43:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:43:59 INFO - Downloaded 56794672 bytes. 09:43:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:43:59 INFO - mkdir: /builds/slave/test/properties 09:43:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:43:59 INFO - Writing to file /builds/slave/test/properties/build_url 09:43:59 INFO - Contents: 09:43:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 09:43:59 INFO - Running post-action listener: _resource_record_post_action 09:43:59 INFO - Running post-action listener: set_extra_try_arguments 09:43:59 INFO - [mozharness: 2016-09-22 16:43:59.739081Z] Finished download-and-extract step (success) 09:43:59 INFO - [mozharness: 2016-09-22 16:43:59.739314Z] Running populate-webroot step. 09:43:59 INFO - Running pre-action listener: _resource_record_pre_action 09:43:59 INFO - Running main action method: populate_webroot 09:43:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:43:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:43:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:43:59 INFO - '--tppagecycles', 09:43:59 INFO - '7'], 09:43:59 INFO - 'tests': ['tsvgm']}}, 09:43:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:43:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:43:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:43:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:43:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:43:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:43:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:59 INFO - 'talos_options': ['--disable-e10s'], 09:43:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:59 INFO - 'talos_options': ['--disable-e10s'], 09:43:59 INFO - 'tests': ['damp', 'tps']}, 09:43:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:59 INFO - 'tests': ['damp', 'tps']}, 09:43:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:43:59 INFO - 'tests': ['dromaeo_dom']}, 09:43:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:43:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:43:59 INFO - 'tests': ['basic_compositor_video']}, 09:43:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:43:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:43:59 INFO - 'tests': ['a11yr', 09:43:59 INFO - 'ts_paint', 09:43:59 INFO - 'tpaint', 09:43:59 INFO - 'sessionrestore', 09:43:59 INFO - 'sessionrestore_no_auto_restore', 09:43:59 INFO - 'tabpaint']}, 09:43:59 INFO - 'other-e10s': {'tests': ['a11yr', 09:43:59 INFO - 'ts_paint', 09:43:59 INFO - 'tpaint', 09:43:59 INFO - 'sessionrestore', 09:43:59 INFO - 'sessionrestore_no_auto_restore', 09:43:59 INFO - 'tabpaint']}, 09:43:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:43:59 INFO - 'ts_paint', 09:43:59 INFO - 'tpaint', 09:43:59 INFO - 'sessionrestore', 09:43:59 INFO - 'sessionrestore_no_auto_restore', 09:43:59 INFO - 'tabpaint']}, 09:43:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:43:59 INFO - 'ts_paint', 09:43:59 INFO - 'tpaint', 09:43:59 INFO - 'sessionrestore', 09:43:59 INFO - 'sessionrestore_no_auto_restore', 09:43:59 INFO - 'tabpaint']}, 09:43:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:43:59 INFO - 'tests': ['a11yr', 09:43:59 INFO - 'ts_paint', 09:43:59 INFO - 'tpaint', 09:43:59 INFO - 'sessionrestore', 09:43:59 INFO - 'sessionrestore_no_auto_restore', 09:43:59 INFO - 'tabpaint']}, 09:43:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:43:59 INFO - 'tests': ['a11yr', 09:43:59 INFO - 'ts_paint', 09:43:59 INFO - 'tpaint', 09:43:59 INFO - 'sessionrestore', 09:43:59 INFO - 'sessionrestore_no_auto_restore', 09:43:59 INFO - 'tabpaint']}, 09:43:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:43:59 INFO - 'tests': ['tsvgx', 09:43:59 INFO - 'tsvgr_opacity', 09:43:59 INFO - 'tart', 09:43:59 INFO - 'tscrollx', 09:43:59 INFO - 'cart']}, 09:43:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:43:59 INFO - 'tsvgr_opacity', 09:43:59 INFO - 'tart', 09:43:59 INFO - 'tscrollx', 09:43:59 INFO - 'cart']}, 09:43:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:59 INFO - 'talos_options': ['--disable-e10s'], 09:43:59 INFO - 'tests': ['tp5o']}, 09:43:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:59 INFO - 'tests': ['tp5o']}, 09:43:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:59 INFO - 'talos_options': ['--disable-e10s', 09:43:59 INFO - '--xperf_path', 09:43:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:59 INFO - 'tests': ['tp5n']}, 09:43:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:59 INFO - 'talos_options': ['--xperf_path', 09:43:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:59 INFO - 'tests': ['tp5n']}}, 09:43:59 INFO - 'talos.zip': {'path': '', 09:43:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:43:59 INFO - Running post-action listener: _resource_record_post_action 09:43:59 INFO - [mozharness: 2016-09-22 16:43:59.757888Z] Finished populate-webroot step (success) 09:43:59 INFO - [mozharness: 2016-09-22 16:43:59.757977Z] Running create-virtualenv step. 09:43:59 INFO - Running pre-action listener: _resource_record_pre_action 09:43:59 INFO - Running main action method: create_virtualenv 09:43:59 INFO - Creating virtualenv /builds/slave/test/build/venv 09:43:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:43:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:43:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:43:59 INFO - Using real prefix '/usr' 09:43:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:44:00 INFO - Installing distribute.............................................................................................................................................................................................done. 09:44:02 INFO - Installing pip.................done. 09:44:02 INFO - Return code: 0 09:44:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:02 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x11ff030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14be3b0>, '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 0x14bf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bf740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', '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 09:44:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:44:02 INFO - Using env: {'DISPLAY': ':0', 09:44:02 INFO - 'HOME': '/home/cltbld', 09:44:02 INFO - 'LANG': 'en_US.UTF-8', 09:44:02 INFO - 'LANGUAGE': 'en_US:en', 09:44:02 INFO - 'LOGNAME': 'cltbld', 09:44:02 INFO - 'MAIL': '/var/mail/cltbld', 09:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:02 INFO - 'MOZ_NO_REMOTE': '1', 09:44:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:44:02 INFO - 'NO_EM_RESTART': '1', 09:44:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:02 INFO - 'PWD': '/builds/slave/test', 09:44:02 INFO - 'SHELL': '/bin/bash', 09:44:02 INFO - 'SHLVL': '1', 09:44:02 INFO - 'TERM': 'linux', 09:44:02 INFO - 'TMOUT': '86400', 09:44:02 INFO - 'USER': 'cltbld', 09:44:02 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', 09:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:02 INFO - '_': '/tools/buildbot/bin/python'} 09:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:44:02 INFO - Cleaning up... 09:44:02 INFO - Return code: 0 09:44:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:02 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x11ff030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14be3b0>, '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 0x14bf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bf740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', '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 09:44:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:44:02 INFO - Using env: {'DISPLAY': ':0', 09:44:02 INFO - 'HOME': '/home/cltbld', 09:44:02 INFO - 'LANG': 'en_US.UTF-8', 09:44:02 INFO - 'LANGUAGE': 'en_US:en', 09:44:02 INFO - 'LOGNAME': 'cltbld', 09:44:02 INFO - 'MAIL': '/var/mail/cltbld', 09:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:02 INFO - 'MOZ_NO_REMOTE': '1', 09:44:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:44:02 INFO - 'NO_EM_RESTART': '1', 09:44:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:02 INFO - 'PWD': '/builds/slave/test', 09:44:02 INFO - 'SHELL': '/bin/bash', 09:44:02 INFO - 'SHLVL': '1', 09:44:02 INFO - 'TERM': 'linux', 09:44:02 INFO - 'TMOUT': '86400', 09:44:02 INFO - 'USER': 'cltbld', 09:44:02 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', 09:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:02 INFO - '_': '/tools/buildbot/bin/python'} 09:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:02 INFO - Downloading/unpacking psutil>=3.1.1 09:44:02 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 09:44:02 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 09:44:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:02 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 09:44:02 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 09:44:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:44:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:44:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:44:04 INFO - Installing collected packages: psutil 09:44:04 INFO - Running setup.py install for psutil 09:44:04 INFO - building 'psutil._psutil_linux' extension 09:44:04 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 09:44:05 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 09:44:05 INFO - building 'psutil._psutil_posix' extension 09:44:05 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 09:44:05 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 09:44:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:44:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:44:05 INFO - Successfully installed psutil 09:44:05 INFO - Cleaning up... 09:44:05 INFO - Return code: 0 09:44:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x11ff030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14be3b0>, '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 0x14bf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bf740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', '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 09:44:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:44:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:44:05 INFO - Using env: {'DISPLAY': ':0', 09:44:05 INFO - 'HOME': '/home/cltbld', 09:44:05 INFO - 'LANG': 'en_US.UTF-8', 09:44:05 INFO - 'LANGUAGE': 'en_US:en', 09:44:05 INFO - 'LOGNAME': 'cltbld', 09:44:05 INFO - 'MAIL': '/var/mail/cltbld', 09:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:05 INFO - 'MOZ_NO_REMOTE': '1', 09:44:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:44:05 INFO - 'NO_EM_RESTART': '1', 09:44:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:05 INFO - 'PWD': '/builds/slave/test', 09:44:05 INFO - 'SHELL': '/bin/bash', 09:44:05 INFO - 'SHLVL': '1', 09:44:05 INFO - 'TERM': 'linux', 09:44:05 INFO - 'TMOUT': '86400', 09:44:05 INFO - 'USER': 'cltbld', 09:44:05 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', 09:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:05 INFO - '_': '/tools/buildbot/bin/python'} 09:44:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:44:05 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 09:44:05 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 09:44:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:05 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 09:44:05 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 09:44:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:44:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:44:07 INFO - Installing collected packages: mozsystemmonitor 09:44:07 INFO - Running setup.py install for mozsystemmonitor 09:44:07 INFO - Successfully installed mozsystemmonitor 09:44:07 INFO - Cleaning up... 09:44:07 INFO - Return code: 0 09:44:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:44:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:07 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x11ff030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14be3b0>, '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 0x14bf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bf740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', '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 09:44:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:44:07 INFO - Using env: {'DISPLAY': ':0', 09:44:07 INFO - 'HOME': '/home/cltbld', 09:44:07 INFO - 'LANG': 'en_US.UTF-8', 09:44:07 INFO - 'LANGUAGE': 'en_US:en', 09:44:07 INFO - 'LOGNAME': 'cltbld', 09:44:07 INFO - 'MAIL': '/var/mail/cltbld', 09:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:07 INFO - 'MOZ_NO_REMOTE': '1', 09:44:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:44:07 INFO - 'NO_EM_RESTART': '1', 09:44:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:07 INFO - 'PWD': '/builds/slave/test', 09:44:07 INFO - 'SHELL': '/bin/bash', 09:44:07 INFO - 'SHLVL': '1', 09:44:07 INFO - 'TERM': 'linux', 09:44:07 INFO - 'TMOUT': '86400', 09:44:07 INFO - 'USER': 'cltbld', 09:44:07 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', 09:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:07 INFO - '_': '/tools/buildbot/bin/python'} 09:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:07 INFO - Downloading/unpacking jsonschema==2.5.1 09:44:07 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 09:44:07 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 09:44:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:07 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 09:44:07 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 09:44:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:44:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:44:09 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 09:44:09 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 09:44:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:09 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 09:44:09 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 09:44:10 INFO - Downloading functools32-3.2.3-2.tar.gz 09:44:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:44:10 INFO - warning: no files found matching '*.txt' 09:44:10 INFO - no previously-included directories found matching 'build' 09:44:10 INFO - no previously-included directories found matching 'dist' 09:44:10 INFO - no previously-included directories found matching '.git*' 09:44:10 INFO - Installing collected packages: jsonschema, functools32 09:44:10 INFO - Running setup.py install for functools32 09:44:10 INFO - warning: no files found matching '*.txt' 09:44:10 INFO - no previously-included directories found matching 'build' 09:44:10 INFO - no previously-included directories found matching 'dist' 09:44:10 INFO - no previously-included directories found matching '.git*' 09:44:10 INFO - Successfully installed jsonschema functools32 09:44:10 INFO - Cleaning up... 09:44:10 INFO - Return code: 0 09:44:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:10 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x11ff030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14be3b0>, '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 0x14bf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bf740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', '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 09:44:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:44:10 INFO - Using env: {'DISPLAY': ':0', 09:44:10 INFO - 'HOME': '/home/cltbld', 09:44:10 INFO - 'LANG': 'en_US.UTF-8', 09:44:10 INFO - 'LANGUAGE': 'en_US:en', 09:44:10 INFO - 'LOGNAME': 'cltbld', 09:44:10 INFO - 'MAIL': '/var/mail/cltbld', 09:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:10 INFO - 'MOZ_NO_REMOTE': '1', 09:44:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:44:10 INFO - 'NO_EM_RESTART': '1', 09:44:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:10 INFO - 'PWD': '/builds/slave/test', 09:44:10 INFO - 'SHELL': '/bin/bash', 09:44:10 INFO - 'SHLVL': '1', 09:44:10 INFO - 'TERM': 'linux', 09:44:10 INFO - 'TMOUT': '86400', 09:44:10 INFO - 'USER': 'cltbld', 09:44:10 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', 09:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:10 INFO - '_': '/tools/buildbot/bin/python'} 09:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:44:10 INFO - Cleaning up... 09:44:10 INFO - Return code: 0 09:44:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:10 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x11ff030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14be3b0>, '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 0x14bf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bf740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', '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 09:44:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:44:10 INFO - Using env: {'DISPLAY': ':0', 09:44:10 INFO - 'HOME': '/home/cltbld', 09:44:10 INFO - 'LANG': 'en_US.UTF-8', 09:44:10 INFO - 'LANGUAGE': 'en_US:en', 09:44:10 INFO - 'LOGNAME': 'cltbld', 09:44:10 INFO - 'MAIL': '/var/mail/cltbld', 09:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:10 INFO - 'MOZ_NO_REMOTE': '1', 09:44:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:44:10 INFO - 'NO_EM_RESTART': '1', 09:44:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:10 INFO - 'PWD': '/builds/slave/test', 09:44:10 INFO - 'SHELL': '/bin/bash', 09:44:10 INFO - 'SHLVL': '1', 09:44:10 INFO - 'TERM': 'linux', 09:44:10 INFO - 'TMOUT': '86400', 09:44:10 INFO - 'USER': 'cltbld', 09:44:10 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', 09:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:10 INFO - '_': '/tools/buildbot/bin/python'} 09:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:10 INFO - Downloading/unpacking blobuploader==1.2.4 09:44:10 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 09:44:10 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 09:44:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:10 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 09:44:10 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 09:44:12 INFO - Downloading blobuploader-1.2.4.tar.gz 09:44:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:44:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:44:12 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 09:44:12 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 09:44:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:12 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 09:44:12 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 09:44:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:44:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:44:13 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 09:44:13 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 09:44:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:13 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 09:44:13 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 09:44:13 INFO - Downloading docopt-0.6.1.tar.gz 09:44:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:44:13 INFO - Installing collected packages: blobuploader, requests, docopt 09:44:13 INFO - Running setup.py install for blobuploader 09:44:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:44:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:44:13 INFO - Running setup.py install for requests 09:44:13 INFO - Running setup.py install for docopt 09:44:13 INFO - Successfully installed blobuploader requests docopt 09:44:13 INFO - Cleaning up... 09:44:14 INFO - Return code: 0 09:44:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:14 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x11ff030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14be3b0>, '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 0x14bf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bf740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', '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 09:44:14 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:44:14 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:44:14 INFO - Using env: {'DISPLAY': ':0', 09:44:14 INFO - 'HOME': '/home/cltbld', 09:44:14 INFO - 'LANG': 'en_US.UTF-8', 09:44:14 INFO - 'LANGUAGE': 'en_US:en', 09:44:14 INFO - 'LOGNAME': 'cltbld', 09:44:14 INFO - 'MAIL': '/var/mail/cltbld', 09:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:14 INFO - 'MOZ_NO_REMOTE': '1', 09:44:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:44:14 INFO - 'NO_EM_RESTART': '1', 09:44:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:14 INFO - 'PWD': '/builds/slave/test', 09:44:14 INFO - 'SHELL': '/bin/bash', 09:44:14 INFO - 'SHLVL': '1', 09:44:14 INFO - 'TERM': 'linux', 09:44:14 INFO - 'TMOUT': '86400', 09:44:14 INFO - 'USER': 'cltbld', 09:44:14 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', 09:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:14 INFO - '_': '/tools/buildbot/bin/python'} 09:44:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:14 INFO - Running setup.py (path:/tmp/pip-MJg071-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:14 INFO - Running setup.py (path:/tmp/pip-zK2zAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:14 INFO - Running setup.py (path:/tmp/pip-TbwJ_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:14 INFO - Running setup.py (path:/tmp/pip-teamO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:14 INFO - Running setup.py (path:/tmp/pip-rzGbPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:14 INFO - Running setup.py (path:/tmp/pip-NUZQ_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:14 INFO - Running setup.py (path:/tmp/pip-fvD9G6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:14 INFO - Running setup.py (path:/tmp/pip-Bd9yN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:14 INFO - Running setup.py (path:/tmp/pip-dNy8Jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:14 INFO - Running setup.py (path:/tmp/pip-eJhAa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:14 INFO - Running setup.py (path:/tmp/pip-VMYtMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:15 INFO - Running setup.py (path:/tmp/pip-AbgmpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:15 INFO - Running setup.py (path:/tmp/pip-BpfPZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:15 INFO - Running setup.py (path:/tmp/pip-DTFk97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:15 INFO - Running setup.py (path:/tmp/pip-5Fnv9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:15 INFO - Running setup.py (path:/tmp/pip-96MEBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:15 INFO - Running setup.py (path:/tmp/pip-AnREEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:44:15 INFO - Running setup.py install for manifestparser 09:44:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:44:15 INFO - Running setup.py install for mozcrash 09:44:15 INFO - Running setup.py install for mozdebug 09:44:15 INFO - Running setup.py install for mozdevice 09:44:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:44:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:44:15 INFO - Running setup.py install for mozfile 09:44:15 INFO - Running setup.py install for mozhttpd 09:44:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Running setup.py install for mozinfo 09:44:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Running setup.py install for mozInstall 09:44:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Running setup.py install for mozleak 09:44:16 INFO - Running setup.py install for mozlog 09:44:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Running setup.py install for moznetwork 09:44:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Running setup.py install for mozprocess 09:44:16 INFO - Running setup.py install for mozprofile 09:44:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Running setup.py install for mozrunner 09:44:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:44:16 INFO - Running setup.py install for mozscreenshot 09:44:17 INFO - Running setup.py install for moztest 09:44:17 INFO - Running setup.py install for mozversion 09:44:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:44:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:44:17 INFO - Cleaning up... 09:44:17 INFO - Return code: 0 09:44:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:17 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x11ff030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14be3b0>, '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 0x14bf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bf740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', '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 09:44:17 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:44:17 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:44:17 INFO - Using env: {'DISPLAY': ':0', 09:44:17 INFO - 'HOME': '/home/cltbld', 09:44:17 INFO - 'LANG': 'en_US.UTF-8', 09:44:17 INFO - 'LANGUAGE': 'en_US:en', 09:44:17 INFO - 'LOGNAME': 'cltbld', 09:44:17 INFO - 'MAIL': '/var/mail/cltbld', 09:44:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:17 INFO - 'MOZ_NO_REMOTE': '1', 09:44:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:44:17 INFO - 'NO_EM_RESTART': '1', 09:44:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:44:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:17 INFO - 'PWD': '/builds/slave/test', 09:44:17 INFO - 'SHELL': '/bin/bash', 09:44:17 INFO - 'SHLVL': '1', 09:44:17 INFO - 'TERM': 'linux', 09:44:17 INFO - 'TMOUT': '86400', 09:44:17 INFO - 'USER': 'cltbld', 09:44:17 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', 09:44:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:17 INFO - '_': '/tools/buildbot/bin/python'} 09:44:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:17 INFO - Running setup.py (path:/tmp/pip-P9jG_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:17 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)) 09:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:17 INFO - Running setup.py (path:/tmp/pip-T50hQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 09:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:17 INFO - Running setup.py (path:/tmp/pip-EO9BeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:17 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)) 09:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:17 INFO - Running setup.py (path:/tmp/pip-pJBCDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 09:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:17 INFO - Running setup.py (path:/tmp/pip-FMnd2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:17 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)) 09:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:17 INFO - Running setup.py (path:/tmp/pip-C3357R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:17 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)) 09:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:17 INFO - Running setup.py (path:/tmp/pip-fMqWqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 09:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:17 INFO - Running setup.py (path:/tmp/pip-YXTeR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 09:44:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:18 INFO - Running setup.py (path:/tmp/pip-dHW0DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:18 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)) 09:44:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:18 INFO - Running setup.py (path:/tmp/pip-PcARyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 09:44:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:18 INFO - Running setup.py (path:/tmp/pip-cQmjm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:18 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)) 09:44:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:18 INFO - Running setup.py (path:/tmp/pip-m3n2x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 09:44:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:18 INFO - Running setup.py (path:/tmp/pip-oGJ9as-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:18 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)) 09:44:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:18 INFO - Running setup.py (path:/tmp/pip-_kv4LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 09:44:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:18 INFO - Running setup.py (path:/tmp/pip-E2CQIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:18 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)) 09:44:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:18 INFO - Running setup.py (path:/tmp/pip-jdbwkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 09:44:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:18 INFO - Running setup.py (path:/tmp/pip-S08hUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:18 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)) 09:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:44:18 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)) 09:44:18 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:44:18 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:44:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:44:18 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 09:44:18 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 09:44:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:18 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 09:44:18 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 09:44:20 INFO - Downloading blessings-1.6.tar.gz 09:44:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:44:20 INFO - Installing collected packages: blessings 09:44:20 INFO - Running setup.py install for blessings 09:44:20 INFO - Successfully installed blessings 09:44:20 INFO - Cleaning up... 09:44:20 INFO - Return code: 0 09:44:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:44:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:21 INFO - Reading from file tmpfile_stdout 09:44:21 INFO - Current package versions: 09:44:21 INFO - argparse == 1.2.1 09:44:21 INFO - blessings == 1.6 09:44:21 INFO - blobuploader == 1.2.4 09:44:21 INFO - docopt == 0.6.1 09:44:21 INFO - functools32 == 3.2.3-2 09:44:21 INFO - jsonschema == 2.5.1 09:44:21 INFO - manifestparser == 1.1 09:44:21 INFO - mozInstall == 1.12 09:44:21 INFO - mozcrash == 0.17 09:44:21 INFO - mozdebug == 0.1 09:44:21 INFO - mozdevice == 0.48 09:44:21 INFO - mozfile == 1.2 09:44:21 INFO - mozhttpd == 0.7 09:44:21 INFO - mozinfo == 0.9 09:44:21 INFO - mozleak == 0.1 09:44:21 INFO - mozlog == 3.3 09:44:21 INFO - moznetwork == 0.27 09:44:21 INFO - mozprocess == 0.23 09:44:21 INFO - mozprofile == 0.28 09:44:21 INFO - mozrunner == 6.12 09:44:21 INFO - mozscreenshot == 0.1 09:44:21 INFO - mozsystemmonitor == 0.3 09:44:21 INFO - moztest == 0.7 09:44:21 INFO - mozversion == 1.4 09:44:21 INFO - psutil == 3.1.1 09:44:21 INFO - requests == 1.2.3 09:44:21 INFO - wsgiref == 0.1.2 09:44:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x11ff030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14be3b0>, '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 0x14bf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bf740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', '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 09:44:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:44:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:44:21 INFO - Using env: {'DISPLAY': ':0', 09:44:21 INFO - 'HOME': '/home/cltbld', 09:44:21 INFO - 'LANG': 'en_US.UTF-8', 09:44:21 INFO - 'LANGUAGE': 'en_US:en', 09:44:21 INFO - 'LOGNAME': 'cltbld', 09:44:21 INFO - 'MAIL': '/var/mail/cltbld', 09:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:21 INFO - 'MOZ_NO_REMOTE': '1', 09:44:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:44:21 INFO - 'NO_EM_RESTART': '1', 09:44:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:21 INFO - 'PWD': '/builds/slave/test', 09:44:21 INFO - 'SHELL': '/bin/bash', 09:44:21 INFO - 'SHLVL': '1', 09:44:21 INFO - 'TERM': 'linux', 09:44:21 INFO - 'TMOUT': '86400', 09:44:21 INFO - 'USER': 'cltbld', 09:44:21 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', 09:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:21 INFO - '_': '/tools/buildbot/bin/python'} 09:44:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:21 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)) 09:44:21 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)) 09:44:21 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)) 09:44:21 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)) 09:44:21 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)) 09:44:21 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)) 09:44:21 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)) 09:44:21 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)) 09:44:21 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)) 09:44:21 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)) 09:44:21 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)) 09:44:21 INFO - Cleaning up... 09:44:21 INFO - Return code: 0 09:44:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x11ff030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13628a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14c0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14be3b0>, '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 0x14bf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bf740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', '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 09:44:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:44:21 INFO - Using env: {'DISPLAY': ':0', 09:44:21 INFO - 'HOME': '/home/cltbld', 09:44:21 INFO - 'LANG': 'en_US.UTF-8', 09:44:21 INFO - 'LANGUAGE': 'en_US:en', 09:44:21 INFO - 'LOGNAME': 'cltbld', 09:44:21 INFO - 'MAIL': '/var/mail/cltbld', 09:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:21 INFO - 'MOZ_NO_REMOTE': '1', 09:44:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:44:21 INFO - 'NO_EM_RESTART': '1', 09:44:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:21 INFO - 'PWD': '/builds/slave/test', 09:44:21 INFO - 'SHELL': '/bin/bash', 09:44:21 INFO - 'SHLVL': '1', 09:44:21 INFO - 'TERM': 'linux', 09:44:21 INFO - 'TMOUT': '86400', 09:44:21 INFO - 'USER': 'cltbld', 09:44:21 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', 09:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:21 INFO - '_': '/tools/buildbot/bin/python'} 09:44:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:44:21 INFO - Cleaning up... 09:44:21 INFO - Return code: 0 09:44:21 INFO - Running post-action listener: _resource_record_post_action 09:44:21 INFO - Running post-action listener: _start_resource_monitoring 09:44:21 INFO - Starting resource monitoring. 09:44:21 INFO - [mozharness: 2016-09-22 16:44:21.519765Z] Finished create-virtualenv step (success) 09:44:21 INFO - [mozharness: 2016-09-22 16:44:21.520205Z] Running install step. 09:44:21 INFO - Running pre-action listener: _resource_record_pre_action 09:44:21 INFO - Running main action method: install 09:44:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:21 INFO - Reading from file tmpfile_stdout 09:44:21 INFO - Detecting whether we're running mozinstall >=1.0... 09:44:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:44:21 INFO - Reading from file tmpfile_stdout 09:44:21 INFO - Output received: 09:44:21 INFO - Usage: mozinstall [options] installer 09:44:21 INFO - Options: 09:44:21 INFO - -h, --help show this help message and exit 09:44:21 INFO - -d DEST, --destination=DEST 09:44:21 INFO - Directory to install application into. [default: 09:44:21 INFO - "/builds/slave/test"] 09:44:21 INFO - --app=APP Application being installed. [default: firefox] 09:44:21 INFO - mkdir: /builds/slave/test/build/application 09:44:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:44:36 INFO - Reading from file tmpfile_stdout 09:44:36 INFO - Output received: 09:44:36 INFO - /builds/slave/test/build/application/firefox/firefox 09:44:36 INFO - Running post-action listener: _resource_record_post_action 09:44:36 INFO - [mozharness: 2016-09-22 16:44:36.135110Z] Finished install step (success) 09:44:36 INFO - [mozharness: 2016-09-22 16:44:36.135502Z] Running run-tests step. 09:44:36 INFO - Running pre-action listener: _resource_record_pre_action 09:44:36 INFO - Running main action method: run_tests 09:44:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:44:36 INFO - Python 2.7.3 09:44:36 INFO - Return code: 0 09:44:36 INFO - grabbing minidump binary from tooltool 09:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:36 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0x14be3b0>, '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 0x14bf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14bf740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:44:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 09:44:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:44:36 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 09:44:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:44:36 INFO - Return code: 0 09:44:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:44:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:44:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:44:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:44:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:44:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:44:36 INFO - Using env: {'DISPLAY': ':0', 09:44:36 INFO - 'HOME': '/home/cltbld', 09:44:36 INFO - 'LANG': 'en_US.UTF-8', 09:44:36 INFO - 'LANGUAGE': 'en_US:en', 09:44:36 INFO - 'LOGNAME': 'cltbld', 09:44:36 INFO - 'MAIL': '/var/mail/cltbld', 09:44:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:44:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:36 INFO - 'MOZ_NO_REMOTE': '1', 09:44:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:44:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:44:36 INFO - 'NO_EM_RESTART': '1', 09:44:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:36 INFO - 'PWD': '/builds/slave/test', 09:44:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:44:36 INFO - 'SHELL': '/bin/bash', 09:44:36 INFO - 'SHLVL': '1', 09:44:36 INFO - 'TERM': 'linux', 09:44:36 INFO - 'TMOUT': '86400', 09:44:36 INFO - 'USER': 'cltbld', 09:44:36 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334', 09:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:36 INFO - '_': '/tools/buildbot/bin/python'} 09:44:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:44:36 INFO - mozversion application_buildid: 20160922064859 09:44:36 INFO - mozversion application_changeset: aee0f090712afdefc44ddf6198515d31d3053a1b 09:44:36 INFO - mozversion application_display_name: Firefox 09:44:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:44:36 INFO - mozversion application_name: Firefox 09:44:36 INFO - mozversion application_remotingname: firefox 09:44:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:44:36 INFO - mozversion application_vendor: Mozilla 09:44:36 INFO - mozversion application_version: 50.0 09:44:36 INFO - mozversion platform_buildid: 20160922064859 09:44:36 INFO - mozversion platform_changeset: aee0f090712afdefc44ddf6198515d31d3053a1b 09:44:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:44:36 INFO - mozversion platform_version: 50.0 09:44:36 INFO - using testdate: 1474562676 09:44:36 INFO - actual date: 1474562676 09:44:36 INFO - starting webserver on 'localhost:49025' 09:44:36 INFO - SUITE-START | Running 2 tests 09:44:36 INFO - TEST-START | dromaeo_css 09:44:36 INFO - operating with platform_type : linux_ 09:44:36 INFO - Initialising browser for dromaeo_css test... 09:44:36 INFO - TEST-INFO | started process 27708 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWxPyef/profile http://localhost:49025/getInfo.html) 09:44:40 INFO - PROCESS | 27708 | __metrics Screen width/height:1600/1200 09:44:40 INFO - PROCESS | 27708 | colorDepth:24 09:44:40 INFO - PROCESS | 27708 | Browser inner width/height: 1024/697 09:44:40 INFO - PROCESS | 27708 | __metrics 09:44:41 INFO - PROCESS | 27708 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:44:41 INFO - PROCESS | 27708 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:44:42 INFO - TEST-INFO | 27708: exit 0 09:44:42 INFO - Browser initialized. 09:44:42 INFO - Running cycle 1/1 for dromaeo_css test... 09:44:42 INFO - TEST-INFO | started process 27796 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWxPyef/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:44:54 INFO - PROCESS | 27796 | RSS: Main: 144027648 09:44:54 INFO - PROCESS | 27796 | 09:45:45 INFO - PROCESS | 27796 | 1474562745409 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:47:54 INFO - PROCESS | 27796 | Cycle 1(1): loaded http://localhost:49025/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49025/tests/dromaeo/cssquery-ext.html) 09:47:55 INFO - PROCESS | 27796 | RSS: Main: 219598848 09:47:55 INFO - PROCESS | 27796 | 09:50:57 INFO - PROCESS | 27796 | Cycle 1(1): loaded http://localhost:49025/tests/dromaeo/cssquery-ext.html (next: http://localhost:49025/tests/dromaeo/cssquery-jquery.html) 09:50:58 INFO - PROCESS | 27796 | RSS: Main: 236777472 09:50:58 INFO - PROCESS | 27796 | 09:54:00 INFO - PROCESS | 27796 | Cycle 1(1): loaded http://localhost:49025/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49025/tests/dromaeo/cssquery-mootools.html) 09:54:00 INFO - PROCESS | 27796 | RSS: Main: 233373696 09:54:00 INFO - PROCESS | 27796 | 09:57:02 INFO - PROCESS | 27796 | Cycle 1(1): loaded http://localhost:49025/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49025/tests/dromaeo/cssquery-prototype.html) 09:57:03 INFO - PROCESS | 27796 | RSS: Main: 176226304 09:57:03 INFO - PROCESS | 27796 | 10:00:06 INFO - PROCESS | 27796 | Cycle 1(1): loaded http://localhost:49025/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49025/tests/dromaeo/cssquery-yui.html) 10:00:06 INFO - PROCESS | 27796 | RSS: Main: 176906240 10:00:06 INFO - PROCESS | 27796 | 10:03:09 INFO - PROCESS | 27796 | Cycle 1(1): loaded http://localhost:49025/tests/dromaeo/cssquery-yui.html (next: http://localhost:49025/tests/dromaeo/cssquery-dojo.html) 10:03:09 INFO - PROCESS | 27796 | RSS: Main: 261414912 10:03:09 INFO - PROCESS | 27796 | 10:03:09 INFO - PROCESS | 27796 | __start_tp_report 10:03:09 INFO - PROCESS | 27796 | _x_x_mozilla_page_load 10:03:09 INFO - PROCESS | 27796 | _x_x_mozilla_page_load_details 10:03:09 INFO - PROCESS | 27796 | |i|pagename|runs| 10:03:09 INFO - PROCESS | 27796 | |0;dojo.html;3232.838358082096;3253.4794432890735;3276.2628408608066;3293.7035666790016;3324.3517514084747;5596.80411185608;5663.405342439047;5671.971640141799;5686.090225563906;5702.3157221133415;5587.804426845061;5633.971830140844;5646.6894320812335;5648.830535083944;5665.291838520185;5051.848444546651;5114.130597798383;5121.999999999991;5124.974375128119;5176.5858731301405;5165.328507294046;5195.168772996321;5198.246254293125;5245.370555533342;5261.868453288679;5107.182850743881;5148.279240906276;5177.249298851664;5194.610404219679;5234.110201265794;5266.104762190437;5271.762770675329;5272.156454967206;5272.947270527322;5308.5487733542695;5063.316452278932;5081.847544573632;5119.0273847968765;5147.742612869341;5151.021305951895;5502.642328248691;5523.061079616475;5528.364238112588;5557.944420555783;5558.138488534284;6073.939260607382;6085.513158947274;6085.786997455112;6086.543509236825;6095.451409373133;5933.554983376221;5996.670183139926;6027.879442411171;6054.243219597551;6122.816315510498;5690.2033715279895;5693.686847223443;5697.743601537941;5708.514776244025;5722.942770572283;6180.6600636965395;6198.2871969723155;6199.101130336076;6202.28673702521;6203.16257305267;5809.82570522885;5897.321807992136;5940.673262970578;5956.225690660273;6059.151718759377;6107.511399088063;6109.676484838171;6111.586445669263;6111.877762444727;6137.41694539018;6019.488343490765;6070.2412198475195;6075.969620151871;6086.969565152146;6096.69516524172;5783.479486846204;5933;5992.0712289595185;5994.1608174855555;6090.878182436327;4605.539446055434;4635.096156249499;4635.513271106553;4639.466461356919;4671.719696818202;4138.79306034697;4149.875503734893;4154.688398370074;4162.875113746592;4185.288500954845;5530.446955304437;5687.203791469197;5724.255846739898;5738.770449181986;5771.942280577225;6069.3627169147485;6070.332263451017;6071.089336599545;6073.362296958844;6078.665673387918;5787.421257874263;5794.855128621818;5801.187833703285;5801.6519008859605;5802.883942321215;5064.442911279756;5193.584513239008;5226.8954620908125;5229.686218826882;5246.6065045121;6256.061590761422;6267.2165979252595;6268.937310626927;6294.4020318070625;6296.307406185316;6023.066424704178;6059.727312271047;6079.513638908788;6083.1483592298;6097.756089756537;6055.031195008777;6108.144859719731;6115.6662951758;6116.061877111602;6189.102580125947;5934.970325148347;5949.702514874152;5951.970240148771;5962.284525856925;5996.190514280691;6051;6054.697265136813;6058.909116363347;6058.969705151622;6067.332593414809;5758.740856661377;5830.650160990354;5872.794452194153;5876.1773351729935;5974.312954010345;5906.291245050449;5913.201717768046;5922.437368449991;5925.496332811586;5926.762929482945;5852.853678658068;5872.735726892215;5914.526837853047;5921.289445266596;5929.0217114175675;5763.596548241583;5894.882102358015;5927.733252003756;5935.762569497345;6033.064874944391;5854.941450585439;5860.94139058621;5865.853353666193;5872.03111486617;5897.321807992136 10:03:09 INFO - PROCESS | 27796 | |1;ext.html;601.3295175878897;633.7750098714956;654.8788474132284;664.6842749693895;666.7632990288445;35709.82145089274;46790;47517.28724069133;47540.28689569659;47578.76210618942;48728.756356218255;49107;49287;49536.50463495364;49744.25383619249;8632;9489.857652135206;9530.761730956712;9646.565904534278;11095.55617775292;11013.229073964785;11520.42397880107;11525.077993760478;11527.365994870279;11533.423328833567;11542.71143221422;11586.59446919358;11587.478563464621;11594.30434173948;11599.072074234084;11285.661430157108;11360.943195284053;11424.485898134604;11446.427678616035;11452.31286122831;11262.380569068699;11318.660440186808;11341.659750207424;11348.886511134948;11356.432178391047;46540.7672961633;46844;46923.765381173216;46993.765031174626;47044;3964.920701585952;3967.3255546556866;4069.9186016279805;4130.297849365585;4184.28867092595;34460.65539344624;34719.652803471894;35405.82297088524;35543.82228088843;35676.28647427063;4674.345591617176;4786.832460863853;4790.8323208687525;4825.179719447668;4842.1526232909455;368859;369481;370204;371741;373850;60895;61187;61331.386686132566;61353.38646613566;61378.693106534185;39869.800650996556;40786.38820417696;41016.58983410128;42840.357394639104;42912.78543607262;20046.89976550137;20232.291869784487;20241;20251.392458226277;20264.290749823685;13886.652833679238;14057.226852523005;14075.718485630436;14094.71810563783;14817.629559261104;21109.577808444054;21125.154993800385;21275;21301.89349053245;21351.786482135292;7539.547627142279;7562;7595.3164215219795;7661.731839385595;7726.652300646484;1066.861308029951;1082.1775450657558;1086.7393823165091;1159.756451145269;1165.9125565582615;35140.29719405597;35784.46323305152;35836.28327433437;35964.82017589895;36136.63863361385;1134.625573560723;1184.5498710489958;1196.3838623109107;1238.8017917133395;1317.374247232576;18668.53328666767;18748.531286717942;18862.622747545247;18919.148638311308;19053.523661908286;600174;603158;604355;604752;605882;63558.68220658867;64193.679031605476;64262;64268.03597946034;64287.678561606896;1351.4796803230827;1455.9636009099856;1482.0959214878915;1498.0936533397573;1509.3736099518576;34321.485177722854;35908.4613730789;36472.63527364693;36813.81593092017;36861.81569092137;53765.46234537604;54358.72820635871;54420.18369724378;54426;54448.72775636097;437338;438198;438906;439714;693349;30599.847000764854;30774.53838192384;30859.84570077135;32412;32657;53057.73471132619;53648.73175634253;53798.462015379344;53888;54054.189187163014;44359.77820111008;44526.332105017806;44643.776781115885;46332;48751.5124848747;36696.08259793527;36943.630563694016;37230;37857;38375.80812095922;45560.77219613881;46398.76800615976;46627.30059049185;46784.53215467802;46925.76537117293;38876;39895.60104398919;40764.59235407608;40833.38749918813;41001.38497922474;31199.688003120587;31413.214669633442;31497.8425107873;31576.842115790194;31715.20711982219 10:03:09 INFO - PROCESS | 27796 | |2;jquery.html;415.5491291948236;430.0573085417944;432.64955386137234;435.1601409280089;439.41996564534804;20082.39752807416;20882;21124;21808.672869906964;21977.67033494499;20154.596908061867;20462.633451957285;20590.89704551475;20730.792692073075;21056.57886842262;5861.85345366365;6068.939310606882;6069.393060693939;6073.817785466443;6076.969615151918;5853.385394533577;5865.560082993771;5960.612560183596;5983.491403230731;5990.131430942511;5861.2087368205175;5971.104334349839;5975.641461512323;5977.641341519501;5979.611325263866;5940.108983652443;5984.551158663117;5995.130706047642;5997.940020599782;5998.220231369937;5365.579246588652;5378.273933019032;5395.973020134874;5418.674879507243;5430.13117901134;24123.276301710976;24919.875400623063;25090;25135.748642513707;25311.367215819566;2581.2514370832623;2806.326481644411;2814.6059551662784;3220.146661134801;3273.1489812648647;15319.387224511007;15398.153101579408;15436.53690389279;15927.681446371123;16023;1975.9802401976083;2065.0913598016823;2097.737782777153;2106.747190337154;2138.9251376201914;67693.3230667692;67925;83512.74730879042;90787;91045.63431548532;9848.359856609297;10166.237532185116;10325.84511232332;10855.891441085647;10949.562017519369;14533.200673962821;14594.781078283835;14611.707765844836;14619.78070328946;14620.707585848437;8235.21765432283;8246.546439945741;8261.463004904663;8447.873281900776;8551.828963420698;8007.879881801661;8211.302039326605;8271.793205169919;8420.4947703138;8659.610317535726;4451.59935605797;4456.977715111469;4496.043997498329;4587.9541204588195;4626.421697287839;1928.662484065283;2007.7289565908416;2107.978920210778;2139.1443422631073;2140.6788981652567;936.8360536906014;1036.3315661398356;1043.3426941027105;1065.5950738719391;1120.0871289898707;20646;21237.893810530848;21387.8930605346;21405.571888562143;21413;1076.4294923690456;1123.2081382625229;1146.2893006335958;1153.6423708650345;1168.5150662475148;11093.33439993603;11869.347185904859;12015.459304331325;12018.278903265833;12074.698132546757;620624;622527;628788;631115;635404;76851.2314876844;89852;92847.53576232075;101641;106253;1011.6813203840619;1019.0675531888239;1062.8086944349936;1063.239783554762;1065.1212749481708;17900;18755.249790008245;18904.338348157748;19399.515012124808;19508;42817;44383.77808110939;44619.776901115285;44825.55174448214;46856.062878741555;63707.681461592394;64196;65014;65237.34762652503;65380.34619653742;9227.538623068955;9464.526773661428;9667.516624168904;9682.225421966084;9714.222862170867;13220.338983050617;13749.931250343685;14521.128732276096;14551.563453096423;14647.340869660678;11903.821442678194;12198.939005305272;12638.810417843924;12897.41961611711;12907.548235811702;11918.106142039554;11979.580714674945;12199.634010979686;12289.1397602167;12919.483220671067;12458.75082498363;12515;12856.421461034462;12903.225806451643;13225.272610006543;9026.86459703118;9226.446413215228;9264.212541934012;9311.255099591881;9387.249020078514;10904.400257985886;12500.31248281317;12522.248665080124;12640.178388404724;12889.226646401246 10:03:09 INFO - PROCESS | 27796 | |3;mootools.html;1532.6934613077383;1577.4163559482993;1756.1745979389682;1812.3566134022412;1859.6466671332446;9172.495512746797;9866.851997220047;9872.16086632637;9879.802403951917;9883.901160988396;8505.319574434046;9253.583588738506;9479;9492.382995105314;9634.325597208199;2500.4749002709455;2528.608065749812;2532.126416386353;2532.278300684305;2534.42975330551;2468.4075821802817;2530.5698031334623;2538.987305063472;2538.987305063472;2541.669582954213;2500.362407586063;2504;2535.2014115553634;2535.9239222823253;2538.010176031349;2396.161343529768;2401.2076014915037;2413.215704895907;2424.2000139953775;2425.842320249178;2395.4370722880108;2409.229046705055;2417.4319035026756;2420.3586049696846;2433.513297340539;9725.56234969428;9750.46372449515;9843.90156098437;9862.704118876374;9886.307958442912;5725.370209276976;5875.882482350372;5877.764889404419;5890.175375447441;5891.675957822318;3618.6019537850816;3695.2794205130012;3804.1821008483034;3805.7335986480957;3841.4621952926614;1142.6115120858947;1197.9880201197964;1214.769193853165;1219.5792451604182;1223.2660403757764;13867.37596808146;13874;13925.582232533141;13928.37322320498;13959.511417100453;5053.747312634354;5141.845744627667;5276.92084618731;5281.630285879952;5324.8817748273295;10476.580936762444;10548.578056877639;10556.419396933243;10562.049415552638;10566.471676416302;5859;5865;5869.706514674249;5874.970625146847;5877.970610146922;4671.85984420468;4802.975985120122;5340.385855626575;5387.0841956867425;5468.91796623051;4088.1999460401007;4139.399787030864;4189.476315460567;4191.093052661032;4215.810288537024;1211.7879371110196;1212.8847759462838;1216.750566133958;1230.501646833034;1244.2783185752241;996.8903420623726;1003.6437064842022;1019.7705516258931;1031.7626945802508;1032.7160030991388;6183.598066125687;6327.841803954938;6362.618242905441;6372.2353317601255;6499.935000649933;496.85591178557814;497.19950879084644;503;506.0789363358725;509.4497942222391;4271.273883439822;4294.463192100988;4425.645948324126;4465.173942820588;4470.977645111753;10879.184061195441;11924;12156.696082598006;12258.632241032783;12289.016878649687;11081.501332440059;11390.25963312382;11406.942965285121;11409.201355905006;11414.086873050135;8943.642254309754;9072.364934454657;9087.863682044775;9097.272218222526;9136.771580710536;6244.1570387997035;6482.513811464253;6486.54594178403;6519.674016299261;6605.768798092044;10908.618198363336;11327.376994265394;11370.94314528422;11375.54497820078;11473;10967.396793176453;11530.135239857213;11538.596149135075;11573.247738896946;11678.24091434054;7665.961670191837;8038.83922321562;8127.471714338549;8564.700235491728;8854.29165666758;9232.815343692953;9323.766905827408;9325.86011209846;9329.673461428818;9330.393524420891;8016.559089250147;8130.105688374156;8194.959025204837;8255.545944973084;8325.417220794729;7893.644785984759;7939;8101.716439924574;8208.261256486945;8395.202455766692;7030.578165310098;7160.677769500281;7164.068671072936;7229.602371869598;7412.332890039922;5480.150576660624;5582.413846546055;5679.574031947538;5698.715064246854;5723.570732195185;5574.414686457863;5585.469380408855;5594.300712410949;5732.828015159552;5838.503727183067 10:03:09 INFO - PROCESS | 27796 | |4;prototype.html;353.31633289584653;356.4510653593466;358.1351037245053;370.93694072007787;372.6236501133854;5637.041702910505;5668.319801623806;5704;5711.914321285184;5725;5387.838364849051;5556.499915007648;5637.267155269819;5644.585026059237;5657.51911087557;4401.405810215612;4418.403515525411;4421.579949904753;4425.247707889658;4425.402570652969;4403.273459879116;4421.336799480072;4434.979954610441;4443.977780111095;4450.376947227391;4158.272302347093;4196.643285320752;4390.2317094508335;4409.404730361408;4432.468103827545;4297.462817147856;4299.720518166325;4303.203907277148;4311.784410779448;4322.416473776063;3847.615238476158;3853.903652408684;3857.324968130594;3857.7492462990094;3859.1895701902636;5290.68255904647;5410.56715462766;5439.347278326626;5446.863828404322;5456.236126942231;4996.775145118442;5002.324686167357;5005.624578156617;5008.148614735467;5024.221245706921;5794.652320860761;5802.535797136219;5805.706745835371;5809.593328467009;5830.533557315447;4165.166966606691;4254.957450425487;4278.593533614302;4299.78501074945;4308.18144552534;5993.441705156647;5995.940040599582;6000.459958603703;6014.78948236814;6029.728662210167;4375.256206444912;4375.956240437555;4379.343098535245;4387.771424001221;4426.667999900021;4297.968487563025;4428.22506061438;4430.379746835446;4489.744934922221;4494.1461122386645;4926.187179115477;4992.350994370709;5006.299118123466;5008.899822003539;5032.72320022395;5833.649981001154;5859.853503662443;5865.882682346414;5871.412858714094;5875.9118613220835;1528.4879565345511;1541.3295216580823;1546.5592306192682;1549.387991743255;1556.8209655889718;965.6909788867636;966.9951650241704;996.7109538233831;997.1424574865609;998.9550470228858;4909.68087074339;4973.626977976666;4982.8754281143265;5006.222790909174;5037.571806396424;5201.1275940661335;5233.528982391527;5248.606354523426;5276.525112739795;5311.229871668586;5017.874553136201;5450.863728406822;5479.643823151482;5663.830085097454;5667.433256674382;3138.419392412411;3170.685399658262;3174.444472217399;3182.3317103408544;3189.218641432864;6025.879482410327;6157.630542167484;6178.907316390258;6195.132681424604;6200.3799620037635;6056.939430605637;6060.333363330118;6080.449485381199;6082.391760823883;6097.878042439215;6049.334573197033;6188.164597779332;6193.411625895533;6204.7828326008375;6213.96893015532;5863.364713877423;5929.407059294209;5944.4352786485215;5968.373320801254;5968.880622387441;5908.113782932594;5911.349751527415;5911.408859114054;5921.763129474945;5984.222051133324;5936.287645482397;6224.657643829632;6224.844378890382;6235.657038862906;6291.433770960635;4344.739315641072;4367.170237654836;4371.956280437155;4383.824647014084;4396.626286765533;6102.664353460602;6102.908456373069;6117.388261173847;6120.234970628671;6127.448529632355;5721.399253078534;5838.503727183067;5865.3254875689845;5911.074018360767;5911.5516698409165;5858.560607954335;5863.882722345615;5872.559558033079;5901.5868889177345;5919.733611987384;5567.8886422272135;5851.268591426072;5857.447776339188;5881.529477641864;5921.703914804157;5868.354481007001;5903.848749493807;5930.509186141745;5933.673647949404;5944.494717949023;5688.175214593778;5765.8558536036935;5840.182374467663;5847.94152058491;5894.734736936763 10:03:09 INFO - PROCESS | 27796 | |5;yui.html;841.920017598328;853.3386625365338;886.8935727712676;892.6786356911507;926.8285367207067;16179.433719819808;16275.83724162758;16277.918610406947;16302.021878687287;16804.57988550287;16020.75968860468;16126.193690315467;16143.515694529153;16194.433194838182;17006.659866802656;892.6340200517773;905.1627244798568;916.3448134583771;918.1552971266451;919.5723988345418;913.4154141349542;918.669279059538;919.9356045076847;920.3741455810047;920.4201353147507;961.3270710502641;967.7435479597877;968.1528662420383;968.6658102954469;969.4231932000483;959.3524371049549;960.3709570231509;963.3208587945481;963.7012526116891;963.9974426596332;696.8188271049513;702.3503259484957;704.1128178495081;704.531486561436;704.968276427562;17339.219735111823;17348.39280625172;17437.040962747044;17485.825141748675;17487.213075411637;15967.201639918074;16216.432424865192;16508.6698266034;16600.50198494047;17088.57278568033;13330.400131994083;13550.59348219545;13632.454701811916;13660.180389176681;13726.519571814939;4121.8969525761795;4159.168166366709;4265.0616864289805;4274.337477690963;4277.593628605277;49484;70471.64764176197;83366;83512;87151;12662.36688165588;12666.303353315656;12950.352482375843;13107.213567186;13331.266780326981;22870.542589148125;22898.542029159657;22899.08403663835;22901.541969160524;22915.312540623476;8827.117288271122;8919.955400222958;9296.907030929739;9376.062393760705;9602.32783705148;4807.663463557587;4903.338049363361;4995.525425084611;5030.572401345927;5058.494150584986;4049.6962727795535;4196.832126714898;4241.151769646083;4244.108737165168;4248.277792775236;696.0637941968045;706.6855249414065;750.1335956969709;780.5862892666896;783.698276163681;655.829484334067;722.4762047515615;766.8121310279017;771.3944553525456;778.5951305321202;10892.455377231106;10931.726706832393;11257.887421125848;11501.367424791717;11776.11679124052;673.515069150211;689.3520091114336;698.155232169081;761.2121454294778;786.7010535996285;5077.339945807022;5415.350157981067;5461.044317244467;5463.235147079412;5488.588355873326;137881;137986;138582.30708846392;141317;143064.28467857803;70994.2900570998;96129;109647;116670.41664791622;120654.39672801756;81172.59413702894;81673.59163204265;82977;86120.13879861246;94868.52565737265;10362.585496580052;10776.784464310827;11170;11241.437928103398;11531.538738450368;47757.52242477531;48326.27510587414;52405;53765.193522096415;55103.72448137894;75786.62106689431;77430.2256977423;79685;81696;84073;13344.933275333562;14224.786628200378;14365.20991345486;14556.272186390426;14723.852761472248;17069.40257090996;17405.216765245343;18133.90933045326;18476.630467390307;18523.44429667112;14781.556553303419;15492.838037147034;15978.760318595463;16106.597335066717;16882.49352519377;14739.336729846968;15670.138142402277;15926.203689815231;16395.42616008434;16410.917945410198;14450.638734031312;14596;14799.852001480278;14925.328360224034;15366.539003829903;12982.221066736027;13122.343882806012;13281.203127812361;13666.453341866214;13670.863291367357;13011.349432528146;13034.283114428797;13039.674008149872;13128.60614181576;13320.25871888593 10:03:09 INFO - PROCESS | 27796 | __end_tp_report 10:03:09 INFO - PROCESS | 27796 | __start_cc_report 10:03:09 INFO - PROCESS | 27796 | _x_x_mozilla_cycle_collect,1006 10:03:09 INFO - PROCESS | 27796 | __end_cc_report 10:03:09 INFO - PROCESS | 27796 | __startTimestamp1474563789325__endTimestamp 10:03:09 INFO - PROCESS | 27796 | 10:03:09 INFO - PROCESS | 27796 | ------- Summary: start ------- 10:03:09 INFO - PROCESS | 27796 | Number of tests: 6 10:03:09 INFO - PROCESS | 27796 | 10:03:09 INFO - PROCESS | 27796 | [#0] dojo.html Cycles:165 Average:5623.71 Median:5860.94 stddev:630.11 (10.8%) stddev-sans-first:603.49 10:03:09 INFO - PROCESS | 27796 | Values: 3232.8 3253.5 3276.3 3293.7 3324.4 5596.8 5663.4 5672.0 5686.1 5702.3 5587.8 5634.0 5646.7 5648.8 5665.3 5051.8 5114.1 5122.0 5125.0 5176.6 5165.3 5195.2 5198.2 5245.4 5261.9 5107.2 5148.3 5177.2 5194.6 5234.1 5266.1 5271.8 5272.2 5272.9 5308.5 5063.3 5081.8 5119.0 5147.7 5151.0 5502.6 5523.1 5528.4 5557.9 5558.1 6073.9 6085.5 6085.8 6086.5 6095.5 5933.6 5996.7 6027.9 6054.2 6122.8 5690.2 5693.7 5697.7 5708.5 5722.9 6180.7 6198.3 6199.1 6202.3 6203.2 5809.8 5897.3 5940.7 5956.2 6059.2 6107.5 6109.7 6111.6 6111.9 6137.4 6019.5 6070.2 6076.0 6087.0 6096.7 5783.5 5933.0 5992.1 5994.2 6090.9 4605.5 4635.1 4635.5 4639.5 4671.7 4138.8 4149.9 4154.7 4162.9 4185.3 5530.4 5687.2 5724.3 5738.8 5771.9 6069.4 6070.3 6071.1 6073.4 6078.7 5787.4 5794.9 5801.2 5801.7 5802.9 5064.4 5193.6 5226.9 5229.7 5246.6 6256.1 6267.2 6268.9 6294.4 6296.3 6023.1 6059.7 6079.5 6083.1 6097.8 6055.0 6108.1 6115.7 6116.1 6189.1 5935.0 5949.7 5952.0 5962.3 5996.2 6051.0 6054.7 6058.9 6059.0 6067.3 5758.7 5830.7 5872.8 5876.2 5974.3 5906.3 5913.2 5922.4 5925.5 5926.8 5852.9 5872.7 5914.5 5921.3 5929.0 5763.6 5894.9 5927.7 5935.8 6033.1 5854.9 5860.9 5865.9 5872.0 5897.3 10:03:09 INFO - PROCESS | 27796 | 10:03:09 INFO - PROCESS | 27796 | [#1] ext.html Cycles:180 Average:65849.28 Median:38116.40 stddev:132985.45 (348.9%) stddev-sans-first:133268.27 10:03:09 INFO - PROCESS | 27796 | Values: 601.3 633.8 654.9 664.7 666.8 35709.8 46790.0 47517.3 47540.3 47578.8 48728.8 49107.0 49287.0 49536.5 49744.3 8632.0 9489.9 9530.8 9646.6 11095.6 11013.2 11520.4 11525.1 11527.4 11533.4 11542.7 11586.6 11587.5 11594.3 11599.1 11285.7 11360.9 11424.5 11446.4 11452.3 11262.4 11318.7 11341.7 11348.9 11356.4 46540.8 46844.0 46923.8 46993.8 47044.0 3964.9 3967.3 4069.9 4130.3 4184.3 34460.7 34719.7 35405.8 35543.8 35676.3 4674.3 4786.8 4790.8 4825.2 4842.2 368859.0 369481.0 370204.0 371741.0 373850.0 60895.0 61187.0 61331.4 61353.4 61378.7 39869.8 40786.4 41016.6 42840.4 42912.8 20046.9 20232.3 20241.0 20251.4 20264.3 13886.7 14057.2 14075.7 14094.7 14817.6 21109.6 21125.2 21275.0 21301.9 21351.8 7539.5 7562.0 7595.3 7661.7 7726.7 1066.9 1082.2 1086.7 1159.8 1165.9 35140.3 35784.5 35836.3 35964.8 36136.6 1134.6 1184.5 1196.4 1238.8 1317.4 18668.5 18748.5 18862.6 18919.1 19053.5 600174.0 603158.0 604355.0 604752.0 605882.0 63558.7 64193.7 64262.0 64268.0 64287.7 1351.5 1456.0 1482.1 1498.1 1509.4 34321.5 35908.5 36472.6 36813.8 36861.8 53765.5 54358.7 54420.2 54426.0 54448.7 437338.0 438198.0 438906.0 439714.0 693349.0 30599.8 30774.5 30859.8 32412.0 32657.0 53057.7 53648.7 53798.5 53888.0 54054.2 44359.8 44526.3 44643.8 46332.0 48751.5 36696.1 36943.6 37230.0 37857.0 38375.8 45560.8 46398.8 46627.3 46784.5 46925.8 38876.0 39895.6 40764.6 40833.4 41001.4 31199.7 31413.2 31497.8 31576.8 31715.2 10:03:09 INFO - PROCESS | 27796 | 10:03:09 INFO - PROCESS | 27796 | [#2] jquery.html Cycles:180 Average:33723.33 Median:24521.58 stddev:102893.34 (419.6%) stddev-sans-first:103151.59 10:03:09 INFO - PROCESS | 27796 | Values: 415.5 430.1 432.6 435.2 439.4 20082.4 20882.0 21124.0 21808.7 21977.7 20154.6 20462.6 20590.9 20730.8 21056.6 5861.9 6068.9 6069.4 6073.8 6077.0 5853.4 5865.6 5960.6 5983.5 5990.1 5861.2 5971.1 5975.6 5977.6 5979.6 5940.1 5984.6 5995.1 5997.9 5998.2 5365.6 5378.3 5396.0 5418.7 5430.1 24123.3 24919.9 25090.0 25135.7 25311.4 2581.3 2806.3 2814.6 3220.1 3273.1 15319.4 15398.2 15436.5 15927.7 16023.0 1976.0 2065.1 2097.7 2106.7 2138.9 67693.3 67925.0 83512.7 90787.0 91045.6 9848.4 10166.2 10325.8 10855.9 10949.6 14533.2 14594.8 14611.7 14619.8 14620.7 8235.2 8246.5 8261.5 8447.9 8551.8 8007.9 8211.3 8271.8 8420.5 8659.6 4451.6 4457.0 4496.0 4588.0 4626.4 1928.7 2007.7 2108.0 2139.1 2140.7 936.8 1036.3 1043.3 1065.6 1120.1 20646.0 21237.9 21387.9 21405.6 21413.0 1076.4 1123.2 1146.3 1153.6 1168.5 11093.3 11869.3 12015.5 12018.3 12074.7 620624.0 622527.0 628788.0 631115.0 635404.0 76851.2 89852.0 92847.5 101641.0 106253.0 1011.7 1019.1 1062.8 1063.2 1065.1 17900.0 18755.2 18904.3 19399.5 19508.0 42817.0 44383.8 44619.8 44825.6 46856.1 63707.7 64196.0 65014.0 65237.3 65380.3 9227.5 9464.5 9667.5 9682.2 9714.2 13220.3 13749.9 14521.1 14551.6 14647.3 11903.8 12198.9 12638.8 12897.4 12907.5 11918.1 11979.6 12199.6 12289.1 12919.5 12458.8 12515.0 12856.4 12903.2 13225.3 9026.9 9226.4 9264.2 9311.3 9387.2 10904.4 12500.3 12522.2 12640.2 12889.2 10:03:09 INFO - PROCESS | 27796 | 10:03:09 INFO - PROCESS | 27796 | [#3] mootools.html Cycles:180 Average:6275.62 Median:2649.92 stddev:3632.16 (137.1%) stddev-sans-first:3624.87 10:03:09 INFO - PROCESS | 27796 | Values: 1532.7 1577.4 1756.2 1812.4 1859.6 9172.5 9866.9 9872.2 9879.8 9883.9 8505.3 9253.6 9479.0 9492.4 9634.3 2500.5 2528.6 2532.1 2532.3 2534.4 2468.4 2530.6 2539.0 2539.0 2541.7 2500.4 2504.0 2535.2 2535.9 2538.0 2396.2 2401.2 2413.2 2424.2 2425.8 2395.4 2409.2 2417.4 2420.4 2433.5 9725.6 9750.5 9843.9 9862.7 9886.3 5725.4 5875.9 5877.8 5890.2 5891.7 3618.6 3695.3 3804.2 3805.7 3841.5 1142.6 1198.0 1214.8 1219.6 1223.3 13867.4 13874.0 13925.6 13928.4 13959.5 5053.7 5141.8 5276.9 5281.6 5324.9 10476.6 10548.6 10556.4 10562.0 10566.5 5859.0 5865.0 5869.7 5875.0 5878.0 4671.9 4803.0 5340.4 5387.1 5468.9 4088.2 4139.4 4189.5 4191.1 4215.8 1211.8 1212.9 1216.8 1230.5 1244.3 996.9 1003.6 1019.8 1031.8 1032.7 6183.6 6327.8 6362.6 6372.2 6499.9 496.9 497.2 503.0 506.1 509.4 4271.3 4294.5 4425.6 4465.2 4471.0 10879.2 11924.0 12156.7 12258.6 12289.0 11081.5 11390.3 11406.9 11409.2 11414.1 8943.6 9072.4 9087.9 9097.3 9136.8 6244.2 6482.5 6486.5 6519.7 6605.8 10908.6 11327.4 11370.9 11375.5 11473.0 10967.4 11530.1 11538.6 11573.2 11678.2 7666.0 8038.8 8127.5 8564.7 8854.3 9232.8 9323.8 9325.9 9329.7 9330.4 8016.6 8130.1 8195.0 8255.5 8325.4 7893.6 7939.0 8101.7 8208.3 8395.2 7030.6 7160.7 7164.1 7229.6 7412.3 5480.2 5582.4 5679.6 5698.7 5723.6 5574.4 5585.5 5594.3 5732.8 5838.5 10:03:09 INFO - PROCESS | 27796 | 10:03:09 INFO - PROCESS | 27796 | [#4] prototype.html Cycles:180 Average:4908.77 Median:5602.47 stddev:1426.67 (25.5%) stddev-sans-first:1389.09 10:03:09 INFO - PROCESS | 27796 | Values: 353.3 356.5 358.1 370.9 372.6 5637.0 5668.3 5704.0 5711.9 5725.0 5387.8 5556.5 5637.3 5644.6 5657.5 4401.4 4418.4 4421.6 4425.2 4425.4 4403.3 4421.3 4435.0 4444.0 4450.4 4158.3 4196.6 4390.2 4409.4 4432.5 4297.5 4299.7 4303.2 4311.8 4322.4 3847.6 3853.9 3857.3 3857.7 3859.2 5290.7 5410.6 5439.3 5446.9 5456.2 4996.8 5002.3 5005.6 5008.1 5024.2 5794.7 5802.5 5805.7 5809.6 5830.5 4165.2 4255.0 4278.6 4299.8 4308.2 5993.4 5995.9 6000.5 6014.8 6029.7 4375.3 4376.0 4379.3 4387.8 4426.7 4298.0 4428.2 4430.4 4489.7 4494.1 4926.2 4992.4 5006.3 5008.9 5032.7 5833.6 5859.9 5865.9 5871.4 5875.9 1528.5 1541.3 1546.6 1549.4 1556.8 965.7 967.0 996.7 997.1 999.0 4909.7 4973.6 4982.9 5006.2 5037.6 5201.1 5233.5 5248.6 5276.5 5311.2 5017.9 5450.9 5479.6 5663.8 5667.4 3138.4 3170.7 3174.4 3182.3 3189.2 6025.9 6157.6 6178.9 6195.1 6200.4 6056.9 6060.3 6080.4 6082.4 6097.9 6049.3 6188.2 6193.4 6204.8 6214.0 5863.4 5929.4 5944.4 5968.4 5968.9 5908.1 5911.3 5911.4 5921.8 5984.2 5936.3 6224.7 6224.8 6235.7 6291.4 4344.7 4367.2 4372.0 4383.8 4396.6 6102.7 6102.9 6117.4 6120.2 6127.4 5721.4 5838.5 5865.3 5911.1 5911.6 5858.6 5863.9 5872.6 5901.6 5919.7 5567.9 5851.3 5857.4 5881.5 5921.7 5868.4 5903.8 5930.5 5933.7 5944.5 5688.2 5765.9 5840.2 5847.9 5894.7 10:03:09 INFO - PROCESS | 27796 | 10:03:09 INFO - PROCESS | 27796 | [#5] yui.html Cycles:180 Average:22579.10 Median:4178.00 stddev:32671.78 (782.0%) stddev-sans-first:32722.67 10:03:09 INFO - PROCESS | 27796 | Values: 841.9 853.3 886.9 892.7 926.8 16179.4 16275.8 16277.9 16302.0 16804.6 16020.8 16126.2 16143.5 16194.4 17006.7 892.6 905.2 916.3 918.2 919.6 913.4 918.7 919.9 920.4 920.4 961.3 967.7 968.2 968.7 969.4 959.4 960.4 963.3 963.7 964.0 696.8 702.4 704.1 704.5 705.0 17339.2 17348.4 17437.0 17485.8 17487.2 15967.2 16216.4 16508.7 16600.5 17088.6 13330.4 13550.6 13632.5 13660.2 13726.5 4121.9 4159.2 4265.1 4274.3 4277.6 49484.0 70471.6 83366.0 83512.0 87151.0 12662.4 12666.3 12950.4 13107.2 13331.3 22870.5 22898.5 22899.1 22901.5 22915.3 8827.1 8920.0 9296.9 9376.1 9602.3 4807.7 4903.3 4995.5 5030.6 5058.5 4049.7 4196.8 4241.2 4244.1 4248.3 696.1 706.7 750.1 780.6 783.7 655.8 722.5 766.8 771.4 778.6 10892.5 10931.7 11257.9 11501.4 11776.1 673.5 689.4 698.2 761.2 786.7 5077.3 5415.4 5461.0 5463.2 5488.6 137881.0 137986.0 138582.3 141317.0 143064.3 70994.3 96129.0 109647.0 116670.4 120654.4 81172.6 81673.6 82977.0 86120.1 94868.5 10362.6 10776.8 11170.0 11241.4 11531.5 47757.5 48326.3 52405.0 53765.2 55103.7 75786.6 77430.2 79685.0 81696.0 84073.0 13344.9 14224.8 14365.2 14556.3 14723.9 17069.4 17405.2 18133.9 18476.6 18523.4 14781.6 15492.8 15978.8 16106.6 16882.5 14739.3 15670.1 15926.2 16395.4 16410.9 14450.6 14596.0 14799.9 14925.3 15366.5 12982.2 13122.3 13281.2 13666.5 13670.9 13011.3 13034.3 13039.7 13128.6 13320.3 10:03:09 INFO - PROCESS | 27796 | -------- Summary: end -------- 10:03:09 INFO - PROCESS | 27796 | 10:03:10 INFO - TEST-INFO | 27796: exit 0 10:03:10 INFO - TEST-OK | dromaeo_css | took 1113674ms 10:03:10 INFO - TEST-START | kraken 10:03:10 INFO - operating with platform_type : linux_ 10:03:10 INFO - Initialising browser for kraken test... 10:03:10 INFO - TEST-INFO | started process 27984 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGmD1DK/profile http://localhost:49025/getInfo.html) 10:03:14 INFO - PROCESS | 27984 | __metrics Screen width/height:1600/1200 10:03:14 INFO - PROCESS | 27984 | colorDepth:24 10:03:14 INFO - PROCESS | 27984 | Browser inner width/height: 1024/697 10:03:14 INFO - PROCESS | 27984 | __metrics 10:03:15 INFO - PROCESS | 27984 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:03:15 INFO - PROCESS | 27984 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:03:15 INFO - PROCESS | 27984 | console.error: PushService: 10:03:15 INFO - PROCESS | 27984 | stateChangeProcessEnqueue: Error transitioning state 10:03:15 INFO - PROCESS | 27984 | UnknownError 10:03:16 INFO - TEST-INFO | 27984: exit 0 10:03:16 INFO - Browser initialized. 10:03:16 INFO - Running cycle 1/1 for kraken test... 10:03:16 INFO - TEST-INFO | started process 28075 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGmD1DK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:03:27 INFO - PROCESS | 28075 | RSS: Main: 143499264 10:03:27 INFO - PROCESS | 28075 | 10:03:30 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49025/tests/kraken/driver.html?testName=audio-beat-detection) 10:03:31 INFO - PROCESS | 28075 | RSS: Main: 199106560 10:03:31 INFO - PROCESS | 28075 | 10:03:33 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49025/tests/kraken/driver.html?testName=audio-dft) 10:03:34 INFO - PROCESS | 28075 | RSS: Main: 182648832 10:03:34 INFO - PROCESS | 28075 | 10:03:37 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49025/tests/kraken/driver.html?testName=audio-fft) 10:03:37 INFO - PROCESS | 28075 | RSS: Main: 274141184 10:03:37 INFO - PROCESS | 28075 | 10:03:39 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49025/tests/kraken/driver.html?testName=audio-oscillator) 10:03:40 INFO - PROCESS | 28075 | RSS: Main: 180035584 10:03:40 INFO - PROCESS | 28075 | 10:03:42 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49025/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:03:43 INFO - PROCESS | 28075 | RSS: Main: 178749440 10:03:43 INFO - PROCESS | 28075 | 10:03:46 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49025/tests/kraken/driver.html?testName=imaging-darkroom) 10:03:46 INFO - PROCESS | 28075 | RSS: Main: 214794240 10:03:46 INFO - PROCESS | 28075 | 10:03:50 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49025/tests/kraken/driver.html?testName=imaging-desaturate) 10:03:50 INFO - PROCESS | 28075 | RSS: Main: 212840448 10:03:50 INFO - PROCESS | 28075 | 10:03:53 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49025/tests/kraken/driver.html?testName=json-parse-financial) 10:03:54 INFO - PROCESS | 28075 | RSS: Main: 213458944 10:03:54 INFO - PROCESS | 28075 | 10:03:55 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49025/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:03:56 INFO - PROCESS | 28075 | RSS: Main: 175362048 10:03:56 INFO - PROCESS | 28075 | 10:03:57 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49025/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:03:58 INFO - PROCESS | 28075 | RSS: Main: 182673408 10:03:58 INFO - PROCESS | 28075 | 10:04:00 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49025/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:04:01 INFO - PROCESS | 28075 | RSS: Main: 185159680 10:04:01 INFO - PROCESS | 28075 | 10:04:03 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:04:04 INFO - PROCESS | 28075 | RSS: Main: 185008128 10:04:04 INFO - PROCESS | 28075 | 10:04:06 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:04:07 INFO - PROCESS | 28075 | RSS: Main: 182988800 10:04:07 INFO - PROCESS | 28075 | 10:04:08 INFO - PROCESS | 28075 | Cycle 1(1): loaded http://localhost:49025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49025/tests/kraken/driver.html?testName=ai-astar) 10:04:09 INFO - PROCESS | 28075 | RSS: Main: 234565632 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | __start_tp_report 10:04:09 INFO - PROCESS | 28075 | _x_x_mozilla_page_load 10:04:09 INFO - PROCESS | 28075 | _x_x_mozilla_page_load_details 10:04:09 INFO - PROCESS | 28075 | |i|pagename|runs| 10:04:09 INFO - PROCESS | 28075 | |0;ai-astar;112;111;120;110;111;111;109;111;111;110 10:04:09 INFO - PROCESS | 28075 | |1;audio-beat-detection;145;116;146;188;116;151;145;123;176;120 10:04:09 INFO - PROCESS | 28075 | |2;audio-dft;160;161;159;161;160;161;159;160;160;160 10:04:09 INFO - PROCESS | 28075 | |3;audio-fft;75;76;75;178;76;74;75;75;187;74 10:04:09 INFO - PROCESS | 28075 | |4;audio-oscillator;84;83;157;84;83;84;141;84;83;148 10:04:09 INFO - PROCESS | 28075 | |5;imaging-gaussian-blur;116;116;116;117;117;117;116;116;117;116 10:04:09 INFO - PROCESS | 28075 | |6;imaging-darkroom;146;147;145;147;147;147;146;147;145;145 10:04:09 INFO - PROCESS | 28075 | |7;imaging-desaturate;94;94;95;95;94;95;94;94;94;94 10:04:09 INFO - PROCESS | 28075 | |8;json-parse-financial;49;50;49;50;49;50;50;49;49;50 10:04:09 INFO - PROCESS | 28075 | |9;json-stringify-tinderbox;59;58;60;59;59;57;59;59;59;59 10:04:09 INFO - PROCESS | 28075 | |10;stanford-crypto-aes;73;73;73;82;77;72;73;72;72;72 10:04:09 INFO - PROCESS | 28075 | |11;stanford-crypto-ccm;130;119;129;119;125;119;115;115;151;117 10:04:09 INFO - PROCESS | 28075 | |12;stanford-crypto-pbkdf2;152;151;150;154;152;147;150;154;148;148 10:04:09 INFO - PROCESS | 28075 | |13;stanford-crypto-sha256-iterative;61;60;64;64;61;63;61;61;60;61 10:04:09 INFO - PROCESS | 28075 | __end_tp_report 10:04:09 INFO - PROCESS | 28075 | __start_cc_report 10:04:09 INFO - PROCESS | 28075 | _x_x_mozilla_cycle_collect,1685 10:04:09 INFO - PROCESS | 28075 | __end_cc_report 10:04:09 INFO - PROCESS | 28075 | __startTimestamp1474563849219__endTimestamp 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | ------- Summary: start ------- 10:04:09 INFO - PROCESS | 28075 | Number of tests: 14 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#0] ai-astar Cycles:10 Average:111.60 Median:111.00 stddev:3.06 (2.8%) stddev-sans-first:3.24 10:04:09 INFO - PROCESS | 28075 | Values: 112.0 111.0 120.0 110.0 111.0 111.0 109.0 111.0 111.0 110.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:145.50 stddev:24.90 (17.1%) stddev-sans-first:26.40 10:04:09 INFO - PROCESS | 28075 | Values: 145.0 116.0 146.0 188.0 116.0 151.0 145.0 123.0 176.0 120.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 10:04:09 INFO - PROCESS | 28075 | Values: 160.0 161.0 159.0 161.0 160.0 161.0 159.0 160.0 160.0 160.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#3] audio-fft Cycles:10 Average:96.50 Median:75.00 stddev:45.38 (60.5%) stddev-sans-first:47.46 10:04:09 INFO - PROCESS | 28075 | Values: 75.0 76.0 75.0 178.0 76.0 74.0 75.0 75.0 187.0 74.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#4] audio-oscillator Cycles:10 Average:103.10 Median:83.50 stddev:31.67 (37.9%) stddev-sans-first:32.83 10:04:09 INFO - PROCESS | 28075 | Values: 84.0 83.0 157.0 84.0 83.0 84.0 141.0 84.0 83.0 148.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 10:04:09 INFO - PROCESS | 28075 | Values: 116.0 116.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:147.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 10:04:09 INFO - PROCESS | 28075 | Values: 146.0 147.0 145.0 147.0 147.0 147.0 146.0 147.0 145.0 145.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:04:09 INFO - PROCESS | 28075 | Values: 94.0 94.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 94.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:50.00 stddev:0.53 (1.1%) stddev-sans-first:0.53 10:04:09 INFO - PROCESS | 28075 | Values: 49.0 50.0 49.0 50.0 49.0 50.0 50.0 49.0 49.0 50.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:0.79 (1.3%) stddev-sans-first:0.83 10:04:09 INFO - PROCESS | 28075 | Values: 59.0 58.0 60.0 59.0 59.0 57.0 59.0 59.0 59.0 59.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:73.00 stddev:3.21 (4.4%) stddev-sans-first:3.39 10:04:09 INFO - PROCESS | 28075 | Values: 73.0 73.0 73.0 82.0 77.0 72.0 73.0 72.0 72.0 72.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:122.00 stddev:10.94 (9.0%) stddev-sans-first:11.38 10:04:09 INFO - PROCESS | 28075 | Values: 130.0 119.0 129.0 119.0 125.0 119.0 115.0 115.0 151.0 117.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.60 Median:151.50 stddev:2.46 (1.6%) stddev-sans-first:2.55 10:04:09 INFO - PROCESS | 28075 | Values: 152.0 151.0 150.0 154.0 152.0 147.0 150.0 154.0 148.0 148.0 10:04:09 INFO - PROCESS | 28075 | 10:04:09 INFO - PROCESS | 28075 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.00 stddev:1.51 (2.5%) stddev-sans-first:1.58 10:04:09 INFO - PROCESS | 28075 | Values: 61.0 60.0 64.0 64.0 61.0 63.0 61.0 61.0 60.0 61.0 10:04:09 INFO - PROCESS | 28075 | -------- Summary: end -------- 10:04:09 INFO - PROCESS | 28075 | 10:04:10 INFO - TEST-INFO | 28075: exit 0 10:04:10 INFO - TEST-OK | kraken | took 60093ms 10:04:10 INFO - SUITE-END | took 1173s 10:04:10 INFO - Completed test suite (00:19:33) 10:04:10 INFO - javascript benchmark 10:04:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3232.838358082096, 3253.4794432890735, 3276.2628408608066, 3293.7035666790016, 3324.3517514084747, 5596.80411185608, 5663.405342439047, 5671.971640141799, 5686.090225563906, 5702.3157221133415, 5587.804426845061, 5633.971830140844, 5646.6894320812335, 5648.830535083944, 5665.291838520185, 5051.848444546651, 5114.130597798383, 5121.999999999991, 5124.974375128119, 5176.5858731301405, 5165.328507294046, 5195.168772996321, 5198.246254293125, 5245.370555533342, 5261.868453288679, 5107.182850743881, 5148.279240906276, 5177.249298851664, 5194.610404219679, 5234.110201265794, 5266.104762190437, 5271.762770675329, 5272.156454967206, 5272.947270527322, 5308.5487733542695, 5063.316452278932, 5081.847544573632, 5119.0273847968765, 5147.742612869341, 5151.021305951895, 5502.642328248691, 5523.061079616475, 5528.364238112588, 5557.944420555783, 5558.138488534284, 6073.939260607382, 6085.513158947274, 6085.786997455112, 6086.543509236825, 6095.451409373133, 5933.554983376221, 5996.670183139926, 6027.879442411171, 6054.243219597551, 6122.816315510498, 5690.2033715279895, 5693.686847223443, 5697.743601537941, 5708.514776244025, 5722.942770572283, 6180.6600636965395, 6198.2871969723155, 6199.101130336076, 6202.28673702521, 6203.16257305267, 5809.82570522885, 5897.321807992136, 5940.673262970578, 5956.225690660273, 6059.151718759377, 6107.511399088063, 6109.676484838171, 6111.586445669263, 6111.877762444727, 6137.41694539018, 6019.488343490765, 6070.2412198475195, 6075.969620151871, 6086.969565152146, 6096.69516524172, 5783.479486846204, 5933.0, 5992.0712289595185, 5994.1608174855555, 6090.878182436327, 4605.539446055434, 4635.096156249499, 4635.513271106553, 4639.466461356919, 4671.719696818202, 4138.79306034697, 4149.875503734893, 4154.688398370074, 4162.875113746592, 4185.288500954845, 5530.446955304437, 5687.203791469197, 5724.255846739898, 5738.770449181986, 5771.942280577225, 6069.3627169147485, 6070.332263451017, 6071.089336599545, 6073.362296958844, 6078.665673387918, 5787.421257874263, 5794.855128621818, 5801.187833703285, 5801.6519008859605, 5802.883942321215, 5064.442911279756, 5193.584513239008, 5226.8954620908125, 5229.686218826882, 5246.6065045121, 6256.061590761422, 6267.2165979252595, 6268.937310626927, 6294.4020318070625, 6296.307406185316, 6023.066424704178, 6059.727312271047, 6079.513638908788, 6083.1483592298, 6097.756089756537, 6055.031195008777, 6108.144859719731, 6115.6662951758, 6116.061877111602, 6189.102580125947, 5934.970325148347, 5949.702514874152, 5951.970240148771, 5962.284525856925, 5996.190514280691, 6051.0, 6054.697265136813, 6058.909116363347, 6058.969705151622, 6067.332593414809, 5758.740856661377, 5830.650160990354, 5872.794452194153, 5876.1773351729935, 5974.312954010345, 5906.291245050449, 5913.201717768046, 5922.437368449991, 5925.496332811586, 5926.762929482945, 5852.853678658068, 5872.735726892215, 5914.526837853047, 5921.289445266596, 5929.0217114175675, 5763.596548241583, 5894.882102358015, 5927.733252003756, 5935.762569497345, 6033.064874944391, 5854.941450585439, 5860.94139058621, 5865.853353666193, 5872.03111486617, 5897.321807992136], "value": 5581.494011723053, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.3295175878897, 633.7750098714956, 654.8788474132284, 664.6842749693895, 666.7632990288445, 35709.82145089274, 46790.0, 47517.28724069133, 47540.28689569659, 47578.76210618942, 48728.756356218255, 49107.0, 49287.0, 49536.50463495364, 49744.25383619249, 8632.0, 9489.857652135206, 9530.761730956712, 9646.565904534278, 11095.55617775292, 11013.229073964785, 11520.42397880107, 11525.077993760478, 11527.365994870279, 11533.423328833567, 11542.71143221422, 11586.59446919358, 11587.478563464621, 11594.30434173948, 11599.072074234084, 11285.661430157108, 11360.943195284053, 11424.485898134604, 11446.427678616035, 11452.31286122831, 11262.380569068699, 11318.660440186808, 11341.659750207424, 11348.886511134948, 11356.432178391047, 46540.7672961633, 46844.0, 46923.765381173216, 46993.765031174626, 47044.0, 3964.920701585952, 3967.3255546556866, 4069.9186016279805, 4130.297849365585, 4184.28867092595, 34460.65539344624, 34719.652803471894, 35405.82297088524, 35543.82228088843, 35676.28647427063, 4674.345591617176, 4786.832460863853, 4790.8323208687525, 4825.179719447668, 4842.1526232909455, 368859.0, 369481.0, 370204.0, 371741.0, 373850.0, 60895.0, 61187.0, 61331.386686132566, 61353.38646613566, 61378.693106534185, 39869.800650996556, 40786.38820417696, 41016.58983410128, 42840.357394639104, 42912.78543607262, 20046.89976550137, 20232.291869784487, 20241.0, 20251.392458226277, 20264.290749823685, 13886.652833679238, 14057.226852523005, 14075.718485630436, 14094.71810563783, 14817.629559261104, 21109.577808444054, 21125.154993800385, 21275.0, 21301.89349053245, 21351.786482135292, 7539.547627142279, 7562.0, 7595.3164215219795, 7661.731839385595, 7726.652300646484, 1066.861308029951, 1082.1775450657558, 1086.7393823165091, 1159.756451145269, 1165.9125565582615, 35140.29719405597, 35784.46323305152, 35836.28327433437, 35964.82017589895, 36136.63863361385, 1134.625573560723, 1184.5498710489958, 1196.3838623109107, 1238.8017917133395, 1317.374247232576, 18668.53328666767, 18748.531286717942, 18862.622747545247, 18919.148638311308, 19053.523661908286, 600174.0, 603158.0, 604355.0, 604752.0, 605882.0, 63558.68220658867, 64193.679031605476, 64262.0, 64268.03597946034, 64287.678561606896, 1351.4796803230827, 1455.9636009099856, 1482.0959214878915, 1498.0936533397573, 1509.3736099518576, 34321.485177722854, 35908.4613730789, 36472.63527364693, 36813.81593092017, 36861.81569092137, 53765.46234537604, 54358.72820635871, 54420.18369724378, 54426.0, 54448.72775636097, 437338.0, 438198.0, 438906.0, 439714.0, 693349.0, 30599.847000764854, 30774.53838192384, 30859.84570077135, 32412.0, 32657.0, 53057.73471132619, 53648.73175634253, 53798.462015379344, 53888.0, 54054.189187163014, 44359.77820111008, 44526.332105017806, 44643.776781115885, 46332.0, 48751.5124848747, 36696.08259793527, 36943.630563694016, 37230.0, 37857.0, 38375.80812095922, 45560.77219613881, 46398.76800615976, 46627.30059049185, 46784.53215467802, 46925.76537117293, 38876.0, 39895.60104398919, 40764.59235407608, 40833.38749918813, 41001.38497922474, 31199.688003120587, 31413.214669633442, 31497.8425107873, 31576.842115790194, 31715.20711982219], "value": 22166.32487446441, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.5491291948236, 430.0573085417944, 432.64955386137234, 435.1601409280089, 439.41996564534804, 20082.39752807416, 20882.0, 21124.0, 21808.672869906964, 21977.67033494499, 20154.596908061867, 20462.633451957285, 20590.89704551475, 20730.792692073075, 21056.57886842262, 5861.85345366365, 6068.939310606882, 6069.393060693939, 6073.817785466443, 6076.969615151918, 5853.385394533577, 5865.560082993771, 5960.612560183596, 5983.491403230731, 5990.131430942511, 5861.2087368205175, 5971.104334349839, 5975.641461512323, 5977.641341519501, 5979.611325263866, 5940.108983652443, 5984.551158663117, 5995.130706047642, 5997.940020599782, 5998.220231369937, 5365.579246588652, 5378.273933019032, 5395.973020134874, 5418.674879507243, 5430.13117901134, 24123.276301710976, 24919.875400623063, 25090.0, 25135.748642513707, 25311.367215819566, 2581.2514370832623, 2806.326481644411, 2814.6059551662784, 3220.146661134801, 3273.1489812648647, 15319.387224511007, 15398.153101579408, 15436.53690389279, 15927.681446371123, 16023.0, 1975.9802401976083, 2065.0913598016823, 2097.737782777153, 2106.747190337154, 2138.9251376201914, 67693.3230667692, 67925.0, 83512.74730879042, 90787.0, 91045.63431548532, 9848.359856609297, 10166.237532185116, 10325.84511232332, 10855.891441085647, 10949.562017519369, 14533.200673962821, 14594.781078283835, 14611.707765844836, 14619.78070328946, 14620.707585848437, 8235.21765432283, 8246.546439945741, 8261.463004904663, 8447.873281900776, 8551.828963420698, 8007.879881801661, 8211.302039326605, 8271.793205169919, 8420.4947703138, 8659.610317535726, 4451.59935605797, 4456.977715111469, 4496.043997498329, 4587.9541204588195, 4626.421697287839, 1928.662484065283, 2007.7289565908416, 2107.978920210778, 2139.1443422631073, 2140.6788981652567, 936.8360536906014, 1036.3315661398356, 1043.3426941027105, 1065.5950738719391, 1120.0871289898707, 20646.0, 21237.893810530848, 21387.8930605346, 21405.571888562143, 21413.0, 1076.4294923690456, 1123.2081382625229, 1146.2893006335958, 1153.6423708650345, 1168.5150662475148, 11093.33439993603, 11869.347185904859, 12015.459304331325, 12018.278903265833, 12074.698132546757, 620624.0, 622527.0, 628788.0, 631115.0, 635404.0, 76851.2314876844, 89852.0, 92847.53576232075, 101641.0, 106253.0, 1011.6813203840619, 1019.0675531888239, 1062.8086944349936, 1063.239783554762, 1065.1212749481708, 17900.0, 18755.249790008245, 18904.338348157748, 19399.515012124808, 19508.0, 42817.0, 44383.77808110939, 44619.776901115285, 44825.55174448214, 46856.062878741555, 63707.681461592394, 64196.0, 65014.0, 65237.34762652503, 65380.34619653742, 9227.538623068955, 9464.526773661428, 9667.516624168904, 9682.225421966084, 9714.222862170867, 13220.338983050617, 13749.931250343685, 14521.128732276096, 14551.563453096423, 14647.340869660678, 11903.821442678194, 12198.939005305272, 12638.810417843924, 12897.41961611711, 12907.548235811702, 11918.106142039554, 11979.580714674945, 12199.634010979686, 12289.1397602167, 12919.483220671067, 12458.75082498363, 12515.0, 12856.421461034462, 12903.225806451643, 13225.272610006543, 9026.86459703118, 9226.446413215228, 9264.212541934012, 9311.255099591881, 9387.249020078514, 10904.400257985886, 12500.31248281317, 12522.248665080124, 12640.178388404724, 12889.226646401246], "value": 9912.056751897555, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1532.6934613077383, 1577.4163559482993, 1756.1745979389682, 1812.3566134022412, 1859.6466671332446, 9172.495512746797, 9866.851997220047, 9872.16086632637, 9879.802403951917, 9883.901160988396, 8505.319574434046, 9253.583588738506, 9479.0, 9492.382995105314, 9634.325597208199, 2500.4749002709455, 2528.608065749812, 2532.126416386353, 2532.278300684305, 2534.42975330551, 2468.4075821802817, 2530.5698031334623, 2538.987305063472, 2538.987305063472, 2541.669582954213, 2500.362407586063, 2504.0, 2535.2014115553634, 2535.9239222823253, 2538.010176031349, 2396.161343529768, 2401.2076014915037, 2413.215704895907, 2424.2000139953775, 2425.842320249178, 2395.4370722880108, 2409.229046705055, 2417.4319035026756, 2420.3586049696846, 2433.513297340539, 9725.56234969428, 9750.46372449515, 9843.90156098437, 9862.704118876374, 9886.307958442912, 5725.370209276976, 5875.882482350372, 5877.764889404419, 5890.175375447441, 5891.675957822318, 3618.6019537850816, 3695.2794205130012, 3804.1821008483034, 3805.7335986480957, 3841.4621952926614, 1142.6115120858947, 1197.9880201197964, 1214.769193853165, 1219.5792451604182, 1223.2660403757764, 13867.37596808146, 13874.0, 13925.582232533141, 13928.37322320498, 13959.511417100453, 5053.747312634354, 5141.845744627667, 5276.92084618731, 5281.630285879952, 5324.8817748273295, 10476.580936762444, 10548.578056877639, 10556.419396933243, 10562.049415552638, 10566.471676416302, 5859.0, 5865.0, 5869.706514674249, 5874.970625146847, 5877.970610146922, 4671.85984420468, 4802.975985120122, 5340.385855626575, 5387.0841956867425, 5468.91796623051, 4088.1999460401007, 4139.399787030864, 4189.476315460567, 4191.093052661032, 4215.810288537024, 1211.7879371110196, 1212.8847759462838, 1216.750566133958, 1230.501646833034, 1244.2783185752241, 996.8903420623726, 1003.6437064842022, 1019.7705516258931, 1031.7626945802508, 1032.7160030991388, 6183.598066125687, 6327.841803954938, 6362.618242905441, 6372.2353317601255, 6499.935000649933, 496.85591178557814, 497.19950879084644, 503.0, 506.0789363358725, 509.4497942222391, 4271.273883439822, 4294.463192100988, 4425.645948324126, 4465.173942820588, 4470.977645111753, 10879.184061195441, 11924.0, 12156.696082598006, 12258.632241032783, 12289.016878649687, 11081.501332440059, 11390.25963312382, 11406.942965285121, 11409.201355905006, 11414.086873050135, 8943.642254309754, 9072.364934454657, 9087.863682044775, 9097.272218222526, 9136.771580710536, 6244.1570387997035, 6482.513811464253, 6486.54594178403, 6519.674016299261, 6605.768798092044, 10908.618198363336, 11327.376994265394, 11370.94314528422, 11375.54497820078, 11473.0, 10967.396793176453, 11530.135239857213, 11538.596149135075, 11573.247738896946, 11678.24091434054, 7665.961670191837, 8038.83922321562, 8127.471714338549, 8564.700235491728, 8854.29165666758, 9232.815343692953, 9323.766905827408, 9325.86011209846, 9329.673461428818, 9330.393524420891, 8016.559089250147, 8130.105688374156, 8194.959025204837, 8255.545944973084, 8325.417220794729, 7893.644785984759, 7939.0, 8101.716439924574, 8208.261256486945, 8395.202455766692, 7030.578165310098, 7160.677769500281, 7164.068671072936, 7229.602371869598, 7412.332890039922, 5480.150576660624, 5582.413846546055, 5679.574031947538, 5698.715064246854, 5723.570732195185, 5574.414686457863, 5585.469380408855, 5594.300712410949, 5732.828015159552, 5838.503727183067], "value": 4908.907486554004, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.31633289584653, 356.4510653593466, 358.1351037245053, 370.93694072007787, 372.6236501133854, 5637.041702910505, 5668.319801623806, 5704.0, 5711.914321285184, 5725.0, 5387.838364849051, 5556.499915007648, 5637.267155269819, 5644.585026059237, 5657.51911087557, 4401.405810215612, 4418.403515525411, 4421.579949904753, 4425.247707889658, 4425.402570652969, 4403.273459879116, 4421.336799480072, 4434.979954610441, 4443.977780111095, 4450.376947227391, 4158.272302347093, 4196.643285320752, 4390.2317094508335, 4409.404730361408, 4432.468103827545, 4297.462817147856, 4299.720518166325, 4303.203907277148, 4311.784410779448, 4322.416473776063, 3847.615238476158, 3853.903652408684, 3857.324968130594, 3857.7492462990094, 3859.1895701902636, 5290.68255904647, 5410.56715462766, 5439.347278326626, 5446.863828404322, 5456.236126942231, 4996.775145118442, 5002.324686167357, 5005.624578156617, 5008.148614735467, 5024.221245706921, 5794.652320860761, 5802.535797136219, 5805.706745835371, 5809.593328467009, 5830.533557315447, 4165.166966606691, 4254.957450425487, 4278.593533614302, 4299.78501074945, 4308.18144552534, 5993.441705156647, 5995.940040599582, 6000.459958603703, 6014.78948236814, 6029.728662210167, 4375.256206444912, 4375.956240437555, 4379.343098535245, 4387.771424001221, 4426.667999900021, 4297.968487563025, 4428.22506061438, 4430.379746835446, 4489.744934922221, 4494.1461122386645, 4926.187179115477, 4992.350994370709, 5006.299118123466, 5008.899822003539, 5032.72320022395, 5833.649981001154, 5859.853503662443, 5865.882682346414, 5871.412858714094, 5875.9118613220835, 1528.4879565345511, 1541.3295216580823, 1546.5592306192682, 1549.387991743255, 1556.8209655889718, 965.6909788867636, 966.9951650241704, 996.7109538233831, 997.1424574865609, 998.9550470228858, 4909.68087074339, 4973.626977976666, 4982.8754281143265, 5006.222790909174, 5037.571806396424, 5201.1275940661335, 5233.528982391527, 5248.606354523426, 5276.525112739795, 5311.229871668586, 5017.874553136201, 5450.863728406822, 5479.643823151482, 5663.830085097454, 5667.433256674382, 3138.419392412411, 3170.685399658262, 3174.444472217399, 3182.3317103408544, 3189.218641432864, 6025.879482410327, 6157.630542167484, 6178.907316390258, 6195.132681424604, 6200.3799620037635, 6056.939430605637, 6060.333363330118, 6080.449485381199, 6082.391760823883, 6097.878042439215, 6049.334573197033, 6188.164597779332, 6193.411625895533, 6204.7828326008375, 6213.96893015532, 5863.364713877423, 5929.407059294209, 5944.4352786485215, 5968.373320801254, 5968.880622387441, 5908.113782932594, 5911.349751527415, 5911.408859114054, 5921.763129474945, 5984.222051133324, 5936.287645482397, 6224.657643829632, 6224.844378890382, 6235.657038862906, 6291.433770960635, 4344.739315641072, 4367.170237654836, 4371.956280437155, 4383.824647014084, 4396.626286765533, 6102.664353460602, 6102.908456373069, 6117.388261173847, 6120.234970628671, 6127.448529632355, 5721.399253078534, 5838.503727183067, 5865.3254875689845, 5911.074018360767, 5911.5516698409165, 5858.560607954335, 5863.882722345615, 5872.559558033079, 5901.5868889177345, 5919.733611987384, 5567.8886422272135, 5851.268591426072, 5857.447776339188, 5881.529477641864, 5921.703914804157, 5868.354481007001, 5903.848749493807, 5930.509186141745, 5933.673647949404, 5944.494717949023, 5688.175214593778, 5765.8558536036935, 5840.182374467663, 5847.94152058491, 5894.734736936763], "value": 4458.803912348075, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [841.920017598328, 853.3386625365338, 886.8935727712676, 892.6786356911507, 926.8285367207067, 16179.433719819808, 16275.83724162758, 16277.918610406947, 16302.021878687287, 16804.57988550287, 16020.75968860468, 16126.193690315467, 16143.515694529153, 16194.433194838182, 17006.659866802656, 892.6340200517773, 905.1627244798568, 916.3448134583771, 918.1552971266451, 919.5723988345418, 913.4154141349542, 918.669279059538, 919.9356045076847, 920.3741455810047, 920.4201353147507, 961.3270710502641, 967.7435479597877, 968.1528662420383, 968.6658102954469, 969.4231932000483, 959.3524371049549, 960.3709570231509, 963.3208587945481, 963.7012526116891, 963.9974426596332, 696.8188271049513, 702.3503259484957, 704.1128178495081, 704.531486561436, 704.968276427562, 17339.219735111823, 17348.39280625172, 17437.040962747044, 17485.825141748675, 17487.213075411637, 15967.201639918074, 16216.432424865192, 16508.6698266034, 16600.50198494047, 17088.57278568033, 13330.400131994083, 13550.59348219545, 13632.454701811916, 13660.180389176681, 13726.519571814939, 4121.8969525761795, 4159.168166366709, 4265.0616864289805, 4274.337477690963, 4277.593628605277, 49484.0, 70471.64764176197, 83366.0, 83512.0, 87151.0, 12662.36688165588, 12666.303353315656, 12950.352482375843, 13107.213567186, 13331.266780326981, 22870.542589148125, 22898.542029159657, 22899.08403663835, 22901.541969160524, 22915.312540623476, 8827.117288271122, 8919.955400222958, 9296.907030929739, 9376.062393760705, 9602.32783705148, 4807.663463557587, 4903.338049363361, 4995.525425084611, 5030.572401345927, 5058.494150584986, 4049.6962727795535, 4196.832126714898, 4241.151769646083, 4244.108737165168, 4248.277792775236, 696.0637941968045, 706.6855249414065, 750.1335956969709, 780.5862892666896, 783.698276163681, 655.829484334067, 722.4762047515615, 766.8121310279017, 771.3944553525456, 778.5951305321202, 10892.455377231106, 10931.726706832393, 11257.887421125848, 11501.367424791717, 11776.11679124052, 673.515069150211, 689.3520091114336, 698.155232169081, 761.2121454294778, 786.7010535996285, 5077.339945807022, 5415.350157981067, 5461.044317244467, 5463.235147079412, 5488.588355873326, 137881.0, 137986.0, 138582.30708846392, 141317.0, 143064.28467857803, 70994.2900570998, 96129.0, 109647.0, 116670.41664791622, 120654.39672801756, 81172.59413702894, 81673.59163204265, 82977.0, 86120.13879861246, 94868.52565737265, 10362.585496580052, 10776.784464310827, 11170.0, 11241.437928103398, 11531.538738450368, 47757.52242477531, 48326.27510587414, 52405.0, 53765.193522096415, 55103.72448137894, 75786.62106689431, 77430.2256977423, 79685.0, 81696.0, 84073.0, 13344.933275333562, 14224.786628200378, 14365.20991345486, 14556.272186390426, 14723.852761472248, 17069.40257090996, 17405.216765245343, 18133.90933045326, 18476.630467390307, 18523.44429667112, 14781.556553303419, 15492.838037147034, 15978.760318595463, 16106.597335066717, 16882.49352519377, 14739.336729846968, 15670.138142402277, 15926.203689815231, 16395.42616008434, 16410.917945410198, 14450.638734031312, 14596.0, 14799.852001480278, 14925.328360224034, 15366.539003829903, 12982.221066736027, 13122.343882806012, 13281.203127812361, 13666.453341866214, 13670.863291367357, 13011.349432528146, 13034.283114428797, 13039.674008149872, 13128.60614181576, 13320.25871888593], "value": 8414.903784095368, "unit": "score"}], "name": "dromaeo_css", "value": 7804.0045982544325, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 120.0, 110.0, 111.0, 111.0, 109.0, 111.0, 111.0, 110.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 146.0, 188.0, 116.0, 151.0, 145.0, 123.0, 176.0, 120.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 161.0, 160.0, 161.0, 159.0, 160.0, 160.0, 160.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 178.0, 76.0, 74.0, 75.0, 75.0, 187.0, 74.0], "value": 96.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 157.0, 84.0, 83.0, 84.0, 141.0, 84.0, 83.0, 148.0], "value": 103.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 147.0, 147.0, 147.0, 146.0, 147.0, 145.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 50.0, 49.0, 50.0, 50.0, 49.0, 49.0, 50.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 60.0, 59.0, 59.0, 57.0, 59.0, 59.0, 59.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 82.0, 77.0, 72.0, 73.0, 72.0, 72.0, 72.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 119.0, 129.0, 119.0, 125.0, 119.0, 115.0, 115.0, 151.0, 117.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 150.0, 154.0, 152.0, 147.0, 150.0, 154.0, 148.0, 148.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 64.0, 64.0, 61.0, 63.0, 61.0, 61.0, 60.0, 61.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1489.1, "alertThreshold": 2.0}]} 10:04:10 INFO - Return code: 0 10:04:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:04:10 INFO - # TBPL SUCCESS # 10:04:10 INFO - Running post-action listener: _resource_record_post_action 10:04:10 INFO - [mozharness: 2016-09-22 17:04:10.513338Z] Finished run-tests step (success) 10:04:10 INFO - Running post-run listener: _resource_record_post_run 10:04:10 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 8192; Write bytes: 287543296; Read time: 96; Write time: 272504 10:04:10 INFO - TinderboxPrint: CPU usage
12.9% 10:04:10 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 96 10:04:10 INFO - TinderboxPrint: I/O write bytes / time
287,543,296 / 272,504 10:04:10 INFO - TinderboxPrint: CPU idle
8,256.9 (87.1%) 10:04:10 INFO - TinderboxPrint: CPU user
1,180.6 (12.4%) 10:04:10 INFO - TinderboxPrint: Swap in / out
0 / 0 10:04:10 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 71270400; Read time: 0; Write time: 120360 10:04:10 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 216272896; Read time: 84; Write time: 152144 10:04:11 INFO - Running post-run listener: _upload_blobber_files 10:04:11 INFO - Blob upload gear active. 10:04:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:04:11 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 10:04:11 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:04:11 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:04:11 INFO - (blobuploader) - INFO - Open directory for files ... 10:04:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:04:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:04:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:15 INFO - (blobuploader) - INFO - Done attempting. 10:04:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:04:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:04:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:16 INFO - (blobuploader) - INFO - Done attempting. 10:04:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:04:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:04:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:17 INFO - (blobuploader) - INFO - Done attempting. 10:04:17 INFO - (blobuploader) - INFO - Iteration through files over. 10:04:17 INFO - Return code: 0 10:04:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:04:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:04:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43c6a95169781875a07009ebae41714e9fc3a7147398075bf56ea91126c88e400c2db66c8fdaca01132d1be8cd1158c6c65d80387857b8b327945ee86c5c75a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60b5501166bf029e8f13183ff499d0a261046c101c10feee9ff7de5abe15118626ce2048ce69f2495cc2b697aed6b602b47d7874a9f5621f3ac0e2bbb63280fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb984a63299f1759cb8972a9890529436dbd6efb2b48d04bbe681ea13f6a81314b7fa5d9b314b05c1572862fc4f47e16de875dff81ea79e182ef9e464291f4b6"} 10:04:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:04:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:04:17 INFO - Contents: 10:04:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43c6a95169781875a07009ebae41714e9fc3a7147398075bf56ea91126c88e400c2db66c8fdaca01132d1be8cd1158c6c65d80387857b8b327945ee86c5c75a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60b5501166bf029e8f13183ff499d0a261046c101c10feee9ff7de5abe15118626ce2048ce69f2495cc2b697aed6b602b47d7874a9f5621f3ac0e2bbb63280fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb984a63299f1759cb8972a9890529436dbd6efb2b48d04bbe681ea13f6a81314b7fa5d9b314b05c1572862fc4f47e16de875dff81ea79e182ef9e464291f4b6"} 10:04:17 INFO - Running post-run listener: copy_logs_to_upload_dir 10:04:17 INFO - Copying logs to upload dir... 10:04:17 INFO - mkdir: /builds/slave/test/build/upload/logs 10:04:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.309157 ========= master_lag: 35.89 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 34 secs) (at 2016-09-22 10:04:51.305849) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 10:04:51.309547) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43c6a95169781875a07009ebae41714e9fc3a7147398075bf56ea91126c88e400c2db66c8fdaca01132d1be8cd1158c6c65d80387857b8b327945ee86c5c75a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60b5501166bf029e8f13183ff499d0a261046c101c10feee9ff7de5abe15118626ce2048ce69f2495cc2b697aed6b602b47d7874a9f5621f3ac0e2bbb63280fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb984a63299f1759cb8972a9890529436dbd6efb2b48d04bbe681ea13f6a81314b7fa5d9b314b05c1572862fc4f47e16de875dff81ea79e182ef9e464291f4b6"} build_url:https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010211 build_url: 'https://queue.taskcluster.net/v1/task/Dvez57VdTWW00I2jaR6fNQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43c6a95169781875a07009ebae41714e9fc3a7147398075bf56ea91126c88e400c2db66c8fdaca01132d1be8cd1158c6c65d80387857b8b327945ee86c5c75a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60b5501166bf029e8f13183ff499d0a261046c101c10feee9ff7de5abe15118626ce2048ce69f2495cc2b697aed6b602b47d7874a9f5621f3ac0e2bbb63280fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb984a63299f1759cb8972a9890529436dbd6efb2b48d04bbe681ea13f6a81314b7fa5d9b314b05c1572862fc4f47e16de875dff81ea79e182ef9e464291f4b6"}' ========= master_lag: 0.15 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 10:04:51.466602) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-09-22 10:04:51.466966) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1474562177.182864-238563334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004680 ========= master_lag: 2.87 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-09-22 10:04:54.338124) ========= ========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2016-09-22 10:04:54.338531) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2016-09-22 10:04:56.388446) ========= ========= Total master_lag: 38.97 =========