builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-041 starttime: 1477355234.95 results: success (0) buildid: 20161024154922 builduid: e383cf7212a842c4aa78236149b1114f revision: 0c671ce41082a2bba72b4e2340c391f9a5d83b6b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:14.949002) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:14.949459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:14.949730) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:14.982197) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:14.982548) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-24 17:27:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.6M=0s 2016-10-24 17:27:15 (50.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121019 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:15.117902) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:15.118236) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019525 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:15.169460) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:15.169826) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0c671ce41082a2bba72b4e2340c391f9a5d83b6b --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 0c671ce41082a2bba72b4e2340c391f9a5d83b6b --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=8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-24 17:27:15,269 truncating revision to first 12 chars 2016-10-24 17:27:15,269 Setting DEBUG logging. 2016-10-24 17:27:15,269 attempt 1/10 2016-10-24 17:27:15,269 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0c671ce41082?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-24 17:27:15,570 unpacking tar archive at: mozilla-beta-0c671ce41082/testing/mozharness/ program finished with exit code 0 elapsedTime=0.650608 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:15.834016) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:15.834343) ========= script_repo_revision: 0c671ce41082a2bba72b4e2340c391f9a5d83b6b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:15.834761) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:15.835024) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 17:27:15.845809) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-24 17:27:15.846185) ========= /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=8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:27:16 INFO - MultiFileLogger online at 20161024 17:27:16 in /builds/slave/test 17:27:16 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 17:27:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:27:16 INFO - {'append_to_log': False, 17:27:16 INFO - 'base_work_dir': '/builds/slave/test', 17:27:16 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:27:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:27:16 INFO - 'branch': 'Mozilla-Beta', 17:27:16 INFO - 'buildbot_json_path': 'buildprops.json', 17:27:16 INFO - 'config_files': ('talos/linux_config.py',), 17:27:16 INFO - 'default_actions': ('clobber', 17:27:16 INFO - 'read-buildbot-config', 17:27:16 INFO - 'download-and-extract', 17:27:16 INFO - 'populate-webroot', 17:27:16 INFO - 'create-virtualenv', 17:27:16 INFO - 'install', 17:27:16 INFO - 'run-tests'), 17:27:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:27:16 INFO - 'download_minidump_stackwalk': True, 17:27:16 INFO - 'download_symbols': 'ondemand', 17:27:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:27:16 INFO - 'tooltool.py': '/tools/tooltool.py', 17:27:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:27:16 INFO - '/tools/misc-python/virtualenv.py')}, 17:27:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:27:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:27:16 INFO - 'installer_path': 'installer.exe', 17:27:16 INFO - 'log_level': 'info', 17:27:16 INFO - 'log_name': 'talos', 17:27:16 INFO - 'log_to_console': True, 17:27:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:27:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:27:16 INFO - 'opt_config_files': (), 17:27:16 INFO - 'pip_index': False, 17:27:16 INFO - 'sps_profile': False, 17:27:16 INFO - 'sps_profile_interval': 0, 17:27:16 INFO - 'suite': 'dromaeojs', 17:27:16 INFO - 'system_bits': '32', 17:27:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:27:16 INFO - 'title': 'talos-linux64-ix-041', 17:27:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:27:16 INFO - 'use_talos_json': True, 17:27:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:27:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:27:16 INFO - 'work_dir': 'build'} 17:27:16 INFO - [mozharness: 2016-10-25 00:27:16.038504Z] Running clobber step. 17:27:16 INFO - Running pre-action listener: _resource_record_pre_action 17:27:16 INFO - Running main action method: clobber 17:27:16 INFO - rmtree: /builds/slave/test/build 17:27:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:27:17 INFO - Running post-action listener: _resource_record_post_action 17:27:17 INFO - [mozharness: 2016-10-25 00:27:17.001671Z] Finished clobber step (success) 17:27:17 INFO - [mozharness: 2016-10-25 00:27:17.001774Z] Running read-buildbot-config step. 17:27:17 INFO - Running pre-action listener: _resource_record_pre_action 17:27:17 INFO - Running main action method: read_buildbot_config 17:27:17 INFO - Using buildbot properties: 17:27:17 INFO - { 17:27:17 INFO - "project": "", 17:27:17 INFO - "product": "firefox", 17:27:17 INFO - "script_repo_revision": "production", 17:27:17 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 17:27:17 INFO - "repository": "", 17:27:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 17:27:17 INFO - "buildid": "20161024154922", 17:27:17 INFO - "pgo_build": "True", 17:27:17 INFO - "basedir": "/builds/slave/test", 17:27:17 INFO - "buildnumber": 284, 17:27:17 INFO - "slavename": "talos-linux64-ix-041", 17:27:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:27:17 INFO - "platform": "ubuntu64_hw", 17:27:17 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 17:27:17 INFO - "branch": "mozilla-beta", 17:27:17 INFO - "repo_path": "releases/mozilla-beta", 17:27:17 INFO - "revision": "0c671ce41082a2bba72b4e2340c391f9a5d83b6b", 17:27:17 INFO - "stage_platform": "linux64-pgo", 17:27:17 INFO - "builduid": "e383cf7212a842c4aa78236149b1114f", 17:27:17 INFO - "slavebuilddir": "test" 17:27:17 INFO - } 17:27:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 17:27:17 INFO - Running post-action listener: _resource_record_post_action 17:27:17 INFO - [mozharness: 2016-10-25 00:27:17.004203Z] Finished read-buildbot-config step (success) 17:27:17 INFO - [mozharness: 2016-10-25 00:27:17.004292Z] Running download-and-extract step. 17:27:17 INFO - Running pre-action listener: _resource_record_pre_action 17:27:17 INFO - Running main action method: download_and_extract 17:27:17 INFO - mkdir: /builds/slave/test/build/tests 17:27: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')]} 17:27:17 INFO - https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:27:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:27:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:27:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:27:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:27:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:27:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:27:17 INFO - retry: Failed, sleeping 30 seconds before retrying 17:27:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:27:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:27:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:27:47 INFO - retry: Failed, sleeping 60 seconds before retrying 17:28:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:28:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:28:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:28:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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! 17:28:47 INFO - Caught exception: HTTP Error 404: Not Found 17:28:47 INFO - Caught exception: HTTP Error 404: Not Found 17:28:47 INFO - Caught exception: HTTP Error 404: Not Found 17:28:47 INFO - trying https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:28:47 INFO - Downloading https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:28:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:28:47 INFO - Downloaded 1327 bytes. 17:28:47 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:28:47 INFO - Using the following test package requirements: 17:28:47 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:28:47 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:28:47 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 17:28:47 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:28:47 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 17:28:47 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:28:47 INFO - u'jsshell-linux-x86_64.zip'], 17:28:47 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:28:47 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 17:28:47 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:28:47 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:28:47 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 17:28:47 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:28:47 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 17:28:47 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:28:47 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 17:28:47 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:28:47 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 17:28:47 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:28:47 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')]} 17:28:47 INFO - https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:28:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:28:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:28:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:28:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:28:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:28:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:28:47 INFO - retry: Failed, sleeping 30 seconds before retrying 17:29:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:29:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:29:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:29:17 INFO - retry: Failed, sleeping 60 seconds before retrying 17:30:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:30:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:30:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:30:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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! 17:30:17 INFO - Caught exception: HTTP Error 404: Not Found 17:30:17 INFO - Caught exception: HTTP Error 404: Not Found 17:30:17 INFO - Caught exception: HTTP Error 404: Not Found 17:30:17 INFO - trying https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:30:17 INFO - Downloading https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:30:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:30:18 INFO - Downloaded 21773202 bytes. 17:30:18 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'] 17:30:18 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 17:30:19 INFO - Return code: 0 17:30:19 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 17:30: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')]} 17:30:19 INFO - https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:30:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:30:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:30:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:30:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:30:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:30:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:30:19 INFO - retry: Failed, sleeping 30 seconds before retrying 17:30:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:30:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:30:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:30:49 INFO - retry: Failed, sleeping 60 seconds before retrying 17:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:31:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:31:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:31:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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! 17:31:49 INFO - Caught exception: HTTP Error 404: Not Found 17:31:49 INFO - Caught exception: HTTP Error 404: Not Found 17:31:49 INFO - Caught exception: HTTP Error 404: Not Found 17:31:49 INFO - trying https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:31:49 INFO - Downloading https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:31:50 INFO - Downloaded 21773202 bytes. 17:31:50 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'] 17:31:50 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 17:31:52 INFO - Return code: 0 17:31:52 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')]} 17:31:52 INFO - https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:31:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:31:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:31:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:31:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:31:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:31:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:31:52 INFO - retry: Failed, sleeping 30 seconds before retrying 17:32:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:32:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:32:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:32:22 INFO - retry: Failed, sleeping 60 seconds before retrying 17:33:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:33:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:33:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:33:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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! 17:33:22 INFO - Caught exception: HTTP Error 404: Not Found 17:33:22 INFO - Caught exception: HTTP Error 404: Not Found 17:33:22 INFO - Caught exception: HTTP Error 404: Not Found 17:33:22 INFO - trying https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:33:22 INFO - Downloading https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:33:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:33:22 INFO - Downloaded 14413762 bytes. 17:33:22 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'] 17:33:22 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 17:33:23 INFO - Return code: 0 17:33: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')]} 17:33:23 INFO - https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:33:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:33:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:33:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:33:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:33:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:33:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:33:23 INFO - retry: Failed, sleeping 30 seconds before retrying 17:33:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:33:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:33:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:33:53 INFO - retry: Failed, sleeping 60 seconds before retrying 17:34:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:34:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:34:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:34:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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! 17:34:53 INFO - Caught exception: HTTP Error 404: Not Found 17:34:53 INFO - Caught exception: HTTP Error 404: Not Found 17:34:53 INFO - Caught exception: HTTP Error 404: Not Found 17:34:53 INFO - trying https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:34:53 INFO - Downloading https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:34:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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 17:34:54 INFO - Downloaded 56796336 bytes. 17:34:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:34:54 INFO - mkdir: /builds/slave/test/properties 17:34:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:34:54 INFO - Writing to file /builds/slave/test/properties/build_url 17:34:54 INFO - Contents: 17:34:54 INFO - build_url:https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:34:55 INFO - Running post-action listener: _resource_record_post_action 17:34:55 INFO - Running post-action listener: set_extra_try_arguments 17:34:55 INFO - [mozharness: 2016-10-25 00:34:55.153761Z] Finished download-and-extract step (success) 17:34:55 INFO - [mozharness: 2016-10-25 00:34:55.153997Z] Running populate-webroot step. 17:34:55 INFO - Running pre-action listener: _resource_record_pre_action 17:34:55 INFO - Running main action method: populate_webroot 17:34:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:34:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:34:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:34:55 INFO - '--tppagecycles', 17:34:55 INFO - '7'], 17:34:55 INFO - 'tests': ['tsvgm']}}, 17:34:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:34:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:34:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:34:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:34:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:34:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:34:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:55 INFO - 'talos_options': ['--disable-e10s'], 17:34:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:34:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:34:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:55 INFO - 'talos_options': ['--disable-e10s'], 17:34:55 INFO - 'tests': ['damp', 'tps']}, 17:34:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:55 INFO - 'tests': ['damp', 'tps']}, 17:34:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:34:55 INFO - 'tests': ['dromaeo_dom']}, 17:34:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:34:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:34:55 INFO - 'tests': ['basic_compositor_video']}, 17:34:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:34:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:34:55 INFO - 'tests': ['a11yr', 17:34:55 INFO - 'ts_paint', 17:34:55 INFO - 'tpaint', 17:34:55 INFO - 'sessionrestore', 17:34:55 INFO - 'sessionrestore_no_auto_restore', 17:34:55 INFO - 'tabpaint']}, 17:34:55 INFO - 'other-e10s': {'tests': ['a11yr', 17:34:55 INFO - 'ts_paint', 17:34:55 INFO - 'tpaint', 17:34:55 INFO - 'sessionrestore', 17:34:55 INFO - 'sessionrestore_no_auto_restore', 17:34:55 INFO - 'tabpaint']}, 17:34:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:34:55 INFO - 'ts_paint', 17:34:55 INFO - 'tpaint', 17:34:55 INFO - 'sessionrestore', 17:34:55 INFO - 'sessionrestore_no_auto_restore', 17:34:55 INFO - 'tabpaint']}, 17:34:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:34:55 INFO - 'ts_paint', 17:34:55 INFO - 'tpaint', 17:34:55 INFO - 'sessionrestore', 17:34:55 INFO - 'sessionrestore_no_auto_restore', 17:34:55 INFO - 'tabpaint']}, 17:34:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:34:55 INFO - 'tests': ['a11yr', 17:34:55 INFO - 'ts_paint', 17:34:55 INFO - 'tpaint', 17:34:55 INFO - 'sessionrestore', 17:34:55 INFO - 'sessionrestore_no_auto_restore', 17:34:55 INFO - 'tabpaint']}, 17:34:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:34:55 INFO - 'tests': ['a11yr', 17:34:55 INFO - 'ts_paint', 17:34:55 INFO - 'tpaint', 17:34:55 INFO - 'sessionrestore', 17:34:55 INFO - 'sessionrestore_no_auto_restore', 17:34:55 INFO - 'tabpaint']}, 17:34:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:34:55 INFO - 'tests': ['tsvgx', 17:34:55 INFO - 'tsvgr_opacity', 17:34:55 INFO - 'tart', 17:34:55 INFO - 'tscrollx', 17:34:55 INFO - 'cart']}, 17:34:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:34:55 INFO - 'tsvgr_opacity', 17:34:55 INFO - 'tart', 17:34:55 INFO - 'tscrollx', 17:34:55 INFO - 'cart']}, 17:34:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:55 INFO - 'talos_options': ['--disable-e10s'], 17:34:55 INFO - 'tests': ['tp5o']}, 17:34:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:55 INFO - 'tests': ['tp5o']}, 17:34:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:55 INFO - 'talos_options': ['--disable-e10s', 17:34:55 INFO - '--xperf_path', 17:34:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:34:55 INFO - 'tests': ['tp5n']}, 17:34:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:55 INFO - 'talos_options': ['--xperf_path', 17:34:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:34:55 INFO - 'tests': ['tp5n']}}, 17:34:55 INFO - 'talos.zip': {'path': '', 17:34:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:34:55 INFO - Running post-action listener: _resource_record_post_action 17:34:55 INFO - [mozharness: 2016-10-25 00:34:55.169729Z] Finished populate-webroot step (success) 17:34:55 INFO - [mozharness: 2016-10-25 00:34:55.169818Z] Running create-virtualenv step. 17:34:55 INFO - Running pre-action listener: _resource_record_pre_action 17:34:55 INFO - Running main action method: create_virtualenv 17:34:55 INFO - Creating virtualenv /builds/slave/test/build/venv 17:34:55 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 17:34:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:34:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:34:55 INFO - Using real prefix '/usr' 17:34:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:34:55 INFO - Installing distribute.............................................................................................................................................................................................done. 17:34:57 INFO - Installing pip.................done. 17:34:57 INFO - Return code: 0 17:34:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:34:57 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')]} 17:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:57 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')]} 17:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:57 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 0x127a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15393b0>, '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 0x153a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x153a740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', '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 17:34:57 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 17:34:57 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 17:34:57 INFO - Using env: {'DISPLAY': ':0', 17:34:57 INFO - 'HOME': '/home/cltbld', 17:34:57 INFO - 'LANG': 'en_US.UTF-8', 17:34:57 INFO - 'LANGUAGE': 'en_US:en', 17:34:57 INFO - 'LOGNAME': 'cltbld', 17:34:57 INFO - 'MAIL': '/var/mail/cltbld', 17:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:57 INFO - 'MOZ_NO_REMOTE': '1', 17:34:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:34:57 INFO - 'NO_EM_RESTART': '1', 17:34:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:57 INFO - 'PWD': '/builds/slave/test', 17:34:57 INFO - 'SHELL': '/bin/bash', 17:34:57 INFO - 'SHLVL': '1', 17:34:57 INFO - 'TERM': 'linux', 17:34:57 INFO - 'TMOUT': '86400', 17:34:57 INFO - 'USER': 'cltbld', 17:34:57 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', 17:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:57 INFO - '_': '/tools/buildbot/bin/python'} 17:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:57 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 17:34:57 INFO - Cleaning up... 17:34:57 INFO - Return code: 0 17:34:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:34:57 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')]} 17:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:57 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')]} 17:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:57 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 0x127a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15393b0>, '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 0x153a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x153a740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', '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 17:34:57 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 17:34:57 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 17:34:57 INFO - Using env: {'DISPLAY': ':0', 17:34:57 INFO - 'HOME': '/home/cltbld', 17:34:57 INFO - 'LANG': 'en_US.UTF-8', 17:34:57 INFO - 'LANGUAGE': 'en_US:en', 17:34:57 INFO - 'LOGNAME': 'cltbld', 17:34:57 INFO - 'MAIL': '/var/mail/cltbld', 17:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:57 INFO - 'MOZ_NO_REMOTE': '1', 17:34:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:34:57 INFO - 'NO_EM_RESTART': '1', 17:34:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:57 INFO - 'PWD': '/builds/slave/test', 17:34:57 INFO - 'SHELL': '/bin/bash', 17:34:57 INFO - 'SHLVL': '1', 17:34:57 INFO - 'TERM': 'linux', 17:34:57 INFO - 'TMOUT': '86400', 17:34:57 INFO - 'USER': 'cltbld', 17:34:57 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', 17:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:57 INFO - '_': '/tools/buildbot/bin/python'} 17:34:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:58 INFO - Downloading/unpacking psutil>=3.1.1 17:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:58 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 17:34:58 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 17:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:35:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:35:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:35:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:35:00 INFO - Installing collected packages: psutil 17:35:00 INFO - Running setup.py install for psutil 17:35:00 INFO - building 'psutil._psutil_linux' extension 17:35:00 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 17:35:00 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 17:35:00 INFO - building 'psutil._psutil_posix' extension 17:35:00 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 17:35:00 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 17:35:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:35:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:35:00 INFO - Successfully installed psutil 17:35:00 INFO - Cleaning up... 17:35:00 INFO - Return code: 0 17:35:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:35:00 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')]} 17:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:00 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')]} 17:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x127a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15393b0>, '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 0x153a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x153a740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', '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 17:35:00 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 17:35:00 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 17:35:00 INFO - Using env: {'DISPLAY': ':0', 17:35:00 INFO - 'HOME': '/home/cltbld', 17:35:00 INFO - 'LANG': 'en_US.UTF-8', 17:35:00 INFO - 'LANGUAGE': 'en_US:en', 17:35:00 INFO - 'LOGNAME': 'cltbld', 17:35:00 INFO - 'MAIL': '/var/mail/cltbld', 17:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:00 INFO - 'MOZ_NO_REMOTE': '1', 17:35:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:00 INFO - 'NO_EM_RESTART': '1', 17:35:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:00 INFO - 'PWD': '/builds/slave/test', 17:35:00 INFO - 'SHELL': '/bin/bash', 17:35:00 INFO - 'SHLVL': '1', 17:35:00 INFO - 'TERM': 'linux', 17:35:00 INFO - 'TMOUT': '86400', 17:35:00 INFO - 'USER': 'cltbld', 17:35:00 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', 17:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:00 INFO - '_': '/tools/buildbot/bin/python'} 17:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:35:00 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 17:35:00 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 17:35:00 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 17:35:00 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 17:35:00 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 17:35:00 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 17:35:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:35:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:35:02 INFO - Installing collected packages: mozsystemmonitor 17:35:02 INFO - Running setup.py install for mozsystemmonitor 17:35:03 INFO - Successfully installed mozsystemmonitor 17:35:03 INFO - Cleaning up... 17:35:03 INFO - Return code: 0 17:35:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:35:03 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')]} 17:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:03 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')]} 17:35:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:03 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 0x127a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15393b0>, '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 0x153a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x153a740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', '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 17:35:03 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 17:35:03 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 17:35:03 INFO - Using env: {'DISPLAY': ':0', 17:35:03 INFO - 'HOME': '/home/cltbld', 17:35:03 INFO - 'LANG': 'en_US.UTF-8', 17:35:03 INFO - 'LANGUAGE': 'en_US:en', 17:35:03 INFO - 'LOGNAME': 'cltbld', 17:35:03 INFO - 'MAIL': '/var/mail/cltbld', 17:35:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:03 INFO - 'MOZ_NO_REMOTE': '1', 17:35:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:03 INFO - 'NO_EM_RESTART': '1', 17:35:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:03 INFO - 'PWD': '/builds/slave/test', 17:35:03 INFO - 'SHELL': '/bin/bash', 17:35:03 INFO - 'SHLVL': '1', 17:35:03 INFO - 'TERM': 'linux', 17:35:03 INFO - 'TMOUT': '86400', 17:35:03 INFO - 'USER': 'cltbld', 17:35:03 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', 17:35:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:03 INFO - '_': '/tools/buildbot/bin/python'} 17:35:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:03 INFO - Downloading/unpacking jsonschema==2.5.1 17:35:03 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 17:35:03 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 17:35:03 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 17:35:03 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 17:35:03 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 17:35:03 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 17:35:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:35:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:35: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 17:35: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 17:35: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 17:35: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 17:35: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 17:35: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 17:35:05 INFO - Downloading functools32-3.2.3-2.tar.gz 17:35:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:35:05 INFO - warning: no files found matching '*.txt' 17:35:05 INFO - no previously-included directories found matching 'build' 17:35:05 INFO - no previously-included directories found matching 'dist' 17:35:05 INFO - no previously-included directories found matching '.git*' 17:35:05 INFO - Installing collected packages: jsonschema, functools32 17:35:05 INFO - Running setup.py install for functools32 17:35:05 INFO - warning: no files found matching '*.txt' 17:35:05 INFO - no previously-included directories found matching 'build' 17:35:05 INFO - no previously-included directories found matching 'dist' 17:35:05 INFO - no previously-included directories found matching '.git*' 17:35:05 INFO - Successfully installed jsonschema functools32 17:35:05 INFO - Cleaning up... 17:35:05 INFO - Return code: 0 17:35:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:35: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')]} 17:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35: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')]} 17:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35: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', '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 0x127a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15393b0>, '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 0x153a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x153a740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', '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 17:35: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:35: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 functools32==3.2.3-2 17:35:05 INFO - Using env: {'DISPLAY': ':0', 17:35:05 INFO - 'HOME': '/home/cltbld', 17:35:05 INFO - 'LANG': 'en_US.UTF-8', 17:35:05 INFO - 'LANGUAGE': 'en_US:en', 17:35:05 INFO - 'LOGNAME': 'cltbld', 17:35:05 INFO - 'MAIL': '/var/mail/cltbld', 17:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:05 INFO - 'MOZ_NO_REMOTE': '1', 17:35:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:05 INFO - 'NO_EM_RESTART': '1', 17:35:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:05 INFO - 'PWD': '/builds/slave/test', 17:35:05 INFO - 'SHELL': '/bin/bash', 17:35:05 INFO - 'SHLVL': '1', 17:35:05 INFO - 'TERM': 'linux', 17:35:05 INFO - 'TMOUT': '86400', 17:35:05 INFO - 'USER': 'cltbld', 17:35:05 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', 17:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:05 INFO - '_': '/tools/buildbot/bin/python'} 17:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:35:05 INFO - Cleaning up... 17:35:05 INFO - Return code: 0 17:35:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:35: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')]} 17:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35: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')]} 17:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35: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', '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 0x127a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15393b0>, '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 0x153a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x153a740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', '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 17:35: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:35: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 blobuploader==1.2.4 17:35:05 INFO - Using env: {'DISPLAY': ':0', 17:35:05 INFO - 'HOME': '/home/cltbld', 17:35:05 INFO - 'LANG': 'en_US.UTF-8', 17:35:05 INFO - 'LANGUAGE': 'en_US:en', 17:35:05 INFO - 'LOGNAME': 'cltbld', 17:35:05 INFO - 'MAIL': '/var/mail/cltbld', 17:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:05 INFO - 'MOZ_NO_REMOTE': '1', 17:35:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:05 INFO - 'NO_EM_RESTART': '1', 17:35:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:05 INFO - 'PWD': '/builds/slave/test', 17:35:05 INFO - 'SHELL': '/bin/bash', 17:35:05 INFO - 'SHLVL': '1', 17:35:05 INFO - 'TERM': 'linux', 17:35:05 INFO - 'TMOUT': '86400', 17:35:05 INFO - 'USER': 'cltbld', 17:35:05 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', 17:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:05 INFO - '_': '/tools/buildbot/bin/python'} 17:35:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:06 INFO - Downloading/unpacking blobuploader==1.2.4 17:35:06 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 17:35:06 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 17:35:06 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 17:35:06 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 17:35:06 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 17:35:06 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 17:35:08 INFO - Downloading blobuploader-1.2.4.tar.gz 17:35:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:35:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:35:08 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 17:35:08 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 17:35:08 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 17:35:08 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 17:35:08 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 17:35:08 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 17:35:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:35:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:35:08 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 17:35:08 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 17:35:08 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 17:35:08 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 17:35:08 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 17:35:08 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 17:35:08 INFO - Downloading docopt-0.6.1.tar.gz 17:35:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:35:08 INFO - Installing collected packages: blobuploader, requests, docopt 17:35:08 INFO - Running setup.py install for blobuploader 17:35:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:35:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:35:08 INFO - Running setup.py install for requests 17:35:09 INFO - Running setup.py install for docopt 17:35:09 INFO - Successfully installed blobuploader requests docopt 17:35:09 INFO - Cleaning up... 17:35:09 INFO - Return code: 0 17:35:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:35:09 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')]} 17:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:09 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')]} 17:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:09 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 0x127a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15393b0>, '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 0x153a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x153a740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', '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 17:35:09 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 17:35:09 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 17:35:09 INFO - Using env: {'DISPLAY': ':0', 17:35:09 INFO - 'HOME': '/home/cltbld', 17:35:09 INFO - 'LANG': 'en_US.UTF-8', 17:35:09 INFO - 'LANGUAGE': 'en_US:en', 17:35:09 INFO - 'LOGNAME': 'cltbld', 17:35:09 INFO - 'MAIL': '/var/mail/cltbld', 17:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:09 INFO - 'MOZ_NO_REMOTE': '1', 17:35:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:09 INFO - 'NO_EM_RESTART': '1', 17:35:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:09 INFO - 'PWD': '/builds/slave/test', 17:35:09 INFO - 'SHELL': '/bin/bash', 17:35:09 INFO - 'SHLVL': '1', 17:35:09 INFO - 'TERM': 'linux', 17:35:09 INFO - 'TMOUT': '86400', 17:35:09 INFO - 'USER': 'cltbld', 17:35:09 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', 17:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:09 INFO - '_': '/tools/buildbot/bin/python'} 17:35:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:35:09 INFO - Running setup.py (path:/tmp/pip-__74R3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:35:09 INFO - Running setup.py (path:/tmp/pip-KNCL3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:35:09 INFO - Running setup.py (path:/tmp/pip-psJKbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:35:09 INFO - Running setup.py (path:/tmp/pip-QJWqde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:35:09 INFO - Running setup.py (path:/tmp/pip-Md_Krc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:35:10 INFO - Running setup.py (path:/tmp/pip-Ry5iI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:35:10 INFO - Running setup.py (path:/tmp/pip-CPKADf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:35:10 INFO - Running setup.py (path:/tmp/pip-WXZLly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:35:10 INFO - Running setup.py (path:/tmp/pip-i5_UEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:35:10 INFO - Running setup.py (path:/tmp/pip-h3dri4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:35:10 INFO - Running setup.py (path:/tmp/pip-BC3OD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:35:10 INFO - Running setup.py (path:/tmp/pip-mzGQwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:35:10 INFO - Running setup.py (path:/tmp/pip-hGG0Z9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:35:10 INFO - Running setup.py (path:/tmp/pip-jmL8NC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:10 INFO - Running setup.py (path:/tmp/pip-hp_ehv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:35:10 INFO - Running setup.py (path:/tmp/pip-i5RwJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:35:10 INFO - Running setup.py (path:/tmp/pip-8pkNOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:35:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:35:10 INFO - Running setup.py install for manifestparser 17:35:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:35:10 INFO - Running setup.py install for mozcrash 17:35:11 INFO - Running setup.py install for mozdebug 17:35:11 INFO - Running setup.py install for mozdevice 17:35:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Running setup.py install for mozfile 17:35:11 INFO - Running setup.py install for mozhttpd 17:35:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Running setup.py install for mozinfo 17:35:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Running setup.py install for mozInstall 17:35:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Running setup.py install for mozleak 17:35:11 INFO - Running setup.py install for mozlog 17:35:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:35:11 INFO - Running setup.py install for moznetwork 17:35:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:35:12 INFO - Running setup.py install for mozprocess 17:35:12 INFO - Running setup.py install for mozprofile 17:35:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:35:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:35:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:35:12 INFO - Running setup.py install for mozrunner 17:35:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:35:12 INFO - Running setup.py install for mozscreenshot 17:35:12 INFO - Running setup.py install for moztest 17:35:12 INFO - Running setup.py install for mozversion 17:35:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:35:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:35:12 INFO - Cleaning up... 17:35:12 INFO - Return code: 0 17:35:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:35:12 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')]} 17:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:12 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')]} 17:35:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:12 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 0x127a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15393b0>, '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 0x153a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x153a740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', '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 17:35:12 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 17:35:12 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 17:35:12 INFO - Using env: {'DISPLAY': ':0', 17:35:12 INFO - 'HOME': '/home/cltbld', 17:35:12 INFO - 'LANG': 'en_US.UTF-8', 17:35:12 INFO - 'LANGUAGE': 'en_US:en', 17:35:12 INFO - 'LOGNAME': 'cltbld', 17:35:12 INFO - 'MAIL': '/var/mail/cltbld', 17:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:12 INFO - 'MOZ_NO_REMOTE': '1', 17:35:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:12 INFO - 'NO_EM_RESTART': '1', 17:35:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:12 INFO - 'PWD': '/builds/slave/test', 17:35:12 INFO - 'SHELL': '/bin/bash', 17:35:12 INFO - 'SHLVL': '1', 17:35:12 INFO - 'TERM': 'linux', 17:35:12 INFO - 'TMOUT': '86400', 17:35:12 INFO - 'USER': 'cltbld', 17:35:12 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', 17:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:12 INFO - '_': '/tools/buildbot/bin/python'} 17:35:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:35:12 INFO - Running setup.py (path:/tmp/pip-Mm2i7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:35:12 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)) 17:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:35:12 INFO - Running setup.py (path:/tmp/pip-S8qbzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:35:13 INFO - Running setup.py (path:/tmp/pip-9lFGeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:35:13 INFO - Running setup.py (path:/tmp/pip-uTFcri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:35:13 INFO - Running setup.py (path:/tmp/pip-sD2Tjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:35:13 INFO - Running setup.py (path:/tmp/pip-xnVxIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:35:13 INFO - Running setup.py (path:/tmp/pip-fns3aG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:35:13 INFO - Running setup.py (path:/tmp/pip-pu2MAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:35:13 INFO - Running setup.py (path:/tmp/pip-Ms6af3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:35:13 INFO - Running setup.py (path:/tmp/pip-rGxIoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:35:13 INFO - Running setup.py (path:/tmp/pip-z8KyPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:35:13 INFO - Running setup.py (path:/tmp/pip-zOyuY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:35:13 INFO - Running setup.py (path:/tmp/pip-GljOda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:35:13 INFO - Running setup.py (path:/tmp/pip-yLguoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:13 INFO - Running setup.py (path:/tmp/pip-rIg3TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:13 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)) 17:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:35:14 INFO - Running setup.py (path:/tmp/pip-y6I0Hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:35:14 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)) 17:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:35:14 INFO - Running setup.py (path:/tmp/pip-l_ymKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:35:14 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)) 17:35:14 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)) 17:35:14 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)) 17:35:14 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)) 17:35:14 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)) 17:35:14 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)) 17:35:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:35:14 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 17:35:14 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 17:35:14 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 17:35:14 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 17:35:14 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 17:35:14 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 17:35:16 INFO - Downloading blessings-1.6.tar.gz 17:35:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:35:16 INFO - Installing collected packages: blessings 17:35:16 INFO - Running setup.py install for blessings 17:35:16 INFO - Successfully installed blessings 17:35:16 INFO - Cleaning up... 17:35:16 INFO - Return code: 0 17:35:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:35:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:35:16 INFO - Reading from file tmpfile_stdout 17:35:16 INFO - Current package versions: 17:35:16 INFO - argparse == 1.2.1 17:35:16 INFO - blessings == 1.6 17:35:16 INFO - blobuploader == 1.2.4 17:35:16 INFO - docopt == 0.6.1 17:35:16 INFO - functools32 == 3.2.3-2 17:35:16 INFO - jsonschema == 2.5.1 17:35:16 INFO - manifestparser == 1.1 17:35:16 INFO - mozInstall == 1.12 17:35:16 INFO - mozcrash == 0.17 17:35:16 INFO - mozdebug == 0.1 17:35:16 INFO - mozdevice == 0.48 17:35:16 INFO - mozfile == 1.2 17:35:16 INFO - mozhttpd == 0.7 17:35:16 INFO - mozinfo == 0.9 17:35:16 INFO - mozleak == 0.1 17:35:16 INFO - mozlog == 3.3 17:35:16 INFO - moznetwork == 0.27 17:35:16 INFO - mozprocess == 0.23 17:35:16 INFO - mozprofile == 0.28 17:35:16 INFO - mozrunner == 6.12 17:35:16 INFO - mozscreenshot == 0.1 17:35:16 INFO - mozsystemmonitor == 0.3 17:35:16 INFO - moztest == 0.7 17:35:16 INFO - mozversion == 1.4 17:35:16 INFO - psutil == 3.1.1 17:35:16 INFO - requests == 1.2.3 17:35:16 INFO - wsgiref == 0.1.2 17:35:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:35:16 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')]} 17:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:16 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')]} 17:35:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:16 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 0x127a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15393b0>, '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 0x153a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x153a740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', '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 17:35:16 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 17:35:16 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 17:35:16 INFO - Using env: {'DISPLAY': ':0', 17:35:16 INFO - 'HOME': '/home/cltbld', 17:35:16 INFO - 'LANG': 'en_US.UTF-8', 17:35:16 INFO - 'LANGUAGE': 'en_US:en', 17:35:16 INFO - 'LOGNAME': 'cltbld', 17:35:16 INFO - 'MAIL': '/var/mail/cltbld', 17:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:16 INFO - 'MOZ_NO_REMOTE': '1', 17:35:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:16 INFO - 'NO_EM_RESTART': '1', 17:35:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:16 INFO - 'PWD': '/builds/slave/test', 17:35:16 INFO - 'SHELL': '/bin/bash', 17:35:16 INFO - 'SHLVL': '1', 17:35:16 INFO - 'TERM': 'linux', 17:35:16 INFO - 'TMOUT': '86400', 17:35:16 INFO - 'USER': 'cltbld', 17:35:16 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', 17:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:16 INFO - '_': '/tools/buildbot/bin/python'} 17:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:16 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)) 17:35:16 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)) 17:35:16 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)) 17:35:16 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)) 17:35:16 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)) 17:35:16 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)) 17:35:16 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)) 17:35:16 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)) 17:35:16 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)) 17:35:16 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)) 17:35:16 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)) 17:35:16 INFO - Cleaning up... 17:35:16 INFO - Return code: 0 17:35:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:35:16 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')]} 17:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:16 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')]} 17:35:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:16 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 0x127a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15393b0>, '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 0x153a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x153a740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', '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 17:35:16 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 17:35:16 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 17:35:16 INFO - Using env: {'DISPLAY': ':0', 17:35:16 INFO - 'HOME': '/home/cltbld', 17:35:16 INFO - 'LANG': 'en_US.UTF-8', 17:35:16 INFO - 'LANGUAGE': 'en_US:en', 17:35:16 INFO - 'LOGNAME': 'cltbld', 17:35:16 INFO - 'MAIL': '/var/mail/cltbld', 17:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:16 INFO - 'MOZ_NO_REMOTE': '1', 17:35:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:16 INFO - 'NO_EM_RESTART': '1', 17:35:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:16 INFO - 'PWD': '/builds/slave/test', 17:35:16 INFO - 'SHELL': '/bin/bash', 17:35:16 INFO - 'SHLVL': '1', 17:35:16 INFO - 'TERM': 'linux', 17:35:16 INFO - 'TMOUT': '86400', 17:35:16 INFO - 'USER': 'cltbld', 17:35:16 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', 17:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:16 INFO - '_': '/tools/buildbot/bin/python'} 17:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:35:16 INFO - Cleaning up... 17:35:16 INFO - Return code: 0 17:35:16 INFO - Running post-action listener: _resource_record_post_action 17:35:16 INFO - Running post-action listener: _start_resource_monitoring 17:35:16 INFO - Starting resource monitoring. 17:35:16 INFO - [mozharness: 2016-10-25 00:35:16.974897Z] Finished create-virtualenv step (success) 17:35:16 INFO - [mozharness: 2016-10-25 00:35:16.975319Z] Running install step. 17:35:16 INFO - Running pre-action listener: _resource_record_pre_action 17:35:16 INFO - Running main action method: install 17:35:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:35:17 INFO - Reading from file tmpfile_stdout 17:35:17 INFO - Detecting whether we're running mozinstall >=1.0... 17:35:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:35:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:35:17 INFO - Reading from file tmpfile_stdout 17:35:17 INFO - Output received: 17:35:17 INFO - Usage: mozinstall [options] installer 17:35:17 INFO - Options: 17:35:17 INFO - -h, --help show this help message and exit 17:35:17 INFO - -d DEST, --destination=DEST 17:35:17 INFO - Directory to install application into. [default: 17:35:17 INFO - "/builds/slave/test"] 17:35:17 INFO - --app=APP Application being installed. [default: firefox] 17:35:17 INFO - mkdir: /builds/slave/test/build/application 17:35:17 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'] 17:35:17 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 17:35:31 INFO - Reading from file tmpfile_stdout 17:35:31 INFO - Output received: 17:35:31 INFO - /builds/slave/test/build/application/firefox/firefox 17:35:31 INFO - Running post-action listener: _resource_record_post_action 17:35:31 INFO - [mozharness: 2016-10-25 00:35:31.434429Z] Finished install step (success) 17:35:31 INFO - [mozharness: 2016-10-25 00:35:31.434709Z] Running run-tests step. 17:35:31 INFO - Running pre-action listener: _resource_record_pre_action 17:35:31 INFO - Running main action method: run_tests 17:35:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:35:31 INFO - Python 2.7.3 17:35:31 INFO - Return code: 0 17:35:31 INFO - grabbing minidump binary from tooltool 17:35:31 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')]} 17:35:31 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 0x15393b0>, '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 0x153a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x153a740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:35:31 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 17:35:31 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 17:35:31 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 17:35:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:35:31 INFO - Return code: 0 17:35:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:35:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:35:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:35:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:35:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:35:31 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/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 17:35:31 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/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 17:35:31 INFO - Using env: {'DISPLAY': ':0', 17:35:31 INFO - 'HOME': '/home/cltbld', 17:35:31 INFO - 'LANG': 'en_US.UTF-8', 17:35:31 INFO - 'LANGUAGE': 'en_US:en', 17:35:31 INFO - 'LOGNAME': 'cltbld', 17:35:31 INFO - 'MAIL': '/var/mail/cltbld', 17:35:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:35:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:31 INFO - 'MOZ_NO_REMOTE': '1', 17:35:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:35:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:35:31 INFO - 'NO_EM_RESTART': '1', 17:35:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:31 INFO - 'PWD': '/builds/slave/test', 17:35:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:35:31 INFO - 'SHELL': '/bin/bash', 17:35:31 INFO - 'SHLVL': '1', 17:35:31 INFO - 'TERM': 'linux', 17:35:31 INFO - 'TMOUT': '86400', 17:35:31 INFO - 'USER': 'cltbld', 17:35:31 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610', 17:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:31 INFO - '_': '/tools/buildbot/bin/python'} 17:35:31 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/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 17:35:31 INFO - mozversion application_buildid: 20161024154922 17:35:31 INFO - mozversion application_changeset: 0c671ce41082a2bba72b4e2340c391f9a5d83b6b 17:35:31 INFO - mozversion application_display_name: Firefox 17:35:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:35:31 INFO - mozversion application_name: Firefox 17:35:31 INFO - mozversion application_remotingname: firefox 17:35:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:35:31 INFO - mozversion application_vendor: Mozilla 17:35:31 INFO - mozversion application_version: 50.0 17:35:31 INFO - mozversion platform_buildid: 20161024154922 17:35:31 INFO - mozversion platform_changeset: 0c671ce41082a2bba72b4e2340c391f9a5d83b6b 17:35:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:35:31 INFO - mozversion platform_version: 50.0 17:35:31 INFO - using testdate: 1477355731 17:35:31 INFO - actual date: 1477355731 17:35:31 INFO - starting webserver on 'localhost:34152' 17:35:31 INFO - SUITE-START | Running 2 tests 17:35:31 INFO - TEST-START | dromaeo_css 17:35:31 INFO - operating with platform_type : linux_ 17:35:31 INFO - Initialising browser for dromaeo_css test... 17:35:31 INFO - TEST-INFO | started process 11511 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJp3tiQ/profile http://localhost:34152/getInfo.html) 17:35:35 INFO - PROCESS | 11511 | __metrics Screen width/height:1600/1200 17:35:35 INFO - PROCESS | 11511 | colorDepth:24 17:35:35 INFO - PROCESS | 11511 | Browser inner width/height: 1024/697 17:35:35 INFO - PROCESS | 11511 | __metrics 17:35:36 INFO - PROCESS | 11511 | 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" 17:35:36 INFO - PROCESS | 11511 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:35:37 INFO - TEST-INFO | 11511: exit 0 17:35:37 INFO - Browser initialized. 17:35:37 INFO - Running cycle 1/1 for dromaeo_css test... 17:35:37 INFO - TEST-INFO | started process 11600 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJp3tiQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:35:49 INFO - PROCESS | 11600 | RSS: Main: 143659008 17:35:49 INFO - PROCESS | 11600 | 17:36:41 INFO - PROCESS | 11600 | 1477355801032 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] 17:38:49 INFO - PROCESS | 11600 | Cycle 1(1): loaded http://localhost:34152/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34152/tests/dromaeo/cssquery-ext.html) 17:38:50 INFO - PROCESS | 11600 | RSS: Main: 239423488 17:38:50 INFO - PROCESS | 11600 | 17:41:52 INFO - PROCESS | 11600 | Cycle 1(1): loaded http://localhost:34152/tests/dromaeo/cssquery-ext.html (next: http://localhost:34152/tests/dromaeo/cssquery-jquery.html) 17:41:52 INFO - PROCESS | 11600 | RSS: Main: 230105088 17:41:52 INFO - PROCESS | 11600 | 17:44:54 INFO - PROCESS | 11600 | Cycle 1(1): loaded http://localhost:34152/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34152/tests/dromaeo/cssquery-mootools.html) 17:44:55 INFO - PROCESS | 11600 | RSS: Main: 202342400 17:44:55 INFO - PROCESS | 11600 | 17:47:57 INFO - PROCESS | 11600 | Cycle 1(1): loaded http://localhost:34152/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34152/tests/dromaeo/cssquery-prototype.html) 17:47:57 INFO - PROCESS | 11600 | RSS: Main: 200183808 17:47:57 INFO - PROCESS | 11600 | 17:51:00 INFO - PROCESS | 11600 | Cycle 1(1): loaded http://localhost:34152/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34152/tests/dromaeo/cssquery-yui.html) 17:51:00 INFO - PROCESS | 11600 | RSS: Main: 177676288 17:51:00 INFO - PROCESS | 11600 | 17:54:03 INFO - PROCESS | 11600 | Cycle 1(1): loaded http://localhost:34152/tests/dromaeo/cssquery-yui.html (next: http://localhost:34152/tests/dromaeo/cssquery-dojo.html) 17:54:03 INFO - PROCESS | 11600 | RSS: Main: 267788288 17:54:03 INFO - PROCESS | 11600 | 17:54:03 INFO - PROCESS | 11600 | __start_tp_report 17:54:03 INFO - PROCESS | 11600 | _x_x_mozilla_page_load 17:54:03 INFO - PROCESS | 11600 | _x_x_mozilla_page_load_details 17:54:03 INFO - PROCESS | 11600 | |i|pagename|runs| 17:54:03 INFO - PROCESS | 11600 | |0;dojo.html;3211.646718860925;3232.1111694284077;3244.756643251755;3262.1355340834666;3276.803391796492;5526.474984876435;5577.442255774431;5584.553235741141;5588.748506317215;5589.944100558998;5521.089020311654;5582.665040097587;5585.245991791107;5603.187537807015;5621.4659607337235;5060.772265248063;5083.923741143886;5126.179811230204;5134.948650513485;5150.665206761549;5042.520960508764;5140.66585671932;5159.097157997354;5166;5166.767495462695;5006.4492905780335;5008.123578373788;5034.09386310464;5052.14113600687;5075.822346217883;5080.034793389244;5171.870703232412;5198.9480105198845;5204.505571970657;5229.294045303911;4917.139500587418;5094.694318340874;5108.361454818148;5136.64043516954;5169.483051694838;5431.972840135774;5440.9934162180125;5441.673499590037;5442.9727851360885;5485.341758988907;5848.64908105515;5879.2944846618675;5881;5882.617629854046;5886.3230728465915;5779.335376431723;5881.117832325142;5913.2608423947;5921.200637913913;5926.525877929756;5512.366077901052;5577.9163312550345;5595.496405323541;5602.243697100921;5612.15817627355;6088.593123624011;6091.908621370683;6095.024796032702;6097.237845269341;6100.359462256488;5713.600047996601;5725.828225153252;5772.191893134965;5868.119782032729;5883.61756485827;5975.880482390415;5984.521238300926;5985.191999080069;6018.458338749533;6027.517798576191;5944.227250457413;5955.404459554096;5960.165576819162;5964.373740757158;5969.403059693996;5675.148727690879;5768;5774.88450230993;5965.910511342333;5972.940270597239;4525.321201819754;4530.932036019462;4534.319852022157;4535.63714902807;4538.432695913011;4019.718619696593;4074.022234663719;4077.918441631151;4081.2653722329674;4103.281925662997;5706.201131841545;5714.79998200061;5715.057015592381;5723.3418156912485;5740.799072032459;6023.548233882477;6023.909641355383;6029.035354343372;6034.728437220336;6035.909461358083;5500.80747173847;5529.447055294519;5585.609007369527;5624.100143976944;5703.401142879938;5000.374953130859;5003.524665156804;5004.049230646238;5022.773975171126;5039.5212454817465;6069.51443884497;6094.786682466092;6094.878102437927;6135.386461353918;6138.355472675305;5874.618149820248;5880.176775251553;5928.229330187048;5932.2584676915385;5947.286325640951;6154.5384096192065;6155.569110162246;6158.568900176944;6165.198524191826;6171.47542458896;5887.381824908494;5889.734961926809;5892.882142357043;5893.793717219876;5922.289325280994;5804.2744656917885;5887.58786884931;5903.291605007426;5917.171595976481;5979.730912108878;5782.479576837936;5810.477057064715;5825.446582574648;5839.970800145972;5842.2405087338375;5816.563757718272;5821.738021789114;5829.621074630136;5832.941670583409;5836.503897168788;5626.831195064155;5674.347450043133;5678.744456499385;5707.68607726579;5724.255846739898;5842.3281322646735;5843.474087332161;5849.766009359578;5853.970730146492;5873.089671100986;5713.428657134254;5714.257146570919;5716.28546431696;5717.714114294352;5747.712614369181 17:54:03 INFO - PROCESS | 11600 | |1;ext.html;599.3796420704568;623.2552100240214;652.6932341799355;663.6117871045439;672.4116398151617;34209.65790342096;37983.43024854626;46356;47080;47502.28746568796;49383.50616493834;49815.25277120846;49931.50068499314;50003.74998125005;50048.24927626088;8506.872396914021;9521.761955951088;9674.854877176847;9681.951590242039;9687.128158465737;10811.513481893295;11239.550417983311;11244;11599.420028998516;11599.594014209462;11340.376279304635;11446.599369022086;11459.312441253509;11466.197366184371;11468.311901285908;11537.884621153766;11567.884321156766;11578.305301681841;11600.535978560847;11607.303561786277;11359.034482069033;11439.542418303257;11447.08423326132;11447.484863181093;11449.427528623619;47263.5273647266;47617.52382476166;47692;47701.76149119267;47744.28383574249;3776.622337766229;4015.4779878615886;4077.143799802045;4091.40674602184;4268.487781466244;32744.508832367534;32790;33157.50263746046;33506;33506.6649333506;4641.559051890065;4705.270683044134;4811.639127065449;4840.75796210188;4900.950990490085;374421;375195;375363;375521;377103;67425;68007;68112;68161;68345.31654683489;40773;41756.791216043726;41809.58190418118;41948.58051419508;70089.64955175192;19621;20114.69827952582;20181.697274540893;20193.293234737008;20205.090770915343;13247.20516768997;13295.135816171918;13425.39585718645;14569.708605827824;14719.852801472063;20893.26873559418;21119.155233790785;21151.365459036253;21204.36386908395;21288.574228515343;7437.367823735043;7648.464607477423;7694.307512323918;7725.49784264021;7806.41451891099;1049.5801679328329;1053.931494452858;1102.4763237462141;1118.703543560957;1162.105179012156;34189.65810341915;34871.12822179466;35059.64940350564;35088.47367289493;35567.64432355644;1168.39243593331;1225.1179151011258;1249.2941488059218;1251.361805479212;1299.5451591942744;17273.031624918018;18397.54006149857;18513.259469621335;18545.25818967253;18609.81390186108;663814;663941;664754;667085;669112;65572.34427655757;66110;66117.33882661207;66190;66226.66886665535;1309.306067784076;1373.8282714660668;1374.6082366525693;1424.7079348733484;1450.6155868694805;31241.37517249688;36583;36607.81696091503;36711;36746;49167.7541612304;49909.25136122888;49921;49982;50039.749801250764;418742;419750;419959;419963;420298;26947.595786063615;27855;27925.720742792313;27998.86000569984;32431;52011.73994130005;52669.73665131803;52682.47317526929;52712.73643631757;52931.470685292654;44316.778416107714;44696.776516117214;45184.77407612941;46274.30588541242;46671;43984;44682.3297650529;44839;45012.77493612511;46249.76875115603;43404.348934765374;44124.33813492864;44431.33352999772;44483.33275000813;44702.55297447114;36926.63073369232;39076;39372.40941385939;39499.80250098731;40135.79932100321;29806.25484362908;30062.014659810382;30190.54714179333;30217.395652086394;30376.240593985327 17:54:03 INFO - PROCESS | 11600 | |2;jquery.html;402.95768944260834;410.40696193999685;414.815407143821;426.9060806622543;432.6063282413005;19516.804831951675;20522.2817201398;21170.57658846826;21291.68062479062;21545.67681484779;19245.903770481164;19773.50566235845;19812.70280945787;19893.3037343693;19962.800371996276;5615.859603509904;5882.088276317178;5888.793892213774;5896.027155519334;5896.587238893279;5625.831225063234;5801.24583804107;5814.622049566786;5824.679642619654;5885.20549725788;5841.299044114712;5858.472737453629;5862.208601838761;5864.824055278327;5870.26621672291;5780.0751879699255;5829.854253643672;5840.503557197643;5844.473997340238;5852.063669812852;5187.170052791573;5306.7611957462;5309.495597918191;5311.309529761106;5311.309529761145;21720.239791607375;22451.32646020622;24336.87831560831;24344.634830477557;24548.63177052328;2491.638712386712;2557.1944837376354;2639.973600263992;2792.3577577157366;2809.3117186289287;9977.401355918668;10390.584376624925;11232.550697972072;11236.157288203418;11297.887021129765;1998.2506560039985;2038.5413282011577;2123.9150433982777;2148.130007347027;2171.695962565226;86372.0027733853;87629.12370876274;88936.55531722364;89293.10706892912;89888.55055724745;11497.540098396137;11512.654620361403;11649.76700465986;11704.356260405759;11801.52793888252;16309.83690163107;16346.100964446832;16382.672346553241;16417.343306267856;16463.506094817174;8651.6971905983;8716;8931.866022009675;8976.05751396107;9106.726798196065;8635.870461943075;8658.567071646392;8674.826503469896;8866.645334186689;8892.84304072058;4991.450940396553;5034.773435195425;5050.090983622983;5052.545270925634;5147.408048074444;1853.6292741451764;1977.6637971544872;2032.949176270605;2121.384798408475;2141.689455029012;921.8847644044495;990.9405435673883;1024.8667673202435;1041.6979076068008;1062.3944351719447;19643.64232322035;20109.29617463382;20232.69650955237;20251.797482025286;20368.79631203669;1020.2705065877933;1044.5038606661774;1059.8251288537306;1087.581281206741;1094.5020015892749;10431.582736690447;10954.123670106372;11222;11265.54937802495;11298;509299;512014;513151;528798;600729;63799.47125048223;82579;99565.50217249012;101076.94615269206;102983;937.5265490927038;976.4287891583361;1024.262530977695;1025.779457416659;1036.212478516318;17607.29570817153;18089;18126.63746725032;18213.271469141087;18549.90725046366;41279.58720412757;45798;46276.305855412866;47189.764051179525;47699;53822.46177538331;60976.69511652413;61848.690756545926;62017;62780;5582.4138465462165;7484.588347640932;7685.884711729217;8633.654653813777;9316.860247096163;13456;13688.247146407044;14039.929800350932;14064.859351406354;14191.787123192953;11609.013233875216;11670.649880503599;11783.941080294544;11833.763324733285;11843.467043983213;11942.522299107939;12093.455794489446;12220.083493738182;12238.143329966817;12593.622191334274;11645.88354116448;12108.21296615717;12129.69675758113;12283.201591896499;12383.566575169825;8445.324374050184;8536.786580335542;8593.269572086443;8707.303415726854;8875.822483550164;9109.77225569366;10815.72960675958;11407.657770266906;11709;11840 17:54:03 INFO - PROCESS | 11600 | |3;mootools.html;1517.6357674158198;1524.451197568875;1684.1579210394802;1778.9110544472774;1808.0417378789239;8638.179372959574;9301.069893010705;9334.29992750543;9337.206337461315;9340.626374944994;7986;8535.146485351463;8691.652333906635;8726.95636521818;9096.63613455461;2384.570777260088;2425.514897020594;2425.818063645225;2430.44099857033;2433.0875921529428;2303.8617682938993;2347.58917189492;2392.8085753139794;2397.3287479505766;2397.736249012607;2405.6872606561124;2437.6343548467785;2447.951040979179;2450.620153876152;2452.889619967106;2308.365199570115;2326.8371214015024;2337.345543247885;2337.614293641556;2344.9531009379716;2142.6571748520164;2147.527543940331;2149.3874245840016;2150.2044243629803;2151.3223334649533;9101.089891010846;9142.360034797566;9275.953620231923;9279.907200927972;9296.209822165125;5438.972805135989;5445.83662490122;5511.283533140666;5526.640768350085;5582;3307.6196237432764;3347.548081009057;3408;3437.1922598189403;3446.5347178130883;1086.3590481615806;1101.6694991502598;1108.5842808946645;1135.5230803062734;1138.7437826489056;12836.743265134644;12884.742305153939;12890.80663790044;12903.548375806895;12908.35458227085;4714;4715.976420117878;4789.329503705878;4845.370101886732;4894.706317620954;10778.29941053829;10803.865594112662;10804.405757683402;10809.297395669257;10820.080293175011;5585.301837270341;5590.5248053915875;5593.692346920877;5597.412271711493;5599.356074051455;4408.955910440919;4488.214562451558;4987.850364489072;5023.497650234947;5161.354830646169;3754.605766394544;3878.301905657009;3967.504061992251;4017.8392864285684;4057.229126465962;1250.8749125087418;1262.8989680825512;1294.7475242327655;1305.7062161013696;1309.7380523895258;1069.1820757120809;1074.8441475985937;1084.0568705226347;1087.472575800736;1087.7280679830042;6107.4197951194565;6159.5072394208355;6280.497560195174;6398.808035758934;6406.391392817675;458.2644855007731;460.5417609478523;463.5619339723996;471.99528004719514;473.12235802585707;4275.914481710349;4289.1421715657;4338.501072376717;4473.463184417826;4540.818367265272;10957.50691218897;11207;11235.887641123647;11328.037116844996;11332.546698131982;11244.38155901417;11276.097912167006;11299.661010169708;11317.377544235145;11330.433478326215;8028.799280017929;8349.03986041601;8350.4154709171;8524.190201930807;8541.914580854236;6092.817215483543;6164.506839452923;6292.779752708633;6321.715522801577;6463.612183269019;10245.487725613839;10294.227932905213;10461.47692615351;10479.10927571165;10645.787084258427;10588.84116738265;10618.256722029384;10689.30519516229;10738.516766745359;10739.624113156002;7899.249571290718;8016.839663206587;8120.472169308976;8274.462159959583;8375;8800.559972001502;8827.382083254111;8846.071162527842;8866.157715017063;8872.378933474594;7879.448438609243;7947.761567152995;8054;8186.959065204635;8268.131846156068;7674.309312161932;7796.961015195115;7802.790567462052;7947.5231486111015;8238.382121341043;7431.591262480615;7463;7492.625368731433;7540.924590754242;7594.8860767087435;5525.751341189576;5575.470330318613;5596.076647353304;5597.104463285855;5654.3497497788285;5633.436656334334;5701.629394089371;5711.057675483498;5768;5787.594868359174 17:54:03 INFO - PROCESS | 11600 | |4;prototype.html;335.42474655965015;351.9736019798516;353.07846520581285;354.7481288285317;355.42953559536943;5589.441055894414;5599;5621.915671264929;5628.409017053212;5673;5227.111391063519;5376.946230537694;5380.843118729473;5394.838154855361;5545.306836645419;4258.190943720683;4280.379344994982;4357.99298484045;4381.605655491006;4424.469063712343;4415.139047885664;4420.248557745191;4420.690551661385;4422.668299877522;4425.424694789673;4144.254034273829;4347.891302717426;4353.825846966133;4368.126374725051;4378.686394081778;4329.718568293082;4340.544242854487;4344.956550434486;4346.543612920661;4350.825966961318;3866.903327416837;3871.6709079727975;3872.7676339419727;3875.5155605549307;3879.8060096995036;5196.480351964811;5280.445553216895;5391.894661594364;5400.675959442407;5421.340595098293;4939.827106051307;4950.257461380786;4956.157453232959;4976.726280054596;4978.477259887732;5763.567732420043;5772.451617096369;5775.971120144414;5783.5084017858535;5783.739731712041;4112.157007813421;4171.541130475645;4195.328747400432;4218.936715949232;4221.514525829508;5950.404959504015;5956.434138756811;5958.165856780054;5989.31122920861;6013.669248191348;4260.4248426462655;4306.978465107654;4310.159518893848;4312.805923733439;4317.611414972667;4216.620504154641;4316.913661726748;4337.7180483268485;4358.8038538265855;4364.192624364439;4927.457979622239;4927.679700819435;4935.8025678972435;4943.9258411123865;4968.900621987612;5833.7374818133285;5841;5859.472647461749;5860.413958604106;5869.325027621793;1405.8172437583048;1409.9647508812157;1452.847451017628;1470.353044660346;1511.2896938438917;991.8115558043949;1008.5461542305992;1012.1851909213154;1012.2711647613706;1016.5120742043861;4792.95207047932;5000.349954505891;5001.399272378343;5017.37282839645;5031.4465408805;5211.687298762086;5235.560220939272;5240.475952404805;5240.842774716764;5243.711595862188;4798.904021919612;4991.850244492671;5001.44984051754;5005.599552035828;5013.919223522197;2725.318670332417;2751.9036833710725;2758.558630619091;2794.7065558116105;2799.9160025199276;5307.867303317371;5333.466653334713;5353.625246232727;5383.300170977826;5435.510804027656;4957.925631115536;5226;5229.973850130725;5255.55616638265;5292.153255479105;5396.784128634889;5404.567634589223;5415.647982881179;5424.837254882281;5425.294711687456;5091.541761241506;5155.43290238081;5163.4578369270685;5192.896876764917;5193.221016847503;5134.640575159703;5161.974190129025;5564.275074726847;5976.62537616342;5999.670018149044;6226.221722284715;6251.312145720539;6266.498680105672;6272.655003974825;6272.843178920563;4265.637420819266;4272.188284225987;4289.378040184093;4298.333758267474;4305.56944305567;5837.912431313449;6038.63768173911;6050.667213303134;6052.939470605237;6054.122152287981;5638.605297629127;5800.912986305125;5872.794452194153;5900.940990590211;5912.5861189718025;5897.439743224387;5901.173835662925;5904.704764761831;5914.231149950651;5915.438033386821;5643.701948551939;5837.708114594168;5849.970750146222;5853.853653658693;5866.2960444746905;5917.6745279010065;5937.821865344046;5947.405259474018;5983.282006159288;5986;5700;5820.679862607597;5828.0966450200285;5830.708464576669;5845.620034697731 17:54:03 INFO - PROCESS | 11600 | |5;yui.html;790.257158271225;790.794393457701;795.7214974758831;810.1291112054543;840.4831028917216;15769.132697701622;16030.67938641228;16057.598560035976;16105.436309729163;16197.271122799473;14777.187254701013;14906.031107977973;14922.776158357634;14923.626909327273;14936.029158104715;724.583364565377;862.4997501449176;862.573026351955;878.2052242720316;890.3900827932854;879.2745984562729;887.0198430734043;888.0276097673037;888.1296329596998;888.6489836514573;931.0270767048451;934.2525979216599;934.2525979216599;934.8317302885478;935.2985261054209;932.7481579973437;933.444600462727;933.4632586262858;933.6358820060148;934.2012579244749;682.0791930893255;683.2143035509155;683.702589373624;684.1789852177407;685.0238410265401;14326;14342.71314573713;14362.569122926328;14380.424783008668;14405.351759170862;14015.859841401558;14426.134431934013;14692.338844752012;15560.766588501181;16279.674406511804;13828.446862125504;14362.210078445682;14479.42082316706;14487.492937747234;14522.564323070324;4812.711237325771;4885.120678277908;4888.584470320027;4995;5023.974880125613;88912;89187.55406222928;89270.553647232;91499;92165.07834921699;11834.17160798753;11855.02428408135;11906.345151016778;12082.939585302018;12119.39403029845;21812.236571719914;21861.344159675235;21986.560268794536;22008.44978875541;22015.779842201373;8464.746057618282;8591.914080859236;8682.175193356621;8753.431026983226;8771.649134034567;5064.366954130734;5090.338256026693;5118.462561431071;5212.530872221442;5271.367435907716;3359.7480188985924;3469.37551240774;3469.7918124912585;3470.444728843373;3473.4616134499192;625.5714835337808;643.06755204953;643.6106155775782;645.7094307561522;661.1933441201726;591.1487458060379;603.6528995827417;687.4603436302579;723.2658851265969;760.9771706848803;9277.469217579159;9794.16690616105;10284.125849302893;10307;10370.266913411568;700.1353328639127;700.6671830880393;719.8380364417964;729.6424751871544;772.3705180278056;5034.89930201394;5035.219540971156;5163.277141200235;5199.66202196856;5259.684418934799;74917;104066;111129.44435277772;134496;145651.2717436406;67121.66439167772;68979.65510172417;86864.13135868686;99957.50021249847;112962;97451;98714;100010.99999999854;100811.49594251982;103624;9691.563879625293;9808.852867206855;9946.403215807075;10123.493825308558;10137.695869123938;58108.12837807352;58553.70723146357;59361.40638593559;59484;59606.509837254416;77798.22201778136;83594.74607881009;86983;89268;90485.54757226171;11839.644810655695;11905.940470297593;12033.63899083029;12144.817827732768;12266.38668066611;13272.402741876445;13420.597382078162;13490.190588564717;13566.118202316818;13649.522266720616;12850.614481565568;12985.350732463528;13196.736065278832;13433.529826456028;13842.169469831842;12685.8097128545;12946.352682365656;13038.152520086163;13076.280804555841;13148.868511315148;13176.736465270833;13206.471741130246;13268.46926122983;13906.443742250196;14342.99599028058;11063.944680276547;11073.889261107286;11195.216334856483;11371.943140284246;11757.235779674294;11347.773044539228;11350.489227984926;11459.48432320531;11486.94256528712;11518.251313664585 17:54:03 INFO - PROCESS | 11600 | __end_tp_report 17:54:03 INFO - PROCESS | 11600 | __start_cc_report 17:54:03 INFO - PROCESS | 11600 | _x_x_mozilla_cycle_collect,832 17:54:03 INFO - PROCESS | 11600 | __end_cc_report 17:54:03 INFO - PROCESS | 11600 | __startTimestamp1477356843451__endTimestamp 17:54:03 INFO - PROCESS | 11600 | 17:54:03 INFO - PROCESS | 11600 | ------- Summary: start ------- 17:54:03 INFO - PROCESS | 11600 | Number of tests: 6 17:54:03 INFO - PROCESS | 11600 | 17:54:03 INFO - PROCESS | 11600 | [#0] dojo.html Cycles:165 Average:5522.61 Median:5717.71 stddev:616.64 (10.8%) stddev-sans-first:591.28 17:54:03 INFO - PROCESS | 11600 | Values: 3211.6 3232.1 3244.8 3262.1 3276.8 5526.5 5577.4 5584.6 5588.7 5589.9 5521.1 5582.7 5585.2 5603.2 5621.5 5060.8 5083.9 5126.2 5134.9 5150.7 5042.5 5140.7 5159.1 5166.0 5166.8 5006.4 5008.1 5034.1 5052.1 5075.8 5080.0 5171.9 5198.9 5204.5 5229.3 4917.1 5094.7 5108.4 5136.6 5169.5 5432.0 5441.0 5441.7 5443.0 5485.3 5848.6 5879.3 5881.0 5882.6 5886.3 5779.3 5881.1 5913.3 5921.2 5926.5 5512.4 5577.9 5595.5 5602.2 5612.2 6088.6 6091.9 6095.0 6097.2 6100.4 5713.6 5725.8 5772.2 5868.1 5883.6 5975.9 5984.5 5985.2 6018.5 6027.5 5944.2 5955.4 5960.2 5964.4 5969.4 5675.1 5768.0 5774.9 5965.9 5972.9 4525.3 4530.9 4534.3 4535.6 4538.4 4019.7 4074.0 4077.9 4081.3 4103.3 5706.2 5714.8 5715.1 5723.3 5740.8 6023.5 6023.9 6029.0 6034.7 6035.9 5500.8 5529.4 5585.6 5624.1 5703.4 5000.4 5003.5 5004.0 5022.8 5039.5 6069.5 6094.8 6094.9 6135.4 6138.4 5874.6 5880.2 5928.2 5932.3 5947.3 6154.5 6155.6 6158.6 6165.2 6171.5 5887.4 5889.7 5892.9 5893.8 5922.3 5804.3 5887.6 5903.3 5917.2 5979.7 5782.5 5810.5 5825.4 5840.0 5842.2 5816.6 5821.7 5829.6 5832.9 5836.5 5626.8 5674.3 5678.7 5707.7 5724.3 5842.3 5843.5 5849.8 5854.0 5873.1 5713.4 5714.3 5716.3 5717.7 5747.7 17:54:03 INFO - PROCESS | 11600 | 17:54:03 INFO - PROCESS | 11600 | [#1] ext.html Cycles:180 Average:65838.29 Median:39436.11 stddev:133970.53 (339.7%) stddev-sans-first:134256.81 17:54:03 INFO - PROCESS | 11600 | Values: 599.4 623.3 652.7 663.6 672.4 34209.7 37983.4 46356.0 47080.0 47502.3 49383.5 49815.3 49931.5 50003.7 50048.2 8506.9 9521.8 9674.9 9682.0 9687.1 10811.5 11239.6 11244.0 11599.4 11599.6 11340.4 11446.6 11459.3 11466.2 11468.3 11537.9 11567.9 11578.3 11600.5 11607.3 11359.0 11439.5 11447.1 11447.5 11449.4 47263.5 47617.5 47692.0 47701.8 47744.3 3776.6 4015.5 4077.1 4091.4 4268.5 32744.5 32790.0 33157.5 33506.0 33506.7 4641.6 4705.3 4811.6 4840.8 4901.0 374421.0 375195.0 375363.0 375521.0 377103.0 67425.0 68007.0 68112.0 68161.0 68345.3 40773.0 41756.8 41809.6 41948.6 70089.6 19621.0 20114.7 20181.7 20193.3 20205.1 13247.2 13295.1 13425.4 14569.7 14719.9 20893.3 21119.2 21151.4 21204.4 21288.6 7437.4 7648.5 7694.3 7725.5 7806.4 1049.6 1053.9 1102.5 1118.7 1162.1 34189.7 34871.1 35059.6 35088.5 35567.6 1168.4 1225.1 1249.3 1251.4 1299.5 17273.0 18397.5 18513.3 18545.3 18609.8 663814.0 663941.0 664754.0 667085.0 669112.0 65572.3 66110.0 66117.3 66190.0 66226.7 1309.3 1373.8 1374.6 1424.7 1450.6 31241.4 36583.0 36607.8 36711.0 36746.0 49167.8 49909.3 49921.0 49982.0 50039.7 418742.0 419750.0 419959.0 419963.0 420298.0 26947.6 27855.0 27925.7 27998.9 32431.0 52011.7 52669.7 52682.5 52712.7 52931.5 44316.8 44696.8 45184.8 46274.3 46671.0 43984.0 44682.3 44839.0 45012.8 46249.8 43404.3 44124.3 44431.3 44483.3 44702.6 36926.6 39076.0 39372.4 39499.8 40135.8 29806.3 30062.0 30190.5 30217.4 30376.2 17:54:03 INFO - PROCESS | 11600 | 17:54:03 INFO - PROCESS | 11600 | [#2] jquery.html Cycles:180 Average:30782.27 Median:13520.14 stddev:87937.69 (650.4%) stddev-sans-first:88154.80 17:54:03 INFO - PROCESS | 11600 | Values: 403.0 410.4 414.8 426.9 432.6 19516.8 20522.3 21170.6 21291.7 21545.7 19245.9 19773.5 19812.7 19893.3 19962.8 5615.9 5882.1 5888.8 5896.0 5896.6 5625.8 5801.2 5814.6 5824.7 5885.2 5841.3 5858.5 5862.2 5864.8 5870.3 5780.1 5829.9 5840.5 5844.5 5852.1 5187.2 5306.8 5309.5 5311.3 5311.3 21720.2 22451.3 24336.9 24344.6 24548.6 2491.6 2557.2 2640.0 2792.4 2809.3 9977.4 10390.6 11232.6 11236.2 11297.9 1998.3 2038.5 2123.9 2148.1 2171.7 86372.0 87629.1 88936.6 89293.1 89888.6 11497.5 11512.7 11649.8 11704.4 11801.5 16309.8 16346.1 16382.7 16417.3 16463.5 8651.7 8716.0 8931.9 8976.1 9106.7 8635.9 8658.6 8674.8 8866.6 8892.8 4991.5 5034.8 5050.1 5052.5 5147.4 1853.6 1977.7 2032.9 2121.4 2141.7 921.9 990.9 1024.9 1041.7 1062.4 19643.6 20109.3 20232.7 20251.8 20368.8 1020.3 1044.5 1059.8 1087.6 1094.5 10431.6 10954.1 11222.0 11265.5 11298.0 509299.0 512014.0 513151.0 528798.0 600729.0 63799.5 82579.0 99565.5 101076.9 102983.0 937.5 976.4 1024.3 1025.8 1036.2 17607.3 18089.0 18126.6 18213.3 18549.9 41279.6 45798.0 46276.3 47189.8 47699.0 53822.5 60976.7 61848.7 62017.0 62780.0 5582.4 7484.6 7685.9 8633.7 9316.9 13456.0 13688.2 14039.9 14064.9 14191.8 11609.0 11670.6 11783.9 11833.8 11843.5 11942.5 12093.5 12220.1 12238.1 12593.6 11645.9 12108.2 12129.7 12283.2 12383.6 8445.3 8536.8 8593.3 8707.3 8875.8 9109.8 10815.7 11407.7 11709.0 11840.0 17:54:03 INFO - PROCESS | 11600 | 17:54:03 INFO - PROCESS | 11600 | [#3] mootools.html Cycles:180 Average:6018.96 Median:4514.52 stddev:3455.29 (76.5%) stddev-sans-first:3448.42 17:54:03 INFO - PROCESS | 11600 | Values: 1517.6 1524.5 1684.2 1778.9 1808.0 8638.2 9301.1 9334.3 9337.2 9340.6 7986.0 8535.1 8691.7 8727.0 9096.6 2384.6 2425.5 2425.8 2430.4 2433.1 2303.9 2347.6 2392.8 2397.3 2397.7 2405.7 2437.6 2448.0 2450.6 2452.9 2308.4 2326.8 2337.3 2337.6 2345.0 2142.7 2147.5 2149.4 2150.2 2151.3 9101.1 9142.4 9276.0 9279.9 9296.2 5439.0 5445.8 5511.3 5526.6 5582.0 3307.6 3347.5 3408.0 3437.2 3446.5 1086.4 1101.7 1108.6 1135.5 1138.7 12836.7 12884.7 12890.8 12903.5 12908.4 4714.0 4716.0 4789.3 4845.4 4894.7 10778.3 10803.9 10804.4 10809.3 10820.1 5585.3 5590.5 5593.7 5597.4 5599.4 4409.0 4488.2 4987.9 5023.5 5161.4 3754.6 3878.3 3967.5 4017.8 4057.2 1250.9 1262.9 1294.7 1305.7 1309.7 1069.2 1074.8 1084.1 1087.5 1087.7 6107.4 6159.5 6280.5 6398.8 6406.4 458.3 460.5 463.6 472.0 473.1 4275.9 4289.1 4338.5 4473.5 4540.8 10957.5 11207.0 11235.9 11328.0 11332.5 11244.4 11276.1 11299.7 11317.4 11330.4 8028.8 8349.0 8350.4 8524.2 8541.9 6092.8 6164.5 6292.8 6321.7 6463.6 10245.5 10294.2 10461.5 10479.1 10645.8 10588.8 10618.3 10689.3 10738.5 10739.6 7899.2 8016.8 8120.5 8274.5 8375.0 8800.6 8827.4 8846.1 8866.2 8872.4 7879.4 7947.8 8054.0 8187.0 8268.1 7674.3 7797.0 7802.8 7947.5 8238.4 7431.6 7463.0 7492.6 7540.9 7594.9 5525.8 5575.5 5596.1 5597.1 5654.3 5633.4 5701.6 5711.1 5768.0 5787.6 17:54:03 INFO - PROCESS | 11600 | 17:54:03 INFO - PROCESS | 11600 | [#4] prototype.html Cycles:180 Average:4756.58 Median:5193.06 stddev:1381.34 (26.6%) stddev-sans-first:1344.76 17:54:03 INFO - PROCESS | 11600 | Values: 335.4 352.0 353.1 354.7 355.4 5589.4 5599.0 5621.9 5628.4 5673.0 5227.1 5376.9 5380.8 5394.8 5545.3 4258.2 4280.4 4358.0 4381.6 4424.5 4415.1 4420.2 4420.7 4422.7 4425.4 4144.3 4347.9 4353.8 4368.1 4378.7 4329.7 4340.5 4345.0 4346.5 4350.8 3866.9 3871.7 3872.8 3875.5 3879.8 5196.5 5280.4 5391.9 5400.7 5421.3 4939.8 4950.3 4956.2 4976.7 4978.5 5763.6 5772.5 5776.0 5783.5 5783.7 4112.2 4171.5 4195.3 4218.9 4221.5 5950.4 5956.4 5958.2 5989.3 6013.7 4260.4 4307.0 4310.2 4312.8 4317.6 4216.6 4316.9 4337.7 4358.8 4364.2 4927.5 4927.7 4935.8 4943.9 4968.9 5833.7 5841.0 5859.5 5860.4 5869.3 1405.8 1410.0 1452.8 1470.4 1511.3 991.8 1008.5 1012.2 1012.3 1016.5 4793.0 5000.3 5001.4 5017.4 5031.4 5211.7 5235.6 5240.5 5240.8 5243.7 4798.9 4991.9 5001.4 5005.6 5013.9 2725.3 2751.9 2758.6 2794.7 2799.9 5307.9 5333.5 5353.6 5383.3 5435.5 4957.9 5226.0 5230.0 5255.6 5292.2 5396.8 5404.6 5415.6 5424.8 5425.3 5091.5 5155.4 5163.5 5192.9 5193.2 5134.6 5162.0 5564.3 5976.6 5999.7 6226.2 6251.3 6266.5 6272.7 6272.8 4265.6 4272.2 4289.4 4298.3 4305.6 5837.9 6038.6 6050.7 6052.9 6054.1 5638.6 5800.9 5872.8 5900.9 5912.6 5897.4 5901.2 5904.7 5914.2 5915.4 5643.7 5837.7 5850.0 5853.9 5866.3 5917.7 5937.8 5947.4 5983.3 5986.0 5700.0 5820.7 5828.1 5830.7 5845.6 17:54:03 INFO - PROCESS | 11600 | 17:54:03 INFO - PROCESS | 11600 | [#5] yui.html Cycles:180 Average:21865.98 Median:4941.79 stddev:31950.99 (646.5%) stddev-sans-first:32001.44 17:54:03 INFO - PROCESS | 11600 | Values: 790.3 790.8 795.7 810.1 840.5 15769.1 16030.7 16057.6 16105.4 16197.3 14777.2 14906.0 14922.8 14923.6 14936.0 724.6 862.5 862.6 878.2 890.4 879.3 887.0 888.0 888.1 888.6 931.0 934.3 934.3 934.8 935.3 932.7 933.4 933.5 933.6 934.2 682.1 683.2 683.7 684.2 685.0 14326.0 14342.7 14362.6 14380.4 14405.4 14015.9 14426.1 14692.3 15560.8 16279.7 13828.4 14362.2 14479.4 14487.5 14522.6 4812.7 4885.1 4888.6 4995.0 5024.0 88912.0 89187.6 89270.6 91499.0 92165.1 11834.2 11855.0 11906.3 12082.9 12119.4 21812.2 21861.3 21986.6 22008.4 22015.8 8464.7 8591.9 8682.2 8753.4 8771.6 5064.4 5090.3 5118.5 5212.5 5271.4 3359.7 3469.4 3469.8 3470.4 3473.5 625.6 643.1 643.6 645.7 661.2 591.1 603.7 687.5 723.3 761.0 9277.5 9794.2 10284.1 10307.0 10370.3 700.1 700.7 719.8 729.6 772.4 5034.9 5035.2 5163.3 5199.7 5259.7 74917.0 104066.0 111129.4 134496.0 145651.3 67121.7 68979.7 86864.1 99957.5 112962.0 97451.0 98714.0 100011.0 100811.5 103624.0 9691.6 9808.9 9946.4 10123.5 10137.7 58108.1 58553.7 59361.4 59484.0 59606.5 77798.2 83594.7 86983.0 89268.0 90485.5 11839.6 11905.9 12033.6 12144.8 12266.4 13272.4 13420.6 13490.2 13566.1 13649.5 12850.6 12985.4 13196.7 13433.5 13842.2 12685.8 12946.4 13038.2 13076.3 13148.9 13176.7 13206.5 13268.5 13906.4 14343.0 11063.9 11073.9 11195.2 11371.9 11757.2 11347.8 11350.5 11459.5 11486.9 11518.3 17:54:03 INFO - PROCESS | 11600 | -------- Summary: end -------- 17:54:03 INFO - PROCESS | 11600 | 17:54:04 INFO - TEST-INFO | 11600: exit 0 17:54:04 INFO - TEST-OK | dromaeo_css | took 1112465ms 17:54:04 INFO - TEST-START | kraken 17:54:04 INFO - operating with platform_type : linux_ 17:54:04 INFO - Initialising browser for kraken test... 17:54:04 INFO - TEST-INFO | started process 11791 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdDgGto/profile http://localhost:34152/getInfo.html) 17:54:08 INFO - PROCESS | 11791 | __metrics Screen width/height:1600/1200 17:54:08 INFO - PROCESS | 11791 | colorDepth:24 17:54:08 INFO - PROCESS | 11791 | Browser inner width/height: 1024/697 17:54:08 INFO - PROCESS | 11791 | __metrics 17:54:09 INFO - PROCESS | 11791 | 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" 17:54:09 INFO - PROCESS | 11791 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:54:10 INFO - TEST-INFO | 11791: exit 0 17:54:10 INFO - Browser initialized. 17:54:10 INFO - Running cycle 1/1 for kraken test... 17:54:10 INFO - TEST-INFO | started process 11879 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdDgGto/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:54:21 INFO - PROCESS | 11879 | RSS: Main: 143876096 17:54:21 INFO - PROCESS | 11879 | 17:54:24 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34152/tests/kraken/driver.html?testName=audio-beat-detection) 17:54:25 INFO - PROCESS | 11879 | RSS: Main: 198500352 17:54:25 INFO - PROCESS | 11879 | 17:54:27 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34152/tests/kraken/driver.html?testName=audio-dft) 17:54:28 INFO - PROCESS | 11879 | RSS: Main: 182824960 17:54:28 INFO - PROCESS | 11879 | 17:54:31 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34152/tests/kraken/driver.html?testName=audio-fft) 17:54:31 INFO - PROCESS | 11879 | RSS: Main: 274661376 17:54:31 INFO - PROCESS | 11879 | 17:54:33 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34152/tests/kraken/driver.html?testName=audio-oscillator) 17:54:34 INFO - PROCESS | 11879 | RSS: Main: 180137984 17:54:34 INFO - PROCESS | 11879 | 17:54:36 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34152/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:54:37 INFO - PROCESS | 11879 | RSS: Main: 179486720 17:54:37 INFO - PROCESS | 11879 | 17:54:39 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34152/tests/kraken/driver.html?testName=imaging-darkroom) 17:54:40 INFO - PROCESS | 11879 | RSS: Main: 214683648 17:54:40 INFO - PROCESS | 11879 | 17:54:43 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34152/tests/kraken/driver.html?testName=imaging-desaturate) 17:54:44 INFO - PROCESS | 11879 | RSS: Main: 213409792 17:54:44 INFO - PROCESS | 11879 | 17:54:47 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34152/tests/kraken/driver.html?testName=json-parse-financial) 17:54:47 INFO - PROCESS | 11879 | RSS: Main: 213622784 17:54:47 INFO - PROCESS | 11879 | 17:54:49 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34152/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:54:49 INFO - PROCESS | 11879 | RSS: Main: 175767552 17:54:49 INFO - PROCESS | 11879 | 17:54:51 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34152/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:54:52 INFO - PROCESS | 11879 | RSS: Main: 182722560 17:54:52 INFO - PROCESS | 11879 | 17:54:54 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34152/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:54:54 INFO - PROCESS | 11879 | RSS: Main: 189669376 17:54:54 INFO - PROCESS | 11879 | 17:54:57 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34152/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:54:58 INFO - PROCESS | 11879 | RSS: Main: 189399040 17:54:58 INFO - PROCESS | 11879 | 17:55:00 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34152/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:55:01 INFO - PROCESS | 11879 | RSS: Main: 192577536 17:55:01 INFO - PROCESS | 11879 | 17:55:02 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:34152/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34152/tests/kraken/driver.html?testName=ai-astar) 17:55:03 INFO - PROCESS | 11879 | RSS: Main: 242585600 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | __start_tp_report 17:55:03 INFO - PROCESS | 11879 | _x_x_mozilla_page_load 17:55:03 INFO - PROCESS | 11879 | _x_x_mozilla_page_load_details 17:55:03 INFO - PROCESS | 11879 | |i|pagename|runs| 17:55:03 INFO - PROCESS | 11879 | |0;ai-astar;109;109;119;112;109;109;109;112;111;110 17:55:03 INFO - PROCESS | 11879 | |1;audio-beat-detection;147;115;141;182;118;148;150;122;183;116 17:55:03 INFO - PROCESS | 11879 | |2;audio-dft;160;160;160;161;160;161;159;160;159;160 17:55:03 INFO - PROCESS | 11879 | |3;audio-fft;73;75;74;169;75;74;75;74;172;77 17:55:03 INFO - PROCESS | 11879 | |4;audio-oscillator;83;84;143;83;84;82;148;83;84;151 17:55:03 INFO - PROCESS | 11879 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;117 17:55:03 INFO - PROCESS | 11879 | |6;imaging-darkroom;145;146;147;146;147;146;147;146;145;146 17:55:03 INFO - PROCESS | 11879 | |7;imaging-desaturate;95;95;95;95;94;95;95;95;95;95 17:55:03 INFO - PROCESS | 11879 | |8;json-parse-financial;50;48;50;49;49;49;53;50;49;52 17:55:03 INFO - PROCESS | 11879 | |9;json-stringify-tinderbox;60;61;60;60;61;60;60;61;60;61 17:55:03 INFO - PROCESS | 11879 | |10;stanford-crypto-aes;73;72;73;81;77;73;74;73;73;73 17:55:03 INFO - PROCESS | 11879 | |11;stanford-crypto-ccm;128;119;119;117;118;126;117;117;161;117 17:55:03 INFO - PROCESS | 11879 | |12;stanford-crypto-pbkdf2;153;171;151;153;153;157;157;156;152;150 17:55:03 INFO - PROCESS | 11879 | |13;stanford-crypto-sha256-iterative;66;62;63;61;62;63;61;62;61;63 17:55:03 INFO - PROCESS | 11879 | __end_tp_report 17:55:03 INFO - PROCESS | 11879 | __start_cc_report 17:55:03 INFO - PROCESS | 11879 | _x_x_mozilla_cycle_collect,1689 17:55:03 INFO - PROCESS | 11879 | __end_cc_report 17:55:03 INFO - PROCESS | 11879 | __startTimestamp1477356903174__endTimestamp 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | ------- Summary: start ------- 17:55:03 INFO - PROCESS | 11879 | Number of tests: 14 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#0] ai-astar Cycles:10 Average:110.90 Median:110.50 stddev:3.11 (2.8%) stddev-sans-first:3.22 17:55:03 INFO - PROCESS | 11879 | Values: 109.0 109.0 119.0 112.0 109.0 109.0 109.0 112.0 111.0 110.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:147.50 stddev:25.36 (17.2%) stddev-sans-first:26.84 17:55:03 INFO - PROCESS | 11879 | Values: 147.0 115.0 141.0 182.0 118.0 148.0 150.0 122.0 183.0 116.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 17:55:03 INFO - PROCESS | 11879 | Values: 160.0 160.0 160.0 161.0 160.0 161.0 159.0 160.0 159.0 160.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#3] audio-fft Cycles:10 Average:93.80 Median:74.50 stddev:40.44 (54.3%) stddev-sans-first:42.19 17:55:03 INFO - PROCESS | 11879 | Values: 73.0 75.0 74.0 169.0 75.0 74.0 75.0 74.0 172.0 77.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:83.00 stddev:31.00 (37.4%) stddev-sans-first:32.07 17:55:03 INFO - PROCESS | 11879 | Values: 83.0 84.0 143.0 83.0 84.0 82.0 148.0 83.0 84.0 151.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 17:55:03 INFO - PROCESS | 11879 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 17:55:03 INFO - PROCESS | 11879 | Values: 145.0 146.0 147.0 146.0 147.0 146.0 147.0 146.0 145.0 146.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 17:55:03 INFO - PROCESS | 11879 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.00 stddev:1.52 (3.0%) stddev-sans-first:1.62 17:55:03 INFO - PROCESS | 11879 | Values: 50.0 48.0 50.0 49.0 49.0 49.0 53.0 50.0 49.0 52.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 17:55:03 INFO - PROCESS | 11879 | Values: 60.0 61.0 60.0 60.0 61.0 60.0 60.0 61.0 60.0 61.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:73.00 stddev:2.74 (3.8%) stddev-sans-first:2.87 17:55:03 INFO - PROCESS | 11879 | Values: 73.0 72.0 73.0 81.0 77.0 73.0 74.0 73.0 73.0 73.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:119.00 stddev:13.63 (11.5%) stddev-sans-first:14.37 17:55:03 INFO - PROCESS | 11879 | Values: 128.0 119.0 119.0 117.0 118.0 126.0 117.0 117.0 161.0 117.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.30 Median:154.50 stddev:6.02 (3.9%) stddev-sans-first:6.33 17:55:03 INFO - PROCESS | 11879 | Values: 153.0 171.0 151.0 153.0 153.0 157.0 157.0 156.0 152.0 150.0 17:55:03 INFO - PROCESS | 11879 | 17:55:03 INFO - PROCESS | 11879 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:62.50 stddev:1.51 (2.4%) stddev-sans-first:0.87 17:55:03 INFO - PROCESS | 11879 | Values: 66.0 62.0 63.0 61.0 62.0 63.0 61.0 62.0 61.0 63.0 17:55:03 INFO - PROCESS | 11879 | -------- Summary: end -------- 17:55:03 INFO - PROCESS | 11879 | 17:55:04 INFO - TEST-INFO | 11879: exit 0 17:55:04 INFO - TEST-OK | kraken | took 59970ms 17:55:04 INFO - SUITE-END | took 1172s 17:55:04 INFO - Completed test suite (00:19:32) 17:55:04 INFO - javascript benchmark 17:55:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3211.646718860925, 3232.1111694284077, 3244.756643251755, 3262.1355340834666, 3276.803391796492, 5526.474984876435, 5577.442255774431, 5584.553235741141, 5588.748506317215, 5589.944100558998, 5521.089020311654, 5582.665040097587, 5585.245991791107, 5603.187537807015, 5621.4659607337235, 5060.772265248063, 5083.923741143886, 5126.179811230204, 5134.948650513485, 5150.665206761549, 5042.520960508764, 5140.66585671932, 5159.097157997354, 5166.0, 5166.767495462695, 5006.4492905780335, 5008.123578373788, 5034.09386310464, 5052.14113600687, 5075.822346217883, 5080.034793389244, 5171.870703232412, 5198.9480105198845, 5204.505571970657, 5229.294045303911, 4917.139500587418, 5094.694318340874, 5108.361454818148, 5136.64043516954, 5169.483051694838, 5431.972840135774, 5440.9934162180125, 5441.673499590037, 5442.9727851360885, 5485.341758988907, 5848.64908105515, 5879.2944846618675, 5881.0, 5882.617629854046, 5886.3230728465915, 5779.335376431723, 5881.117832325142, 5913.2608423947, 5921.200637913913, 5926.525877929756, 5512.366077901052, 5577.9163312550345, 5595.496405323541, 5602.243697100921, 5612.15817627355, 6088.593123624011, 6091.908621370683, 6095.024796032702, 6097.237845269341, 6100.359462256488, 5713.600047996601, 5725.828225153252, 5772.191893134965, 5868.119782032729, 5883.61756485827, 5975.880482390415, 5984.521238300926, 5985.191999080069, 6018.458338749533, 6027.517798576191, 5944.227250457413, 5955.404459554096, 5960.165576819162, 5964.373740757158, 5969.403059693996, 5675.148727690879, 5768.0, 5774.88450230993, 5965.910511342333, 5972.940270597239, 4525.321201819754, 4530.932036019462, 4534.319852022157, 4535.63714902807, 4538.432695913011, 4019.718619696593, 4074.022234663719, 4077.918441631151, 4081.2653722329674, 4103.281925662997, 5706.201131841545, 5714.79998200061, 5715.057015592381, 5723.3418156912485, 5740.799072032459, 6023.548233882477, 6023.909641355383, 6029.035354343372, 6034.728437220336, 6035.909461358083, 5500.80747173847, 5529.447055294519, 5585.609007369527, 5624.100143976944, 5703.401142879938, 5000.374953130859, 5003.524665156804, 5004.049230646238, 5022.773975171126, 5039.5212454817465, 6069.51443884497, 6094.786682466092, 6094.878102437927, 6135.386461353918, 6138.355472675305, 5874.618149820248, 5880.176775251553, 5928.229330187048, 5932.2584676915385, 5947.286325640951, 6154.5384096192065, 6155.569110162246, 6158.568900176944, 6165.198524191826, 6171.47542458896, 5887.381824908494, 5889.734961926809, 5892.882142357043, 5893.793717219876, 5922.289325280994, 5804.2744656917885, 5887.58786884931, 5903.291605007426, 5917.171595976481, 5979.730912108878, 5782.479576837936, 5810.477057064715, 5825.446582574648, 5839.970800145972, 5842.2405087338375, 5816.563757718272, 5821.738021789114, 5829.621074630136, 5832.941670583409, 5836.503897168788, 5626.831195064155, 5674.347450043133, 5678.744456499385, 5707.68607726579, 5724.255846739898, 5842.3281322646735, 5843.474087332161, 5849.766009359578, 5853.970730146492, 5873.089671100986, 5713.428657134254, 5714.257146570919, 5716.28546431696, 5717.714114294352, 5747.712614369181], "value": 5481.589703163755, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.3796420704568, 623.2552100240214, 652.6932341799355, 663.6117871045439, 672.4116398151617, 34209.65790342096, 37983.43024854626, 46356.0, 47080.0, 47502.28746568796, 49383.50616493834, 49815.25277120846, 49931.50068499314, 50003.74998125005, 50048.24927626088, 8506.872396914021, 9521.761955951088, 9674.854877176847, 9681.951590242039, 9687.128158465737, 10811.513481893295, 11239.550417983311, 11244.0, 11599.420028998516, 11599.594014209462, 11340.376279304635, 11446.599369022086, 11459.312441253509, 11466.197366184371, 11468.311901285908, 11537.884621153766, 11567.884321156766, 11578.305301681841, 11600.535978560847, 11607.303561786277, 11359.034482069033, 11439.542418303257, 11447.08423326132, 11447.484863181093, 11449.427528623619, 47263.5273647266, 47617.52382476166, 47692.0, 47701.76149119267, 47744.28383574249, 3776.622337766229, 4015.4779878615886, 4077.143799802045, 4091.40674602184, 4268.487781466244, 32744.508832367534, 32790.0, 33157.50263746046, 33506.0, 33506.6649333506, 4641.559051890065, 4705.270683044134, 4811.639127065449, 4840.75796210188, 4900.950990490085, 374421.0, 375195.0, 375363.0, 375521.0, 377103.0, 67425.0, 68007.0, 68112.0, 68161.0, 68345.31654683489, 40773.0, 41756.791216043726, 41809.58190418118, 41948.58051419508, 70089.64955175192, 19621.0, 20114.69827952582, 20181.697274540893, 20193.293234737008, 20205.090770915343, 13247.20516768997, 13295.135816171918, 13425.39585718645, 14569.708605827824, 14719.852801472063, 20893.26873559418, 21119.155233790785, 21151.365459036253, 21204.36386908395, 21288.574228515343, 7437.367823735043, 7648.464607477423, 7694.307512323918, 7725.49784264021, 7806.41451891099, 1049.5801679328329, 1053.931494452858, 1102.4763237462141, 1118.703543560957, 1162.105179012156, 34189.65810341915, 34871.12822179466, 35059.64940350564, 35088.47367289493, 35567.64432355644, 1168.39243593331, 1225.1179151011258, 1249.2941488059218, 1251.361805479212, 1299.5451591942744, 17273.031624918018, 18397.54006149857, 18513.259469621335, 18545.25818967253, 18609.81390186108, 663814.0, 663941.0, 664754.0, 667085.0, 669112.0, 65572.34427655757, 66110.0, 66117.33882661207, 66190.0, 66226.66886665535, 1309.306067784076, 1373.8282714660668, 1374.6082366525693, 1424.7079348733484, 1450.6155868694805, 31241.37517249688, 36583.0, 36607.81696091503, 36711.0, 36746.0, 49167.7541612304, 49909.25136122888, 49921.0, 49982.0, 50039.749801250764, 418742.0, 419750.0, 419959.0, 419963.0, 420298.0, 26947.595786063615, 27855.0, 27925.720742792313, 27998.86000569984, 32431.0, 52011.73994130005, 52669.73665131803, 52682.47317526929, 52712.73643631757, 52931.470685292654, 44316.778416107714, 44696.776516117214, 45184.77407612941, 46274.30588541242, 46671.0, 43984.0, 44682.3297650529, 44839.0, 45012.77493612511, 46249.76875115603, 43404.348934765374, 44124.33813492864, 44431.33352999772, 44483.33275000813, 44702.55297447114, 36926.63073369232, 39076.0, 39372.40941385939, 39499.80250098731, 40135.79932100321, 29806.25484362908, 30062.014659810382, 30190.54714179333, 30217.395652086394, 30376.240593985327], "value": 22048.58784933492, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.95768944260834, 410.40696193999685, 414.815407143821, 426.9060806622543, 432.6063282413005, 19516.804831951675, 20522.2817201398, 21170.57658846826, 21291.68062479062, 21545.67681484779, 19245.903770481164, 19773.50566235845, 19812.70280945787, 19893.3037343693, 19962.800371996276, 5615.859603509904, 5882.088276317178, 5888.793892213774, 5896.027155519334, 5896.587238893279, 5625.831225063234, 5801.24583804107, 5814.622049566786, 5824.679642619654, 5885.20549725788, 5841.299044114712, 5858.472737453629, 5862.208601838761, 5864.824055278327, 5870.26621672291, 5780.0751879699255, 5829.854253643672, 5840.503557197643, 5844.473997340238, 5852.063669812852, 5187.170052791573, 5306.7611957462, 5309.495597918191, 5311.309529761106, 5311.309529761145, 21720.239791607375, 22451.32646020622, 24336.87831560831, 24344.634830477557, 24548.63177052328, 2491.638712386712, 2557.1944837376354, 2639.973600263992, 2792.3577577157366, 2809.3117186289287, 9977.401355918668, 10390.584376624925, 11232.550697972072, 11236.157288203418, 11297.887021129765, 1998.2506560039985, 2038.5413282011577, 2123.9150433982777, 2148.130007347027, 2171.695962565226, 86372.0027733853, 87629.12370876274, 88936.55531722364, 89293.10706892912, 89888.55055724745, 11497.540098396137, 11512.654620361403, 11649.76700465986, 11704.356260405759, 11801.52793888252, 16309.83690163107, 16346.100964446832, 16382.672346553241, 16417.343306267856, 16463.506094817174, 8651.6971905983, 8716.0, 8931.866022009675, 8976.05751396107, 9106.726798196065, 8635.870461943075, 8658.567071646392, 8674.826503469896, 8866.645334186689, 8892.84304072058, 4991.450940396553, 5034.773435195425, 5050.090983622983, 5052.545270925634, 5147.408048074444, 1853.6292741451764, 1977.6637971544872, 2032.949176270605, 2121.384798408475, 2141.689455029012, 921.8847644044495, 990.9405435673883, 1024.8667673202435, 1041.6979076068008, 1062.3944351719447, 19643.64232322035, 20109.29617463382, 20232.69650955237, 20251.797482025286, 20368.79631203669, 1020.2705065877933, 1044.5038606661774, 1059.8251288537306, 1087.581281206741, 1094.5020015892749, 10431.582736690447, 10954.123670106372, 11222.0, 11265.54937802495, 11298.0, 509299.0, 512014.0, 513151.0, 528798.0, 600729.0, 63799.47125048223, 82579.0, 99565.50217249012, 101076.94615269206, 102983.0, 937.5265490927038, 976.4287891583361, 1024.262530977695, 1025.779457416659, 1036.212478516318, 17607.29570817153, 18089.0, 18126.63746725032, 18213.271469141087, 18549.90725046366, 41279.58720412757, 45798.0, 46276.305855412866, 47189.764051179525, 47699.0, 53822.46177538331, 60976.69511652413, 61848.690756545926, 62017.0, 62780.0, 5582.4138465462165, 7484.588347640932, 7685.884711729217, 8633.654653813777, 9316.860247096163, 13456.0, 13688.247146407044, 14039.929800350932, 14064.859351406354, 14191.787123192953, 11609.013233875216, 11670.649880503599, 11783.941080294544, 11833.763324733285, 11843.467043983213, 11942.522299107939, 12093.455794489446, 12220.083493738182, 12238.143329966817, 12593.622191334274, 11645.88354116448, 12108.21296615717, 12129.69675758113, 12283.201591896499, 12383.566575169825, 8445.324374050184, 8536.786580335542, 8593.269572086443, 8707.303415726854, 8875.822483550164, 9109.77225569366, 10815.72960675958, 11407.657770266906, 11709.0, 11840.0], "value": 9563.804776914718, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1517.6357674158198, 1524.451197568875, 1684.1579210394802, 1778.9110544472774, 1808.0417378789239, 8638.179372959574, 9301.069893010705, 9334.29992750543, 9337.206337461315, 9340.626374944994, 7986.0, 8535.146485351463, 8691.652333906635, 8726.95636521818, 9096.63613455461, 2384.570777260088, 2425.514897020594, 2425.818063645225, 2430.44099857033, 2433.0875921529428, 2303.8617682938993, 2347.58917189492, 2392.8085753139794, 2397.3287479505766, 2397.736249012607, 2405.6872606561124, 2437.6343548467785, 2447.951040979179, 2450.620153876152, 2452.889619967106, 2308.365199570115, 2326.8371214015024, 2337.345543247885, 2337.614293641556, 2344.9531009379716, 2142.6571748520164, 2147.527543940331, 2149.3874245840016, 2150.2044243629803, 2151.3223334649533, 9101.089891010846, 9142.360034797566, 9275.953620231923, 9279.907200927972, 9296.209822165125, 5438.972805135989, 5445.83662490122, 5511.283533140666, 5526.640768350085, 5582.0, 3307.6196237432764, 3347.548081009057, 3408.0, 3437.1922598189403, 3446.5347178130883, 1086.3590481615806, 1101.6694991502598, 1108.5842808946645, 1135.5230803062734, 1138.7437826489056, 12836.743265134644, 12884.742305153939, 12890.80663790044, 12903.548375806895, 12908.35458227085, 4714.0, 4715.976420117878, 4789.329503705878, 4845.370101886732, 4894.706317620954, 10778.29941053829, 10803.865594112662, 10804.405757683402, 10809.297395669257, 10820.080293175011, 5585.301837270341, 5590.5248053915875, 5593.692346920877, 5597.412271711493, 5599.356074051455, 4408.955910440919, 4488.214562451558, 4987.850364489072, 5023.497650234947, 5161.354830646169, 3754.605766394544, 3878.301905657009, 3967.504061992251, 4017.8392864285684, 4057.229126465962, 1250.8749125087418, 1262.8989680825512, 1294.7475242327655, 1305.7062161013696, 1309.7380523895258, 1069.1820757120809, 1074.8441475985937, 1084.0568705226347, 1087.472575800736, 1087.7280679830042, 6107.4197951194565, 6159.5072394208355, 6280.497560195174, 6398.808035758934, 6406.391392817675, 458.2644855007731, 460.5417609478523, 463.5619339723996, 471.99528004719514, 473.12235802585707, 4275.914481710349, 4289.1421715657, 4338.501072376717, 4473.463184417826, 4540.818367265272, 10957.50691218897, 11207.0, 11235.887641123647, 11328.037116844996, 11332.546698131982, 11244.38155901417, 11276.097912167006, 11299.661010169708, 11317.377544235145, 11330.433478326215, 8028.799280017929, 8349.03986041601, 8350.4154709171, 8524.190201930807, 8541.914580854236, 6092.817215483543, 6164.506839452923, 6292.779752708633, 6321.715522801577, 6463.612183269019, 10245.487725613839, 10294.227932905213, 10461.47692615351, 10479.10927571165, 10645.787084258427, 10588.84116738265, 10618.256722029384, 10689.30519516229, 10738.516766745359, 10739.624113156002, 7899.249571290718, 8016.839663206587, 8120.472169308976, 8274.462159959583, 8375.0, 8800.559972001502, 8827.382083254111, 8846.071162527842, 8866.157715017063, 8872.378933474594, 7879.448438609243, 7947.761567152995, 8054.0, 8186.959065204635, 8268.131846156068, 7674.309312161932, 7796.961015195115, 7802.790567462052, 7947.5231486111015, 8238.382121341043, 7431.591262480615, 7463.0, 7492.625368731433, 7540.924590754242, 7594.8860767087435, 5525.751341189576, 5575.470330318613, 5596.076647353304, 5597.104463285855, 5654.3497497788285, 5633.436656334334, 5701.629394089371, 5711.057675483498, 5768.0, 5787.594868359174], "value": 4718.185380804376, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.42474655965015, 351.9736019798516, 353.07846520581285, 354.7481288285317, 355.42953559536943, 5589.441055894414, 5599.0, 5621.915671264929, 5628.409017053212, 5673.0, 5227.111391063519, 5376.946230537694, 5380.843118729473, 5394.838154855361, 5545.306836645419, 4258.190943720683, 4280.379344994982, 4357.99298484045, 4381.605655491006, 4424.469063712343, 4415.139047885664, 4420.248557745191, 4420.690551661385, 4422.668299877522, 4425.424694789673, 4144.254034273829, 4347.891302717426, 4353.825846966133, 4368.126374725051, 4378.686394081778, 4329.718568293082, 4340.544242854487, 4344.956550434486, 4346.543612920661, 4350.825966961318, 3866.903327416837, 3871.6709079727975, 3872.7676339419727, 3875.5155605549307, 3879.8060096995036, 5196.480351964811, 5280.445553216895, 5391.894661594364, 5400.675959442407, 5421.340595098293, 4939.827106051307, 4950.257461380786, 4956.157453232959, 4976.726280054596, 4978.477259887732, 5763.567732420043, 5772.451617096369, 5775.971120144414, 5783.5084017858535, 5783.739731712041, 4112.157007813421, 4171.541130475645, 4195.328747400432, 4218.936715949232, 4221.514525829508, 5950.404959504015, 5956.434138756811, 5958.165856780054, 5989.31122920861, 6013.669248191348, 4260.4248426462655, 4306.978465107654, 4310.159518893848, 4312.805923733439, 4317.611414972667, 4216.620504154641, 4316.913661726748, 4337.7180483268485, 4358.8038538265855, 4364.192624364439, 4927.457979622239, 4927.679700819435, 4935.8025678972435, 4943.9258411123865, 4968.900621987612, 5833.7374818133285, 5841.0, 5859.472647461749, 5860.413958604106, 5869.325027621793, 1405.8172437583048, 1409.9647508812157, 1452.847451017628, 1470.353044660346, 1511.2896938438917, 991.8115558043949, 1008.5461542305992, 1012.1851909213154, 1012.2711647613706, 1016.5120742043861, 4792.95207047932, 5000.349954505891, 5001.399272378343, 5017.37282839645, 5031.4465408805, 5211.687298762086, 5235.560220939272, 5240.475952404805, 5240.842774716764, 5243.711595862188, 4798.904021919612, 4991.850244492671, 5001.44984051754, 5005.599552035828, 5013.919223522197, 2725.318670332417, 2751.9036833710725, 2758.558630619091, 2794.7065558116105, 2799.9160025199276, 5307.867303317371, 5333.466653334713, 5353.625246232727, 5383.300170977826, 5435.510804027656, 4957.925631115536, 5226.0, 5229.973850130725, 5255.55616638265, 5292.153255479105, 5396.784128634889, 5404.567634589223, 5415.647982881179, 5424.837254882281, 5425.294711687456, 5091.541761241506, 5155.43290238081, 5163.4578369270685, 5192.896876764917, 5193.221016847503, 5134.640575159703, 5161.974190129025, 5564.275074726847, 5976.62537616342, 5999.670018149044, 6226.221722284715, 6251.312145720539, 6266.498680105672, 6272.655003974825, 6272.843178920563, 4265.637420819266, 4272.188284225987, 4289.378040184093, 4298.333758267474, 4305.56944305567, 5837.912431313449, 6038.63768173911, 6050.667213303134, 6052.939470605237, 6054.122152287981, 5638.605297629127, 5800.912986305125, 5872.794452194153, 5900.940990590211, 5912.5861189718025, 5897.439743224387, 5901.173835662925, 5904.704764761831, 5914.231149950651, 5915.438033386821, 5643.701948551939, 5837.708114594168, 5849.970750146222, 5853.853653658693, 5866.2960444746905, 5917.6745279010065, 5937.821865344046, 5947.405259474018, 5983.282006159288, 5986.0, 5700.0, 5820.679862607597, 5828.0966450200285, 5830.708464576669, 5845.620034697731], "value": 4321.821670528123, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.257158271225, 790.794393457701, 795.7214974758831, 810.1291112054543, 840.4831028917216, 15769.132697701622, 16030.67938641228, 16057.598560035976, 16105.436309729163, 16197.271122799473, 14777.187254701013, 14906.031107977973, 14922.776158357634, 14923.626909327273, 14936.029158104715, 724.583364565377, 862.4997501449176, 862.573026351955, 878.2052242720316, 890.3900827932854, 879.2745984562729, 887.0198430734043, 888.0276097673037, 888.1296329596998, 888.6489836514573, 931.0270767048451, 934.2525979216599, 934.2525979216599, 934.8317302885478, 935.2985261054209, 932.7481579973437, 933.444600462727, 933.4632586262858, 933.6358820060148, 934.2012579244749, 682.0791930893255, 683.2143035509155, 683.702589373624, 684.1789852177407, 685.0238410265401, 14326.0, 14342.71314573713, 14362.569122926328, 14380.424783008668, 14405.351759170862, 14015.859841401558, 14426.134431934013, 14692.338844752012, 15560.766588501181, 16279.674406511804, 13828.446862125504, 14362.210078445682, 14479.42082316706, 14487.492937747234, 14522.564323070324, 4812.711237325771, 4885.120678277908, 4888.584470320027, 4995.0, 5023.974880125613, 88912.0, 89187.55406222928, 89270.553647232, 91499.0, 92165.07834921699, 11834.17160798753, 11855.02428408135, 11906.345151016778, 12082.939585302018, 12119.39403029845, 21812.236571719914, 21861.344159675235, 21986.560268794536, 22008.44978875541, 22015.779842201373, 8464.746057618282, 8591.914080859236, 8682.175193356621, 8753.431026983226, 8771.649134034567, 5064.366954130734, 5090.338256026693, 5118.462561431071, 5212.530872221442, 5271.367435907716, 3359.7480188985924, 3469.37551240774, 3469.7918124912585, 3470.444728843373, 3473.4616134499192, 625.5714835337808, 643.06755204953, 643.6106155775782, 645.7094307561522, 661.1933441201726, 591.1487458060379, 603.6528995827417, 687.4603436302579, 723.2658851265969, 760.9771706848803, 9277.469217579159, 9794.16690616105, 10284.125849302893, 10307.0, 10370.266913411568, 700.1353328639127, 700.6671830880393, 719.8380364417964, 729.6424751871544, 772.3705180278056, 5034.89930201394, 5035.219540971156, 5163.277141200235, 5199.66202196856, 5259.684418934799, 74917.0, 104066.0, 111129.44435277772, 134496.0, 145651.2717436406, 67121.66439167772, 68979.65510172417, 86864.13135868686, 99957.50021249847, 112962.0, 97451.0, 98714.0, 100010.99999999854, 100811.49594251982, 103624.0, 9691.563879625293, 9808.852867206855, 9946.403215807075, 10123.493825308558, 10137.695869123938, 58108.12837807352, 58553.70723146357, 59361.40638593559, 59484.0, 59606.509837254416, 77798.22201778136, 83594.74607881009, 86983.0, 89268.0, 90485.54757226171, 11839.644810655695, 11905.940470297593, 12033.63899083029, 12144.817827732768, 12266.38668066611, 13272.402741876445, 13420.597382078162, 13490.190588564717, 13566.118202316818, 13649.522266720616, 12850.614481565568, 12985.350732463528, 13196.736065278832, 13433.529826456028, 13842.169469831842, 12685.8097128545, 12946.352682365656, 13038.152520086163, 13076.280804555841, 13148.868511315148, 13176.736465270833, 13206.471741130246, 13268.46926122983, 13906.443742250196, 14342.99599028058, 11063.944680276547, 11073.889261107286, 11195.216334856483, 11371.943140284246, 11757.235779674294, 11347.773044539228, 11350.489227984926, 11459.48432320531, 11486.94256528712, 11518.251313664585], "value": 7880.920789144434, "unit": "score"}], "name": "dromaeo_css", "value": 7553.773692987503, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 119.0, 112.0, 109.0, 109.0, 109.0, 112.0, 111.0, 110.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 141.0, 182.0, 118.0, 148.0, 150.0, 122.0, 183.0, 116.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 161.0, 160.0, 161.0, 159.0, 160.0, 159.0, 160.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 169.0, 75.0, 74.0, 75.0, 74.0, 172.0, 77.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 143.0, 83.0, 84.0, 82.0, 148.0, 83.0, 84.0, 151.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 145.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 50.0, 49.0, 49.0, 49.0, 53.0, 50.0, 49.0, 52.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 61.0, 60.0, 60.0, 61.0, 60.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 73.0, 81.0, 77.0, 73.0, 74.0, 73.0, 73.0, 73.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 119.0, 119.0, 117.0, 118.0, 126.0, 117.0, 117.0, 161.0, 117.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 171.0, 151.0, 153.0, 153.0, 157.0, 157.0, 156.0, 152.0, 150.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 63.0, 61.0, 62.0, 63.0, 61.0, 62.0, 61.0, 63.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1493.4000000000003, "alertThreshold": 2.0}]} 17:55:04 INFO - Return code: 0 17:55:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:55:04 INFO - # TBPL SUCCESS # 17:55:04 INFO - Running post-action listener: _resource_record_post_action 17:55:04 INFO - [mozharness: 2016-10-25 00:55:04.472810Z] Finished run-tests step (success) 17:55:04 INFO - Running post-run listener: _resource_record_post_run 17:55:04 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 1740800; Write bytes: 289746944; Read time: 60; Write time: 226416 17:55:04 INFO - TinderboxPrint: CPU usage
12.9% 17:55:04 INFO - TinderboxPrint: I/O read bytes / time
1,740,800 / 60 17:55:04 INFO - TinderboxPrint: I/O write bytes / time
289,746,944 / 226,416 17:55:04 INFO - TinderboxPrint: CPU idle
8,248.4 (87.0%) 17:55:04 INFO - TinderboxPrint: CPU user
1,182.1 (12.5%) 17:55:04 INFO - TinderboxPrint: Swap in / out
0 / 0 17:55:04 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71290880; Read time: 0; Write time: 46532 17:55:04 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 218456064; Read time: 24; Write time: 179884 17:55:05 INFO - Running post-run listener: _upload_blobber_files 17:55:05 INFO - Blob upload gear active. 17:55:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:55:05 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 17:55:05 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'] 17:55:05 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 17:55:05 INFO - (blobuploader) - INFO - Open directory for files ... 17:55:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:55:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:55:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:08 INFO - (blobuploader) - INFO - Done attempting. 17:55:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:55:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:55:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:09 INFO - (blobuploader) - INFO - Done attempting. 17:55:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:55:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:55:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:10 INFO - (blobuploader) - INFO - Done attempting. 17:55:10 INFO - (blobuploader) - INFO - Iteration through files over. 17:55:10 INFO - Return code: 0 17:55:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:55:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:55:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d86a07cf1ca481636e8d420b17fdebab4ad50241323bf64113a40d0a8c2182ac1bf251e4525fae28bb7a2a8f24a4c1d19b94a625622682cb4552e38a5503e372", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f268f2a06f0fa465b196fb4b175de7c31b9aab855af1741bbc4634424d658021f0a6ee78f62257370c8ce653218080cbfcb4fc876ccf6350c40caade02a39d60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/205e9f788d6edd0a2c4eb81b005b47cc698a06d4d5509d8b872a60a6b6c7a0031b89598daf04f2cdbeb634fb1c4f226f9ac138f0ee359fae3a4e105bb949d43d"} 17:55:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:55:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:55:10 INFO - Contents: 17:55:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d86a07cf1ca481636e8d420b17fdebab4ad50241323bf64113a40d0a8c2182ac1bf251e4525fae28bb7a2a8f24a4c1d19b94a625622682cb4552e38a5503e372", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f268f2a06f0fa465b196fb4b175de7c31b9aab855af1741bbc4634424d658021f0a6ee78f62257370c8ce653218080cbfcb4fc876ccf6350c40caade02a39d60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/205e9f788d6edd0a2c4eb81b005b47cc698a06d4d5509d8b872a60a6b6c7a0031b89598daf04f2cdbeb634fb1c4f226f9ac138f0ee359fae3a4e105bb949d43d"} 17:55:10 INFO - Running post-run listener: copy_logs_to_upload_dir 17:55:10 INFO - Copying logs to upload dir... 17:55:10 INFO - mkdir: /builds/slave/test/build/upload/logs 17:55:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.758427 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-24 17:55:10.631352) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 17:55:10.635259) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610 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/d86a07cf1ca481636e8d420b17fdebab4ad50241323bf64113a40d0a8c2182ac1bf251e4525fae28bb7a2a8f24a4c1d19b94a625622682cb4552e38a5503e372", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f268f2a06f0fa465b196fb4b175de7c31b9aab855af1741bbc4634424d658021f0a6ee78f62257370c8ce653218080cbfcb4fc876ccf6350c40caade02a39d60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/205e9f788d6edd0a2c4eb81b005b47cc698a06d4d5509d8b872a60a6b6c7a0031b89598daf04f2cdbeb634fb1c4f226f9ac138f0ee359fae3a4e105bb949d43d"} build_url:https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011483 build_url: 'https://queue.taskcluster.net/v1/task/K_jLoE7vQ6-qeVRkHivzDg/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/d86a07cf1ca481636e8d420b17fdebab4ad50241323bf64113a40d0a8c2182ac1bf251e4525fae28bb7a2a8f24a4c1d19b94a625622682cb4552e38a5503e372", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f268f2a06f0fa465b196fb4b175de7c31b9aab855af1741bbc4634424d658021f0a6ee78f62257370c8ce653218080cbfcb4fc876ccf6350c40caade02a39d60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/205e9f788d6edd0a2c4eb81b005b47cc698a06d4d5509d8b872a60a6b6c7a0031b89598daf04f2cdbeb634fb1c4f226f9ac138f0ee359fae3a4e105bb949d43d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 17:55:10.667328) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:55:10.667919) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1477353127.708587-1842349610 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004450 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:55:10.752707) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 17:55:10.753039) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 17:55:10.753339) ========= ========= Total master_lag: 0.19 =========