builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-059 starttime: 1477674944.6 results: success (0) buildid: 20161028072558 builduid: 0a8fd668e6134952980be5e1cd8d2c54 revision: 0918613d9d586fe570fd4d8c51faea34f2ce790a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:44.598949) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:44.599551) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:44.599872) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:44.620082) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:44.620389) ========= 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=3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-28 10:15:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.2M=0s 2016-10-28 10:15:45 (70.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105364 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:44.745475) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:44.746649) ========= 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=3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013867 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:44.798448) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-28 10:15:44.798855) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0918613d9d586fe570fd4d8c51faea34f2ce790a --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 0918613d9d586fe570fd4d8c51faea34f2ce790a --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=3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-28 10:15:45,251 truncating revision to first 12 chars 2016-10-28 10:15:45,251 Setting DEBUG logging. 2016-10-28 10:15:45,251 attempt 1/10 2016-10-28 10:15:45,251 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0918613d9d58?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-28 10:15:46,655 unpacking tar archive at: mozilla-beta-0918613d9d58/testing/mozharness/ program finished with exit code 0 elapsedTime=1.753810 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-28 10:15:46.567353) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:46.567751) ========= script_repo_revision: 0918613d9d586fe570fd4d8c51faea34f2ce790a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:46.568302) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:46.568692) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-28 10:15:46.579851) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-28 10:15:46.580185) ========= /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=3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:15:47 INFO - MultiFileLogger online at 20161028 10:15:47 in /builds/slave/test 10:15:47 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 10:15:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:15:47 INFO - {'append_to_log': False, 10:15:47 INFO - 'base_work_dir': '/builds/slave/test', 10:15:47 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:15:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:15:47 INFO - 'branch': 'Mozilla-Beta', 10:15:47 INFO - 'buildbot_json_path': 'buildprops.json', 10:15:47 INFO - 'config_files': ('talos/linux_config.py',), 10:15:47 INFO - 'default_actions': ('clobber', 10:15:47 INFO - 'read-buildbot-config', 10:15:47 INFO - 'download-and-extract', 10:15:47 INFO - 'populate-webroot', 10:15:47 INFO - 'create-virtualenv', 10:15:47 INFO - 'install', 10:15:47 INFO - 'run-tests'), 10:15:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:15:47 INFO - 'download_minidump_stackwalk': True, 10:15:47 INFO - 'download_symbols': 'ondemand', 10:15:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:15:47 INFO - 'tooltool.py': '/tools/tooltool.py', 10:15:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:15:47 INFO - '/tools/misc-python/virtualenv.py')}, 10:15:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:15:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:15:47 INFO - 'installer_path': 'installer.exe', 10:15:47 INFO - 'log_level': 'info', 10:15:47 INFO - 'log_name': 'talos', 10:15:47 INFO - 'log_to_console': True, 10:15:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:15:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:15:47 INFO - 'opt_config_files': (), 10:15:47 INFO - 'pip_index': False, 10:15:47 INFO - 'sps_profile': False, 10:15:47 INFO - 'sps_profile_interval': 0, 10:15:47 INFO - 'suite': 'dromaeojs', 10:15:47 INFO - 'system_bits': '32', 10:15:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:15:47 INFO - 'title': 'talos-linux64-ix-059', 10:15:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:15:47 INFO - 'use_talos_json': True, 10:15:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:15:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:15:47 INFO - 'work_dir': 'build'} 10:15:47 INFO - [mozharness: 2016-10-28 17:15:47.120686Z] Running clobber step. 10:15:47 INFO - Running pre-action listener: _resource_record_pre_action 10:15:47 INFO - Running main action method: clobber 10:15:47 INFO - rmtree: /builds/slave/test/build 10:15:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:15:47 INFO - Running post-action listener: _resource_record_post_action 10:15:47 INFO - [mozharness: 2016-10-28 17:15:47.573532Z] Finished clobber step (success) 10:15:47 INFO - [mozharness: 2016-10-28 17:15:47.573633Z] Running read-buildbot-config step. 10:15:47 INFO - Running pre-action listener: _resource_record_pre_action 10:15:47 INFO - Running main action method: read_buildbot_config 10:15:47 INFO - Using buildbot properties: 10:15:47 INFO - { 10:15:47 INFO - "project": "", 10:15:47 INFO - "product": "firefox", 10:15:47 INFO - "script_repo_revision": "production", 10:15:47 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:15:47 INFO - "repository": "", 10:15:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:15:47 INFO - "buildid": "20161028072558", 10:15:47 INFO - "pgo_build": "True", 10:15:47 INFO - "basedir": "/builds/slave/test", 10:15:47 INFO - "buildnumber": 452, 10:15:47 INFO - "slavename": "talos-linux64-ix-059", 10:15:47 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:15:47 INFO - "platform": "ubuntu64_hw", 10:15:47 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:15:47 INFO - "branch": "mozilla-beta", 10:15:47 INFO - "repo_path": "releases/mozilla-beta", 10:15:47 INFO - "revision": "0918613d9d586fe570fd4d8c51faea34f2ce790a", 10:15:47 INFO - "stage_platform": "linux64-pgo", 10:15:47 INFO - "builduid": "0a8fd668e6134952980be5e1cd8d2c54", 10:15:47 INFO - "slavebuilddir": "test" 10:15:47 INFO - } 10:15:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 10:15:47 INFO - Running post-action listener: _resource_record_post_action 10:15:47 INFO - [mozharness: 2016-10-28 17:15:47.576105Z] Finished read-buildbot-config step (success) 10:15:47 INFO - [mozharness: 2016-10-28 17:15:47.576193Z] Running download-and-extract step. 10:15:47 INFO - Running pre-action listener: _resource_record_pre_action 10:15:47 INFO - Running main action method: download_and_extract 10:15:47 INFO - mkdir: /builds/slave/test/build/tests 10:15: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')]} 10:15:47 INFO - https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:15:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:15:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:15:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:15:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:15:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:15:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:15:47 INFO - retry: Failed, sleeping 30 seconds before retrying 10:16:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:16:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:16:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:16:17 INFO - retry: Failed, sleeping 60 seconds before retrying 10:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:17:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:17:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:17:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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! 10:17:17 INFO - Caught exception: HTTP Error 404: Not Found 10:17:17 INFO - Caught exception: HTTP Error 404: Not Found 10:17:17 INFO - Caught exception: HTTP Error 404: Not Found 10:17:17 INFO - trying https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:17:17 INFO - Downloading https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:17:18 INFO - Downloaded 1327 bytes. 10:17:18 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:17:18 INFO - Using the following test package requirements: 10:17:18 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:17:18 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:17:18 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:17:18 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:17:18 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 10:17:18 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:17:18 INFO - u'jsshell-linux-x86_64.zip'], 10:17:18 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:17:18 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:17:18 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:17:18 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:17:18 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 10:17:18 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:17:18 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 10:17:18 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:17:18 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:17:18 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:17:18 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:17:18 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:17:18 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')]} 10:17:18 INFO - https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:17:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:17:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:17:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:17:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:17:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:17:18 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:17:18 INFO - retry: Failed, sleeping 30 seconds before retrying 10:17:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:17:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:17:48 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:17:48 INFO - retry: Failed, sleeping 60 seconds before retrying 10:18:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:18:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:18:48 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:18:48 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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! 10:18:48 INFO - Caught exception: HTTP Error 404: Not Found 10:18:48 INFO - Caught exception: HTTP Error 404: Not Found 10:18:48 INFO - Caught exception: HTTP Error 404: Not Found 10:18:48 INFO - trying https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:18:48 INFO - Downloading https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:18:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:18:49 INFO - Downloaded 21787620 bytes. 10:18:49 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'] 10:18:49 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 10:18:50 INFO - Return code: 0 10:18:50 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 10:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:18:50 INFO - https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:18:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:18:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:18:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:18:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:18:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:18:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:18:50 INFO - retry: Failed, sleeping 30 seconds before retrying 10:19:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:19:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:19:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:19:20 INFO - retry: Failed, sleeping 60 seconds before retrying 10:20:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:20:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:20:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:20:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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! 10:20:20 INFO - Caught exception: HTTP Error 404: Not Found 10:20:20 INFO - Caught exception: HTTP Error 404: Not Found 10:20:20 INFO - Caught exception: HTTP Error 404: Not Found 10:20:20 INFO - trying https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:20:20 INFO - Downloading https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:20:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:20:21 INFO - Downloaded 21787620 bytes. 10:20:21 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'] 10:20:21 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 10:20:22 INFO - Return code: 0 10:20:22 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')]} 10:20:22 INFO - https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:20:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:20:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:20:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:20:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:20:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:20:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:20:22 INFO - retry: Failed, sleeping 30 seconds before retrying 10:20:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:20:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:20:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:20:52 INFO - retry: Failed, sleeping 60 seconds before retrying 10:21:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:21:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:21:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:21:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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! 10:21:52 INFO - Caught exception: HTTP Error 404: Not Found 10:21:52 INFO - Caught exception: HTTP Error 404: Not Found 10:21:52 INFO - Caught exception: HTTP Error 404: Not Found 10:21:52 INFO - trying https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:21:52 INFO - Downloading https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:21:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:21:54 INFO - Downloaded 14413762 bytes. 10:21:54 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'] 10:21:54 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 10:21:54 INFO - Return code: 0 10:21:54 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')]} 10:21:54 INFO - https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:21:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:21:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:21:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:21:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:21:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:21:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:21:54 INFO - retry: Failed, sleeping 30 seconds before retrying 10:22:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:22:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:22:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:22:24 INFO - retry: Failed, sleeping 60 seconds before retrying 10:23:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:23:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:23:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:23:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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! 10:23:24 INFO - Caught exception: HTTP Error 404: Not Found 10:23:24 INFO - Caught exception: HTTP Error 404: Not Found 10:23:24 INFO - Caught exception: HTTP Error 404: Not Found 10:23:24 INFO - trying https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:23:24 INFO - Downloading https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:23:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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 10:23:25 INFO - Downloaded 56792063 bytes. 10:23:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:23:25 INFO - mkdir: /builds/slave/test/properties 10:23:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:23:25 INFO - Writing to file /builds/slave/test/properties/build_url 10:23:25 INFO - Contents: 10:23:25 INFO - build_url:https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:23:26 INFO - Running post-action listener: _resource_record_post_action 10:23:26 INFO - Running post-action listener: set_extra_try_arguments 10:23:26 INFO - [mozharness: 2016-10-28 17:23:26.299316Z] Finished download-and-extract step (success) 10:23:26 INFO - [mozharness: 2016-10-28 17:23:26.299549Z] Running populate-webroot step. 10:23:26 INFO - Running pre-action listener: _resource_record_pre_action 10:23:26 INFO - Running main action method: populate_webroot 10:23:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:23:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:23:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:23:26 INFO - '--tppagecycles', 10:23:26 INFO - '7'], 10:23:26 INFO - 'tests': ['tsvgm']}}, 10:23:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:23:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:23:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:23:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:23:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:23:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:23:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:23:26 INFO - 'talos_options': ['--disable-e10s'], 10:23:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:23:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:23:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:23:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:23:26 INFO - 'talos_options': ['--disable-e10s'], 10:23:26 INFO - 'tests': ['damp', 'tps']}, 10:23:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:23:26 INFO - 'tests': ['damp', 'tps']}, 10:23:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:23:26 INFO - 'tests': ['dromaeo_dom']}, 10:23:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:23:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:23:26 INFO - 'tests': ['basic_compositor_video']}, 10:23:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:23:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:23:26 INFO - 'tests': ['a11yr', 10:23:26 INFO - 'ts_paint', 10:23:26 INFO - 'tpaint', 10:23:26 INFO - 'sessionrestore', 10:23:26 INFO - 'sessionrestore_no_auto_restore', 10:23:26 INFO - 'tabpaint']}, 10:23:26 INFO - 'other-e10s': {'tests': ['a11yr', 10:23:26 INFO - 'ts_paint', 10:23:26 INFO - 'tpaint', 10:23:26 INFO - 'sessionrestore', 10:23:26 INFO - 'sessionrestore_no_auto_restore', 10:23:26 INFO - 'tabpaint']}, 10:23:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:23:26 INFO - 'ts_paint', 10:23:26 INFO - 'tpaint', 10:23:26 INFO - 'sessionrestore', 10:23:26 INFO - 'sessionrestore_no_auto_restore', 10:23:26 INFO - 'tabpaint']}, 10:23:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:23:26 INFO - 'ts_paint', 10:23:26 INFO - 'tpaint', 10:23:26 INFO - 'sessionrestore', 10:23:26 INFO - 'sessionrestore_no_auto_restore', 10:23:26 INFO - 'tabpaint']}, 10:23:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:23:26 INFO - 'tests': ['a11yr', 10:23:26 INFO - 'ts_paint', 10:23:26 INFO - 'tpaint', 10:23:26 INFO - 'sessionrestore', 10:23:26 INFO - 'sessionrestore_no_auto_restore', 10:23:26 INFO - 'tabpaint']}, 10:23:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:23:26 INFO - 'tests': ['a11yr', 10:23:26 INFO - 'ts_paint', 10:23:26 INFO - 'tpaint', 10:23:26 INFO - 'sessionrestore', 10:23:26 INFO - 'sessionrestore_no_auto_restore', 10:23:26 INFO - 'tabpaint']}, 10:23:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:23:26 INFO - 'tests': ['tsvgx', 10:23:26 INFO - 'tsvgr_opacity', 10:23:26 INFO - 'tart', 10:23:26 INFO - 'tscrollx', 10:23:26 INFO - 'cart']}, 10:23:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:23:26 INFO - 'tsvgr_opacity', 10:23:26 INFO - 'tart', 10:23:26 INFO - 'tscrollx', 10:23:26 INFO - 'cart']}, 10:23:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:23:26 INFO - 'talos_options': ['--disable-e10s'], 10:23:26 INFO - 'tests': ['tp5o']}, 10:23:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:23:26 INFO - 'tests': ['tp5o']}, 10:23:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:23:26 INFO - 'talos_options': ['--disable-e10s', 10:23:26 INFO - '--xperf_path', 10:23:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:23:26 INFO - 'tests': ['tp5n']}, 10:23:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:23:26 INFO - 'talos_options': ['--xperf_path', 10:23:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:23:26 INFO - 'tests': ['tp5n']}}, 10:23:26 INFO - 'talos.zip': {'path': '', 10:23:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:23:26 INFO - Running post-action listener: _resource_record_post_action 10:23:26 INFO - [mozharness: 2016-10-28 17:23:26.320616Z] Finished populate-webroot step (success) 10:23:26 INFO - [mozharness: 2016-10-28 17:23:26.320705Z] Running create-virtualenv step. 10:23:26 INFO - Running pre-action listener: _resource_record_pre_action 10:23:26 INFO - Running main action method: create_virtualenv 10:23:26 INFO - Creating virtualenv /builds/slave/test/build/venv 10:23:26 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 10:23:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:23:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:23:26 INFO - Using real prefix '/usr' 10:23:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:23:26 INFO - Installing distribute.............................................................................................................................................................................................done. 10:23:28 INFO - Installing pip.................done. 10:23:28 INFO - Return code: 0 10:23:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:23:28 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')]} 10:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:28 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')]} 10:23:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:28 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 0x1089030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13483b0>, '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 0x13492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1349740>, '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': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', '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 10:23:28 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 10:23:28 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 10:23:28 INFO - Using env: {'DISPLAY': ':0', 10:23:28 INFO - 'HOME': '/home/cltbld', 10:23:28 INFO - 'LANG': 'en_US.UTF-8', 10:23:28 INFO - 'LANGUAGE': 'en_US:en', 10:23:28 INFO - 'LOGNAME': 'cltbld', 10:23:28 INFO - 'MAIL': '/var/mail/cltbld', 10:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:28 INFO - 'MOZ_NO_REMOTE': '1', 10:23:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:28 INFO - 'NO_EM_RESTART': '1', 10:23:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:28 INFO - 'PWD': '/builds/slave/test', 10:23:28 INFO - 'SHELL': '/bin/bash', 10:23:28 INFO - 'SHLVL': '1', 10:23:28 INFO - 'TERM': 'linux', 10:23:28 INFO - 'TMOUT': '86400', 10:23:28 INFO - 'USER': 'cltbld', 10:23:28 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', 10:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:28 INFO - '_': '/tools/buildbot/bin/python'} 10:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:29 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 10:23:29 INFO - Cleaning up... 10:23:29 INFO - Return code: 0 10:23:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:23:29 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')]} 10:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:29 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')]} 10:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:29 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 0x1089030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13483b0>, '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 0x13492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1349740>, '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': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', '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 10:23:29 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 10:23:29 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 10:23:29 INFO - Using env: {'DISPLAY': ':0', 10:23:29 INFO - 'HOME': '/home/cltbld', 10:23:29 INFO - 'LANG': 'en_US.UTF-8', 10:23:29 INFO - 'LANGUAGE': 'en_US:en', 10:23:29 INFO - 'LOGNAME': 'cltbld', 10:23:29 INFO - 'MAIL': '/var/mail/cltbld', 10:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:29 INFO - 'MOZ_NO_REMOTE': '1', 10:23:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:29 INFO - 'NO_EM_RESTART': '1', 10:23:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:29 INFO - 'PWD': '/builds/slave/test', 10:23:29 INFO - 'SHELL': '/bin/bash', 10:23:29 INFO - 'SHLVL': '1', 10:23:29 INFO - 'TERM': 'linux', 10:23:29 INFO - 'TMOUT': '86400', 10:23:29 INFO - 'USER': 'cltbld', 10:23:29 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', 10:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:29 INFO - '_': '/tools/buildbot/bin/python'} 10:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:29 INFO - Downloading/unpacking psutil>=3.1.1 10:23:29 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 10:23:29 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 10:23:29 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 10:23:29 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 10:23:29 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 10:23:29 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 10:23:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:23:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:23:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:23:31 INFO - Installing collected packages: psutil 10:23:31 INFO - Running setup.py install for psutil 10:23:31 INFO - building 'psutil._psutil_linux' extension 10:23:31 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 10:23:31 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 10:23:31 INFO - building 'psutil._psutil_posix' extension 10:23:31 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 10:23:31 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 10:23:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:23:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:23:31 INFO - Successfully installed psutil 10:23:31 INFO - Cleaning up... 10:23:32 INFO - Return code: 0 10:23:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:23:32 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')]} 10:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:32 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')]} 10:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:32 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 0x1089030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13483b0>, '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 0x13492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1349740>, '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': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', '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 10:23:32 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 10:23:32 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 10:23:32 INFO - Using env: {'DISPLAY': ':0', 10:23:32 INFO - 'HOME': '/home/cltbld', 10:23:32 INFO - 'LANG': 'en_US.UTF-8', 10:23:32 INFO - 'LANGUAGE': 'en_US:en', 10:23:32 INFO - 'LOGNAME': 'cltbld', 10:23:32 INFO - 'MAIL': '/var/mail/cltbld', 10:23:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:32 INFO - 'MOZ_NO_REMOTE': '1', 10:23:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:32 INFO - 'NO_EM_RESTART': '1', 10:23:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:32 INFO - 'PWD': '/builds/slave/test', 10:23:32 INFO - 'SHELL': '/bin/bash', 10:23:32 INFO - 'SHLVL': '1', 10:23:32 INFO - 'TERM': 'linux', 10:23:32 INFO - 'TMOUT': '86400', 10:23:32 INFO - 'USER': 'cltbld', 10:23:32 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', 10:23:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:32 INFO - '_': '/tools/buildbot/bin/python'} 10:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:23:32 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 10:23:32 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 10:23:32 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 10:23:32 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 10:23:32 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 10:23:32 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 10:23:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:23:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:23:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:23:34 INFO - Installing collected packages: mozsystemmonitor 10:23:34 INFO - Running setup.py install for mozsystemmonitor 10:23:34 INFO - Successfully installed mozsystemmonitor 10:23:34 INFO - Cleaning up... 10:23:34 INFO - Return code: 0 10:23:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:23:34 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')]} 10:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:34 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')]} 10:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:34 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 0x1089030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13483b0>, '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 0x13492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1349740>, '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': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', '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 10:23:34 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 10:23:34 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 10:23:34 INFO - Using env: {'DISPLAY': ':0', 10:23:34 INFO - 'HOME': '/home/cltbld', 10:23:34 INFO - 'LANG': 'en_US.UTF-8', 10:23:34 INFO - 'LANGUAGE': 'en_US:en', 10:23:34 INFO - 'LOGNAME': 'cltbld', 10:23:34 INFO - 'MAIL': '/var/mail/cltbld', 10:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:34 INFO - 'MOZ_NO_REMOTE': '1', 10:23:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:34 INFO - 'NO_EM_RESTART': '1', 10:23:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:34 INFO - 'PWD': '/builds/slave/test', 10:23:34 INFO - 'SHELL': '/bin/bash', 10:23:34 INFO - 'SHLVL': '1', 10:23:34 INFO - 'TERM': 'linux', 10:23:34 INFO - 'TMOUT': '86400', 10:23:34 INFO - 'USER': 'cltbld', 10:23:34 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', 10:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:34 INFO - '_': '/tools/buildbot/bin/python'} 10:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:34 INFO - Downloading/unpacking jsonschema==2.5.1 10:23:34 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 10:23:34 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 10:23:34 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 10:23:34 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 10:23:34 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 10:23:34 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 10:23:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:23:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:23:36 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 10:23:36 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 10:23:36 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 10:23:36 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 10:23:36 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 10:23:36 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 10:23:36 INFO - Downloading functools32-3.2.3-2.tar.gz 10:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:23:36 INFO - warning: no files found matching '*.txt' 10:23:36 INFO - no previously-included directories found matching 'build' 10:23:36 INFO - no previously-included directories found matching 'dist' 10:23:36 INFO - no previously-included directories found matching '.git*' 10:23:36 INFO - Installing collected packages: jsonschema, functools32 10:23:36 INFO - Running setup.py install for functools32 10:23:36 INFO - warning: no files found matching '*.txt' 10:23:36 INFO - no previously-included directories found matching 'build' 10:23:36 INFO - no previously-included directories found matching 'dist' 10:23:36 INFO - no previously-included directories found matching '.git*' 10:23:36 INFO - Successfully installed jsonschema functools32 10:23:36 INFO - Cleaning up... 10:23:36 INFO - Return code: 0 10:23:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:36 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 0x1089030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13483b0>, '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 0x13492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1349740>, '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': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', '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 10:23:36 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 10:23:36 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 10:23:36 INFO - Using env: {'DISPLAY': ':0', 10:23:36 INFO - 'HOME': '/home/cltbld', 10:23:36 INFO - 'LANG': 'en_US.UTF-8', 10:23:36 INFO - 'LANGUAGE': 'en_US:en', 10:23:36 INFO - 'LOGNAME': 'cltbld', 10:23:36 INFO - 'MAIL': '/var/mail/cltbld', 10:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:36 INFO - 'MOZ_NO_REMOTE': '1', 10:23:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:36 INFO - 'NO_EM_RESTART': '1', 10:23:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:36 INFO - 'PWD': '/builds/slave/test', 10:23:36 INFO - 'SHELL': '/bin/bash', 10:23:36 INFO - 'SHLVL': '1', 10:23:36 INFO - 'TERM': 'linux', 10:23:36 INFO - 'TMOUT': '86400', 10:23:36 INFO - 'USER': 'cltbld', 10:23:36 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', 10:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:36 INFO - '_': '/tools/buildbot/bin/python'} 10:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:23:37 INFO - Cleaning up... 10:23:37 INFO - Return code: 0 10:23:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:23:37 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')]} 10:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:37 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')]} 10:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:37 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 0x1089030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13483b0>, '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 0x13492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1349740>, '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': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', '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 10:23:37 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 10:23:37 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 10:23:37 INFO - Using env: {'DISPLAY': ':0', 10:23:37 INFO - 'HOME': '/home/cltbld', 10:23:37 INFO - 'LANG': 'en_US.UTF-8', 10:23:37 INFO - 'LANGUAGE': 'en_US:en', 10:23:37 INFO - 'LOGNAME': 'cltbld', 10:23:37 INFO - 'MAIL': '/var/mail/cltbld', 10:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:37 INFO - 'MOZ_NO_REMOTE': '1', 10:23:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:37 INFO - 'NO_EM_RESTART': '1', 10:23:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:37 INFO - 'PWD': '/builds/slave/test', 10:23:37 INFO - 'SHELL': '/bin/bash', 10:23:37 INFO - 'SHLVL': '1', 10:23:37 INFO - 'TERM': 'linux', 10:23:37 INFO - 'TMOUT': '86400', 10:23:37 INFO - 'USER': 'cltbld', 10:23:37 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', 10:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:37 INFO - '_': '/tools/buildbot/bin/python'} 10:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:37 INFO - Downloading/unpacking blobuploader==1.2.4 10:23:37 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 10:23:37 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 10:23:37 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 10:23:37 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 10:23:37 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 10:23:37 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 10:23:39 INFO - Downloading blobuploader-1.2.4.tar.gz 10:23:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:23:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:23:39 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 10:23:39 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 10:23:39 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 10:23:39 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 10:23:39 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 10:23:39 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 10:23:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:23:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:23:39 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 10:23:39 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 10:23:39 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 10:23:39 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 10:23:39 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 10:23:39 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 10:23:39 INFO - Downloading docopt-0.6.1.tar.gz 10:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:23:40 INFO - Installing collected packages: blobuploader, requests, docopt 10:23:40 INFO - Running setup.py install for blobuploader 10:23:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:23:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:23:40 INFO - Running setup.py install for requests 10:23:40 INFO - Running setup.py install for docopt 10:23:40 INFO - Successfully installed blobuploader requests docopt 10:23:40 INFO - Cleaning up... 10:23:40 INFO - Return code: 0 10:23:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23:40 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')]} 10:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:40 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')]} 10:23:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:40 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 0x1089030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13483b0>, '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 0x13492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1349740>, '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': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', '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 10:23:40 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 10:23:40 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 10:23:40 INFO - Using env: {'DISPLAY': ':0', 10:23:40 INFO - 'HOME': '/home/cltbld', 10:23:40 INFO - 'LANG': 'en_US.UTF-8', 10:23:40 INFO - 'LANGUAGE': 'en_US:en', 10:23:40 INFO - 'LOGNAME': 'cltbld', 10:23:40 INFO - 'MAIL': '/var/mail/cltbld', 10:23:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:40 INFO - 'MOZ_NO_REMOTE': '1', 10:23:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:40 INFO - 'NO_EM_RESTART': '1', 10:23:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:40 INFO - 'PWD': '/builds/slave/test', 10:23:40 INFO - 'SHELL': '/bin/bash', 10:23:40 INFO - 'SHLVL': '1', 10:23:40 INFO - 'TERM': 'linux', 10:23:40 INFO - 'TMOUT': '86400', 10:23:40 INFO - 'USER': 'cltbld', 10:23:40 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', 10:23:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:40 INFO - '_': '/tools/buildbot/bin/python'} 10:23:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:23:40 INFO - Running setup.py (path:/tmp/pip-GjRmEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:23:40 INFO - Running setup.py (path:/tmp/pip-nn29zR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:23:41 INFO - Running setup.py (path:/tmp/pip-Mdpes1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:23:41 INFO - Running setup.py (path:/tmp/pip-QwEFW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:23:41 INFO - Running setup.py (path:/tmp/pip-uZNQGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:23:41 INFO - Running setup.py (path:/tmp/pip-Zre0Xq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:23:41 INFO - Running setup.py (path:/tmp/pip-6nmKds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:23:41 INFO - Running setup.py (path:/tmp/pip-Rcv1yh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:23:41 INFO - Running setup.py (path:/tmp/pip-mo73kK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:23:41 INFO - Running setup.py (path:/tmp/pip-eXQo3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:23:41 INFO - Running setup.py (path:/tmp/pip-bzDDUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:23:41 INFO - Running setup.py (path:/tmp/pip-bJPApd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:23:41 INFO - Running setup.py (path:/tmp/pip-y52TD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:23:41 INFO - Running setup.py (path:/tmp/pip-gAjZQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:41 INFO - Running setup.py (path:/tmp/pip-9pctwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:23:41 INFO - Running setup.py (path:/tmp/pip-9e58vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:23:42 INFO - Running setup.py (path:/tmp/pip-Ll0Dbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:23:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:23:42 INFO - Running setup.py install for manifestparser 10:23:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:23:42 INFO - Running setup.py install for mozcrash 10:23:42 INFO - Running setup.py install for mozdebug 10:23:42 INFO - Running setup.py install for mozdevice 10:23:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:23:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:23:42 INFO - Running setup.py install for mozfile 10:23:42 INFO - Running setup.py install for mozhttpd 10:23:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:23:42 INFO - Running setup.py install for mozinfo 10:23:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:23:42 INFO - Running setup.py install for mozInstall 10:23:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:23:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:23:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:23:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:23:42 INFO - Running setup.py install for mozleak 10:23:43 INFO - Running setup.py install for mozlog 10:23:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:23:43 INFO - Running setup.py install for moznetwork 10:23:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:23:43 INFO - Running setup.py install for mozprocess 10:23:43 INFO - Running setup.py install for mozprofile 10:23:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:23:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:23:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:23:43 INFO - Running setup.py install for mozrunner 10:23:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:23:43 INFO - Running setup.py install for mozscreenshot 10:23:43 INFO - Running setup.py install for moztest 10:23:43 INFO - Running setup.py install for mozversion 10:23:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:23:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:23:43 INFO - Cleaning up... 10:23:43 INFO - Return code: 0 10:23:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23:43 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')]} 10:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:43 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')]} 10:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:43 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 0x1089030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13483b0>, '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 0x13492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1349740>, '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': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', '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 10:23:43 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 10:23:43 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 10:23:43 INFO - Using env: {'DISPLAY': ':0', 10:23:43 INFO - 'HOME': '/home/cltbld', 10:23:43 INFO - 'LANG': 'en_US.UTF-8', 10:23:43 INFO - 'LANGUAGE': 'en_US:en', 10:23:43 INFO - 'LOGNAME': 'cltbld', 10:23:43 INFO - 'MAIL': '/var/mail/cltbld', 10:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:43 INFO - 'MOZ_NO_REMOTE': '1', 10:23:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:43 INFO - 'NO_EM_RESTART': '1', 10:23:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:43 INFO - 'PWD': '/builds/slave/test', 10:23:43 INFO - 'SHELL': '/bin/bash', 10:23:43 INFO - 'SHLVL': '1', 10:23:43 INFO - 'TERM': 'linux', 10:23:43 INFO - 'TMOUT': '86400', 10:23:43 INFO - 'USER': 'cltbld', 10:23:43 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', 10:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:43 INFO - '_': '/tools/buildbot/bin/python'} 10:23:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:23:44 INFO - Running setup.py (path:/tmp/pip-rtEOm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:23:44 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)) 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:23:44 INFO - Running setup.py (path:/tmp/pip-QxrTg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:23:44 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)) 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:23:44 INFO - Running setup.py (path:/tmp/pip-DeLEt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:23:44 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)) 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:23:44 INFO - Running setup.py (path:/tmp/pip-rKAvAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:23:44 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)) 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:23:44 INFO - Running setup.py (path:/tmp/pip-FKERoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:23:44 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)) 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:23:44 INFO - Running setup.py (path:/tmp/pip-srOtR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:23:44 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)) 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:23:44 INFO - Running setup.py (path:/tmp/pip-HySCyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:23:44 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)) 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:23:44 INFO - Running setup.py (path:/tmp/pip-gMlpC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:23:44 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)) 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:23:44 INFO - Running setup.py (path:/tmp/pip-4gcQ0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:23:44 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)) 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:23:44 INFO - Running setup.py (path:/tmp/pip-I9DGr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:23:44 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)) 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:23:44 INFO - Running setup.py (path:/tmp/pip-g_GGSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:23:44 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)) 10:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:23:44 INFO - Running setup.py (path:/tmp/pip-39UL37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:23:44 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)) 10:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:23:45 INFO - Running setup.py (path:/tmp/pip-gt8hvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:23:45 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)) 10:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:23:45 INFO - Running setup.py (path:/tmp/pip-STgAAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:23:45 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)) 10:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:45 INFO - Running setup.py (path:/tmp/pip-3jX4hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:45 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)) 10:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:23:45 INFO - Running setup.py (path:/tmp/pip-TviAei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:23:45 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)) 10:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:23:45 INFO - Running setup.py (path:/tmp/pip-_mKXWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:23:45 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)) 10:23:45 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)) 10:23:45 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)) 10:23:45 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)) 10:23:45 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)) 10:23:45 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)) 10:23:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:23:45 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 10:23:45 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 10:23:45 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 10:23:45 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 10:23:45 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 10:23:45 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 10:23:47 INFO - Downloading blessings-1.6.tar.gz 10:23:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:23:47 INFO - Installing collected packages: blessings 10:23:47 INFO - Running setup.py install for blessings 10:23:47 INFO - Successfully installed blessings 10:23:47 INFO - Cleaning up... 10:23:47 INFO - Return code: 0 10:23:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:23:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:23:47 INFO - Reading from file tmpfile_stdout 10:23:47 INFO - Current package versions: 10:23:47 INFO - argparse == 1.2.1 10:23:47 INFO - blessings == 1.6 10:23:47 INFO - blobuploader == 1.2.4 10:23:47 INFO - docopt == 0.6.1 10:23:47 INFO - functools32 == 3.2.3-2 10:23:47 INFO - jsonschema == 2.5.1 10:23:47 INFO - manifestparser == 1.1 10:23:47 INFO - mozInstall == 1.12 10:23:47 INFO - mozcrash == 0.17 10:23:47 INFO - mozdebug == 0.1 10:23:47 INFO - mozdevice == 0.48 10:23:47 INFO - mozfile == 1.2 10:23:47 INFO - mozhttpd == 0.7 10:23:47 INFO - mozinfo == 0.9 10:23:47 INFO - mozleak == 0.1 10:23:47 INFO - mozlog == 3.3 10:23:47 INFO - moznetwork == 0.27 10:23:47 INFO - mozprocess == 0.23 10:23:47 INFO - mozprofile == 0.28 10:23:47 INFO - mozrunner == 6.12 10:23:47 INFO - mozscreenshot == 0.1 10:23:47 INFO - mozsystemmonitor == 0.3 10:23:47 INFO - moztest == 0.7 10:23:47 INFO - mozversion == 1.4 10:23:47 INFO - psutil == 3.1.1 10:23:47 INFO - requests == 1.2.3 10:23:47 INFO - wsgiref == 0.1.2 10:23:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23: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')]} 10:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23: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')]} 10:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:47 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 0x1089030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13483b0>, '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 0x13492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1349740>, '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': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', '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 10:23:47 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 10:23:47 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 10:23:47 INFO - Using env: {'DISPLAY': ':0', 10:23:47 INFO - 'HOME': '/home/cltbld', 10:23:47 INFO - 'LANG': 'en_US.UTF-8', 10:23:47 INFO - 'LANGUAGE': 'en_US:en', 10:23:47 INFO - 'LOGNAME': 'cltbld', 10:23:47 INFO - 'MAIL': '/var/mail/cltbld', 10:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:47 INFO - 'MOZ_NO_REMOTE': '1', 10:23:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:47 INFO - 'NO_EM_RESTART': '1', 10:23:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:47 INFO - 'PWD': '/builds/slave/test', 10:23:47 INFO - 'SHELL': '/bin/bash', 10:23:47 INFO - 'SHLVL': '1', 10:23:47 INFO - 'TERM': 'linux', 10:23:47 INFO - 'TMOUT': '86400', 10:23:47 INFO - 'USER': 'cltbld', 10:23:47 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', 10:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:47 INFO - '_': '/tools/buildbot/bin/python'} 10:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:47 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)) 10:23:47 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)) 10:23:47 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)) 10:23:47 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)) 10:23:47 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)) 10:23:47 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)) 10:23:47 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)) 10:23:47 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)) 10:23:47 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)) 10:23:47 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)) 10:23:47 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)) 10:23:47 INFO - Cleaning up... 10:23:47 INFO - Return code: 0 10:23:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:23: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')]} 10:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23: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')]} 10:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:48 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 0x1089030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13483b0>, '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 0x13492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1349740>, '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': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', '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 10:23:48 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 10:23:48 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 10:23:48 INFO - Using env: {'DISPLAY': ':0', 10:23:48 INFO - 'HOME': '/home/cltbld', 10:23:48 INFO - 'LANG': 'en_US.UTF-8', 10:23:48 INFO - 'LANGUAGE': 'en_US:en', 10:23:48 INFO - 'LOGNAME': 'cltbld', 10:23:48 INFO - 'MAIL': '/var/mail/cltbld', 10:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:48 INFO - 'MOZ_NO_REMOTE': '1', 10:23:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:48 INFO - 'NO_EM_RESTART': '1', 10:23:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:48 INFO - 'PWD': '/builds/slave/test', 10:23:48 INFO - 'SHELL': '/bin/bash', 10:23:48 INFO - 'SHLVL': '1', 10:23:48 INFO - 'TERM': 'linux', 10:23:48 INFO - 'TMOUT': '86400', 10:23:48 INFO - 'USER': 'cltbld', 10:23:48 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', 10:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:48 INFO - '_': '/tools/buildbot/bin/python'} 10:23:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:23:48 INFO - Cleaning up... 10:23:48 INFO - Return code: 0 10:23:48 INFO - Running post-action listener: _resource_record_post_action 10:23:48 INFO - Running post-action listener: _start_resource_monitoring 10:23:48 INFO - Starting resource monitoring. 10:23:48 INFO - [mozharness: 2016-10-28 17:23:48.252446Z] Finished create-virtualenv step (success) 10:23:48 INFO - [mozharness: 2016-10-28 17:23:48.252921Z] Running install step. 10:23:48 INFO - Running pre-action listener: _resource_record_pre_action 10:23:48 INFO - Running main action method: install 10:23:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:23:48 INFO - Reading from file tmpfile_stdout 10:23:48 INFO - Detecting whether we're running mozinstall >=1.0... 10:23:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:23:48 INFO - Reading from file tmpfile_stdout 10:23:48 INFO - Output received: 10:23:48 INFO - Usage: mozinstall [options] installer 10:23:48 INFO - Options: 10:23:48 INFO - -h, --help show this help message and exit 10:23:48 INFO - -d DEST, --destination=DEST 10:23:48 INFO - Directory to install application into. [default: 10:23:48 INFO - "/builds/slave/test"] 10:23:48 INFO - --app=APP Application being installed. [default: firefox] 10:23:48 INFO - mkdir: /builds/slave/test/build/application 10:23:48 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'] 10:23:48 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 10:24:02 INFO - Reading from file tmpfile_stdout 10:24:02 INFO - Output received: 10:24:02 INFO - /builds/slave/test/build/application/firefox/firefox 10:24:02 INFO - Running post-action listener: _resource_record_post_action 10:24:02 INFO - [mozharness: 2016-10-28 17:24:02.557483Z] Finished install step (success) 10:24:02 INFO - [mozharness: 2016-10-28 17:24:02.557608Z] Running run-tests step. 10:24:02 INFO - Running pre-action listener: _resource_record_pre_action 10:24:02 INFO - Running main action method: run_tests 10:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:24:02 INFO - Python 2.7.3 10:24:02 INFO - Return code: 0 10:24:02 INFO - grabbing minidump binary from tooltool 10:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:02 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 0x13483b0>, '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 0x13492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1349740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:24:02 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 10:24:02 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 10:24:02 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 10:24:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:24:02 INFO - Return code: 0 10:24:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:24:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:24:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:24:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:24:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:24:02 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/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 10:24:02 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/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 10:24:02 INFO - Using env: {'DISPLAY': ':0', 10:24:02 INFO - 'HOME': '/home/cltbld', 10:24:02 INFO - 'LANG': 'en_US.UTF-8', 10:24:02 INFO - 'LANGUAGE': 'en_US:en', 10:24:02 INFO - 'LOGNAME': 'cltbld', 10:24:02 INFO - 'MAIL': '/var/mail/cltbld', 10:24:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:24:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:02 INFO - 'MOZ_NO_REMOTE': '1', 10:24:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:24:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:24:02 INFO - 'NO_EM_RESTART': '1', 10:24:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:02 INFO - 'PWD': '/builds/slave/test', 10:24:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:24:02 INFO - 'SHELL': '/bin/bash', 10:24:02 INFO - 'SHLVL': '1', 10:24:02 INFO - 'TERM': 'linux', 10:24:02 INFO - 'TMOUT': '86400', 10:24:02 INFO - 'USER': 'cltbld', 10:24:02 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553', 10:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:02 INFO - '_': '/tools/buildbot/bin/python'} 10:24:02 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/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 10:24:02 INFO - mozversion application_buildid: 20161028072558 10:24:02 INFO - mozversion application_changeset: 0918613d9d586fe570fd4d8c51faea34f2ce790a 10:24:02 INFO - mozversion application_display_name: Firefox 10:24:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:24:02 INFO - mozversion application_name: Firefox 10:24:02 INFO - mozversion application_remotingname: firefox 10:24:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:24:02 INFO - mozversion application_vendor: Mozilla 10:24:02 INFO - mozversion application_version: 50.0 10:24:02 INFO - mozversion platform_buildid: 20161028072558 10:24:02 INFO - mozversion platform_changeset: 0918613d9d586fe570fd4d8c51faea34f2ce790a 10:24:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:24:02 INFO - mozversion platform_version: 50.0 10:24:02 INFO - using testdate: 1477675442 10:24:02 INFO - actual date: 1477675442 10:24:02 INFO - starting webserver on 'localhost:35713' 10:24:02 INFO - SUITE-START | Running 2 tests 10:24:02 INFO - TEST-START | dromaeo_css 10:24:02 INFO - operating with platform_type : linux_ 10:24:02 INFO - Initialising browser for dromaeo_css test... 10:24:02 INFO - TEST-INFO | started process 7003 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLzeNzC/profile http://localhost:35713/getInfo.html) 10:24:06 INFO - PROCESS | 7003 | __metrics Screen width/height:1600/1200 10:24:06 INFO - PROCESS | 7003 | colorDepth:24 10:24:06 INFO - PROCESS | 7003 | Browser inner width/height: 1024/697 10:24:06 INFO - PROCESS | 7003 | __metrics 10:24:07 INFO - PROCESS | 7003 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:24:07 INFO - PROCESS | 7003 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:24:08 INFO - TEST-INFO | 7003: exit 0 10:24:08 INFO - Browser initialized. 10:24:08 INFO - Running cycle 1/1 for dromaeo_css test... 10:24:08 INFO - TEST-INFO | started process 7090 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLzeNzC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:24:20 INFO - PROCESS | 7090 | RSS: Main: 143601664 10:24:20 INFO - PROCESS | 7090 | 10:25:12 INFO - PROCESS | 7090 | 1477675512011 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] 10:27:19 INFO - PROCESS | 7090 | Cycle 1(1): loaded http://localhost:35713/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35713/tests/dromaeo/cssquery-ext.html) 10:27:20 INFO - PROCESS | 7090 | RSS: Main: 271777792 10:27:20 INFO - PROCESS | 7090 | 10:30:23 INFO - PROCESS | 7090 | Cycle 1(1): loaded http://localhost:35713/tests/dromaeo/cssquery-ext.html (next: http://localhost:35713/tests/dromaeo/cssquery-jquery.html) 10:30:23 INFO - PROCESS | 7090 | RSS: Main: 235237376 10:30:23 INFO - PROCESS | 7090 | 10:33:25 INFO - PROCESS | 7090 | Cycle 1(1): loaded http://localhost:35713/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35713/tests/dromaeo/cssquery-mootools.html) 10:33:26 INFO - PROCESS | 7090 | RSS: Main: 175976448 10:33:26 INFO - PROCESS | 7090 | 10:36:28 INFO - PROCESS | 7090 | Cycle 1(1): loaded http://localhost:35713/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35713/tests/dromaeo/cssquery-prototype.html) 10:36:28 INFO - PROCESS | 7090 | RSS: Main: 174538752 10:36:28 INFO - PROCESS | 7090 | 10:39:31 INFO - PROCESS | 7090 | Cycle 1(1): loaded http://localhost:35713/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35713/tests/dromaeo/cssquery-yui.html) 10:39:31 INFO - PROCESS | 7090 | RSS: Main: 176947200 10:39:31 INFO - PROCESS | 7090 | 10:42:34 INFO - PROCESS | 7090 | Cycle 1(1): loaded http://localhost:35713/tests/dromaeo/cssquery-yui.html (next: http://localhost:35713/tests/dromaeo/cssquery-dojo.html) 10:42:34 INFO - PROCESS | 7090 | RSS: Main: 265510912 10:42:34 INFO - PROCESS | 7090 | 10:42:34 INFO - PROCESS | 7090 | __start_tp_report 10:42:34 INFO - PROCESS | 7090 | _x_x_mozilla_page_load 10:42:34 INFO - PROCESS | 7090 | _x_x_mozilla_page_load_details 10:42:34 INFO - PROCESS | 7090 | |i|pagename|runs| 10:42:34 INFO - PROCESS | 7090 | |0;dojo.html;3200.855961481733;3225.0324902529264;3237.902862914112;3240;3242.14083267934;5620.5784566157545;5707.828765137047;5711.7144142792795;5715.799947001861;5718.59969802114;5723.656580605156;5767.163761254616;5810.535157187425;5832.825015249538;5833.270841144857;5242.606804489669;5257.579393648499;5265.078611243036;5316.9734151329385;5325;5126.974365128169;5309.840704778843;5321.52106310432;5332.626716129873;5365.08793505105;5101.413337466183;5148.304978827867;5149.510796474347;5176.0683077046115;5195.064888320101;5175.974120129413;5197.974010129944;5224.477552244745;5331.226972089025;5333.706646134461;5171.5862730981435;5215.295935048795;5287.391949925731;5302.549283310923;5326.6537675050995;5510.980468613319;5619.438056194389;5644.238027866268;5662.433756624346;5668.574856885709;6065;6066.8786624267705;6154.046122850965;6177.351378105324;6180.505559555225;6006.969965150146;6199.938000619981;6221;6225.875482490324;6238.656873871935;5672.8865422691315;5750.1949727038245;5771.826845194609;5808.128780682847;5815.563832712563;6352.777652782131;6359.745610175633;6363.236411630634;6368.42684158428;6369.808905732835;5812.128180772918;5922;6102.542309326818;6112.205413296243;6116.908246376308;6069.0592958091565;6109.101029780167;6111.480524155408;6114.145302971479;6126.761967422298;6123.418275263843;6124.540659450559;6127.356627554132;6128.540359473057;6130.877382452326;5894.381089985575;5991.940080599138;6078.209832721718;6121.510279177655;6135.44780969706;4651.976740116278;4671.672982891233;4675.158471475099;4698.318743782201;4699.530046995274;4102.364133559303;4136.379543068564;4143.875683729493;4151.73013754105;4167.333226683777;5637.8590535236945;5697.401772813878;5799.420057994251;5812.273465816773;5822.941770582324;5852.7658893643775;6150.600210986271;6157.568970172045;6165.229346331708;6173.969130154321;5791.623544469596;5806.419358064244;5809.68046757424;5811.506021988178;5839.737211825478;5168.2764412982215;5237.895242095213;5306.46935306474;5315.973420132952;5318.760655770499;6247.843803904939;6389.201349831271;6393.776217832354;6394.328595497405;6395.584287021329;6145.262568491631;6185.969070154801;6196.442320191024;6197.597156185014;6199.318075011655;6077.05805600115;6152.16945712341;6407.967960160169;6412.35876412355;6417.743290268337;5811.0121279381765;5862.237909071793;5870.119482077723;5874.765009399576;5918.733656985532;5971.731272092679;5983.222181116427;5987.760489580368;5994.07091900756;5997.790077347272;5846.386129456346;5853.912191317048;5862.530997520274;5899.410058994066;5939.881202376015;6027.60820546663;6047.0627052806885;6065.787697430569;6094.51243900478;6103.511719062376;5912.970435147797;5966.701664916824;5974.9402505974385;5976.103584462366;5977.760889564369;5833.562482813721;5901.144334071621;5953.732082056405;6010.398960103955;6030.125631783455;5855.9121613176685;5905.291365036223;5920.526357891445;5921.733521991435;5953.672548009901 10:42:34 INFO - PROCESS | 7090 | |1;ext.html;589.3487696095814;608.9939100608995;643.1670986073034;647.5790736021588;677.6984242012301;35244.64755352445;47364.526354736445;48286.275705864355;48299;48321.27518087227;49009;49375;49415.25877111837;49531.75234123824;49552.50447495524;8460.111688272704;9223.169914707674;9383.06169383063;9383.390079644802;10888.183386246024;11058.391788451672;11099;11147.331160130418;11211.775764484706;11224.719382015433;11092.500837462294;11137.443127843617;11148.665540033771;11151.330920144777;11152;11091.168162387812;11151.553937842516;11172.888271117346;11215.439228038647;11303;11060.17048721349;11135.387553684544;11137.832932506019;11142.554297828077;11151.052160566362;45679;46108.847278817964;46133.76933115313;46191.53808461906;46201.768991155164;3797.2974999625158;3821.178446633959;4009.578994205596;4213.47331583552;4404.075144219686;35189.472157917655;35504;35513.401896914445;35770.64229357699;37140.442893356354;4636.5131661175765;4681.555252251031;4731.905361892743;4777.713337199744;4861.708297502161;379819;380344;381612;381958;382585.08707456564;61810.38189618136;62265;62285.68857155776;62286;62320.68839655863;38837.41743873844;40299.395509067384;40430.19139617191;42177.57822421798;65781;15837.604059898595;15889.126098064717;15923.840761592468;15947.920260398623;15967.201639917957;11789.646310610695;11954.820677689842;12010.75978480443;12049.457774400173;12251.326177060348;20713.58572828535;20873.895630522053;20972;21029.527933044847;21038.684419733716;7327.37717294036;7388.374557597427;7544.8491030180185;7595.734149304748;7665.209244222361;1015.8476228565627;1175.6663023897556;1214.2289646074805;1236.190295356543;1248.1325478792335;34752.30495390078;35031.29937401238;35112.297754045285;35230.90226578619;35273.294534109686;1146.1690274550883;1193.3078814287692;1196.3180986837592;1252.2674235572292;1263.816746571742;18335.174917128763;18509.814901851078;18611.90694046521;18634;18651.533711657317;577510;596114;599046;607685;607817;64712;64791.67604161949;64943;65126;65507;1315.2963164707028;1453.3750487290356;1459.306829256095;1475.527831094061;1481.2889812889873;32794.836025820674;36981;38267;38711.41878889428;39308;60151.39848601458;61022;61091;61141.38858611357;61162.6360550174;404639;405742;408587;409106;409115;27418.862905686143;30058.098257052323;31235.687643123278;31317.68682313239;31653.525197121602;52697.20954185614;52892;53190.46809531855;53242;53861.73069134629;43583.34624980691;44292.55707442884;44607.55392446034;45402.31896521489;46123.3081503771;43318.34291330385;44516.55483445254;45138.322925155495;45678.54321456876;46226.76886615546;44287.26715011175;44791.55208447874;45456.772716136205;45718.54281457276;45796.08407831891;37384.81307593445;38997.22005559816;39619.801900991464;40061;40400.1919961605;29642.25894352572;29909.70090299069;30228.24429389195;30292.54561181628;30370 10:42:34 INFO - PROCESS | 7090 | |2;jquery.html;389.7525071579546;394.6960840153084;408.61998341542363;409.39000888675884;422.8646833013435;14652.633684157901;19404;20694.586108277865;20895.582088358224;21052;19477.415677529698;20260.59478810423;20326.89836550815;20462.488437789027;20841.583168336623;5277.683338999663;5579.69311687859;5589.552835773128;5591.692456914868;5591.888162236753;5430.75561599727;5446.210299506569;5451.972740136293;5521.199426083215;5529.778808847641;5396.028714831329;5782.624129431573;5907.5864689471755;5921.792737254197;5922.022866227067;5856.238688970407;5923.407659234063;5948;5976.073708575178;5978.252718410199;5450.4549545045575;5474.671519708831;5493.2034854945805;5494.835154945318;5496.422875598045;13894.096883702527;25113.121040763668;25115.36750111237;25263.494730105296;25422.110226141995;2408.2654790288957;2465.457599328145;2529.051605647882;2538.327343254039;2808.620836187109;10202.591896324138;10971.451427428598;10990.450477476174;11046.226764126515;11186.105111591134;2082.2920207129496;2141.1328411993168;2150.3656421355736;2228.0864845413303;2243.450354663118;87881;89466.5526672369;89930;90267.09732902718;91323.54338228266;11460.140489463323;11992.220505667105;12195.841395067455;12314.384280785926;12404;17337.133143343035;17340.91329543344;17355.13224338778;17398.739018914726;17399.82600173982;8518.616662250213;8651.221390074923;8728.869066964;8797.516136612549;8802.779930501789;8182.263596276363;8229.95885020571;8233.958830205811;8374.665013399517;8398.580070996426;5141.920196758648;5144.562712169432;5179.430262671103;5273.472652734696;5332.040232758063;2140.1867290429536;2147.903344349508;2169.1648715244746;2177.8366622503377;2238.6120605224537;1037.6316407675317;1056.3450660590483;1059.0891833023593;1059.6185373265619;1137.1641843245209;20977.475563111046;21492.892535537223;21694;21706.240281590068;21924;1050.9842352364722;1067.64767626683;1074.7313171707074;1097.6213206443765;1146.363768108691;11633.6509904703;11928.701782455335;11993.760124797629;12170.695732606579;12300.507979680713;543602;545145;553034;561623;564133;68485.31514684789;84014.57992709997;97396;100565;102658.48670756599;1018.7351288665001;1035.6634093919506;1041.2294901772634;1045.5451878432907;1059.7933402986498;18480.815191848447;19333.323333683817;19444.902775486597;19540.8045919539;19560.315388961317;44945.32582011338;45821.31268030916;46150.53849461597;46833.765831170625;48292.7585362071;63452;64215;64445.67777161272;65488.345116548226;66083;7564.432667549846;8135.471194372347;8230.300424463987;8739.519326437106;8777.648894044421;9106.772330691785;9396.95301523488;9477.099675530813;9524.61901523959;10253.692389228336;8143.75568732939;8501.87247191305;8556.058833528183;8898.065703101081;9145.085491450802;8720.825583488422;8835.514046727234;8944.418612790148;9178.54107294646;9408.294377921546;8598.269147122564;8740.431871928304;8757.824843503222;9066.27469802401;9650.372725772802;11074.280171788809;12315.69210769738;12675.17611355259;12756.107072504836;13269.800952985906;11691.415429228335;12387.132900696864;12707.93646031764;12746.745065098832;12879.80680289815 10:42:34 INFO - PROCESS | 7090 | |3;mootools.html;1538.3615799443228;1546.6674664947038;1737.5134962210577;1869.6354210928873;1876.6246750649873;9195.494247816376;9950.452725100116;9951.701448956524;9962.103410693036;9984.251181161406;8464.111268316816;9034.54827258636;9410.858837117448;9440.433573985565;9441.81116377672;2437.086092715236;2502.3368807265997;2506.4736405354897;2506.9094943699456;2508.3352911478473;2348.941276468085;2453.092355828337;2456.1771806444863;2457.533068716947;2458.0536493450063;2484.1553871683623;2489.726130125685;2530.784883284923;2532.518821423933;2532.645429639852;2458.938526536842;2467.802575793932;2472.863992480413;2498.263012411325;2503.2865633294605;2435.6346548017764;2437.2200895713377;2447.131268399728;2447.7674620910984;2509.2221411362534;9673.758156046084;9719.368241064309;9727.464989425578;9741.756456088584;9747.805043899154;5618.578606604521;5722.942770572283;5777.104548794901;5783.652980821122;5793.13103034544;3395.134240768613;3500.7374446916583;3564.518789963348;3588.4437912123662;3597.9820100899587;1134.8808375120575;1169.502961241469;1188.2395267029108;1195.171387776703;1219.9695007624791;13825.170489770646;14063.789043164361;14128.364223609859;14130.92934535331;14140.222287774168;4874.172185430463;4905.2151655734915;5001.7499125043605;5091.414487334004;5096.108181068298;11800.114991375682;11842.644720658394;11869.762604747857;11873;11888.34614096233;5761.798337058183;5844.386339434383;5844.503217226584;5850.3564607893095;5855.736491857877;4661.696989695659;4969.105560999054;5057.797688092509;5091.1090559152;5344.278522399504;4298.849540266076;4337.9783101084295;4386.2762644163995;4433.113377324548;4439.200943830142;1319.4985905355904;1365.1263191557412;1367.863213678624;1390.2145287912297;1394.8814350780094;895.9820803584022;965.4689920543673;1026.2046913641987;1029.8043371759343;1055.714956961616;6286.088517164985;6304.779332723333;6419.165508483868;6419.711112999927;6449.774257900951;1030.1346868630237;1130.7286251299793;1138.9658310250707;1141.14414189358;1154.7459558897197;4272.423222864873;4373.278409062469;4393.231184542692;4429.933550996738;4503.864884053483;12218.877811221952;12234.694132646755;12312.876871231172;12521.123521353418;12629.494820207272;12131.393430328624;12418.627441176779;12439.37803109841;12468.5012599495;12487.06347023977;9099.317551183687;9132.634694612274;9191.724248272696;9206;9209.309301802392;6221.595596286227;6261.279952805304;6287.056941458818;6311.368863113652;6370.044493326038;11144.498497567467;11241.381724005258;11383.487743051417;11390.202685811913;11440;11143.331400115694;11627.47676354583;11704.94147529257;11755.88244117548;11814.527418903148;7436.0333156689285;8035.959820201096;8180;8192.713255036046;8406.915930840612;9670.75823104428;9675.322727409013;9693.903060969582;9739.561719722487;9747.658831940847;8442.197991190827;8518.446300990416;8585.699500517452;8648.654053837776;8658.826823463622;8317.459365141489;8453.239208471266;8484.575771211537;8525.95737021311;8543;7912.208779122042;7929.167437419219;7985.08171560278;7988.121306656147;8005.59972001386;5751.913721294268;5763.250777398911;5799.507041901317;5840.123981402823;5948.345681975065;5468.835934921959;5585.581081418991;5611.775528978795;5787.797427090031;5875.236219291465 10:42:34 INFO - PROCESS | 7090 | |4;prototype.html;338.4314351888827;340.9203856475499;344.9603295621003;350.5390411608736;350.97016753575946;5398.433164517728;5451.727413629317;5490.313710786152;5494.186918316956;5539.25220095287;5462;5481.232627432153;5492.176173573966;5498.670079795215;5503.3120859892515;3909.4424026343286;4141.550457339937;4263.573642635727;4279.550647182048;4314.395984562164;4272.764997925113;4287.957120428787;4288.506821715496;4291;4310.418093557361;4272.42322286492;4275.187714334282;4281.229378711831;4285.400043993844;4298.355246713002;4267.317229243306;4268.295731204347;4277.315629499296;4291.828326866922;4298.441202643652;3790.5830358660523;3792.50697409335;3796;3806.2387522495333;3840.7311488195837;5216;5285.339332583427;5293.444188360244;5318.946810531884;5329.894691596797;4783.95216047842;4898.902021959577;4945.282933974553;4951.480094590052;4958.281049247839;5627.240322556443;5661.273311639949;5663.716814159276;5677.495463702122;5706.828795136153;4177.143685544456;4188.643965262956;4203.285441474926;4226.577342265779;4231.936520952188;5849.6197747146725;5887.293524777011;5915.5859089863725;5970.701464926737;6011.75952961881;4276.2516559601945;4280.507741609755;4280.850170244026;4283.271843786564;4284.164587905391;4330;4341.567282796733;4341.717788343748;4358.929508483445;4362.669385837286;4789.976050119797;4893.657443978958;4938.777755001033;4962.73450270186;4968.080905032581;5620.325560932714;5685.971570142123;5691.9715401423555;5693.68684722336;5741.942580574224;1500.8199016118135;1542.1364036139573;1542.2288855572215;1542.2288855572215;1549.7055559443895;945.4091193004373;970.1656575345197;989.8465737810651;993.6522217223913;1001.6694490818159;4764.666473346832;4838.540338667821;4853.830115945925;4880;4961.503849615082;5121.4622464641425;5127.333446651912;5143.459936706667;5163.082858185114;5183.352080989876;4542.9772851136195;4815.879603009953;4847.515248475124;4864.36763220779;4903.485134060944;2732.27594687408;2739.643846299968;2745.4509098180442;2747.0659975608387;2754.862256887148;5225.608079394061;5228.764705588258;5297.205419187153;5340.839774806762;5348.91976620351;5273.837726532181;5301.920471192935;5630.324361076697;6022.548308876764;6058.636481811105;5810.680412577264;5932.228810254639;6069.787557435557;6078.696065196723;6093.14159181445;5888.528917686661;5912.526997840077;5918.141869428822;5936.041106434953;5942.673152976455;5526.696031718294;5779.219805326227;5856.619319744203;5858.238429004373;5891.941080589139;6129.295131059806;6136.478399336106;6138.693065346625;6154.323024467394;6160.845978850385;4393.868183954486;4453.35426363182;4462.419885414875;4471.814969033274;4500.167469018242;5770.826875193751;5785.450382213683;5869.148973398918;5879.235699359056;5879.882402352015;5663.518600918968;5677.1768093627015;5687.037971899688;5719.971400142973;5749.039244789021;5427.24018637399;5684.516816070515;5716.485516303553;5717.685527296038;5782.653040817564;5726.482482142153;5742.7702891883855;5749.425057494217;5756.265773757432;5781.653100813965;5798.971005144947;5806.274215723035;5818.709064546841;5829.76680932758;5840.474357307863;5534.640248383842;5626.409227031103;5645.915311270416;5663.603547751618;5679.233303503974 10:42:34 INFO - PROCESS | 7090 | |5;yui.html;774.7908064822498;808.1837344282274;816.5386556595523;882.8101958079012;919.5999740113048;15684.921575392122;17694.292228310867;17949.91025044873;17990.460286191417;18067.096645167756;16026.95824771392;16365.181740912936;16386.83613163868;16440.42458513952;16544.75182872255;869.1221865915439;891.1222445890793;893.3657103456553;893.7765558610347;896.5472436419599;890.8173824365989;895.8029233568637;896.188949001155;896.368060517337;896.7220161749846;936.3819878879941;937.995310023449;939.4363381970797;941.1858742188026;941.6421759731293;927.0729270729271;931.7158266728645;933.0576118120679;933.1788026536672;933.5099072986668;689.4553302890761;690.3165865792829;691.9965400173018;692.2489099327236;692.394155114275;11843.23019003762;12005.579804706793;12096.879031209663;12210.145289829716;12279.631611051593;13992.370343334574;14051.156930584199;14245.43018279278;14317.713645727026;14345.856541434556;11907.642770716893;11951.940240298743;12013.279203247746;12036.939815300868;12284.078694097889;4000.7799571023875;4008.2785098682225;4013.13717550728;4166.1251137261215;4206.20082184384;53499.46500535023;71037;72051;80987;81798.59100704518;11896.405179740977;13210.73578528424;13211;13741.931290343484;13763.518276860263;22588.435289117904;22896.198633047763;22920.770792292198;22955.655665165035;23073.07707691707;7961.800954976056;8021.478603890729;8087.110417854032;8107.918920810835;8276.048254450694;4668.5564871337165;4871.80512779492;4876.390451193601;5004.174311238678;5010.899782004412;3643.9635603643624;3727.310447567209;3756.079760458705;3759.981200093982;3762.6237376262156;640.5196102922808;640.759715106835;642.7846671365052;647.9481641468672;656.1994366872408;654.8493846415352;701.8491024429773;716.6954044531053;727.1710250314645;740.7740639105125;8572.52851093196;9067.637294508279;9792.461414622121;9855.162311203629;10034.69895903124;595.880823835226;600.2047287344199;623.2054130982942;624.1542709628479;633.8478765096436;4737.502562230985;4771.761411929389;4910.950890491121;4979.6265280104135;5060.51925067118;81393.18606813975;82487.17512824915;83539;135408;137042;87618.56190719132;90959;113448.43275783733;113859;116210;54964;66710;86736.566317168;87277;89869;10056.245781566266;10176.65936834547;10259.640912568024;10487.790244195226;10845.078168355778;48909.75545122252;49088.2636760456;50611.74694126506;53278.2008269884;54417;48935.26597100975;50451.74774126106;69587.65206173937;73827;75332.62333688297;14575.635609109857;14609.561713148623;14699.779503307673;14822.925885370503;14825;16153;16719.832801671826;16947.830521694625;17025.574360641083;17099.572510687332;14316.284185790877;14931.178785166485;15119.16844573515;15580.220988950734;16229.837701622833;14125.505607303696;14457;14883.47907823221;14937.551873443814;15106.320215590105;14559.636009099857;14583.489577864724;14688.41246350134;14836.703265934837;15219.315130818919;11591.0442837076;12036.338001409656;12295.32375719344;12339.259644421363;12561.371931403575;12264.693382665504;12488;12758.681032973878;12854.742905142031;13001.934990324988 10:42:34 INFO - PROCESS | 7090 | __end_tp_report 10:42:34 INFO - PROCESS | 7090 | __start_cc_report 10:42:34 INFO - PROCESS | 7090 | _x_x_mozilla_cycle_collect,1061 10:42:34 INFO - PROCESS | 7090 | __end_cc_report 10:42:34 INFO - PROCESS | 7090 | __startTimestamp1477676554499__endTimestamp 10:42:34 INFO - PROCESS | 7090 | 10:42:34 INFO - PROCESS | 7090 | ------- Summary: start ------- 10:42:34 INFO - PROCESS | 7090 | Number of tests: 6 10:42:34 INFO - PROCESS | 7090 | 10:42:34 INFO - PROCESS | 7090 | [#0] dojo.html Cycles:165 Average:5682.48 Median:5862.24 stddev:653.32 (11.1%) stddev-sans-first:625.65 10:42:34 INFO - PROCESS | 7090 | Values: 3200.9 3225.0 3237.9 3240.0 3242.1 5620.6 5707.8 5711.7 5715.8 5718.6 5723.7 5767.2 5810.5 5832.8 5833.3 5242.6 5257.6 5265.1 5317.0 5325.0 5127.0 5309.8 5321.5 5332.6 5365.1 5101.4 5148.3 5149.5 5176.1 5195.1 5176.0 5198.0 5224.5 5331.2 5333.7 5171.6 5215.3 5287.4 5302.5 5326.7 5511.0 5619.4 5644.2 5662.4 5668.6 6065.0 6066.9 6154.0 6177.4 6180.5 6007.0 6199.9 6221.0 6225.9 6238.7 5672.9 5750.2 5771.8 5808.1 5815.6 6352.8 6359.7 6363.2 6368.4 6369.8 5812.1 5922.0 6102.5 6112.2 6116.9 6069.1 6109.1 6111.5 6114.1 6126.8 6123.4 6124.5 6127.4 6128.5 6130.9 5894.4 5991.9 6078.2 6121.5 6135.4 4652.0 4671.7 4675.2 4698.3 4699.5 4102.4 4136.4 4143.9 4151.7 4167.3 5637.9 5697.4 5799.4 5812.3 5822.9 5852.8 6150.6 6157.6 6165.2 6174.0 5791.6 5806.4 5809.7 5811.5 5839.7 5168.3 5237.9 5306.5 5316.0 5318.8 6247.8 6389.2 6393.8 6394.3 6395.6 6145.3 6186.0 6196.4 6197.6 6199.3 6077.1 6152.2 6408.0 6412.4 6417.7 5811.0 5862.2 5870.1 5874.8 5918.7 5971.7 5983.2 5987.8 5994.1 5997.8 5846.4 5853.9 5862.5 5899.4 5939.9 6027.6 6047.1 6065.8 6094.5 6103.5 5913.0 5966.7 5974.9 5976.1 5977.8 5833.6 5901.1 5953.7 6010.4 6030.1 5855.9 5905.3 5920.5 5921.7 5953.7 10:42:34 INFO - PROCESS | 7090 | 10:42:34 INFO - PROCESS | 7090 | [#1] ext.html Cycles:180 Average:63864.39 Median:39152.61 stddev:125127.51 (319.6%) stddev-sans-first:125388.33 10:42:34 INFO - PROCESS | 7090 | Values: 589.3 609.0 643.2 647.6 677.7 35244.6 47364.5 48286.3 48299.0 48321.3 49009.0 49375.0 49415.3 49531.8 49552.5 8460.1 9223.2 9383.1 9383.4 10888.2 11058.4 11099.0 11147.3 11211.8 11224.7 11092.5 11137.4 11148.7 11151.3 11152.0 11091.2 11151.6 11172.9 11215.4 11303.0 11060.2 11135.4 11137.8 11142.6 11151.1 45679.0 46108.8 46133.8 46191.5 46201.8 3797.3 3821.2 4009.6 4213.5 4404.1 35189.5 35504.0 35513.4 35770.6 37140.4 4636.5 4681.6 4731.9 4777.7 4861.7 379819.0 380344.0 381612.0 381958.0 382585.1 61810.4 62265.0 62285.7 62286.0 62320.7 38837.4 40299.4 40430.2 42177.6 65781.0 15837.6 15889.1 15923.8 15947.9 15967.2 11789.6 11954.8 12010.8 12049.5 12251.3 20713.6 20873.9 20972.0 21029.5 21038.7 7327.4 7388.4 7544.8 7595.7 7665.2 1015.8 1175.7 1214.2 1236.2 1248.1 34752.3 35031.3 35112.3 35230.9 35273.3 1146.2 1193.3 1196.3 1252.3 1263.8 18335.2 18509.8 18611.9 18634.0 18651.5 577510.0 596114.0 599046.0 607685.0 607817.0 64712.0 64791.7 64943.0 65126.0 65507.0 1315.3 1453.4 1459.3 1475.5 1481.3 32794.8 36981.0 38267.0 38711.4 39308.0 60151.4 61022.0 61091.0 61141.4 61162.6 404639.0 405742.0 408587.0 409106.0 409115.0 27418.9 30058.1 31235.7 31317.7 31653.5 52697.2 52892.0 53190.5 53242.0 53861.7 43583.3 44292.6 44607.6 45402.3 46123.3 43318.3 44516.6 45138.3 45678.5 46226.8 44287.3 44791.6 45456.8 45718.5 45796.1 37384.8 38997.2 39619.8 40061.0 40400.2 29642.3 29909.7 30228.2 30292.5 30370.0 10:42:34 INFO - PROCESS | 7090 | 10:42:34 INFO - PROCESS | 7090 | [#2] jquery.html Cycles:180 Average:31418.14 Median:409.00 stddev:91179.83 (22293.1%) stddev-sans-first:91405.85 10:42:34 INFO - PROCESS | 7090 | Values: 389.8 394.7 408.6 409.4 422.9 14652.6 19404.0 20694.6 20895.6 21052.0 19477.4 20260.6 20326.9 20462.5 20841.6 5277.7 5579.7 5589.6 5591.7 5591.9 5430.8 5446.2 5452.0 5521.2 5529.8 5396.0 5782.6 5907.6 5921.8 5922.0 5856.2 5923.4 5948.0 5976.1 5978.3 5450.5 5474.7 5493.2 5494.8 5496.4 13894.1 25113.1 25115.4 25263.5 25422.1 2408.3 2465.5 2529.1 2538.3 2808.6 10202.6 10971.5 10990.5 11046.2 11186.1 2082.3 2141.1 2150.4 2228.1 2243.5 87881.0 89466.6 89930.0 90267.1 91323.5 11460.1 11992.2 12195.8 12314.4 12404.0 17337.1 17340.9 17355.1 17398.7 17399.8 8518.6 8651.2 8728.9 8797.5 8802.8 8182.3 8230.0 8234.0 8374.7 8398.6 5141.9 5144.6 5179.4 5273.5 5332.0 2140.2 2147.9 2169.2 2177.8 2238.6 1037.6 1056.3 1059.1 1059.6 1137.2 20977.5 21492.9 21694.0 21706.2 21924.0 1051.0 1067.6 1074.7 1097.6 1146.4 11633.7 11928.7 11993.8 12170.7 12300.5 543602.0 545145.0 553034.0 561623.0 564133.0 68485.3 84014.6 97396.0 100565.0 102658.5 1018.7 1035.7 1041.2 1045.5 1059.8 18480.8 19333.3 19444.9 19540.8 19560.3 44945.3 45821.3 46150.5 46833.8 48292.8 63452.0 64215.0 64445.7 65488.3 66083.0 7564.4 8135.5 8230.3 8739.5 8777.6 9106.8 9397.0 9477.1 9524.6 10253.7 8143.8 8501.9 8556.1 8898.1 9145.1 8720.8 8835.5 8944.4 9178.5 9408.3 8598.3 8740.4 8757.8 9066.3 9650.4 11074.3 12315.7 12675.2 12756.1 13269.8 11691.4 12387.1 12707.9 12746.7 12879.8 10:42:34 INFO - PROCESS | 7090 | 10:42:34 INFO - PROCESS | 7090 | [#3] mootools.html Cycles:180 Average:6417.34 Median:4436.16 stddev:3757.80 (84.7%) stddev-sans-first:3750.46 10:42:34 INFO - PROCESS | 7090 | Values: 1538.4 1546.7 1737.5 1869.6 1876.6 9195.5 9950.5 9951.7 9962.1 9984.3 8464.1 9034.5 9410.9 9440.4 9441.8 2437.1 2502.3 2506.5 2506.9 2508.3 2348.9 2453.1 2456.2 2457.5 2458.1 2484.2 2489.7 2530.8 2532.5 2532.6 2458.9 2467.8 2472.9 2498.3 2503.3 2435.6 2437.2 2447.1 2447.8 2509.2 9673.8 9719.4 9727.5 9741.8 9747.8 5618.6 5722.9 5777.1 5783.7 5793.1 3395.1 3500.7 3564.5 3588.4 3598.0 1134.9 1169.5 1188.2 1195.2 1220.0 13825.2 14063.8 14128.4 14130.9 14140.2 4874.2 4905.2 5001.7 5091.4 5096.1 11800.1 11842.6 11869.8 11873.0 11888.3 5761.8 5844.4 5844.5 5850.4 5855.7 4661.7 4969.1 5057.8 5091.1 5344.3 4298.8 4338.0 4386.3 4433.1 4439.2 1319.5 1365.1 1367.9 1390.2 1394.9 896.0 965.5 1026.2 1029.8 1055.7 6286.1 6304.8 6419.2 6419.7 6449.8 1030.1 1130.7 1139.0 1141.1 1154.7 4272.4 4373.3 4393.2 4429.9 4503.9 12218.9 12234.7 12312.9 12521.1 12629.5 12131.4 12418.6 12439.4 12468.5 12487.1 9099.3 9132.6 9191.7 9206.0 9209.3 6221.6 6261.3 6287.1 6311.4 6370.0 11144.5 11241.4 11383.5 11390.2 11440.0 11143.3 11627.5 11704.9 11755.9 11814.5 7436.0 8036.0 8180.0 8192.7 8406.9 9670.8 9675.3 9693.9 9739.6 9747.7 8442.2 8518.4 8585.7 8648.7 8658.8 8317.5 8453.2 8484.6 8526.0 8543.0 7912.2 7929.2 7985.1 7988.1 8005.6 5751.9 5763.3 5799.5 5840.1 5948.3 5468.8 5585.6 5611.8 5787.8 5875.2 10:42:34 INFO - PROCESS | 7090 | 10:42:34 INFO - PROCESS | 7090 | [#4] prototype.html Cycles:180 Average:4752.17 Median:5289.39 stddev:1382.34 (26.1%) stddev-sans-first:1345.93 10:42:34 INFO - PROCESS | 7090 | Values: 338.4 340.9 345.0 350.5 351.0 5398.4 5451.7 5490.3 5494.2 5539.3 5462.0 5481.2 5492.2 5498.7 5503.3 3909.4 4141.6 4263.6 4279.6 4314.4 4272.8 4288.0 4288.5 4291.0 4310.4 4272.4 4275.2 4281.2 4285.4 4298.4 4267.3 4268.3 4277.3 4291.8 4298.4 3790.6 3792.5 3796.0 3806.2 3840.7 5216.0 5285.3 5293.4 5318.9 5329.9 4784.0 4898.9 4945.3 4951.5 4958.3 5627.2 5661.3 5663.7 5677.5 5706.8 4177.1 4188.6 4203.3 4226.6 4231.9 5849.6 5887.3 5915.6 5970.7 6011.8 4276.3 4280.5 4280.9 4283.3 4284.2 4330.0 4341.6 4341.7 4358.9 4362.7 4790.0 4893.7 4938.8 4962.7 4968.1 5620.3 5686.0 5692.0 5693.7 5741.9 1500.8 1542.1 1542.2 1542.2 1549.7 945.4 970.2 989.8 993.7 1001.7 4764.7 4838.5 4853.8 4880.0 4961.5 5121.5 5127.3 5143.5 5163.1 5183.4 4543.0 4815.9 4847.5 4864.4 4903.5 2732.3 2739.6 2745.5 2747.1 2754.9 5225.6 5228.8 5297.2 5340.8 5348.9 5273.8 5301.9 5630.3 6022.5 6058.6 5810.7 5932.2 6069.8 6078.7 6093.1 5888.5 5912.5 5918.1 5936.0 5942.7 5526.7 5779.2 5856.6 5858.2 5891.9 6129.3 6136.5 6138.7 6154.3 6160.8 4393.9 4453.4 4462.4 4471.8 4500.2 5770.8 5785.5 5869.1 5879.2 5879.9 5663.5 5677.2 5687.0 5720.0 5749.0 5427.2 5684.5 5716.5 5717.7 5782.7 5726.5 5742.8 5749.4 5756.3 5781.7 5799.0 5806.3 5818.7 5829.8 5840.5 5534.6 5626.4 5645.9 5663.6 5679.2 10:42:34 INFO - PROCESS | 7090 | 10:42:34 INFO - PROCESS | 7090 | [#5] yui.html Cycles:180 Average:20393.82 Median:4437.38 stddev:28655.62 (645.8%) stddev-sans-first:28698.14 10:42:34 INFO - PROCESS | 7090 | Values: 774.8 808.2 816.5 882.8 919.6 15684.9 17694.3 17949.9 17990.5 18067.1 16027.0 16365.2 16386.8 16440.4 16544.8 869.1 891.1 893.4 893.8 896.5 890.8 895.8 896.2 896.4 896.7 936.4 938.0 939.4 941.2 941.6 927.1 931.7 933.1 933.2 933.5 689.5 690.3 692.0 692.2 692.4 11843.2 12005.6 12096.9 12210.1 12279.6 13992.4 14051.2 14245.4 14317.7 14345.9 11907.6 11951.9 12013.3 12036.9 12284.1 4000.8 4008.3 4013.1 4166.1 4206.2 53499.5 71037.0 72051.0 80987.0 81798.6 11896.4 13210.7 13211.0 13741.9 13763.5 22588.4 22896.2 22920.8 22955.7 23073.1 7961.8 8021.5 8087.1 8107.9 8276.0 4668.6 4871.8 4876.4 5004.2 5010.9 3644.0 3727.3 3756.1 3760.0 3762.6 640.5 640.8 642.8 647.9 656.2 654.8 701.8 716.7 727.2 740.8 8572.5 9067.6 9792.5 9855.2 10034.7 595.9 600.2 623.2 624.2 633.8 4737.5 4771.8 4911.0 4979.6 5060.5 81393.2 82487.2 83539.0 135408.0 137042.0 87618.6 90959.0 113448.4 113859.0 116210.0 54964.0 66710.0 86736.6 87277.0 89869.0 10056.2 10176.7 10259.6 10487.8 10845.1 48909.8 49088.3 50611.7 53278.2 54417.0 48935.3 50451.7 69587.7 73827.0 75332.6 14575.6 14609.6 14699.8 14822.9 14825.0 16153.0 16719.8 16947.8 17025.6 17099.6 14316.3 14931.2 15119.2 15580.2 16229.8 14125.5 14457.0 14883.5 14937.6 15106.3 14559.6 14583.5 14688.4 14836.7 15219.3 11591.0 12036.3 12295.3 12339.3 12561.4 12264.7 12488.0 12758.7 12854.7 13001.9 10:42:34 INFO - PROCESS | 7090 | -------- Summary: end -------- 10:42:34 INFO - PROCESS | 7090 | 10:42:35 INFO - TEST-INFO | 7090: exit 0 10:42:35 INFO - TEST-OK | dromaeo_css | took 1112381ms 10:42:35 INFO - TEST-START | kraken 10:42:35 INFO - operating with platform_type : linux_ 10:42:35 INFO - Initialising browser for kraken test... 10:42:35 INFO - TEST-INFO | started process 7286 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFRpLl4/profile http://localhost:35713/getInfo.html) 10:42:38 INFO - PROCESS | 7286 | __metrics Screen width/height:1600/1200 10:42:38 INFO - PROCESS | 7286 | colorDepth:24 10:42:38 INFO - PROCESS | 7286 | Browser inner width/height: 1024/697 10:42:38 INFO - PROCESS | 7286 | __metrics 10:42:39 INFO - PROCESS | 7286 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:42:39 INFO - PROCESS | 7286 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:42:40 INFO - TEST-INFO | 7286: exit 0 10:42:40 INFO - Browser initialized. 10:42:40 INFO - Running cycle 1/1 for kraken test... 10:42:40 INFO - TEST-INFO | started process 7371 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFRpLl4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:52 INFO - PROCESS | 7371 | RSS: Main: 144105472 10:42:52 INFO - PROCESS | 7371 | 10:42:54 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35713/tests/kraken/driver.html?testName=audio-beat-detection) 10:42:55 INFO - PROCESS | 7371 | RSS: Main: 199159808 10:42:55 INFO - PROCESS | 7371 | 10:42:58 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35713/tests/kraken/driver.html?testName=audio-dft) 10:42:58 INFO - PROCESS | 7371 | RSS: Main: 183685120 10:42:58 INFO - PROCESS | 7371 | 10:43:01 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35713/tests/kraken/driver.html?testName=audio-fft) 10:43:02 INFO - PROCESS | 7371 | RSS: Main: 275693568 10:43:02 INFO - PROCESS | 7371 | 10:43:04 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35713/tests/kraken/driver.html?testName=audio-oscillator) 10:43:04 INFO - PROCESS | 7371 | RSS: Main: 180518912 10:43:04 INFO - PROCESS | 7371 | 10:43:06 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35713/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:43:07 INFO - PROCESS | 7371 | RSS: Main: 178778112 10:43:07 INFO - PROCESS | 7371 | 10:43:10 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35713/tests/kraken/driver.html?testName=imaging-darkroom) 10:43:11 INFO - PROCESS | 7371 | RSS: Main: 215224320 10:43:11 INFO - PROCESS | 7371 | 10:43:14 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35713/tests/kraken/driver.html?testName=imaging-desaturate) 10:43:15 INFO - PROCESS | 7371 | RSS: Main: 214016000 10:43:15 INFO - PROCESS | 7371 | 10:43:17 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35713/tests/kraken/driver.html?testName=json-parse-financial) 10:43:18 INFO - PROCESS | 7371 | RSS: Main: 213778432 10:43:18 INFO - PROCESS | 7371 | 10:43:19 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35713/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:43:20 INFO - PROCESS | 7371 | RSS: Main: 176168960 10:43:20 INFO - PROCESS | 7371 | 10:43:22 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35713/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:43:22 INFO - PROCESS | 7371 | RSS: Main: 183234560 10:43:22 INFO - PROCESS | 7371 | 10:43:24 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35713/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:43:25 INFO - PROCESS | 7371 | RSS: Main: 189906944 10:43:25 INFO - PROCESS | 7371 | 10:43:27 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35713/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:43:28 INFO - PROCESS | 7371 | RSS: Main: 197451776 10:43:28 INFO - PROCESS | 7371 | 10:43:31 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35713/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:43:31 INFO - PROCESS | 7371 | RSS: Main: 193822720 10:43:31 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | Cycle 1(1): loaded http://localhost:35713/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35713/tests/kraken/driver.html?testName=ai-astar) 10:43:33 INFO - PROCESS | 7371 | RSS: Main: 244318208 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | __start_tp_report 10:43:33 INFO - PROCESS | 7371 | _x_x_mozilla_page_load 10:43:33 INFO - PROCESS | 7371 | _x_x_mozilla_page_load_details 10:43:33 INFO - PROCESS | 7371 | |i|pagename|runs| 10:43:33 INFO - PROCESS | 7371 | |0;ai-astar;112;110;120;109;109;111;109;111;111;111 10:43:33 INFO - PROCESS | 7371 | |1;audio-beat-detection;145;117;143;170;117;140;149;117;174;117 10:43:33 INFO - PROCESS | 7371 | |2;audio-dft;160;160;159;160;159;161;159;160;161;159 10:43:33 INFO - PROCESS | 7371 | |3;audio-fft;74;74;75;186;76;76;74;76;165;76 10:43:33 INFO - PROCESS | 7371 | |4;audio-oscillator;84;82;149;83;83;84;146;83;84;147 10:43:33 INFO - PROCESS | 7371 | |5;imaging-gaussian-blur;116;116;116;117;117;116;117;117;116;117 10:43:33 INFO - PROCESS | 7371 | |6;imaging-darkroom;146;147;146;146;146;146;147;146;146;146 10:43:33 INFO - PROCESS | 7371 | |7;imaging-desaturate;94;94;95;94;95;94;94;95;95;95 10:43:33 INFO - PROCESS | 7371 | |8;json-parse-financial;52;51;53;51;51;53;51;50;51;52 10:43:33 INFO - PROCESS | 7371 | |9;json-stringify-tinderbox;60;59;59;59;61;59;60;60;59;60 10:43:33 INFO - PROCESS | 7371 | |10;stanford-crypto-aes;74;73;73;83;83;74;81;74;74;72 10:43:33 INFO - PROCESS | 7371 | |11;stanford-crypto-ccm;123;124;122;121;120;129;127;126;158;123 10:43:33 INFO - PROCESS | 7371 | |12;stanford-crypto-pbkdf2;156;155;156;156;154;159;158;155;155;155 10:43:33 INFO - PROCESS | 7371 | |13;stanford-crypto-sha256-iterative;66;67;62;63;63;63;69;63;65;62 10:43:33 INFO - PROCESS | 7371 | __end_tp_report 10:43:33 INFO - PROCESS | 7371 | __start_cc_report 10:43:33 INFO - PROCESS | 7371 | _x_x_mozilla_cycle_collect,1677 10:43:33 INFO - PROCESS | 7371 | __end_cc_report 10:43:33 INFO - PROCESS | 7371 | __startTimestamp1477676613652__endTimestamp 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | ------- Summary: start ------- 10:43:33 INFO - PROCESS | 7371 | Number of tests: 14 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:3.23 (2.9%) stddev-sans-first:3.42 10:43:33 INFO - PROCESS | 7371 | Values: 112.0 110.0 120.0 109.0 109.0 111.0 109.0 111.0 111.0 111.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:144.00 stddev:21.79 (15.1%) stddev-sans-first:23.00 10:43:33 INFO - PROCESS | 7371 | Values: 145.0 117.0 143.0 170.0 117.0 140.0 149.0 117.0 174.0 117.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 10:43:33 INFO - PROCESS | 7371 | Values: 160.0 160.0 159.0 160.0 159.0 161.0 159.0 160.0 161.0 159.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#3] audio-fft Cycles:10 Average:95.20 Median:75.50 stddev:42.62 (56.4%) stddev-sans-first:44.51 10:43:33 INFO - PROCESS | 7371 | Values: 74.0 74.0 75.0 186.0 76.0 76.0 74.0 76.0 165.0 76.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:83.00 stddev:30.95 (37.3%) stddev-sans-first:32.10 10:43:33 INFO - PROCESS | 7371 | Values: 84.0 82.0 149.0 83.0 83.0 84.0 146.0 83.0 84.0 147.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 10:43:33 INFO - PROCESS | 7371 | Values: 116.0 116.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 10:43:33 INFO - PROCESS | 7371 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 10:43:33 INFO - PROCESS | 7371 | Values: 94.0 94.0 95.0 94.0 95.0 94.0 94.0 95.0 95.0 95.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.50 stddev:0.97 (1.9%) stddev-sans-first:1.01 10:43:33 INFO - PROCESS | 7371 | Values: 52.0 51.0 53.0 51.0 51.0 53.0 51.0 50.0 51.0 52.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:0.70 (1.2%) stddev-sans-first:0.73 10:43:33 INFO - PROCESS | 7371 | Values: 60.0 59.0 59.0 59.0 61.0 59.0 60.0 60.0 59.0 60.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:74.00 stddev:4.38 (5.9%) stddev-sans-first:4.58 10:43:33 INFO - PROCESS | 7371 | Values: 74.0 73.0 73.0 83.0 83.0 74.0 81.0 74.0 74.0 72.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:125.00 stddev:11.14 (8.9%) stddev-sans-first:11.70 10:43:33 INFO - PROCESS | 7371 | Values: 123.0 124.0 122.0 121.0 120.0 129.0 127.0 126.0 158.0 123.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.90 Median:156.00 stddev:1.52 (1.0%) stddev-sans-first:1.62 10:43:33 INFO - PROCESS | 7371 | Values: 156.0 155.0 156.0 156.0 154.0 159.0 158.0 155.0 155.0 155.0 10:43:33 INFO - PROCESS | 7371 | 10:43:33 INFO - PROCESS | 7371 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:2.36 (3.7%) stddev-sans-first:2.42 10:43:33 INFO - PROCESS | 7371 | Values: 66.0 67.0 62.0 63.0 63.0 63.0 69.0 63.0 65.0 62.0 10:43:33 INFO - PROCESS | 7371 | -------- Summary: end -------- 10:43:33 INFO - PROCESS | 7371 | 10:43:34 INFO - TEST-INFO | 7371: exit 0 10:43:34 INFO - TEST-OK | kraken | took 59361ms 10:43:34 INFO - SUITE-END | took 1171s 10:43:34 INFO - Completed test suite (00:19:31) 10:43:34 INFO - javascript benchmark 10:43:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3200.855961481733, 3225.0324902529264, 3237.902862914112, 3240.0, 3242.14083267934, 5620.5784566157545, 5707.828765137047, 5711.7144142792795, 5715.799947001861, 5718.59969802114, 5723.656580605156, 5767.163761254616, 5810.535157187425, 5832.825015249538, 5833.270841144857, 5242.606804489669, 5257.579393648499, 5265.078611243036, 5316.9734151329385, 5325.0, 5126.974365128169, 5309.840704778843, 5321.52106310432, 5332.626716129873, 5365.08793505105, 5101.413337466183, 5148.304978827867, 5149.510796474347, 5176.0683077046115, 5195.064888320101, 5175.974120129413, 5197.974010129944, 5224.477552244745, 5331.226972089025, 5333.706646134461, 5171.5862730981435, 5215.295935048795, 5287.391949925731, 5302.549283310923, 5326.6537675050995, 5510.980468613319, 5619.438056194389, 5644.238027866268, 5662.433756624346, 5668.574856885709, 6065.0, 6066.8786624267705, 6154.046122850965, 6177.351378105324, 6180.505559555225, 6006.969965150146, 6199.938000619981, 6221.0, 6225.875482490324, 6238.656873871935, 5672.8865422691315, 5750.1949727038245, 5771.826845194609, 5808.128780682847, 5815.563832712563, 6352.777652782131, 6359.745610175633, 6363.236411630634, 6368.42684158428, 6369.808905732835, 5812.128180772918, 5922.0, 6102.542309326818, 6112.205413296243, 6116.908246376308, 6069.0592958091565, 6109.101029780167, 6111.480524155408, 6114.145302971479, 6126.761967422298, 6123.418275263843, 6124.540659450559, 6127.356627554132, 6128.540359473057, 6130.877382452326, 5894.381089985575, 5991.940080599138, 6078.209832721718, 6121.510279177655, 6135.44780969706, 4651.976740116278, 4671.672982891233, 4675.158471475099, 4698.318743782201, 4699.530046995274, 4102.364133559303, 4136.379543068564, 4143.875683729493, 4151.73013754105, 4167.333226683777, 5637.8590535236945, 5697.401772813878, 5799.420057994251, 5812.273465816773, 5822.941770582324, 5852.7658893643775, 6150.600210986271, 6157.568970172045, 6165.229346331708, 6173.969130154321, 5791.623544469596, 5806.419358064244, 5809.68046757424, 5811.506021988178, 5839.737211825478, 5168.2764412982215, 5237.895242095213, 5306.46935306474, 5315.973420132952, 5318.760655770499, 6247.843803904939, 6389.201349831271, 6393.776217832354, 6394.328595497405, 6395.584287021329, 6145.262568491631, 6185.969070154801, 6196.442320191024, 6197.597156185014, 6199.318075011655, 6077.05805600115, 6152.16945712341, 6407.967960160169, 6412.35876412355, 6417.743290268337, 5811.0121279381765, 5862.237909071793, 5870.119482077723, 5874.765009399576, 5918.733656985532, 5971.731272092679, 5983.222181116427, 5987.760489580368, 5994.07091900756, 5997.790077347272, 5846.386129456346, 5853.912191317048, 5862.530997520274, 5899.410058994066, 5939.881202376015, 6027.60820546663, 6047.0627052806885, 6065.787697430569, 6094.51243900478, 6103.511719062376, 5912.970435147797, 5966.701664916824, 5974.9402505974385, 5976.103584462366, 5977.760889564369, 5833.562482813721, 5901.144334071621, 5953.732082056405, 6010.398960103955, 6030.125631783455, 5855.9121613176685, 5905.291365036223, 5920.526357891445, 5921.733521991435, 5953.672548009901], "value": 5637.251982878635, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.3487696095814, 608.9939100608995, 643.1670986073034, 647.5790736021588, 677.6984242012301, 35244.64755352445, 47364.526354736445, 48286.275705864355, 48299.0, 48321.27518087227, 49009.0, 49375.0, 49415.25877111837, 49531.75234123824, 49552.50447495524, 8460.111688272704, 9223.169914707674, 9383.06169383063, 9383.390079644802, 10888.183386246024, 11058.391788451672, 11099.0, 11147.331160130418, 11211.775764484706, 11224.719382015433, 11092.500837462294, 11137.443127843617, 11148.665540033771, 11151.330920144777, 11152.0, 11091.168162387812, 11151.553937842516, 11172.888271117346, 11215.439228038647, 11303.0, 11060.17048721349, 11135.387553684544, 11137.832932506019, 11142.554297828077, 11151.052160566362, 45679.0, 46108.847278817964, 46133.76933115313, 46191.53808461906, 46201.768991155164, 3797.2974999625158, 3821.178446633959, 4009.578994205596, 4213.47331583552, 4404.075144219686, 35189.472157917655, 35504.0, 35513.401896914445, 35770.64229357699, 37140.442893356354, 4636.5131661175765, 4681.555252251031, 4731.905361892743, 4777.713337199744, 4861.708297502161, 379819.0, 380344.0, 381612.0, 381958.0, 382585.08707456564, 61810.38189618136, 62265.0, 62285.68857155776, 62286.0, 62320.68839655863, 38837.41743873844, 40299.395509067384, 40430.19139617191, 42177.57822421798, 65781.0, 15837.604059898595, 15889.126098064717, 15923.840761592468, 15947.920260398623, 15967.201639917957, 11789.646310610695, 11954.820677689842, 12010.75978480443, 12049.457774400173, 12251.326177060348, 20713.58572828535, 20873.895630522053, 20972.0, 21029.527933044847, 21038.684419733716, 7327.37717294036, 7388.374557597427, 7544.8491030180185, 7595.734149304748, 7665.209244222361, 1015.8476228565627, 1175.6663023897556, 1214.2289646074805, 1236.190295356543, 1248.1325478792335, 34752.30495390078, 35031.29937401238, 35112.297754045285, 35230.90226578619, 35273.294534109686, 1146.1690274550883, 1193.3078814287692, 1196.3180986837592, 1252.2674235572292, 1263.816746571742, 18335.174917128763, 18509.814901851078, 18611.90694046521, 18634.0, 18651.533711657317, 577510.0, 596114.0, 599046.0, 607685.0, 607817.0, 64712.0, 64791.67604161949, 64943.0, 65126.0, 65507.0, 1315.2963164707028, 1453.3750487290356, 1459.306829256095, 1475.527831094061, 1481.2889812889873, 32794.836025820674, 36981.0, 38267.0, 38711.41878889428, 39308.0, 60151.39848601458, 61022.0, 61091.0, 61141.38858611357, 61162.6360550174, 404639.0, 405742.0, 408587.0, 409106.0, 409115.0, 27418.862905686143, 30058.098257052323, 31235.687643123278, 31317.68682313239, 31653.525197121602, 52697.20954185614, 52892.0, 53190.46809531855, 53242.0, 53861.73069134629, 43583.34624980691, 44292.55707442884, 44607.55392446034, 45402.31896521489, 46123.3081503771, 43318.34291330385, 44516.55483445254, 45138.322925155495, 45678.54321456876, 46226.76886615546, 44287.26715011175, 44791.55208447874, 45456.772716136205, 45718.54281457276, 45796.08407831891, 37384.81307593445, 38997.22005559816, 39619.801900991464, 40061.0, 40400.1919961605, 29642.25894352572, 29909.70090299069, 30228.24429389195, 30292.54561181628, 30370.0], "value": 21892.601519394426, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.7525071579546, 394.6960840153084, 408.61998341542363, 409.39000888675884, 422.8646833013435, 14652.633684157901, 19404.0, 20694.586108277865, 20895.582088358224, 21052.0, 19477.415677529698, 20260.59478810423, 20326.89836550815, 20462.488437789027, 20841.583168336623, 5277.683338999663, 5579.69311687859, 5589.552835773128, 5591.692456914868, 5591.888162236753, 5430.75561599727, 5446.210299506569, 5451.972740136293, 5521.199426083215, 5529.778808847641, 5396.028714831329, 5782.624129431573, 5907.5864689471755, 5921.792737254197, 5922.022866227067, 5856.238688970407, 5923.407659234063, 5948.0, 5976.073708575178, 5978.252718410199, 5450.4549545045575, 5474.671519708831, 5493.2034854945805, 5494.835154945318, 5496.422875598045, 13894.096883702527, 25113.121040763668, 25115.36750111237, 25263.494730105296, 25422.110226141995, 2408.2654790288957, 2465.457599328145, 2529.051605647882, 2538.327343254039, 2808.620836187109, 10202.591896324138, 10971.451427428598, 10990.450477476174, 11046.226764126515, 11186.105111591134, 2082.2920207129496, 2141.1328411993168, 2150.3656421355736, 2228.0864845413303, 2243.450354663118, 87881.0, 89466.5526672369, 89930.0, 90267.09732902718, 91323.54338228266, 11460.140489463323, 11992.220505667105, 12195.841395067455, 12314.384280785926, 12404.0, 17337.133143343035, 17340.91329543344, 17355.13224338778, 17398.739018914726, 17399.82600173982, 8518.616662250213, 8651.221390074923, 8728.869066964, 8797.516136612549, 8802.779930501789, 8182.263596276363, 8229.95885020571, 8233.958830205811, 8374.665013399517, 8398.580070996426, 5141.920196758648, 5144.562712169432, 5179.430262671103, 5273.472652734696, 5332.040232758063, 2140.1867290429536, 2147.903344349508, 2169.1648715244746, 2177.8366622503377, 2238.6120605224537, 1037.6316407675317, 1056.3450660590483, 1059.0891833023593, 1059.6185373265619, 1137.1641843245209, 20977.475563111046, 21492.892535537223, 21694.0, 21706.240281590068, 21924.0, 1050.9842352364722, 1067.64767626683, 1074.7313171707074, 1097.6213206443765, 1146.363768108691, 11633.6509904703, 11928.701782455335, 11993.760124797629, 12170.695732606579, 12300.507979680713, 543602.0, 545145.0, 553034.0, 561623.0, 564133.0, 68485.31514684789, 84014.57992709997, 97396.0, 100565.0, 102658.48670756599, 1018.7351288665001, 1035.6634093919506, 1041.2294901772634, 1045.5451878432907, 1059.7933402986498, 18480.815191848447, 19333.323333683817, 19444.902775486597, 19540.8045919539, 19560.315388961317, 44945.32582011338, 45821.31268030916, 46150.53849461597, 46833.765831170625, 48292.7585362071, 63452.0, 64215.0, 64445.67777161272, 65488.345116548226, 66083.0, 7564.432667549846, 8135.471194372347, 8230.300424463987, 8739.519326437106, 8777.648894044421, 9106.772330691785, 9396.95301523488, 9477.099675530813, 9524.61901523959, 10253.692389228336, 8143.75568732939, 8501.87247191305, 8556.058833528183, 8898.065703101081, 9145.085491450802, 8720.825583488422, 8835.514046727234, 8944.418612790148, 9178.54107294646, 9408.294377921546, 8598.269147122564, 8740.431871928304, 8757.824843503222, 9066.27469802401, 9650.372725772802, 11074.280171788809, 12315.69210769738, 12675.17611355259, 12756.107072504836, 13269.800952985906, 11691.415429228335, 12387.132900696864, 12707.93646031764, 12746.745065098832, 12879.80680289815], "value": 9488.342935585511, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1538.3615799443228, 1546.6674664947038, 1737.5134962210577, 1869.6354210928873, 1876.6246750649873, 9195.494247816376, 9950.452725100116, 9951.701448956524, 9962.103410693036, 9984.251181161406, 8464.111268316816, 9034.54827258636, 9410.858837117448, 9440.433573985565, 9441.81116377672, 2437.086092715236, 2502.3368807265997, 2506.4736405354897, 2506.9094943699456, 2508.3352911478473, 2348.941276468085, 2453.092355828337, 2456.1771806444863, 2457.533068716947, 2458.0536493450063, 2484.1553871683623, 2489.726130125685, 2530.784883284923, 2532.518821423933, 2532.645429639852, 2458.938526536842, 2467.802575793932, 2472.863992480413, 2498.263012411325, 2503.2865633294605, 2435.6346548017764, 2437.2200895713377, 2447.131268399728, 2447.7674620910984, 2509.2221411362534, 9673.758156046084, 9719.368241064309, 9727.464989425578, 9741.756456088584, 9747.805043899154, 5618.578606604521, 5722.942770572283, 5777.104548794901, 5783.652980821122, 5793.13103034544, 3395.134240768613, 3500.7374446916583, 3564.518789963348, 3588.4437912123662, 3597.9820100899587, 1134.8808375120575, 1169.502961241469, 1188.2395267029108, 1195.171387776703, 1219.9695007624791, 13825.170489770646, 14063.789043164361, 14128.364223609859, 14130.92934535331, 14140.222287774168, 4874.172185430463, 4905.2151655734915, 5001.7499125043605, 5091.414487334004, 5096.108181068298, 11800.114991375682, 11842.644720658394, 11869.762604747857, 11873.0, 11888.34614096233, 5761.798337058183, 5844.386339434383, 5844.503217226584, 5850.3564607893095, 5855.736491857877, 4661.696989695659, 4969.105560999054, 5057.797688092509, 5091.1090559152, 5344.278522399504, 4298.849540266076, 4337.9783101084295, 4386.2762644163995, 4433.113377324548, 4439.200943830142, 1319.4985905355904, 1365.1263191557412, 1367.863213678624, 1390.2145287912297, 1394.8814350780094, 895.9820803584022, 965.4689920543673, 1026.2046913641987, 1029.8043371759343, 1055.714956961616, 6286.088517164985, 6304.779332723333, 6419.165508483868, 6419.711112999927, 6449.774257900951, 1030.1346868630237, 1130.7286251299793, 1138.9658310250707, 1141.14414189358, 1154.7459558897197, 4272.423222864873, 4373.278409062469, 4393.231184542692, 4429.933550996738, 4503.864884053483, 12218.877811221952, 12234.694132646755, 12312.876871231172, 12521.123521353418, 12629.494820207272, 12131.393430328624, 12418.627441176779, 12439.37803109841, 12468.5012599495, 12487.06347023977, 9099.317551183687, 9132.634694612274, 9191.724248272696, 9206.0, 9209.309301802392, 6221.595596286227, 6261.279952805304, 6287.056941458818, 6311.368863113652, 6370.044493326038, 11144.498497567467, 11241.381724005258, 11383.487743051417, 11390.202685811913, 11440.0, 11143.331400115694, 11627.47676354583, 11704.94147529257, 11755.88244117548, 11814.527418903148, 7436.0333156689285, 8035.959820201096, 8180.0, 8192.713255036046, 8406.915930840612, 9670.75823104428, 9675.322727409013, 9693.903060969582, 9739.561719722487, 9747.658831940847, 8442.197991190827, 8518.446300990416, 8585.699500517452, 8648.654053837776, 8658.826823463622, 8317.459365141489, 8453.239208471266, 8484.575771211537, 8525.95737021311, 8543.0, 7912.208779122042, 7929.167437419219, 7985.08171560278, 7988.121306656147, 8005.59972001386, 5751.913721294268, 5763.250777398911, 5799.507041901317, 5840.123981402823, 5948.345681975065, 5468.835934921959, 5585.581081418991, 5611.775528978795, 5787.797427090031, 5875.236219291465], "value": 5082.905384261895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.4314351888827, 340.9203856475499, 344.9603295621003, 350.5390411608736, 350.97016753575946, 5398.433164517728, 5451.727413629317, 5490.313710786152, 5494.186918316956, 5539.25220095287, 5462.0, 5481.232627432153, 5492.176173573966, 5498.670079795215, 5503.3120859892515, 3909.4424026343286, 4141.550457339937, 4263.573642635727, 4279.550647182048, 4314.395984562164, 4272.764997925113, 4287.957120428787, 4288.506821715496, 4291.0, 4310.418093557361, 4272.42322286492, 4275.187714334282, 4281.229378711831, 4285.400043993844, 4298.355246713002, 4267.317229243306, 4268.295731204347, 4277.315629499296, 4291.828326866922, 4298.441202643652, 3790.5830358660523, 3792.50697409335, 3796.0, 3806.2387522495333, 3840.7311488195837, 5216.0, 5285.339332583427, 5293.444188360244, 5318.946810531884, 5329.894691596797, 4783.95216047842, 4898.902021959577, 4945.282933974553, 4951.480094590052, 4958.281049247839, 5627.240322556443, 5661.273311639949, 5663.716814159276, 5677.495463702122, 5706.828795136153, 4177.143685544456, 4188.643965262956, 4203.285441474926, 4226.577342265779, 4231.936520952188, 5849.6197747146725, 5887.293524777011, 5915.5859089863725, 5970.701464926737, 6011.75952961881, 4276.2516559601945, 4280.507741609755, 4280.850170244026, 4283.271843786564, 4284.164587905391, 4330.0, 4341.567282796733, 4341.717788343748, 4358.929508483445, 4362.669385837286, 4789.976050119797, 4893.657443978958, 4938.777755001033, 4962.73450270186, 4968.080905032581, 5620.325560932714, 5685.971570142123, 5691.9715401423555, 5693.68684722336, 5741.942580574224, 1500.8199016118135, 1542.1364036139573, 1542.2288855572215, 1542.2288855572215, 1549.7055559443895, 945.4091193004373, 970.1656575345197, 989.8465737810651, 993.6522217223913, 1001.6694490818159, 4764.666473346832, 4838.540338667821, 4853.830115945925, 4880.0, 4961.503849615082, 5121.4622464641425, 5127.333446651912, 5143.459936706667, 5163.082858185114, 5183.352080989876, 4542.9772851136195, 4815.879603009953, 4847.515248475124, 4864.36763220779, 4903.485134060944, 2732.27594687408, 2739.643846299968, 2745.4509098180442, 2747.0659975608387, 2754.862256887148, 5225.608079394061, 5228.764705588258, 5297.205419187153, 5340.839774806762, 5348.91976620351, 5273.837726532181, 5301.920471192935, 5630.324361076697, 6022.548308876764, 6058.636481811105, 5810.680412577264, 5932.228810254639, 6069.787557435557, 6078.696065196723, 6093.14159181445, 5888.528917686661, 5912.526997840077, 5918.141869428822, 5936.041106434953, 5942.673152976455, 5526.696031718294, 5779.219805326227, 5856.619319744203, 5858.238429004373, 5891.941080589139, 6129.295131059806, 6136.478399336106, 6138.693065346625, 6154.323024467394, 6160.845978850385, 4393.868183954486, 4453.35426363182, 4462.419885414875, 4471.814969033274, 4500.167469018242, 5770.826875193751, 5785.450382213683, 5869.148973398918, 5879.235699359056, 5879.882402352015, 5663.518600918968, 5677.1768093627015, 5687.037971899688, 5719.971400142973, 5749.039244789021, 5427.24018637399, 5684.516816070515, 5716.485516303553, 5717.685527296038, 5782.653040817564, 5726.482482142153, 5742.7702891883855, 5749.425057494217, 5756.265773757432, 5781.653100813965, 5798.971005144947, 5806.274215723035, 5818.709064546841, 5829.76680932758, 5840.474357307863, 5534.640248383842, 5626.409227031103, 5645.915311270416, 5663.603547751618, 5679.233303503974], "value": 4316.672975328992, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.7908064822498, 808.1837344282274, 816.5386556595523, 882.8101958079012, 919.5999740113048, 15684.921575392122, 17694.292228310867, 17949.91025044873, 17990.460286191417, 18067.096645167756, 16026.95824771392, 16365.181740912936, 16386.83613163868, 16440.42458513952, 16544.75182872255, 869.1221865915439, 891.1222445890793, 893.3657103456553, 893.7765558610347, 896.5472436419599, 890.8173824365989, 895.8029233568637, 896.188949001155, 896.368060517337, 896.7220161749846, 936.3819878879941, 937.995310023449, 939.4363381970797, 941.1858742188026, 941.6421759731293, 927.0729270729271, 931.7158266728645, 933.0576118120679, 933.1788026536672, 933.5099072986668, 689.4553302890761, 690.3165865792829, 691.9965400173018, 692.2489099327236, 692.394155114275, 11843.23019003762, 12005.579804706793, 12096.879031209663, 12210.145289829716, 12279.631611051593, 13992.370343334574, 14051.156930584199, 14245.43018279278, 14317.713645727026, 14345.856541434556, 11907.642770716893, 11951.940240298743, 12013.279203247746, 12036.939815300868, 12284.078694097889, 4000.7799571023875, 4008.2785098682225, 4013.13717550728, 4166.1251137261215, 4206.20082184384, 53499.46500535023, 71037.0, 72051.0, 80987.0, 81798.59100704518, 11896.405179740977, 13210.73578528424, 13211.0, 13741.931290343484, 13763.518276860263, 22588.435289117904, 22896.198633047763, 22920.770792292198, 22955.655665165035, 23073.07707691707, 7961.800954976056, 8021.478603890729, 8087.110417854032, 8107.918920810835, 8276.048254450694, 4668.5564871337165, 4871.80512779492, 4876.390451193601, 5004.174311238678, 5010.899782004412, 3643.9635603643624, 3727.310447567209, 3756.079760458705, 3759.981200093982, 3762.6237376262156, 640.5196102922808, 640.759715106835, 642.7846671365052, 647.9481641468672, 656.1994366872408, 654.8493846415352, 701.8491024429773, 716.6954044531053, 727.1710250314645, 740.7740639105125, 8572.52851093196, 9067.637294508279, 9792.461414622121, 9855.162311203629, 10034.69895903124, 595.880823835226, 600.2047287344199, 623.2054130982942, 624.1542709628479, 633.8478765096436, 4737.502562230985, 4771.761411929389, 4910.950890491121, 4979.6265280104135, 5060.51925067118, 81393.18606813975, 82487.17512824915, 83539.0, 135408.0, 137042.0, 87618.56190719132, 90959.0, 113448.43275783733, 113859.0, 116210.0, 54964.0, 66710.0, 86736.566317168, 87277.0, 89869.0, 10056.245781566266, 10176.65936834547, 10259.640912568024, 10487.790244195226, 10845.078168355778, 48909.75545122252, 49088.2636760456, 50611.74694126506, 53278.2008269884, 54417.0, 48935.26597100975, 50451.74774126106, 69587.65206173937, 73827.0, 75332.62333688297, 14575.635609109857, 14609.561713148623, 14699.779503307673, 14822.925885370503, 14825.0, 16153.0, 16719.832801671826, 16947.830521694625, 17025.574360641083, 17099.572510687332, 14316.284185790877, 14931.178785166485, 15119.16844573515, 15580.220988950734, 16229.837701622833, 14125.505607303696, 14457.0, 14883.47907823221, 14937.551873443814, 15106.320215590105, 14559.636009099857, 14583.489577864724, 14688.41246350134, 14836.703265934837, 15219.315130818919, 11591.0442837076, 12036.338001409656, 12295.32375719344, 12339.259644421363, 12561.371931403575, 12264.693382665504, 12488.0, 12758.681032973878, 12854.742905142031, 13001.934990324988], "value": 7824.240226071612, "unit": "score"}], "name": "dromaeo_css", "value": 7653.916410252461, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 120.0, 109.0, 109.0, 111.0, 109.0, 111.0, 111.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 117.0, 143.0, 170.0, 117.0, 140.0, 149.0, 117.0, 174.0, 117.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 160.0, 159.0, 161.0, 159.0, 160.0, 161.0, 159.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 186.0, 76.0, 76.0, 74.0, 76.0, 165.0, 76.0], "value": 95.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 149.0, 83.0, 83.0, 84.0, 146.0, 83.0, 84.0, 147.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 51.0, 51.0, 53.0, 51.0, 50.0, 51.0, 52.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 59.0, 61.0, 59.0, 60.0, 60.0, 59.0, 60.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 83.0, 83.0, 74.0, 81.0, 74.0, 74.0, 72.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 124.0, 122.0, 121.0, 120.0, 129.0, 127.0, 126.0, 158.0, 123.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 155.0, 156.0, 156.0, 154.0, 159.0, 158.0, 155.0, 155.0, 155.0], "value": 155.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 62.0, 63.0, 63.0, 63.0, 69.0, 63.0, 65.0, 62.0], "value": 64.3, "unit": "score"}], "name": "kraken", "value": 1499.6, "alertThreshold": 2.0}]} 10:43:34 INFO - Return code: 0 10:43:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:43:34 INFO - # TBPL SUCCESS # 10:43:34 INFO - Running post-action listener: _resource_record_post_action 10:43:34 INFO - [mozharness: 2016-10-28 17:43:34.954232Z] Finished run-tests step (success) 10:43:34 INFO - Running post-run listener: _resource_record_post_run 10:43:35 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 286539776; Read time: 8; Write time: 163284 10:43:35 INFO - TinderboxPrint: CPU usage
12.9% 10:43:35 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 10:43:35 INFO - TinderboxPrint: I/O write bytes / time
286,539,776 / 163,284 10:43:35 INFO - TinderboxPrint: CPU idle
8,243.5 (87.1%) 10:43:35 INFO - TinderboxPrint: CPU user
1,180.6 (12.5%) 10:43:35 INFO - TinderboxPrint: Swap in / out
0 / 0 10:43:35 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70098944; Read time: 0; Write time: 41388 10:43:35 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 216440832; Read time: 0; Write time: 121896 10:43:35 INFO - Running post-run listener: _upload_blobber_files 10:43:35 INFO - Blob upload gear active. 10:43:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:43:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:43:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:43:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:43:35 INFO - (blobuploader) - INFO - Open directory for files ... 10:43:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:43:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:43:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:39 INFO - (blobuploader) - INFO - Done attempting. 10:43:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:43:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:43:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:40 INFO - (blobuploader) - INFO - Done attempting. 10:43:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:43:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:43:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:41 INFO - (blobuploader) - INFO - Done attempting. 10:43:41 INFO - (blobuploader) - INFO - Iteration through files over. 10:43:41 INFO - Return code: 0 10:43:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:43:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:43:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a141ee3b33d59e6e6c60be31fb5c42dd89d973e257d6a0cb3bd41896ebf98d2d159e12b2310a0e2b165ae934d4d198f557a87084d3d298bc13c487a2afecfc3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/31032cba1119310e38fef72f28e4ea1e121b6e1becdbe8bb8ea33d198c40dd16e2e29bde87814b45334b617694f01671b9f4b8a91c77c003ea18c911090bd98d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb5b73d2c2117980c3f205e71c358793e28d0f09f2bf8d82dfae19117c5f6b1b4217f04f7e59b8c2aa3b294108d2c8f1070beea7ca94faa03137a60b40f52c46"} 10:43:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:43:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:43:41 INFO - Contents: 10:43:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a141ee3b33d59e6e6c60be31fb5c42dd89d973e257d6a0cb3bd41896ebf98d2d159e12b2310a0e2b165ae934d4d198f557a87084d3d298bc13c487a2afecfc3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/31032cba1119310e38fef72f28e4ea1e121b6e1becdbe8bb8ea33d198c40dd16e2e29bde87814b45334b617694f01671b9f4b8a91c77c003ea18c911090bd98d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb5b73d2c2117980c3f205e71c358793e28d0f09f2bf8d82dfae19117c5f6b1b4217f04f7e59b8c2aa3b294108d2c8f1070beea7ca94faa03137a60b40f52c46"} 10:43:41 INFO - Running post-run listener: copy_logs_to_upload_dir 10:43:41 INFO - Copying logs to upload dir... 10:43:41 INFO - mkdir: /builds/slave/test/build/upload/logs 10:43:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.279339 ========= master_lag: -0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-28 10:43:40.839594) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-28 10:43:40.845746) ========= 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=3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553 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/a141ee3b33d59e6e6c60be31fb5c42dd89d973e257d6a0cb3bd41896ebf98d2d159e12b2310a0e2b165ae934d4d198f557a87084d3d298bc13c487a2afecfc3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/31032cba1119310e38fef72f28e4ea1e121b6e1becdbe8bb8ea33d198c40dd16e2e29bde87814b45334b617694f01671b9f4b8a91c77c003ea18c911090bd98d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb5b73d2c2117980c3f205e71c358793e28d0f09f2bf8d82dfae19117c5f6b1b4217f04f7e59b8c2aa3b294108d2c8f1070beea7ca94faa03137a60b40f52c46"} build_url:https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011337 build_url: 'https://queue.taskcluster.net/v1/task/dwcQi8UbTrWgBEyOZVo_Kw/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/a141ee3b33d59e6e6c60be31fb5c42dd89d973e257d6a0cb3bd41896ebf98d2d159e12b2310a0e2b165ae934d4d198f557a87084d3d298bc13c487a2afecfc3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/31032cba1119310e38fef72f28e4ea1e121b6e1becdbe8bb8ea33d198c40dd16e2e29bde87814b45334b617694f01671b9f4b8a91c77c003ea18c911090bd98d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb5b73d2c2117980c3f205e71c358793e28d0f09f2bf8d82dfae19117c5f6b1b4217f04f7e59b8c2aa3b294108d2c8f1070beea7ca94faa03137a60b40f52c46"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-28 10:43:40.870511) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:43:40.870817) ========= 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=3a3620fb28aea1604ab609ef000001d2-1477674707.212143-1106033553 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 10:43:40.956263) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-28 10:43:40.956591) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-28 10:43:40.956891) ========= ========= Total master_lag: 0.15 =========