builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1475119636.42 results: success (0) buildid: 20160928172420 builduid: e0f252a43d7d4af08fab12944d009060 revision: 5d980c0c1885844a8604e203ee5ad49628f90de8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:16.415777) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:16.416256) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:16.416546) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:16.431379) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:16.431734) ========= 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=bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-28 20:27:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-09-28 20:27:16 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110406 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:16.556006) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:16.556425) ========= 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=bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019311 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:16.606071) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 20:27:16.606439) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5d980c0c1885844a8604e203ee5ad49628f90de8 --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 5d980c0c1885844a8604e203ee5ad49628f90de8 --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=bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-28 20:27:17,079 truncating revision to first 12 chars 2016-09-28 20:27:17,079 Setting DEBUG logging. 2016-09-28 20:27:17,079 attempt 1/10 2016-09-28 20:27:17,079 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5d980c0c1885?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 20:27:18,297 unpacking tar archive at: mozilla-beta-5d980c0c1885/testing/mozharness/ program finished with exit code 0 elapsedTime=1.569324 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 20:27:18.189367) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:18.189705) ========= script_repo_revision: 5d980c0c1885844a8604e203ee5ad49628f90de8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:18.190122) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:18.190405) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 20:27:18.199888) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-09-28 20:27:18.200303) ========= /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=bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:27:18 INFO - MultiFileLogger online at 20160928 20:27:18 in /builds/slave/test 20:27:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 20:27:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:27:18 INFO - {'append_to_log': False, 20:27:18 INFO - 'base_work_dir': '/builds/slave/test', 20:27:18 INFO - 'blob_upload_branch': 'Mozilla-Beta', 20:27:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:27:18 INFO - 'branch': 'Mozilla-Beta', 20:27:18 INFO - 'buildbot_json_path': 'buildprops.json', 20:27:18 INFO - 'config_files': ('talos/linux_config.py',), 20:27:18 INFO - 'default_actions': ('clobber', 20:27:18 INFO - 'read-buildbot-config', 20:27:18 INFO - 'download-and-extract', 20:27:18 INFO - 'populate-webroot', 20:27:18 INFO - 'create-virtualenv', 20:27:18 INFO - 'install', 20:27:18 INFO - 'run-tests'), 20:27:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:27:18 INFO - 'download_minidump_stackwalk': True, 20:27:18 INFO - 'download_symbols': 'ondemand', 20:27:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:27:18 INFO - 'tooltool.py': '/tools/tooltool.py', 20:27:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:27:18 INFO - '/tools/misc-python/virtualenv.py')}, 20:27:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:27:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:27:18 INFO - 'installer_path': 'installer.exe', 20:27:18 INFO - 'log_level': 'info', 20:27:18 INFO - 'log_name': 'talos', 20:27:18 INFO - 'log_to_console': True, 20:27:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:27:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:27:18 INFO - 'opt_config_files': (), 20:27:18 INFO - 'pip_index': False, 20:27:18 INFO - 'sps_profile': False, 20:27:18 INFO - 'sps_profile_interval': 0, 20:27:18 INFO - 'suite': 'dromaeojs', 20:27:18 INFO - 'system_bits': '32', 20:27:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:27:18 INFO - 'title': 'talos-linux64-ix-055', 20:27:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:27:18 INFO - 'use_talos_json': True, 20:27:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:27:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:27:18 INFO - 'work_dir': 'build'} 20:27:18 INFO - [mozharness: 2016-09-29 03:27:18.763960Z] Running clobber step. 20:27:18 INFO - Running pre-action listener: _resource_record_pre_action 20:27:18 INFO - Running main action method: clobber 20:27:18 INFO - rmtree: /builds/slave/test/build 20:27:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:27:19 INFO - Running post-action listener: _resource_record_post_action 20:27:19 INFO - [mozharness: 2016-09-29 03:27:19.224502Z] Finished clobber step (success) 20:27:19 INFO - [mozharness: 2016-09-29 03:27:19.224602Z] Running read-buildbot-config step. 20:27:19 INFO - Running pre-action listener: _resource_record_pre_action 20:27:19 INFO - Running main action method: read_buildbot_config 20:27:19 INFO - Using buildbot properties: 20:27:19 INFO - { 20:27:19 INFO - "project": "", 20:27:19 INFO - "product": "firefox", 20:27:19 INFO - "script_repo_revision": "production", 20:27:19 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 20:27:19 INFO - "repository": "", 20:27:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 20:27:19 INFO - "buildid": "20160928172420", 20:27:19 INFO - "pgo_build": "True", 20:27:19 INFO - "basedir": "/builds/slave/test", 20:27:19 INFO - "buildnumber": 176, 20:27:19 INFO - "slavename": "talos-linux64-ix-055", 20:27:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:27:19 INFO - "platform": "ubuntu64_hw", 20:27:19 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 20:27:19 INFO - "branch": "mozilla-beta", 20:27:19 INFO - "repo_path": "releases/mozilla-beta", 20:27:19 INFO - "revision": "5d980c0c1885844a8604e203ee5ad49628f90de8", 20:27:19 INFO - "stage_platform": "linux64-pgo", 20:27:19 INFO - "builduid": "e0f252a43d7d4af08fab12944d009060", 20:27:19 INFO - "slavebuilddir": "test" 20:27:19 INFO - } 20:27:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 20:27:19 INFO - Running post-action listener: _resource_record_post_action 20:27:19 INFO - [mozharness: 2016-09-29 03:27:19.227013Z] Finished read-buildbot-config step (success) 20:27:19 INFO - [mozharness: 2016-09-29 03:27:19.227100Z] Running download-and-extract step. 20:27:19 INFO - Running pre-action listener: _resource_record_pre_action 20:27:19 INFO - Running main action method: download_and_extract 20:27:19 INFO - mkdir: /builds/slave/test/build/tests 20:27:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:27:19 INFO - https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:27:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 20:27:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 20:27:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:27:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:27:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 20:27:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:27:19 INFO - retry: Failed, sleeping 30 seconds before retrying 20:27:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:27:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 20:27:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:27:49 INFO - retry: Failed, sleeping 60 seconds before retrying 20:28:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:28:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 20:28:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:28:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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! 20:28:49 INFO - Caught exception: HTTP Error 404: Not Found 20:28:49 INFO - Caught exception: HTTP Error 404: Not Found 20:28:49 INFO - Caught exception: HTTP Error 404: Not Found 20:28:49 INFO - trying https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 20:28:49 INFO - Downloading https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:28:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:28:49 INFO - Downloaded 1327 bytes. 20:28:49 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 20:28:49 INFO - Using the following test package requirements: 20:28:49 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 20:28:49 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 20:28:49 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 20:28:49 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 20:28:49 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 20:28:49 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 20:28:49 INFO - u'jsshell-linux-x86_64.zip'], 20:28:49 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 20:28:49 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 20:28:49 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 20:28:49 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 20:28:49 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 20:28:49 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 20:28:49 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 20:28:49 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 20:28:49 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 20:28:49 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 20:28:49 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 20:28:49 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:28:49 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')]} 20:28:49 INFO - https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:28:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:28:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:28:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:28:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:28:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:28:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:28:49 INFO - retry: Failed, sleeping 30 seconds before retrying 20:29:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:29:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:29:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:29:19 INFO - retry: Failed, sleeping 60 seconds before retrying 20:30:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:30:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:30:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:30:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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! 20:30:20 INFO - Caught exception: HTTP Error 404: Not Found 20:30:20 INFO - Caught exception: HTTP Error 404: Not Found 20:30:20 INFO - Caught exception: HTTP Error 404: Not Found 20:30:20 INFO - trying https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:30:20 INFO - Downloading https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:30:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:30:20 INFO - Downloaded 21772459 bytes. 20:30:20 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'] 20:30:20 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 20:30:22 INFO - Return code: 0 20:30:22 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 20:30: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')]} 20:30:22 INFO - https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:30:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:30:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:30:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:30:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:30:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:30:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:30:22 INFO - retry: Failed, sleeping 30 seconds before retrying 20:30:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:30:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:30:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:30:52 INFO - retry: Failed, sleeping 60 seconds before retrying 20:31:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:31:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:31:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:31:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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! 20:31:52 INFO - Caught exception: HTTP Error 404: Not Found 20:31:52 INFO - Caught exception: HTTP Error 404: Not Found 20:31:52 INFO - Caught exception: HTTP Error 404: Not Found 20:31:52 INFO - trying https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 20:31:52 INFO - Downloading https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:31:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:31:53 INFO - Downloaded 21772459 bytes. 20:31:53 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'] 20:31:53 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 20:31:54 INFO - Return code: 0 20:31: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')]} 20:31:54 INFO - https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:31:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 20:31:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 20:31:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:31:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:31:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 20:31:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:31:54 INFO - retry: Failed, sleeping 30 seconds before retrying 20:32:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:32:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 20:32:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:32:24 INFO - retry: Failed, sleeping 60 seconds before retrying 20:33:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:33:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 20:33:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:33:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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! 20:33:24 INFO - Caught exception: HTTP Error 404: Not Found 20:33:24 INFO - Caught exception: HTTP Error 404: Not Found 20:33:24 INFO - Caught exception: HTTP Error 404: Not Found 20:33:24 INFO - trying https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 20:33:24 INFO - Downloading https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:33:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:33:25 INFO - Downloaded 14413740 bytes. 20:33:25 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'] 20:33:25 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 20:33:25 INFO - Return code: 0 20:33:25 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')]} 20:33:25 INFO - https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:33:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 20:33:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 20:33:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:33:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:33:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 20:33:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:33:25 INFO - retry: Failed, sleeping 30 seconds before retrying 20:33:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:33:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 20:33:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:33:55 INFO - retry: Failed, sleeping 60 seconds before retrying 20:34:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:34:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 20:34:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:34:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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! 20:34:55 INFO - Caught exception: HTTP Error 404: Not Found 20:34:55 INFO - Caught exception: HTTP Error 404: Not Found 20:34:55 INFO - Caught exception: HTTP Error 404: Not Found 20:34:55 INFO - trying https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 20:34:55 INFO - Downloading https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:34:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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 20:34:56 INFO - Downloaded 56764023 bytes. 20:34:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 20:34:56 INFO - mkdir: /builds/slave/test/properties 20:34:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:34:56 INFO - Writing to file /builds/slave/test/properties/build_url 20:34:56 INFO - Contents: 20:34:56 INFO - build_url:https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 20:34:57 INFO - Running post-action listener: _resource_record_post_action 20:34:57 INFO - Running post-action listener: set_extra_try_arguments 20:34:57 INFO - [mozharness: 2016-09-29 03:34:57.519632Z] Finished download-and-extract step (success) 20:34:57 INFO - [mozharness: 2016-09-29 03:34:57.519866Z] Running populate-webroot step. 20:34:57 INFO - Running pre-action listener: _resource_record_pre_action 20:34:57 INFO - Running main action method: populate_webroot 20:34:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:34:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:34:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:34:57 INFO - '--tppagecycles', 20:34:57 INFO - '7'], 20:34:57 INFO - 'tests': ['tsvgm']}}, 20:34:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:34:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:34:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:34:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:34:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:34:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:34:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:34:57 INFO - 'talos_options': ['--disable-e10s'], 20:34:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:34:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:34:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:34:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:34:57 INFO - 'talos_options': ['--disable-e10s'], 20:34:57 INFO - 'tests': ['damp', 'tps']}, 20:34:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:34:57 INFO - 'tests': ['damp', 'tps']}, 20:34:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:34:57 INFO - 'tests': ['dromaeo_dom']}, 20:34:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:34:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:34:57 INFO - 'tests': ['basic_compositor_video']}, 20:34:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:34:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:34:57 INFO - 'tests': ['a11yr', 20:34:57 INFO - 'ts_paint', 20:34:57 INFO - 'tpaint', 20:34:57 INFO - 'sessionrestore', 20:34:57 INFO - 'sessionrestore_no_auto_restore', 20:34:57 INFO - 'tabpaint']}, 20:34:57 INFO - 'other-e10s': {'tests': ['a11yr', 20:34:57 INFO - 'ts_paint', 20:34:57 INFO - 'tpaint', 20:34:57 INFO - 'sessionrestore', 20:34:57 INFO - 'sessionrestore_no_auto_restore', 20:34:57 INFO - 'tabpaint']}, 20:34:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:34:57 INFO - 'ts_paint', 20:34:57 INFO - 'tpaint', 20:34:57 INFO - 'sessionrestore', 20:34:57 INFO - 'sessionrestore_no_auto_restore', 20:34:57 INFO - 'tabpaint']}, 20:34:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:34:57 INFO - 'ts_paint', 20:34:57 INFO - 'tpaint', 20:34:57 INFO - 'sessionrestore', 20:34:57 INFO - 'sessionrestore_no_auto_restore', 20:34:57 INFO - 'tabpaint']}, 20:34:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:34:57 INFO - 'tests': ['a11yr', 20:34:57 INFO - 'ts_paint', 20:34:57 INFO - 'tpaint', 20:34:57 INFO - 'sessionrestore', 20:34:57 INFO - 'sessionrestore_no_auto_restore', 20:34:57 INFO - 'tabpaint']}, 20:34:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:34:57 INFO - 'tests': ['a11yr', 20:34:57 INFO - 'ts_paint', 20:34:57 INFO - 'tpaint', 20:34:57 INFO - 'sessionrestore', 20:34:57 INFO - 'sessionrestore_no_auto_restore', 20:34:57 INFO - 'tabpaint']}, 20:34:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:34:57 INFO - 'tests': ['tsvgx', 20:34:57 INFO - 'tsvgr_opacity', 20:34:57 INFO - 'tart', 20:34:57 INFO - 'tscrollx', 20:34:57 INFO - 'cart']}, 20:34:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:34:57 INFO - 'tsvgr_opacity', 20:34:57 INFO - 'tart', 20:34:57 INFO - 'tscrollx', 20:34:57 INFO - 'cart']}, 20:34:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:34:57 INFO - 'talos_options': ['--disable-e10s'], 20:34:57 INFO - 'tests': ['tp5o']}, 20:34:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:34:57 INFO - 'tests': ['tp5o']}, 20:34:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:34:57 INFO - 'talos_options': ['--disable-e10s', 20:34:57 INFO - '--xperf_path', 20:34:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:34:57 INFO - 'tests': ['tp5n']}, 20:34:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:34:57 INFO - 'talos_options': ['--xperf_path', 20:34:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:34:57 INFO - 'tests': ['tp5n']}}, 20:34:57 INFO - 'talos.zip': {'path': '', 20:34:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:34:57 INFO - Running post-action listener: _resource_record_post_action 20:34:57 INFO - [mozharness: 2016-09-29 03:34:57.540495Z] Finished populate-webroot step (success) 20:34:57 INFO - [mozharness: 2016-09-29 03:34:57.540584Z] Running create-virtualenv step. 20:34:57 INFO - Running pre-action listener: _resource_record_pre_action 20:34:57 INFO - Running main action method: create_virtualenv 20:34:57 INFO - Creating virtualenv /builds/slave/test/build/venv 20:34:57 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 20:34:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:34:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:34:57 INFO - Using real prefix '/usr' 20:34:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:34:58 INFO - Installing distribute.............................................................................................................................................................................................done. 20:35:00 INFO - Installing pip.................done. 20:35:00 INFO - Return code: 0 20:35:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:35:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1341030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1602180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16003b0>, '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 0x16012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1601740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', '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 20:35:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:35:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:35:00 INFO - Using env: {'DISPLAY': ':0', 20:35:00 INFO - 'HOME': '/home/cltbld', 20:35:00 INFO - 'LANG': 'en_US.UTF-8', 20:35:00 INFO - 'LANGUAGE': 'en_US:en', 20:35:00 INFO - 'LOGNAME': 'cltbld', 20:35:00 INFO - 'MAIL': '/var/mail/cltbld', 20:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:35:00 INFO - 'MOZ_NO_REMOTE': '1', 20:35:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:35:00 INFO - 'NO_EM_RESTART': '1', 20:35:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:35:00 INFO - 'PWD': '/builds/slave/test', 20:35:00 INFO - 'SHELL': '/bin/bash', 20:35:00 INFO - 'SHLVL': '1', 20:35:00 INFO - 'TERM': 'linux', 20:35:00 INFO - 'TMOUT': '86400', 20:35:00 INFO - 'USER': 'cltbld', 20:35:00 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', 20:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:35:00 INFO - '_': '/tools/buildbot/bin/python'} 20:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:35:00 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 20:35:00 INFO - Cleaning up... 20:35:00 INFO - Return code: 0 20:35:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:35:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1341030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1602180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16003b0>, '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 0x16012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1601740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', '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 20:35:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:35:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:35:00 INFO - Using env: {'DISPLAY': ':0', 20:35:00 INFO - 'HOME': '/home/cltbld', 20:35:00 INFO - 'LANG': 'en_US.UTF-8', 20:35:00 INFO - 'LANGUAGE': 'en_US:en', 20:35:00 INFO - 'LOGNAME': 'cltbld', 20:35:00 INFO - 'MAIL': '/var/mail/cltbld', 20:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:35:00 INFO - 'MOZ_NO_REMOTE': '1', 20:35:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:35:00 INFO - 'NO_EM_RESTART': '1', 20:35:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:35:00 INFO - 'PWD': '/builds/slave/test', 20:35:00 INFO - 'SHELL': '/bin/bash', 20:35:00 INFO - 'SHLVL': '1', 20:35:00 INFO - 'TERM': 'linux', 20:35:00 INFO - 'TMOUT': '86400', 20:35:00 INFO - 'USER': 'cltbld', 20:35:00 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', 20:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:35:00 INFO - '_': '/tools/buildbot/bin/python'} 20:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:35:00 INFO - Downloading/unpacking psutil>=3.1.1 20:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:35:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:35:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:35:02 INFO - Installing collected packages: psutil 20:35:02 INFO - Running setup.py install for psutil 20:35:02 INFO - building 'psutil._psutil_linux' extension 20:35:02 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 20:35:02 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 20:35:02 INFO - building 'psutil._psutil_posix' extension 20:35:02 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 20:35:02 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 20:35:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:35:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:35:03 INFO - Successfully installed psutil 20:35:03 INFO - Cleaning up... 20:35:03 INFO - Return code: 0 20:35:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:35:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:35:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:35:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1341030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1602180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16003b0>, '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 0x16012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1601740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', '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 20:35:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:35:03 INFO - Using env: {'DISPLAY': ':0', 20:35:03 INFO - 'HOME': '/home/cltbld', 20:35:03 INFO - 'LANG': 'en_US.UTF-8', 20:35:03 INFO - 'LANGUAGE': 'en_US:en', 20:35:03 INFO - 'LOGNAME': 'cltbld', 20:35:03 INFO - 'MAIL': '/var/mail/cltbld', 20:35:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:35:03 INFO - 'MOZ_NO_REMOTE': '1', 20:35:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:35:03 INFO - 'NO_EM_RESTART': '1', 20:35:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:35:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:35:03 INFO - 'PWD': '/builds/slave/test', 20:35:03 INFO - 'SHELL': '/bin/bash', 20:35:03 INFO - 'SHLVL': '1', 20:35:03 INFO - 'TERM': 'linux', 20:35:03 INFO - 'TMOUT': '86400', 20:35:03 INFO - 'USER': 'cltbld', 20:35:03 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', 20:35:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:35:03 INFO - '_': '/tools/buildbot/bin/python'} 20:35:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:35:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:35:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:35:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:35:05 INFO - Installing collected packages: mozsystemmonitor 20:35:05 INFO - Running setup.py install for mozsystemmonitor 20:35:05 INFO - Successfully installed mozsystemmonitor 20:35:05 INFO - Cleaning up... 20:35:05 INFO - Return code: 0 20:35:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:35:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:35:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1341030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1602180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16003b0>, '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 0x16012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1601740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', '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 20:35:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:35:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:35:05 INFO - Using env: {'DISPLAY': ':0', 20:35:05 INFO - 'HOME': '/home/cltbld', 20:35:05 INFO - 'LANG': 'en_US.UTF-8', 20:35:05 INFO - 'LANGUAGE': 'en_US:en', 20:35:05 INFO - 'LOGNAME': 'cltbld', 20:35:05 INFO - 'MAIL': '/var/mail/cltbld', 20:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:35:05 INFO - 'MOZ_NO_REMOTE': '1', 20:35:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:35:05 INFO - 'NO_EM_RESTART': '1', 20:35:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:35:05 INFO - 'PWD': '/builds/slave/test', 20:35:05 INFO - 'SHELL': '/bin/bash', 20:35:05 INFO - 'SHLVL': '1', 20:35:05 INFO - 'TERM': 'linux', 20:35:05 INFO - 'TMOUT': '86400', 20:35:05 INFO - 'USER': 'cltbld', 20:35:05 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', 20:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:35:05 INFO - '_': '/tools/buildbot/bin/python'} 20:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:35:05 INFO - Downloading/unpacking jsonschema==2.5.1 20:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:35:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:07 INFO - Downloading functools32-3.2.3-2.tar.gz 20:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:35:07 INFO - warning: no files found matching '*.txt' 20:35:07 INFO - no previously-included directories found matching 'build' 20:35:07 INFO - no previously-included directories found matching 'dist' 20:35:07 INFO - no previously-included directories found matching '.git*' 20:35:07 INFO - Installing collected packages: jsonschema, functools32 20:35:07 INFO - Running setup.py install for functools32 20:35:07 INFO - warning: no files found matching '*.txt' 20:35:07 INFO - no previously-included directories found matching 'build' 20:35:07 INFO - no previously-included directories found matching 'dist' 20:35:07 INFO - no previously-included directories found matching '.git*' 20:35:07 INFO - Successfully installed jsonschema functools32 20:35:07 INFO - Cleaning up... 20:35:07 INFO - Return code: 0 20:35:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:35:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:35:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1341030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1602180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16003b0>, '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 0x16012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1601740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', '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 20:35:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:35:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:35:07 INFO - Using env: {'DISPLAY': ':0', 20:35:07 INFO - 'HOME': '/home/cltbld', 20:35:07 INFO - 'LANG': 'en_US.UTF-8', 20:35:07 INFO - 'LANGUAGE': 'en_US:en', 20:35:07 INFO - 'LOGNAME': 'cltbld', 20:35:07 INFO - 'MAIL': '/var/mail/cltbld', 20:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:35:07 INFO - 'MOZ_NO_REMOTE': '1', 20:35:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:35:07 INFO - 'NO_EM_RESTART': '1', 20:35:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:35:07 INFO - 'PWD': '/builds/slave/test', 20:35:07 INFO - 'SHELL': '/bin/bash', 20:35:07 INFO - 'SHLVL': '1', 20:35:07 INFO - 'TERM': 'linux', 20:35:07 INFO - 'TMOUT': '86400', 20:35:07 INFO - 'USER': 'cltbld', 20:35:07 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', 20:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:35:07 INFO - '_': '/tools/buildbot/bin/python'} 20:35:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:35:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:35:08 INFO - Cleaning up... 20:35:08 INFO - Return code: 0 20:35:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:35:08 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')]} 20:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:35:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:08 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')]} 20:35:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:35:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:08 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 0x1341030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1602180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16003b0>, '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 0x16012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1601740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', '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 20:35:08 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 20:35:08 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 20:35:08 INFO - Using env: {'DISPLAY': ':0', 20:35:08 INFO - 'HOME': '/home/cltbld', 20:35:08 INFO - 'LANG': 'en_US.UTF-8', 20:35:08 INFO - 'LANGUAGE': 'en_US:en', 20:35:08 INFO - 'LOGNAME': 'cltbld', 20:35:08 INFO - 'MAIL': '/var/mail/cltbld', 20:35:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:35:08 INFO - 'MOZ_NO_REMOTE': '1', 20:35:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:35:08 INFO - 'NO_EM_RESTART': '1', 20:35:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:35:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:35:08 INFO - 'PWD': '/builds/slave/test', 20:35:08 INFO - 'SHELL': '/bin/bash', 20:35:08 INFO - 'SHLVL': '1', 20:35:08 INFO - 'TERM': 'linux', 20:35:08 INFO - 'TMOUT': '86400', 20:35:08 INFO - 'USER': 'cltbld', 20:35:08 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', 20:35:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:35:08 INFO - '_': '/tools/buildbot/bin/python'} 20:35:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:35:08 INFO - Downloading/unpacking blobuploader==1.2.4 20:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:10 INFO - Downloading blobuploader-1.2.4.tar.gz 20:35:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:35:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:35:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:35:10 INFO - Downloading docopt-0.6.1.tar.gz 20:35:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:35:11 INFO - Installing collected packages: blobuploader, requests, docopt 20:35:11 INFO - Running setup.py install for blobuploader 20:35:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:35:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:35:11 INFO - Running setup.py install for requests 20:35:11 INFO - Running setup.py install for docopt 20:35:11 INFO - Successfully installed blobuploader requests docopt 20:35:11 INFO - Cleaning up... 20:35:11 INFO - Return code: 0 20:35:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:35:11 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')]} 20:35:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:35:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:11 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')]} 20:35:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:35:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:11 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 0x1341030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1602180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16003b0>, '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 0x16012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1601740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', '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 20:35:11 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 20:35:11 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 20:35:11 INFO - Using env: {'DISPLAY': ':0', 20:35:11 INFO - 'HOME': '/home/cltbld', 20:35:11 INFO - 'LANG': 'en_US.UTF-8', 20:35:11 INFO - 'LANGUAGE': 'en_US:en', 20:35:11 INFO - 'LOGNAME': 'cltbld', 20:35:11 INFO - 'MAIL': '/var/mail/cltbld', 20:35:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:35:11 INFO - 'MOZ_NO_REMOTE': '1', 20:35:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:35:11 INFO - 'NO_EM_RESTART': '1', 20:35:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:35:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:35:11 INFO - 'PWD': '/builds/slave/test', 20:35:11 INFO - 'SHELL': '/bin/bash', 20:35:11 INFO - 'SHLVL': '1', 20:35:11 INFO - 'TERM': 'linux', 20:35:11 INFO - 'TMOUT': '86400', 20:35:11 INFO - 'USER': 'cltbld', 20:35:11 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', 20:35:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:35:11 INFO - '_': '/tools/buildbot/bin/python'} 20:35:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:35:11 INFO - Running setup.py (path:/tmp/pip-g2SvoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:35:11 INFO - Running setup.py (path:/tmp/pip-EMva9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:35:11 INFO - Running setup.py (path:/tmp/pip-a3vEUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:35:12 INFO - Running setup.py (path:/tmp/pip-xB177l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:35:12 INFO - Running setup.py (path:/tmp/pip-zVqny_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:35:12 INFO - Running setup.py (path:/tmp/pip-LnytV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:35:12 INFO - Running setup.py (path:/tmp/pip-N6Lz4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:35:12 INFO - Running setup.py (path:/tmp/pip-HD1kP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:35:12 INFO - Running setup.py (path:/tmp/pip-YjEk_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:35:12 INFO - Running setup.py (path:/tmp/pip-AgKYkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:35:12 INFO - Running setup.py (path:/tmp/pip-52hkth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:35:12 INFO - Running setup.py (path:/tmp/pip-_b0ekf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:35:12 INFO - Running setup.py (path:/tmp/pip-YUZPOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:35:12 INFO - Running setup.py (path:/tmp/pip-Nf71A0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:35:12 INFO - Running setup.py (path:/tmp/pip-0hzJTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:35:12 INFO - Running setup.py (path:/tmp/pip-sD6k8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:35:13 INFO - Running setup.py (path:/tmp/pip-TGlptd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:35:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:35:13 INFO - Running setup.py install for manifestparser 20:35:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:35:13 INFO - Running setup.py install for mozcrash 20:35:13 INFO - Running setup.py install for mozdebug 20:35:13 INFO - Running setup.py install for mozdevice 20:35:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:35:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:35:13 INFO - Running setup.py install for mozfile 20:35:13 INFO - Running setup.py install for mozhttpd 20:35:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:35:13 INFO - Running setup.py install for mozinfo 20:35:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:35:13 INFO - Running setup.py install for mozInstall 20:35:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:35:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:35:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:35:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:35:13 INFO - Running setup.py install for mozleak 20:35:13 INFO - Running setup.py install for mozlog 20:35:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:35:14 INFO - Running setup.py install for moznetwork 20:35:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:35:14 INFO - Running setup.py install for mozprocess 20:35:14 INFO - Running setup.py install for mozprofile 20:35:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:35:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:35:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:35:14 INFO - Running setup.py install for mozrunner 20:35:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:35:14 INFO - Running setup.py install for mozscreenshot 20:35:14 INFO - Running setup.py install for moztest 20:35:14 INFO - Running setup.py install for mozversion 20:35:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:35:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:35:14 INFO - Cleaning up... 20:35:14 INFO - Return code: 0 20:35:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:35:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:35:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:35:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:14 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 0x1341030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1602180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16003b0>, '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 0x16012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1601740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', '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 20:35:14 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 20:35:14 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 20:35:14 INFO - Using env: {'DISPLAY': ':0', 20:35:14 INFO - 'HOME': '/home/cltbld', 20:35:14 INFO - 'LANG': 'en_US.UTF-8', 20:35:14 INFO - 'LANGUAGE': 'en_US:en', 20:35:14 INFO - 'LOGNAME': 'cltbld', 20:35:14 INFO - 'MAIL': '/var/mail/cltbld', 20:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:35:14 INFO - 'MOZ_NO_REMOTE': '1', 20:35:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:35:14 INFO - 'NO_EM_RESTART': '1', 20:35:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:35:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:35:14 INFO - 'PWD': '/builds/slave/test', 20:35:14 INFO - 'SHELL': '/bin/bash', 20:35:14 INFO - 'SHLVL': '1', 20:35:14 INFO - 'TERM': 'linux', 20:35:14 INFO - 'TMOUT': '86400', 20:35:14 INFO - 'USER': 'cltbld', 20:35:14 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', 20:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:35:14 INFO - '_': '/tools/buildbot/bin/python'} 20:35:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:35:15 INFO - Running setup.py (path:/tmp/pip-aObz72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:35:15 INFO - Running setup.py (path:/tmp/pip-pDRphU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:35:15 INFO - Running setup.py (path:/tmp/pip-k0F3kJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:35:15 INFO - Running setup.py (path:/tmp/pip-7sFljc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:35:15 INFO - Running setup.py (path:/tmp/pip-xFGG0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:35:15 INFO - Running setup.py (path:/tmp/pip-jZgrQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:35:15 INFO - Running setup.py (path:/tmp/pip-0FMQgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:35:15 INFO - Running setup.py (path:/tmp/pip-wzZRuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:35:15 INFO - Running setup.py (path:/tmp/pip-XqhjIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:35:15 INFO - Running setup.py (path:/tmp/pip-z_YkPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:35:15 INFO - Running setup.py (path:/tmp/pip-uHASor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:35:15 INFO - Running setup.py (path:/tmp/pip-pUVPA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:35:15 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)) 20:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:35:15 INFO - Running setup.py (path:/tmp/pip-RRkTgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:35:16 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)) 20:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:35:16 INFO - Running setup.py (path:/tmp/pip-TD_3qK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:35:16 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)) 20:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:35:16 INFO - Running setup.py (path:/tmp/pip-ABiOuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:35:16 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)) 20:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:35:16 INFO - Running setup.py (path:/tmp/pip-DZuj2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:35:16 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)) 20:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:35:16 INFO - Running setup.py (path:/tmp/pip-2JpgxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:35:16 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)) 20:35:16 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)) 20:35:16 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)) 20:35:16 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)) 20:35:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:35:16 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)) 20:35:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:35:16 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 20:35:16 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 20:35:16 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 20:35:16 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 20:35:16 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 20:35:16 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 20:35:18 INFO - Downloading blessings-1.6.tar.gz 20:35:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:35:18 INFO - Installing collected packages: blessings 20:35:18 INFO - Running setup.py install for blessings 20:35:18 INFO - Successfully installed blessings 20:35:18 INFO - Cleaning up... 20:35:18 INFO - Return code: 0 20:35:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:35:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:35:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:35:18 INFO - Reading from file tmpfile_stdout 20:35:18 INFO - Current package versions: 20:35:18 INFO - argparse == 1.2.1 20:35:18 INFO - blessings == 1.6 20:35:18 INFO - blobuploader == 1.2.4 20:35:18 INFO - docopt == 0.6.1 20:35:18 INFO - functools32 == 3.2.3-2 20:35:18 INFO - jsonschema == 2.5.1 20:35:18 INFO - manifestparser == 1.1 20:35:18 INFO - mozInstall == 1.12 20:35:18 INFO - mozcrash == 0.17 20:35:18 INFO - mozdebug == 0.1 20:35:18 INFO - mozdevice == 0.48 20:35:18 INFO - mozfile == 1.2 20:35:18 INFO - mozhttpd == 0.7 20:35:18 INFO - mozinfo == 0.9 20:35:18 INFO - mozleak == 0.1 20:35:18 INFO - mozlog == 3.3 20:35:18 INFO - moznetwork == 0.27 20:35:18 INFO - mozprocess == 0.23 20:35:18 INFO - mozprofile == 0.28 20:35:18 INFO - mozrunner == 6.12 20:35:18 INFO - mozscreenshot == 0.1 20:35:18 INFO - mozsystemmonitor == 0.3 20:35:18 INFO - moztest == 0.7 20:35:18 INFO - mozversion == 1.4 20:35:18 INFO - psutil == 3.1.1 20:35:18 INFO - requests == 1.2.3 20:35:18 INFO - wsgiref == 0.1.2 20:35:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:35: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')]} 20:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:35:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35: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')]} 20:35:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:35:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:18 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 0x1341030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1602180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16003b0>, '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 0x16012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1601740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', '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 20:35:18 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 20:35:18 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 20:35:18 INFO - Using env: {'DISPLAY': ':0', 20:35:18 INFO - 'HOME': '/home/cltbld', 20:35:18 INFO - 'LANG': 'en_US.UTF-8', 20:35:18 INFO - 'LANGUAGE': 'en_US:en', 20:35:18 INFO - 'LOGNAME': 'cltbld', 20:35:18 INFO - 'MAIL': '/var/mail/cltbld', 20:35:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:35:18 INFO - 'MOZ_NO_REMOTE': '1', 20:35:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:35:18 INFO - 'NO_EM_RESTART': '1', 20:35:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:35:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:35:18 INFO - 'PWD': '/builds/slave/test', 20:35:18 INFO - 'SHELL': '/bin/bash', 20:35:18 INFO - 'SHLVL': '1', 20:35:18 INFO - 'TERM': 'linux', 20:35:18 INFO - 'TMOUT': '86400', 20:35:18 INFO - 'USER': 'cltbld', 20:35:18 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', 20:35:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:35:18 INFO - '_': '/tools/buildbot/bin/python'} 20:35:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:35:18 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)) 20:35:18 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)) 20:35:18 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)) 20:35:18 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)) 20:35:18 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)) 20:35:18 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)) 20:35:18 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)) 20:35:18 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)) 20:35:18 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)) 20:35:18 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)) 20:35:18 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)) 20:35:18 INFO - Cleaning up... 20:35:18 INFO - Return code: 0 20:35:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:35: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')]} 20:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:35:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35: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')]} 20:35:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:35:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:35:18 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 0x1341030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1602180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16003b0>, '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 0x16012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1601740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', '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 20:35:18 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 20:35:18 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 20:35:18 INFO - Using env: {'DISPLAY': ':0', 20:35:18 INFO - 'HOME': '/home/cltbld', 20:35:18 INFO - 'LANG': 'en_US.UTF-8', 20:35:18 INFO - 'LANGUAGE': 'en_US:en', 20:35:18 INFO - 'LOGNAME': 'cltbld', 20:35:18 INFO - 'MAIL': '/var/mail/cltbld', 20:35:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:35:18 INFO - 'MOZ_NO_REMOTE': '1', 20:35:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:35:18 INFO - 'NO_EM_RESTART': '1', 20:35:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:35:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:35:18 INFO - 'PWD': '/builds/slave/test', 20:35:18 INFO - 'SHELL': '/bin/bash', 20:35:18 INFO - 'SHLVL': '1', 20:35:18 INFO - 'TERM': 'linux', 20:35:18 INFO - 'TMOUT': '86400', 20:35:18 INFO - 'USER': 'cltbld', 20:35:18 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', 20:35:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:35:18 INFO - '_': '/tools/buildbot/bin/python'} 20:35:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:35:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:35:19 INFO - Cleaning up... 20:35:19 INFO - Return code: 0 20:35:19 INFO - Running post-action listener: _resource_record_post_action 20:35:19 INFO - Running post-action listener: _start_resource_monitoring 20:35:19 INFO - Starting resource monitoring. 20:35:19 INFO - [mozharness: 2016-09-29 03:35:19.181819Z] Finished create-virtualenv step (success) 20:35:19 INFO - [mozharness: 2016-09-29 03:35:19.182295Z] Running install step. 20:35:19 INFO - Running pre-action listener: _resource_record_pre_action 20:35:19 INFO - Running main action method: install 20:35:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:35:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:35:19 INFO - Reading from file tmpfile_stdout 20:35:19 INFO - Detecting whether we're running mozinstall >=1.0... 20:35:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:35:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:35:19 INFO - Reading from file tmpfile_stdout 20:35:19 INFO - Output received: 20:35:19 INFO - Usage: mozinstall [options] installer 20:35:19 INFO - Options: 20:35:19 INFO - -h, --help show this help message and exit 20:35:19 INFO - -d DEST, --destination=DEST 20:35:19 INFO - Directory to install application into. [default: 20:35:19 INFO - "/builds/slave/test"] 20:35:19 INFO - --app=APP Application being installed. [default: firefox] 20:35:19 INFO - mkdir: /builds/slave/test/build/application 20:35:19 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'] 20:35:19 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 20:35:32 INFO - Reading from file tmpfile_stdout 20:35:32 INFO - Output received: 20:35:32 INFO - /builds/slave/test/build/application/firefox/firefox 20:35:32 INFO - Running post-action listener: _resource_record_post_action 20:35:32 INFO - [mozharness: 2016-09-29 03:35:32.974229Z] Finished install step (success) 20:35:32 INFO - [mozharness: 2016-09-29 03:35:32.974504Z] Running run-tests step. 20:35:32 INFO - Running pre-action listener: _resource_record_pre_action 20:35:32 INFO - Running main action method: run_tests 20:35:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:35:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:35:32 INFO - Python 2.7.3 20:35:32 INFO - Return code: 0 20:35:32 INFO - grabbing minidump binary from tooltool 20:35: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')]} 20:35:32 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 0x16003b0>, '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 0x16012b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1601740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:35:32 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 20:35:32 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 20:35:32 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 20:35:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:35:33 INFO - Return code: 0 20:35:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:35:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:35:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:35:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:35:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:35:33 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/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 20:35:33 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/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 20:35:33 INFO - Using env: {'DISPLAY': ':0', 20:35:33 INFO - 'HOME': '/home/cltbld', 20:35:33 INFO - 'LANG': 'en_US.UTF-8', 20:35:33 INFO - 'LANGUAGE': 'en_US:en', 20:35:33 INFO - 'LOGNAME': 'cltbld', 20:35:33 INFO - 'MAIL': '/var/mail/cltbld', 20:35:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:35:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:35:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:35:33 INFO - 'MOZ_NO_REMOTE': '1', 20:35:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:35:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:35:33 INFO - 'NO_EM_RESTART': '1', 20:35:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:35:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:35:33 INFO - 'PWD': '/builds/slave/test', 20:35:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:35:33 INFO - 'SHELL': '/bin/bash', 20:35:33 INFO - 'SHLVL': '1', 20:35:33 INFO - 'TERM': 'linux', 20:35:33 INFO - 'TMOUT': '86400', 20:35:33 INFO - 'USER': 'cltbld', 20:35:33 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972', 20:35:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:35:33 INFO - '_': '/tools/buildbot/bin/python'} 20:35:33 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/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 20:35:33 INFO - mozversion application_buildid: 20160928172420 20:35:33 INFO - mozversion application_changeset: 5d980c0c1885844a8604e203ee5ad49628f90de8 20:35:33 INFO - mozversion application_display_name: Firefox 20:35:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:35:33 INFO - mozversion application_name: Firefox 20:35:33 INFO - mozversion application_remotingname: firefox 20:35:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 20:35:33 INFO - mozversion application_vendor: Mozilla 20:35:33 INFO - mozversion application_version: 50.0 20:35:33 INFO - mozversion platform_buildid: 20160928172420 20:35:33 INFO - mozversion platform_changeset: 5d980c0c1885844a8604e203ee5ad49628f90de8 20:35:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 20:35:33 INFO - mozversion platform_version: 50.0 20:35:33 INFO - using testdate: 1475120133 20:35:33 INFO - actual date: 1475120133 20:35:33 INFO - starting webserver on 'localhost:39542' 20:35:33 INFO - SUITE-START | Running 2 tests 20:35:33 INFO - TEST-START | dromaeo_css 20:35:33 INFO - operating with platform_type : linux_ 20:35:33 INFO - Initialising browser for dromaeo_css test... 20:35:33 INFO - TEST-INFO | started process 29305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptvDqSF/profile http://localhost:39542/getInfo.html) 20:35:37 INFO - PROCESS | 29305 | __metrics Screen width/height:1600/1200 20:35:37 INFO - PROCESS | 29305 | colorDepth:24 20:35:37 INFO - PROCESS | 29305 | Browser inner width/height: 1024/697 20:35:37 INFO - PROCESS | 29305 | __metrics 20:35:37 INFO - PROCESS | 29305 | 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" 20:35:37 INFO - PROCESS | 29305 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:35:38 INFO - TEST-INFO | 29305: exit 0 20:35:38 INFO - Browser initialized. 20:35:38 INFO - Running cycle 1/1 for dromaeo_css test... 20:35:38 INFO - TEST-INFO | started process 29389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptvDqSF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:35:50 INFO - PROCESS | 29389 | RSS: Main: 143413248 20:35:50 INFO - PROCESS | 29389 | 20:36:41 INFO - PROCESS | 29389 | 1475120201774 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] 20:38:50 INFO - PROCESS | 29389 | Cycle 1(1): loaded http://localhost:39542/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39542/tests/dromaeo/cssquery-ext.html) 20:38:51 INFO - PROCESS | 29389 | RSS: Main: 270450688 20:38:51 INFO - PROCESS | 29389 | 20:41:54 INFO - PROCESS | 29389 | Cycle 1(1): loaded http://localhost:39542/tests/dromaeo/cssquery-ext.html (next: http://localhost:39542/tests/dromaeo/cssquery-jquery.html) 20:41:54 INFO - PROCESS | 29389 | RSS: Main: 222855168 20:41:54 INFO - PROCESS | 29389 | 20:44:56 INFO - PROCESS | 29389 | Cycle 1(1): loaded http://localhost:39542/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39542/tests/dromaeo/cssquery-mootools.html) 20:44:57 INFO - PROCESS | 29389 | RSS: Main: 177192960 20:44:57 INFO - PROCESS | 29389 | 20:47:58 INFO - PROCESS | 29389 | Cycle 1(1): loaded http://localhost:39542/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39542/tests/dromaeo/cssquery-prototype.html) 20:47:59 INFO - PROCESS | 29389 | RSS: Main: 226996224 20:47:59 INFO - PROCESS | 29389 | 20:51:02 INFO - PROCESS | 29389 | Cycle 1(1): loaded http://localhost:39542/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39542/tests/dromaeo/cssquery-yui.html) 20:51:02 INFO - PROCESS | 29389 | RSS: Main: 176746496 20:51:02 INFO - PROCESS | 29389 | 20:54:05 INFO - PROCESS | 29389 | Cycle 1(1): loaded http://localhost:39542/tests/dromaeo/cssquery-yui.html (next: http://localhost:39542/tests/dromaeo/cssquery-dojo.html) 20:54:05 INFO - PROCESS | 29389 | RSS: Main: 262602752 20:54:05 INFO - PROCESS | 29389 | 20:54:05 INFO - PROCESS | 29389 | __start_tp_report 20:54:05 INFO - PROCESS | 29389 | _x_x_mozilla_page_load 20:54:05 INFO - PROCESS | 29389 | _x_x_mozilla_page_load_details 20:54:05 INFO - PROCESS | 29389 | |i|pagename|runs| 20:54:05 INFO - PROCESS | 29389 | |0;dojo.html;3240.2547414094756;3241.319322942182;3259.5110733389974;3280.7375409967203;3290.210349516117;5635.380108188096;5736.1682556029355;5742.54059675226;5746.655200687962;5754.223179870716;5651.2653355063785;5689.061304884699;5707.343655479621;5717.857053573663;5718.142278658203;5184.53339199472;5187.844364669066;5200.427952925194;5245.580353571705;5263.184206448007;5144.17693169093;5148.124818780798;5250.422453530108;5256.027634887539;5292.973535132338;5126.871828204287;5137.948620513785;5138.897222055557;5153.793848246066;5288.497592728685;5155.252488389181;5268;5294.073537130987;5302.019126461579;5303.787848486056;5163.225516172567;5192.506711862367;5269.341332333458;5276.577873770089;5304.230886521472;5525.336959564837;5539.861503462445;5553.888922221573;5561.360443549003;5594.804181853616;5972.552058595623;5973.8805223895715;6032.125341825455;6049.879002419971;6078.179445774808;6037.275526936795;6106.084087386883;6117.877642447126;6124.265088189446;6133.6626485543275;5612.130119831432;5658.745356458928;5679.886402271973;5720.370759216483;5740.311162660508;6258.217722784652;6274.905876411857;6304.275008374004;6305;6328.430441260309;5869.618474799125;5880.088586269135;6060.575759696867;6073.058675905242;6083.087536869417;6063.848403789941;6128.044828652092;6138.633639632459;6139.492138333381;6143.754249830046;6086.756529738761;6091.8477038073515;6094.268687757498;6096.0855871619615;6117.480014198754;5955;5997.490213331916;6058.0610005449225;6088.4215999479975;6133.080037994337;4591.862244132682;4609.032103258353;4631.096936097496;4687.20317546018;4696.178168820443;4103.815328310233;4133.23535145999;4146.896327591834;4150.667946564328;4166.375043743463;5711.485966262973;5732.942670573324;5771.480566748929;5772.480476757112;5782.074868021181;6060.757569697251;6138.664296166039;6152.046432802923;6166.568340216231;6170.7223174957235;5808.273965754281;5845.590808643354;5846.766129354864;5848.619839710405;5879.794207202727;5176.068307704574;5245.921311180336;5289.70906600133;5291.444398338196;5292.285541451932;6279.623222606567;6355.046742988588;6367.363263673688;6374.107624932513;6374.26695929974;6137.232845894263;6186.721597528032;6187.752489900354;6193.1329613855;6193.256809182927;6159.72281247336;6213.316535181217;6418.582792118497;6424.9036264455135;6427.0680751289865;5973.701314934149;6048.971674815204;6053.818385448443;6056.152138700673;6093.573449858467;6144.508439324753;6148.16999705052;6152.16945712341;6163.784267550794;6186.226721659793;5907.232059832194;5955.434233747787;5986.88026239464;6033.366496517802;6038.305594856472;5979.671118088373;6069.180660610761;6072;6107.694615269308;6112.358202388685;5938.465538101592;5940.8811823764145;5986.6707331097205;6000;6003.279606447254;5877.970610146922;5917.378675239038;6005.519558435228;6060.484858786876;6064.06007068905;5870.0607902735355;5914.645121292736;5920.230370051866;5927.170196172453;5958.165856779968 20:54:05 INFO - PROCESS | 29389 | |1;ext.html;575.6661136540806;606.4784285514459;643.9774607888723;662.7945336945551;663.0716996205311;34069.48895766562;44479.33281000787;44984.875378115525;45041.549584504144;45071.77464112683;47506.52493475064;47883.281750773676;47911.52088479114;47925.28112078321;48101.759491202494;8650.394472386935;9348.345615806913;9506.572204250791;9512.762180945463;11030.117590592774;10852.348859068441;11234.988851003409;11236.21346505745;11237.494312755945;11243.044341231005;11393.658190254306;11440.19918605698;11446.313221206712;11457.427128643576;11459.140564457657;11334;11362.829557556684;11383.260088094248;11385.886141138566;11401.543938242461;11131.499082541304;11179.776404471946;11186.720331991766;11202.319652052176;11211.103111751121;44972;45010.774946125384;45353.31970020452;45519.772401138114;45736.313955290694;3809.733318667694;3826.2347530493735;3838.4550814330673;3931.12087649875;4076.7553946763287;34716;34780;34881.47677784835;35233.82383088068;35466.64533354659;4655.231886738684;4701.647376446746;4754.809807607691;4755.571998520115;4834.830780922651;364053;364908;365831.9999999973;366222;366639;62465.37534624687;62911.370886291465;62957;63058.36941630525;63086.36913630897;39283.214335713696;40238.396424053666;40368;42290.57709422866;66054;17394.391196308068;17546.473605791845;17553.38563150309;17561.121943902755;17561.736573951403;11155.888441115647;11368.204225704287;11458.083353331713;12472.501099956082;13014.21914685103;20363.287284944956;20824.791752082587;20857.269995550083;20882.791172088084;20899.164033438796;7256.6734496947765;7425.814354641069;7490.700371985168;7544.811379715441;7591.696332146763;1037.2376303416982;1077.8383242513685;1098.3958822647512;1107.363266121967;1172.1326218598176;34899.302013959576;34946;35037.59849606038;35340;35343.11642208965;1101.6805126513382;1122.865256169265;1162.8313894485252;1187.6140254417348;1198.269055875915;15228.543143705707;17323.393681221096;18190.727139092924;18192.909035454737;18410.447686569423;632788;633725;638291;638989;639040;60882.39117608856;60910.39089609136;60972.08541871876;61184.38815611787;61330;1359.5241665417025;1419.06341814402;1446.3708286895444;1461.693044460675;1489.932953017117;32725.50911736278;34004.65995340015;34162;34749;35050;52850.47149528455;53235.4676453246;53432;53563.464365355845;53579.19631205457;515607;515987;518629;520402;522420;26695;27264.318392040357;28738.568921466613;29205.41589168247;29362.412751744418;48992;49601.25598115959;49802.25296620626;50240;50401.74799125981;42485.57514424816;42685.359719603606;43317.3502397458;44484.77757611191;44762.55237447714;42324.153516930106;42613.5738642622;42643;43640;44045.77977110094;42137.26978269236;43320.13359732851;43349.56650433582;43371;43677;36110;37780.62219377771;38258;39112.413313800884;39280;28395.43209135847;28706;29033.564496532992;29043.709562904947;29161.85419072891 20:54:05 INFO - PROCESS | 29389 | |2;jquery.html;406.13087991697773;415.2711990456748;432.5458268817741;433.5144638005434;433.99132017359636;19403;20736.170553177875;20791.896040519812;20924.26765063223;21152.57694846102;19913.800861991374;20701.68947465789;20707.378778636667;21341.893290533524;21458.248961286357;5807.216025836521;6079.99680052793;6090.23872015998;6090.817275481721;6093.81718548442;5771.192033115367;5781.421857814206;5816.505597024258;5823.650580965134;5949.494292985101;5789.710514474259;5798.391168927265;5802.738876750556;5804.9419505805035;5807.854803629901;5620.887582248352;5884.382139875315;5921.526277897758;5948.524118070544;5951.732172052268;5299.284596579451;5363.195520671891;5367.141257398797;5367.597430192712;5373.274607927919;24363.147289844946;24867;25057.373565660822;25192;25397.873010635012;2465.235776909164;2578.1492107604445;2644.8942042318477;2712.240572639664;2904.8402337871407;7429.7399591014055;7432.962835185844;7496;8255.917440825635;11163;2034.1964923855141;2041.11211622943;2052.2611859730487;2057.351934140741;2099.9055042523123;58869;84393.57803211006;86006;89025.99999999935;92401.53799231027;11482.0814334853;11522.827157592643;11588.304701717923;11654.650360489199;11856.466459009365;16454.177291135395;16455.25951332193;16457.67084658324;16463.506094817174;16470.75293870593;8236.341092712688;8490.702825401206;8570.700025499078;8739.126087391338;8755.518446485505;8229.835403291901;8292.253697167283;8334.749957501284;8474.576271186539;8562.357823163287;6890.758823441155;6928.44572434204;6952.860942781217;6972.302769723088;7018.40343570792;1990.4526255279625;2035.8982050897396;2062.5874825035053;2088.6136064828056;2094.4973206430345;998.7353351361895;1084.9132069434427;1118.932864028161;1121.3216004317271;1182.7693599748138;20582.27962021322;20715.27496537614;20880.47798805042;20897.20390227357;21293.893530532558;1063.3991794636006;1065.3714308557987;1072.0994364733506;1076.6231818863334;1124.3085502415956;11783.52865885355;12066.638000859988;12118.939405303094;12322.383880805924;12356;521278;525441;526601;533741;538318;84383;87725;89237.55381223053;90050.54974725084;91385.54307228554;1030.8711411073616;1063.2822844579878;1080.5191689698165;1087.8912108789216;1095.835624656308;17888.28446862111;18200.90899545547;18539.81460185381;18552.420119583596;18732.250709971995;40777;44773.776131119135;45262;45889.54110458853;46525.3021204689;57467;58161.4183858156;58265.417345826;60708.696456517435;63541.682291588244;8741.431806932529;8751.781205469913;8816.118388161132;9066.320025998208;9130.04134565861;12394.75210495803;12921.870781292067;13145.934270328587;13166.144200626928;13448.798268026183;11422.31466112036;11532.827007594726;11536.884631153582;11652.650420487398;11770.293782373084;11189.216754827085;11201.775964480828;11327.773444530898;11329.263597866113;11339.376334301363;11726.472308746297;11750.70623234426;11834.82247766267;12004.75990480168;12165;8743.475391476532;8767.473951562926;8868.822623547621;8985.550722463982;9337.299702522203;9280.211182049332;11058.723531911766;11784.410779461165;11803.291802491878;11910.285382877055 20:54:05 INFO - PROCESS | 29389 | |3;mootools.html;1407.2541552976925;1522.307350155679;1553.6581951970566;1723.7759091318126;1820.3992682414812;9075.546222688863;9575.712728618144;9595.04049595041;9868.06253405927;9898.208143348533;9681.903180968206;9772.267079968995;9782.26633002524;9785.853212201804;9788.41269523829;2463.2610216934936;2488.9004439822384;2490.489449662824;2495.189063554343;2499.2252401755422;2452.730199678034;2507.2728908616477;2509.034021901572;2512.949741005179;2513.42191296002;2424.8787560621986;2437.0251899240357;2502.199296225209;2505.210858579551;2507.661465702125;2426.162973774054;2451.669024681672;2454.0306578901414;2456.4718585504115;2457.7296497385273;2397.640353946922;2419.8185136114685;2423.7939775119135;2426.939326516851;2467.8766061696842;9312.534373281309;9667.178289845371;9692.854607180898;9735.513224338825;9745.610175592969;5587.888242235133;5791.942080579182;5803.912941305884;5806.477417032445;5834.912476312858;3361.8151001694896;3387.745919056056;3429.554157959449;3455.0153206336327;3459.930801383958;1055.963041293551;1056.9312994655322;1064.180580952662;1081.1729027294127;1083.9349639021605;14049.157050576896;14053.648658783612;14061.859381406157;14064.578062658034;14124.929375353058;4796.37647105874;4807.78364973577;4826.444958829709;4942.233953737176;4990.426100998359;10776.94611526953;10785.78428431427;10786.406747628798;10787.730306742395;10792.190585706103;5498.532624726863;5505.064139096363;5508.91736623951;5517.420670829585;5517.724113794295;4601.470830854428;4740.265258884879;4816.590589799836;5142.537171654569;5284.630075894727;4008.939865902014;4012.598740126022;4120.958790412117;4121.732087414309;4121.91756164881;1166.1487114406532;1179.7640471905654;1182.9704257393462;1188.4295538141741;1195.6114262864603;1019.2304809868501;1022.320157095538;1036.5439206749006;1036.7356324137297;1043.4209013997154;6304;6468.191476065492;6489.967550162219;6540.41136297726;6606.834829129214;460.83179639431364;1035.1977217656377;1117.1342209787479;1152.2683096233952;1162.8488296521568;4173;4337.110892267077;4364.803583838735;4542.159700455426;4642.837500687459;11947.223430476992;12021.699457513632;12407.503699852085;12412.62762117138;12433.440495177738;12134.696632584079;12252.081093917992;12256.877431225752;12269.018478521697;12380.690482738004;8872;8910.420822646507;9226;9273.072692730808;9303.953480232556;6240.438360547572;6377.7767778127545;6391.520635952229;6427.807165785034;6502.512311576556;11038.779224415306;11256.943715281372;11300.604478843201;11438.199326047097;11532.538698452303;10039.74900627461;10406.323588966692;11624.476898539755;11693.88306116928;11738.76522469563;7829.412794040356;8130.67477300925;8158.551279679675;8175.345972322081;8182.590870456334;9182.03588623185;9241.445513269226;9264.11990860867;9267.86098208514;9471.905280947103;8222.712205072794;8260.339172866035;8353.83292334162;8505.404621676424;8527.53098579584;7994.2405471480115;8074.434789564674;8529.317654587741;8542.572871356531;8649.30805535543;7500.249975002456;7588.8482230356185;7591.62041897914;7593;7609.771706848804;5156.61325400589;5253.711045892513;5355.25026496283;5456.645318054314;5526.447355264441;5553;5605.691686957257;5708.771649134154;5709;5754.597178197486 20:54:05 INFO - PROCESS | 29389 | |4;prototype.html;354.35684233116893;361.29006502223126;361.76485284565024;371.180881207231;372.3316646619318;5397.4062853086125;5453.427390124039;5485;5538.639988400756;5539.778408863645;5068.518490743373;5206.349206349207;5229.398619158788;5257.526822585966;5396.703181325026;4147.25349437102;4198.622124008839;4209.074003719192;4260.850870219543;4283.336082907154;4208.684348673846;4219.578042195772;4221.345691417835;4238.173556156536;4242;4135.635240370685;4171.833126674945;4234.597713217239;4255.829766809324;4260.297050986584;4233.597808208216;4234.513031001444;4240.512341080769;4246.214450326668;4246.44796176495;3812.80935953204;3815.3704638734575;3824.1586850893036;3837.290101331235;3858.189780146173;5204.635675502716;5258.7370631468275;5271.258945348436;5315.946840531623;5326.334208223972;4840.806367745286;4844.32179494871;4868.269759536098;4870.756462176877;4874.147024270774;5573.442655734394;5583.469570390806;5639.295088113986;5645.717714114319;5663.660180389149;4030.6372426481466;4176.60322269384;4176.62410383067;4192.098698779747;4200.201961627312;5764.250647415809;5795.217645617872;5808.97095514524;5814.186013958049;5853.3561308256485;4266.850660226877;4282.164977829356;4285.700000999963;4308.418363520947;4331.263685173528;4090.6727461802984;4323.589259020388;4376.6498680105515;4391.407160033418;4397.274449715825;4830.478399304208;4832.806687732522;4836.903261934742;4840.419149702058;4888.753367891165;5685.943140568624;5687.687177205294;5688.9759843227785;5723.942760572424;5745.626534275342;1453.3968403112801;1461.744194765933;1481.888858335629;1489.53079779869;1491.7991017231234;910.4264313482464;936.2884208001967;949.5821838391086;963.3882484622176;985;4900.191468407673;4909.656324057353;4931.3589233399425;4938.419705694143;4957.603391728725;5137.51193636604;5197.428282888879;5213.817516386908;5228.686278823283;5232.39827419844;4981.526754958273;4987;4993.70037797726;5000.824971125993;5221.216817477332;3077.446059709274;3123.4846250368887;3131.9217019574694;3134.6520995971496;3137.262743255333;6038.63768173911;6039.969800150971;6048.546359023091;6065.363136870653;6080.57435979486;5644.97177514118;5953.940460595425;5964.463198312173;5985.760569577168;5994.22075130239;6005.489533389711;6028.849278767978;6029.276486821609;6078.209832721718;6098.451139397475;5603.719814009201;5882.3529411764775;5894.732632484037;5959.828120315883;5960.165576819336;5752.68360240174;5842;5848.6783226921225;5852.209951656472;5852.4440178183;6059.454649081604;6060.2729321466095;6089.33017368098;6095.481884039729;6112.8910525868505;4036.172584620087;4242.193983143193;4269.850555230552;4287.506936702319;4323.783810809384;5591.552675785846;5735.340435849931;5804.883902322014;5853.882922341444;5870.5010074142465;5651.57613179005;5691.3170419549915;5717.828465146053;5770.769169233352;5806.925718742046;5584.832455026356;5664.235328230636;5713.628614140068;5714.9142762857755;5719.31280272329;5606.439356064361;5676.858078548069;5678.630888992202;5679.346875109415;5685.658860468385;5714.657055592016;5720.3707592164;5724.454397312786;5748.712564371681;5768.278965129359;5395.89384176235;5526.419725928719;5583.41374155724;5594.636348637326;5618.578606604603 20:54:05 INFO - PROCESS | 29389 | |5;yui.html;744.5197847388434;782.3858271257064;822.2846123872231;844.0673056272815;866.8699695045744;16909.661806763874;18136.637267254646;18498.815011849878;18504.074796260185;19040.33358832441;17983.37058202963;18027.909860450713;18085.276588936427;18118.365857195;18124.45626631203;733.1349008170357;886.778305423644;910.622091831889;912.7124955638985;912.885889263842;849.2017503546678;898.7348732124007;905.7509184974119;911.284641556375;912.2747415804452;941.0307383395079;945.0927109974433;947.8720372749659;949.5347279832902;950.8003319302954;943.7876477792511;943.981120377592;944.9669261575846;945.0785484152965;945.4185675809397;692.882210024297;693.5942473652868;693.8542905989748;693.9444844412435;694.1115372323434;16065.678686426207;16069;16074.196290185444;16075.276612552347;16099.034057956444;14858.925705371405;14962.775558366633;16196.109213993226;16407.753883691756;16737.581560460963;13085;13318.933405332911;13506.459741610422;14103.153810771284;14402.639934001629;4058.9594104059174;4075.53131389891;4113.958860411418;4204.789760511962;4262.147570485884;84346;85806.5709671454;85921.14078859193;87790;87943.56028219817;12213.145079844504;12372.752544949231;12614.684632884251;12697.492100316069;12746;24113.758862411152;24146.275611731675;24522.14172503954;24750.628740568907;24919.50160996806;8717.171868672465;9002.774930626787;9023;9233.353665243369;9352.345335826427;4730.858074257778;4761.809527618926;4762.666613337103;4803.5916947059195;4887.34020907173;3872.8063596820048;3983.402489626579;3999.2201520703184;4026.9194616107516;4045.4943132108488;698.0297386632585;700.1178515070945;700.737223541172;704.1339152842033;708.5571517801374;723.7141329175051;790.8181118342814;823.4400607586779;829.531314807132;851.4848516647463;10502.10732087781;10922.563097476012;11049.834252486218;11078.335299882034;11649.76700465986;825.9339252859756;828.9502629842108;833.0065181022485;842.9957850210833;845.3575282785005;5287.70917599528;5292.126799078109;5313.601479889024;5378.731063446812;5474.562035037187;74015.25984740192;74210.25789742141;74238.62880685562;78529.60735196287;121220.78779212094;110562;113467;117220.41389793166;117365;117580.41209793897;67242;97727.51136244273;97999;98803;99712.5014374938;10133.13868321201;10439.217058720475;10452.947735261276;10537;10540;57541;57591.424085758605;57892.71053644705;59261.70369148126;60250;79400;81973;87970;88008;90488.54755726179;13326.267055312052;13515.526956556474;14137.222452765196;14363.56909292723;15213.92393038072;15424.228788560751;15591.844081559038;15703.450379236672;16516.42192523256;17929.82070179334;15476.148811815458;15566.766498502306;15615.687686246438;15821.446249381217;15827.920860396085;15100.471483498024;15511.224438778241;16209.756853646968;16702.4154154604;16844.747328789832;15075.698486030436;15174.696506070037;15252.923735381695;16063.357465701709;16371.918140409221;11939.701507462036;12987.610371688865;13019.934900325437;13111.082224244212;13521.256330901895;13000.869991300344;13097.738045238852;13135.492083698124;13201.471941122247;13228.272445015231 20:54:05 INFO - PROCESS | 29389 | __end_tp_report 20:54:05 INFO - PROCESS | 29389 | __start_cc_report 20:54:05 INFO - PROCESS | 29389 | _x_x_mozilla_cycle_collect,1047 20:54:05 INFO - PROCESS | 29389 | __end_cc_report 20:54:05 INFO - PROCESS | 29389 | __startTimestamp1475121245531__endTimestamp 20:54:05 INFO - PROCESS | 29389 | 20:54:05 INFO - PROCESS | 29389 | ------- Summary: start ------- 20:54:05 INFO - PROCESS | 29389 | Number of tests: 6 20:54:05 INFO - PROCESS | 29389 | 20:54:05 INFO - PROCESS | 29389 | [#0] dojo.html Cycles:165 Average:5681.22 Median:5920.23 stddev:655.52 (11.1%) stddev-sans-first:628.94 20:54:05 INFO - PROCESS | 29389 | Values: 3240.3 3241.3 3259.5 3280.7 3290.2 5635.4 5736.2 5742.5 5746.7 5754.2 5651.3 5689.1 5707.3 5717.9 5718.1 5184.5 5187.8 5200.4 5245.6 5263.2 5144.2 5148.1 5250.4 5256.0 5293.0 5126.9 5137.9 5138.9 5153.8 5288.5 5155.3 5268.0 5294.1 5302.0 5303.8 5163.2 5192.5 5269.3 5276.6 5304.2 5525.3 5539.9 5553.9 5561.4 5594.8 5972.6 5973.9 6032.1 6049.9 6078.2 6037.3 6106.1 6117.9 6124.3 6133.7 5612.1 5658.7 5679.9 5720.4 5740.3 6258.2 6274.9 6304.3 6305.0 6328.4 5869.6 5880.1 6060.6 6073.1 6083.1 6063.8 6128.0 6138.6 6139.5 6143.8 6086.8 6091.8 6094.3 6096.1 6117.5 5955.0 5997.5 6058.1 6088.4 6133.1 4591.9 4609.0 4631.1 4687.2 4696.2 4103.8 4133.2 4146.9 4150.7 4166.4 5711.5 5732.9 5771.5 5772.5 5782.1 6060.8 6138.7 6152.0 6166.6 6170.7 5808.3 5845.6 5846.8 5848.6 5879.8 5176.1 5245.9 5289.7 5291.4 5292.3 6279.6 6355.0 6367.4 6374.1 6374.3 6137.2 6186.7 6187.8 6193.1 6193.3 6159.7 6213.3 6418.6 6424.9 6427.1 5973.7 6049.0 6053.8 6056.2 6093.6 6144.5 6148.2 6152.2 6163.8 6186.2 5907.2 5955.4 5986.9 6033.4 6038.3 5979.7 6069.2 6072.0 6107.7 6112.4 5938.5 5940.9 5986.7 6000.0 6003.3 5878.0 5917.4 6005.5 6060.5 6064.1 5870.1 5914.6 5920.2 5927.2 5958.2 20:54:05 INFO - PROCESS | 29389 | 20:54:05 INFO - PROCESS | 29389 | [#1] ext.html Cycles:180 Average:66707.25 Median:21042.12 stddev:138144.90 (656.5%) stddev-sans-first:138443.20 20:54:05 INFO - PROCESS | 29389 | Values: 575.7 606.5 644.0 662.8 663.1 34069.5 44479.3 44984.9 45041.5 45071.8 47506.5 47883.3 47911.5 47925.3 48101.8 8650.4 9348.3 9506.6 9512.8 11030.1 10852.3 11235.0 11236.2 11237.5 11243.0 11393.7 11440.2 11446.3 11457.4 11459.1 11334.0 11362.8 11383.3 11385.9 11401.5 11131.5 11179.8 11186.7 11202.3 11211.1 44972.0 45010.8 45353.3 45519.8 45736.3 3809.7 3826.2 3838.5 3931.1 4076.8 34716.0 34780.0 34881.5 35233.8 35466.6 4655.2 4701.6 4754.8 4755.6 4834.8 364053.0 364908.0 365832.0 366222.0 366639.0 62465.4 62911.4 62957.0 63058.4 63086.4 39283.2 40238.4 40368.0 42290.6 66054.0 17394.4 17546.5 17553.4 17561.1 17561.7 11155.9 11368.2 11458.1 12472.5 13014.2 20363.3 20824.8 20857.3 20882.8 20899.2 7256.7 7425.8 7490.7 7544.8 7591.7 1037.2 1077.8 1098.4 1107.4 1172.1 34899.3 34946.0 35037.6 35340.0 35343.1 1101.7 1122.9 1162.8 1187.6 1198.3 15228.5 17323.4 18190.7 18192.9 18410.4 632788.0 633725.0 638291.0 638989.0 639040.0 60882.4 60910.4 60972.1 61184.4 61330.0 1359.5 1419.1 1446.4 1461.7 1489.9 32725.5 34004.7 34162.0 34749.0 35050.0 52850.5 53235.5 53432.0 53563.5 53579.2 515607.0 515987.0 518629.0 520402.0 522420.0 26695.0 27264.3 28738.6 29205.4 29362.4 48992.0 49601.3 49802.3 50240.0 50401.7 42485.6 42685.4 43317.4 44484.8 44762.6 42324.2 42613.6 42643.0 43640.0 44045.8 42137.3 43320.1 43349.6 43371.0 43677.0 36110.0 37780.6 38258.0 39112.4 39280.0 28395.4 28706.0 29033.6 29043.7 29161.9 20:54:05 INFO - PROCESS | 29389 | 20:54:05 INFO - PROCESS | 29389 | [#2] jquery.html Cycles:180 Average:30507.57 Median:2611.52 stddev:86983.24 (3330.7%) stddev-sans-first:87197.88 20:54:05 INFO - PROCESS | 29389 | Values: 406.1 415.3 432.5 433.5 434.0 19403.0 20736.2 20791.9 20924.3 21152.6 19913.8 20701.7 20707.4 21341.9 21458.2 5807.2 6080.0 6090.2 6090.8 6093.8 5771.2 5781.4 5816.5 5823.7 5949.5 5789.7 5798.4 5802.7 5804.9 5807.9 5620.9 5884.4 5921.5 5948.5 5951.7 5299.3 5363.2 5367.1 5367.6 5373.3 24363.1 24867.0 25057.4 25192.0 25397.9 2465.2 2578.1 2644.9 2712.2 2904.8 7429.7 7433.0 7496.0 8255.9 11163.0 2034.2 2041.1 2052.3 2057.4 2099.9 58869.0 84393.6 86006.0 89026.0 92401.5 11482.1 11522.8 11588.3 11654.7 11856.5 16454.2 16455.3 16457.7 16463.5 16470.8 8236.3 8490.7 8570.7 8739.1 8755.5 8229.8 8292.3 8334.7 8474.6 8562.4 6890.8 6928.4 6952.9 6972.3 7018.4 1990.5 2035.9 2062.6 2088.6 2094.5 998.7 1084.9 1118.9 1121.3 1182.8 20582.3 20715.3 20880.5 20897.2 21293.9 1063.4 1065.4 1072.1 1076.6 1124.3 11783.5 12066.6 12118.9 12322.4 12356.0 521278.0 525441.0 526601.0 533741.0 538318.0 84383.0 87725.0 89237.6 90050.5 91385.5 1030.9 1063.3 1080.5 1087.9 1095.8 17888.3 18200.9 18539.8 18552.4 18732.3 40777.0 44773.8 45262.0 45889.5 46525.3 57467.0 58161.4 58265.4 60708.7 63541.7 8741.4 8751.8 8816.1 9066.3 9130.0 12394.8 12921.9 13145.9 13166.1 13448.8 11422.3 11532.8 11536.9 11652.7 11770.3 11189.2 11201.8 11327.8 11329.3 11339.4 11726.5 11750.7 11834.8 12004.8 12165.0 8743.5 8767.5 8868.8 8985.6 9337.3 9280.2 11058.7 11784.4 11803.3 11910.3 20:54:05 INFO - PROCESS | 29389 | 20:54:05 INFO - PROCESS | 29389 | [#3] mootools.html Cycles:180 Average:6295.72 Median:4350.96 stddev:3710.64 (85.3%) stddev-sans-first:3702.86 20:54:05 INFO - PROCESS | 29389 | Values: 1407.3 1522.3 1553.7 1723.8 1820.4 9075.5 9575.7 9595.0 9868.1 9898.2 9681.9 9772.3 9782.3 9785.9 9788.4 2463.3 2488.9 2490.5 2495.2 2499.2 2452.7 2507.3 2509.0 2512.9 2513.4 2424.9 2437.0 2502.2 2505.2 2507.7 2426.2 2451.7 2454.0 2456.5 2457.7 2397.6 2419.8 2423.8 2426.9 2467.9 9312.5 9667.2 9692.9 9735.5 9745.6 5587.9 5791.9 5803.9 5806.5 5834.9 3361.8 3387.7 3429.6 3455.0 3459.9 1056.0 1056.9 1064.2 1081.2 1083.9 14049.2 14053.6 14061.9 14064.6 14124.9 4796.4 4807.8 4826.4 4942.2 4990.4 10776.9 10785.8 10786.4 10787.7 10792.2 5498.5 5505.1 5508.9 5517.4 5517.7 4601.5 4740.3 4816.6 5142.5 5284.6 4008.9 4012.6 4121.0 4121.7 4121.9 1166.1 1179.8 1183.0 1188.4 1195.6 1019.2 1022.3 1036.5 1036.7 1043.4 6304.0 6468.2 6490.0 6540.4 6606.8 460.8 1035.2 1117.1 1152.3 1162.8 4173.0 4337.1 4364.8 4542.2 4642.8 11947.2 12021.7 12407.5 12412.6 12433.4 12134.7 12252.1 12256.9 12269.0 12380.7 8872.0 8910.4 9226.0 9273.1 9304.0 6240.4 6377.8 6391.5 6427.8 6502.5 11038.8 11256.9 11300.6 11438.2 11532.5 10039.7 10406.3 11624.5 11693.9 11738.8 7829.4 8130.7 8158.6 8175.3 8182.6 9182.0 9241.4 9264.1 9267.9 9471.9 8222.7 8260.3 8353.8 8505.4 8527.5 7994.2 8074.4 8529.3 8542.6 8649.3 7500.2 7588.8 7591.6 7593.0 7609.8 5156.6 5253.7 5355.3 5456.6 5526.4 5553.0 5605.7 5708.8 5709.0 5754.6 20:54:05 INFO - PROCESS | 29389 | 20:54:05 INFO - PROCESS | 29389 | [#4] prototype.html Cycles:180 Average:4764.97 Median:5265.00 stddev:1383.56 (26.3%) stddev-sans-first:1347.25 20:54:05 INFO - PROCESS | 29389 | Values: 354.4 361.3 361.8 371.2 372.3 5397.4 5453.4 5485.0 5538.6 5539.8 5068.5 5206.3 5229.4 5257.5 5396.7 4147.3 4198.6 4209.1 4260.9 4283.3 4208.7 4219.6 4221.3 4238.2 4242.0 4135.6 4171.8 4234.6 4255.8 4260.3 4233.6 4234.5 4240.5 4246.2 4246.4 3812.8 3815.4 3824.2 3837.3 3858.2 5204.6 5258.7 5271.3 5315.9 5326.3 4840.8 4844.3 4868.3 4870.8 4874.1 5573.4 5583.5 5639.3 5645.7 5663.7 4030.6 4176.6 4176.6 4192.1 4200.2 5764.3 5795.2 5809.0 5814.2 5853.4 4266.9 4282.2 4285.7 4308.4 4331.3 4090.7 4323.6 4376.6 4391.4 4397.3 4830.5 4832.8 4836.9 4840.4 4888.8 5685.9 5687.7 5689.0 5723.9 5745.6 1453.4 1461.7 1481.9 1489.5 1491.8 910.4 936.3 949.6 963.4 985.0 4900.2 4909.7 4931.4 4938.4 4957.6 5137.5 5197.4 5213.8 5228.7 5232.4 4981.5 4987.0 4993.7 5000.8 5221.2 3077.4 3123.5 3131.9 3134.7 3137.3 6038.6 6040.0 6048.5 6065.4 6080.6 5645.0 5953.9 5964.5 5985.8 5994.2 6005.5 6028.8 6029.3 6078.2 6098.5 5603.7 5882.4 5894.7 5959.8 5960.2 5752.7 5842.0 5848.7 5852.2 5852.4 6059.5 6060.3 6089.3 6095.5 6112.9 4036.2 4242.2 4269.9 4287.5 4323.8 5591.6 5735.3 5804.9 5853.9 5870.5 5651.6 5691.3 5717.8 5770.8 5806.9 5584.8 5664.2 5713.6 5714.9 5719.3 5606.4 5676.9 5678.6 5679.3 5685.7 5714.7 5720.4 5724.5 5748.7 5768.3 5395.9 5526.4 5583.4 5594.6 5618.6 20:54:05 INFO - PROCESS | 29389 | 20:54:05 INFO - PROCESS | 29389 | [#5] yui.html Cycles:180 Average:22259.12 Median:4052.23 stddev:30767.65 (759.3%) stddev-sans-first:30811.55 20:54:05 INFO - PROCESS | 29389 | Values: 744.5 782.4 822.3 844.1 866.9 16909.7 18136.6 18498.8 18504.1 19040.3 17983.4 18027.9 18085.3 18118.4 18124.5 733.1 886.8 910.6 912.7 912.9 849.2 898.7 905.8 911.3 912.3 941.0 945.1 947.9 949.5 950.8 943.8 944.0 945.0 945.1 945.4 692.9 693.6 693.9 693.9 694.1 16065.7 16069.0 16074.2 16075.3 16099.0 14858.9 14962.8 16196.1 16407.8 16737.6 13085.0 13318.9 13506.5 14103.2 14402.6 4059.0 4075.5 4114.0 4204.8 4262.1 84346.0 85806.6 85921.1 87790.0 87943.6 12213.1 12372.8 12614.7 12697.5 12746.0 24113.8 24146.3 24522.1 24750.6 24919.5 8717.2 9002.8 9023.0 9233.4 9352.3 4730.9 4761.8 4762.7 4803.6 4887.3 3872.8 3983.4 3999.2 4026.9 4045.5 698.0 700.1 700.7 704.1 708.6 723.7 790.8 823.4 829.5 851.5 10502.1 10922.6 11049.8 11078.3 11649.8 825.9 829.0 833.0 843.0 845.4 5287.7 5292.1 5313.6 5378.7 5474.6 74015.3 74210.3 74238.6 78529.6 121220.8 110562.0 113467.0 117220.4 117365.0 117580.4 67242.0 97727.5 97999.0 98803.0 99712.5 10133.1 10439.2 10452.9 10537.0 10540.0 57541.0 57591.4 57892.7 59261.7 60250.0 79400.0 81973.0 87970.0 88008.0 90488.5 13326.3 13515.5 14137.2 14363.6 15213.9 15424.2 15591.8 15703.5 16516.4 17929.8 15476.1 15566.8 15615.7 15821.4 15827.9 15100.5 15511.2 16209.8 16702.4 16844.7 15075.7 15174.7 15252.9 16063.4 16371.9 11939.7 12987.6 13019.9 13111.1 13521.3 13000.9 13097.7 13135.5 13201.5 13228.3 20:54:05 INFO - PROCESS | 29389 | -------- Summary: end -------- 20:54:05 INFO - PROCESS | 29389 | 20:54:06 INFO - TEST-INFO | 29389: exit 0 20:54:06 INFO - TEST-OK | dromaeo_css | took 1112970ms 20:54:06 INFO - TEST-START | kraken 20:54:06 INFO - operating with platform_type : linux_ 20:54:06 INFO - Initialising browser for kraken test... 20:54:06 INFO - TEST-INFO | started process 29574 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYU2PPz/profile http://localhost:39542/getInfo.html) 20:54:10 INFO - PROCESS | 29574 | __metrics Screen width/height:1600/1200 20:54:10 INFO - PROCESS | 29574 | colorDepth:24 20:54:10 INFO - PROCESS | 29574 | Browser inner width/height: 1024/697 20:54:10 INFO - PROCESS | 29574 | __metrics 20:54:10 INFO - PROCESS | 29574 | 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" 20:54:10 INFO - PROCESS | 29574 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:54:11 INFO - PROCESS | 29574 | console.error: PushService: 20:54:11 INFO - PROCESS | 29574 | stateChangeProcessEnqueue: Error transitioning state 20:54:11 INFO - PROCESS | 29574 | UnknownError 20:54:11 INFO - TEST-INFO | 29574: exit 0 20:54:11 INFO - Browser initialized. 20:54:11 INFO - Running cycle 1/1 for kraken test... 20:54:11 INFO - TEST-INFO | started process 29666 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYU2PPz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:54:23 INFO - PROCESS | 29666 | RSS: Main: 143278080 20:54:23 INFO - PROCESS | 29666 | 20:54:26 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39542/tests/kraken/driver.html?testName=audio-beat-detection) 20:54:26 INFO - PROCESS | 29666 | RSS: Main: 198778880 20:54:26 INFO - PROCESS | 29666 | 20:54:29 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39542/tests/kraken/driver.html?testName=audio-dft) 20:54:29 INFO - PROCESS | 29666 | RSS: Main: 182505472 20:54:29 INFO - PROCESS | 29666 | 20:54:32 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39542/tests/kraken/driver.html?testName=audio-fft) 20:54:33 INFO - PROCESS | 29666 | RSS: Main: 273526784 20:54:33 INFO - PROCESS | 29666 | 20:54:35 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39542/tests/kraken/driver.html?testName=audio-oscillator) 20:54:36 INFO - PROCESS | 29666 | RSS: Main: 179937280 20:54:36 INFO - PROCESS | 29666 | 20:54:38 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39542/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:54:38 INFO - PROCESS | 29666 | RSS: Main: 178397184 20:54:38 INFO - PROCESS | 29666 | 20:54:41 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39542/tests/kraken/driver.html?testName=imaging-darkroom) 20:54:42 INFO - PROCESS | 29666 | RSS: Main: 214069248 20:54:42 INFO - PROCESS | 29666 | 20:54:45 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39542/tests/kraken/driver.html?testName=imaging-desaturate) 20:54:46 INFO - PROCESS | 29666 | RSS: Main: 212750336 20:54:46 INFO - PROCESS | 29666 | 20:54:48 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39542/tests/kraken/driver.html?testName=json-parse-financial) 20:54:49 INFO - PROCESS | 29666 | RSS: Main: 213458944 20:54:49 INFO - PROCESS | 29666 | 20:54:51 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39542/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:54:51 INFO - PROCESS | 29666 | RSS: Main: 175034368 20:54:51 INFO - PROCESS | 29666 | 20:54:53 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39542/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:54:53 INFO - PROCESS | 29666 | RSS: Main: 182292480 20:54:53 INFO - PROCESS | 29666 | 20:54:55 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39542/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:54:56 INFO - PROCESS | 29666 | RSS: Main: 184745984 20:54:56 INFO - PROCESS | 29666 | 20:54:59 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39542/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:54:59 INFO - PROCESS | 29666 | RSS: Main: 185622528 20:54:59 INFO - PROCESS | 29666 | 20:55:02 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39542/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:55:02 INFO - PROCESS | 29666 | RSS: Main: 182374400 20:55:02 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | Cycle 1(1): loaded http://localhost:39542/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39542/tests/kraken/driver.html?testName=ai-astar) 20:55:04 INFO - PROCESS | 29666 | RSS: Main: 233361408 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | __start_tp_report 20:55:04 INFO - PROCESS | 29666 | _x_x_mozilla_page_load 20:55:04 INFO - PROCESS | 29666 | _x_x_mozilla_page_load_details 20:55:04 INFO - PROCESS | 29666 | |i|pagename|runs| 20:55:04 INFO - PROCESS | 29666 | |0;ai-astar;113;111;119;111;111;111;109;109;111;110 20:55:04 INFO - PROCESS | 29666 | |1;audio-beat-detection;145;116;146;166;115;152;145;122;179;120 20:55:04 INFO - PROCESS | 29666 | |2;audio-dft;162;161;163;159;161;160;161;161;159;159 20:55:04 INFO - PROCESS | 29666 | |3;audio-fft;74;75;76;168;76;75;73;74;176;75 20:55:04 INFO - PROCESS | 29666 | |4;audio-oscillator;82;83;155;83;83;83;141;83;85;150 20:55:04 INFO - PROCESS | 29666 | |5;imaging-gaussian-blur;116;117;117;116;116;116;117;117;116;117 20:55:04 INFO - PROCESS | 29666 | |6;imaging-darkroom;146;145;145;145;146;145;146;145;146;146 20:55:04 INFO - PROCESS | 29666 | |7;imaging-desaturate;95;94;94;94;94;94;95;94;94;95 20:55:04 INFO - PROCESS | 29666 | |8;json-parse-financial;51;51;51;49;50;51;50;50;49;48 20:55:04 INFO - PROCESS | 29666 | |9;json-stringify-tinderbox;59;61;60;60;60;61;60;60;61;61 20:55:04 INFO - PROCESS | 29666 | |10;stanford-crypto-aes;73;73;71;72;77;71;72;72;72;73 20:55:04 INFO - PROCESS | 29666 | |11;stanford-crypto-ccm;121;119;139;117;115;133;119;115;164;133 20:55:04 INFO - PROCESS | 29666 | |12;stanford-crypto-pbkdf2;150;153;150;152;152;151;152;150;149;153 20:55:04 INFO - PROCESS | 29666 | |13;stanford-crypto-sha256-iterative;62;60;62;61;65;64;60;61;61;60 20:55:04 INFO - PROCESS | 29666 | __end_tp_report 20:55:04 INFO - PROCESS | 29666 | __start_cc_report 20:55:04 INFO - PROCESS | 29666 | _x_x_mozilla_cycle_collect,1652 20:55:04 INFO - PROCESS | 29666 | __end_cc_report 20:55:04 INFO - PROCESS | 29666 | __startTimestamp1475121304757__endTimestamp 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | ------- Summary: start ------- 20:55:04 INFO - PROCESS | 29666 | Number of tests: 14 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#0] ai-astar Cycles:10 Average:111.50 Median:111.00 stddev:2.88 (2.6%) stddev-sans-first:3.00 20:55:04 INFO - PROCESS | 29666 | Values: 113.0 111.0 119.0 111.0 111.0 111.0 109.0 109.0 111.0 110.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:145.50 stddev:21.98 (15.1%) stddev-sans-first:23.26 20:55:04 INFO - PROCESS | 29666 | Values: 145.0 116.0 146.0 166.0 115.0 152.0 145.0 122.0 179.0 120.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.35 (0.8%) stddev-sans-first:1.33 20:55:04 INFO - PROCESS | 29666 | Values: 162.0 161.0 163.0 159.0 161.0 160.0 161.0 161.0 159.0 159.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#3] audio-fft Cycles:10 Average:94.20 Median:75.00 stddev:41.06 (54.7%) stddev-sans-first:42.89 20:55:04 INFO - PROCESS | 29666 | Values: 74.0 75.0 76.0 168.0 76.0 75.0 73.0 74.0 176.0 75.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#4] audio-oscillator Cycles:10 Average:102.80 Median:83.00 stddev:31.84 (38.4%) stddev-sans-first:32.87 20:55:04 INFO - PROCESS | 29666 | Values: 82.0 83.0 155.0 83.0 83.0 83.0 141.0 83.0 85.0 150.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 20:55:04 INFO - PROCESS | 29666 | Values: 116.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 116.0 117.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 20:55:04 INFO - PROCESS | 29666 | Values: 146.0 145.0 145.0 145.0 146.0 145.0 146.0 145.0 146.0 146.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 20:55:04 INFO - PROCESS | 29666 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 95.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.50 stddev:1.05 (2.1%) stddev-sans-first:1.05 20:55:04 INFO - PROCESS | 29666 | Values: 51.0 51.0 51.0 49.0 50.0 51.0 50.0 50.0 49.0 48.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.50 stddev:0.67 (1.1%) stddev-sans-first:0.53 20:55:04 INFO - PROCESS | 29666 | Values: 59.0 61.0 60.0 60.0 60.0 61.0 60.0 60.0 61.0 61.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#10] stanford-crypto-aes Cycles:10 Average:72.60 Median:72.50 stddev:1.71 (2.4%) stddev-sans-first:1.81 20:55:04 INFO - PROCESS | 29666 | Values: 73.0 73.0 71.0 72.0 77.0 71.0 72.0 72.0 72.0 73.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#11] stanford-crypto-ccm Cycles:10 Average:127.50 Median:127.00 stddev:15.40 (12.1%) stddev-sans-first:16.15 20:55:04 INFO - PROCESS | 29666 | Values: 121.0 119.0 139.0 117.0 115.0 133.0 119.0 115.0 164.0 133.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.20 Median:152.00 stddev:1.40 (0.9%) stddev-sans-first:1.41 20:55:04 INFO - PROCESS | 29666 | Values: 150.0 153.0 150.0 152.0 152.0 151.0 152.0 150.0 149.0 153.0 20:55:04 INFO - PROCESS | 29666 | 20:55:04 INFO - PROCESS | 29666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.50 stddev:1.71 (2.8%) stddev-sans-first:1.81 20:55:04 INFO - PROCESS | 29666 | Values: 62.0 60.0 62.0 61.0 65.0 64.0 60.0 61.0 61.0 60.0 20:55:04 INFO - PROCESS | 29666 | -------- Summary: end -------- 20:55:04 INFO - PROCESS | 29666 | 20:55:05 INFO - TEST-INFO | 29666: exit 0 20:55:05 INFO - TEST-OK | kraken | took 59492ms 20:55:05 INFO - SUITE-END | took 1172s 20:55:05 INFO - Completed test suite (00:19:32) 20:55:05 INFO - javascript benchmark 20:55:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3240.2547414094756, 3241.319322942182, 3259.5110733389974, 3280.7375409967203, 3290.210349516117, 5635.380108188096, 5736.1682556029355, 5742.54059675226, 5746.655200687962, 5754.223179870716, 5651.2653355063785, 5689.061304884699, 5707.343655479621, 5717.857053573663, 5718.142278658203, 5184.53339199472, 5187.844364669066, 5200.427952925194, 5245.580353571705, 5263.184206448007, 5144.17693169093, 5148.124818780798, 5250.422453530108, 5256.027634887539, 5292.973535132338, 5126.871828204287, 5137.948620513785, 5138.897222055557, 5153.793848246066, 5288.497592728685, 5155.252488389181, 5268.0, 5294.073537130987, 5302.019126461579, 5303.787848486056, 5163.225516172567, 5192.506711862367, 5269.341332333458, 5276.577873770089, 5304.230886521472, 5525.336959564837, 5539.861503462445, 5553.888922221573, 5561.360443549003, 5594.804181853616, 5972.552058595623, 5973.8805223895715, 6032.125341825455, 6049.879002419971, 6078.179445774808, 6037.275526936795, 6106.084087386883, 6117.877642447126, 6124.265088189446, 6133.6626485543275, 5612.130119831432, 5658.745356458928, 5679.886402271973, 5720.370759216483, 5740.311162660508, 6258.217722784652, 6274.905876411857, 6304.275008374004, 6305.0, 6328.430441260309, 5869.618474799125, 5880.088586269135, 6060.575759696867, 6073.058675905242, 6083.087536869417, 6063.848403789941, 6128.044828652092, 6138.633639632459, 6139.492138333381, 6143.754249830046, 6086.756529738761, 6091.8477038073515, 6094.268687757498, 6096.0855871619615, 6117.480014198754, 5955.0, 5997.490213331916, 6058.0610005449225, 6088.4215999479975, 6133.080037994337, 4591.862244132682, 4609.032103258353, 4631.096936097496, 4687.20317546018, 4696.178168820443, 4103.815328310233, 4133.23535145999, 4146.896327591834, 4150.667946564328, 4166.375043743463, 5711.485966262973, 5732.942670573324, 5771.480566748929, 5772.480476757112, 5782.074868021181, 6060.757569697251, 6138.664296166039, 6152.046432802923, 6166.568340216231, 6170.7223174957235, 5808.273965754281, 5845.590808643354, 5846.766129354864, 5848.619839710405, 5879.794207202727, 5176.068307704574, 5245.921311180336, 5289.70906600133, 5291.444398338196, 5292.285541451932, 6279.623222606567, 6355.046742988588, 6367.363263673688, 6374.107624932513, 6374.26695929974, 6137.232845894263, 6186.721597528032, 6187.752489900354, 6193.1329613855, 6193.256809182927, 6159.72281247336, 6213.316535181217, 6418.582792118497, 6424.9036264455135, 6427.0680751289865, 5973.701314934149, 6048.971674815204, 6053.818385448443, 6056.152138700673, 6093.573449858467, 6144.508439324753, 6148.16999705052, 6152.16945712341, 6163.784267550794, 6186.226721659793, 5907.232059832194, 5955.434233747787, 5986.88026239464, 6033.366496517802, 6038.305594856472, 5979.671118088373, 6069.180660610761, 6072.0, 6107.694615269308, 6112.358202388685, 5938.465538101592, 5940.8811823764145, 5986.6707331097205, 6000.0, 6003.279606447254, 5877.970610146922, 5917.378675239038, 6005.519558435228, 6060.484858786876, 6064.06007068905, 5870.0607902735355, 5914.645121292736, 5920.230370051866, 5927.170196172453, 5958.165856779968], "value": 5635.880221760914, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.6661136540806, 606.4784285514459, 643.9774607888723, 662.7945336945551, 663.0716996205311, 34069.48895766562, 44479.33281000787, 44984.875378115525, 45041.549584504144, 45071.77464112683, 47506.52493475064, 47883.281750773676, 47911.52088479114, 47925.28112078321, 48101.759491202494, 8650.394472386935, 9348.345615806913, 9506.572204250791, 9512.762180945463, 11030.117590592774, 10852.348859068441, 11234.988851003409, 11236.21346505745, 11237.494312755945, 11243.044341231005, 11393.658190254306, 11440.19918605698, 11446.313221206712, 11457.427128643576, 11459.140564457657, 11334.0, 11362.829557556684, 11383.260088094248, 11385.886141138566, 11401.543938242461, 11131.499082541304, 11179.776404471946, 11186.720331991766, 11202.319652052176, 11211.103111751121, 44972.0, 45010.774946125384, 45353.31970020452, 45519.772401138114, 45736.313955290694, 3809.733318667694, 3826.2347530493735, 3838.4550814330673, 3931.12087649875, 4076.7553946763287, 34716.0, 34780.0, 34881.47677784835, 35233.82383088068, 35466.64533354659, 4655.231886738684, 4701.647376446746, 4754.809807607691, 4755.571998520115, 4834.830780922651, 364053.0, 364908.0, 365831.9999999973, 366222.0, 366639.0, 62465.37534624687, 62911.370886291465, 62957.0, 63058.36941630525, 63086.36913630897, 39283.214335713696, 40238.396424053666, 40368.0, 42290.57709422866, 66054.0, 17394.391196308068, 17546.473605791845, 17553.38563150309, 17561.121943902755, 17561.736573951403, 11155.888441115647, 11368.204225704287, 11458.083353331713, 12472.501099956082, 13014.21914685103, 20363.287284944956, 20824.791752082587, 20857.269995550083, 20882.791172088084, 20899.164033438796, 7256.6734496947765, 7425.814354641069, 7490.700371985168, 7544.811379715441, 7591.696332146763, 1037.2376303416982, 1077.8383242513685, 1098.3958822647512, 1107.363266121967, 1172.1326218598176, 34899.302013959576, 34946.0, 35037.59849606038, 35340.0, 35343.11642208965, 1101.6805126513382, 1122.865256169265, 1162.8313894485252, 1187.6140254417348, 1198.269055875915, 15228.543143705707, 17323.393681221096, 18190.727139092924, 18192.909035454737, 18410.447686569423, 632788.0, 633725.0, 638291.0, 638989.0, 639040.0, 60882.39117608856, 60910.39089609136, 60972.08541871876, 61184.38815611787, 61330.0, 1359.5241665417025, 1419.06341814402, 1446.3708286895444, 1461.693044460675, 1489.932953017117, 32725.50911736278, 34004.65995340015, 34162.0, 34749.0, 35050.0, 52850.47149528455, 53235.4676453246, 53432.0, 53563.464365355845, 53579.19631205457, 515607.0, 515987.0, 518629.0, 520402.0, 522420.0, 26695.0, 27264.318392040357, 28738.568921466613, 29205.41589168247, 29362.412751744418, 48992.0, 49601.25598115959, 49802.25296620626, 50240.0, 50401.74799125981, 42485.57514424816, 42685.359719603606, 43317.3502397458, 44484.77757611191, 44762.55237447714, 42324.153516930106, 42613.5738642622, 42643.0, 43640.0, 44045.77977110094, 42137.26978269236, 43320.13359732851, 43349.56650433582, 43371.0, 43677.0, 36110.0, 37780.62219377771, 38258.0, 39112.413313800884, 39280.0, 28395.43209135847, 28706.0, 29033.564496532992, 29043.709562904947, 29161.85419072891], "value": 21562.361796204896, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.13087991697773, 415.2711990456748, 432.5458268817741, 433.5144638005434, 433.99132017359636, 19403.0, 20736.170553177875, 20791.896040519812, 20924.26765063223, 21152.57694846102, 19913.800861991374, 20701.68947465789, 20707.378778636667, 21341.893290533524, 21458.248961286357, 5807.216025836521, 6079.99680052793, 6090.23872015998, 6090.817275481721, 6093.81718548442, 5771.192033115367, 5781.421857814206, 5816.505597024258, 5823.650580965134, 5949.494292985101, 5789.710514474259, 5798.391168927265, 5802.738876750556, 5804.9419505805035, 5807.854803629901, 5620.887582248352, 5884.382139875315, 5921.526277897758, 5948.524118070544, 5951.732172052268, 5299.284596579451, 5363.195520671891, 5367.141257398797, 5367.597430192712, 5373.274607927919, 24363.147289844946, 24867.0, 25057.373565660822, 25192.0, 25397.873010635012, 2465.235776909164, 2578.1492107604445, 2644.8942042318477, 2712.240572639664, 2904.8402337871407, 7429.7399591014055, 7432.962835185844, 7496.0, 8255.917440825635, 11163.0, 2034.1964923855141, 2041.11211622943, 2052.2611859730487, 2057.351934140741, 2099.9055042523123, 58869.0, 84393.57803211006, 86006.0, 89025.99999999935, 92401.53799231027, 11482.0814334853, 11522.827157592643, 11588.304701717923, 11654.650360489199, 11856.466459009365, 16454.177291135395, 16455.25951332193, 16457.67084658324, 16463.506094817174, 16470.75293870593, 8236.341092712688, 8490.702825401206, 8570.700025499078, 8739.126087391338, 8755.518446485505, 8229.835403291901, 8292.253697167283, 8334.749957501284, 8474.576271186539, 8562.357823163287, 6890.758823441155, 6928.44572434204, 6952.860942781217, 6972.302769723088, 7018.40343570792, 1990.4526255279625, 2035.8982050897396, 2062.5874825035053, 2088.6136064828056, 2094.4973206430345, 998.7353351361895, 1084.9132069434427, 1118.932864028161, 1121.3216004317271, 1182.7693599748138, 20582.27962021322, 20715.27496537614, 20880.47798805042, 20897.20390227357, 21293.893530532558, 1063.3991794636006, 1065.3714308557987, 1072.0994364733506, 1076.6231818863334, 1124.3085502415956, 11783.52865885355, 12066.638000859988, 12118.939405303094, 12322.383880805924, 12356.0, 521278.0, 525441.0, 526601.0, 533741.0, 538318.0, 84383.0, 87725.0, 89237.55381223053, 90050.54974725084, 91385.54307228554, 1030.8711411073616, 1063.2822844579878, 1080.5191689698165, 1087.8912108789216, 1095.835624656308, 17888.28446862111, 18200.90899545547, 18539.81460185381, 18552.420119583596, 18732.250709971995, 40777.0, 44773.776131119135, 45262.0, 45889.54110458853, 46525.3021204689, 57467.0, 58161.4183858156, 58265.417345826, 60708.696456517435, 63541.682291588244, 8741.431806932529, 8751.781205469913, 8816.118388161132, 9066.320025998208, 9130.04134565861, 12394.75210495803, 12921.870781292067, 13145.934270328587, 13166.144200626928, 13448.798268026183, 11422.31466112036, 11532.827007594726, 11536.884631153582, 11652.650420487398, 11770.293782373084, 11189.216754827085, 11201.775964480828, 11327.773444530898, 11329.263597866113, 11339.376334301363, 11726.472308746297, 11750.70623234426, 11834.82247766267, 12004.75990480168, 12165.0, 8743.475391476532, 8767.473951562926, 8868.822623547621, 8985.550722463982, 9337.299702522203, 9280.211182049332, 11058.723531911766, 11784.410779461165, 11803.291802491878, 11910.285382877055], "value": 9658.903812416698, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.2541552976925, 1522.307350155679, 1553.6581951970566, 1723.7759091318126, 1820.3992682414812, 9075.546222688863, 9575.712728618144, 9595.04049595041, 9868.06253405927, 9898.208143348533, 9681.903180968206, 9772.267079968995, 9782.26633002524, 9785.853212201804, 9788.41269523829, 2463.2610216934936, 2488.9004439822384, 2490.489449662824, 2495.189063554343, 2499.2252401755422, 2452.730199678034, 2507.2728908616477, 2509.034021901572, 2512.949741005179, 2513.42191296002, 2424.8787560621986, 2437.0251899240357, 2502.199296225209, 2505.210858579551, 2507.661465702125, 2426.162973774054, 2451.669024681672, 2454.0306578901414, 2456.4718585504115, 2457.7296497385273, 2397.640353946922, 2419.8185136114685, 2423.7939775119135, 2426.939326516851, 2467.8766061696842, 9312.534373281309, 9667.178289845371, 9692.854607180898, 9735.513224338825, 9745.610175592969, 5587.888242235133, 5791.942080579182, 5803.912941305884, 5806.477417032445, 5834.912476312858, 3361.8151001694896, 3387.745919056056, 3429.554157959449, 3455.0153206336327, 3459.930801383958, 1055.963041293551, 1056.9312994655322, 1064.180580952662, 1081.1729027294127, 1083.9349639021605, 14049.157050576896, 14053.648658783612, 14061.859381406157, 14064.578062658034, 14124.929375353058, 4796.37647105874, 4807.78364973577, 4826.444958829709, 4942.233953737176, 4990.426100998359, 10776.94611526953, 10785.78428431427, 10786.406747628798, 10787.730306742395, 10792.190585706103, 5498.532624726863, 5505.064139096363, 5508.91736623951, 5517.420670829585, 5517.724113794295, 4601.470830854428, 4740.265258884879, 4816.590589799836, 5142.537171654569, 5284.630075894727, 4008.939865902014, 4012.598740126022, 4120.958790412117, 4121.732087414309, 4121.91756164881, 1166.1487114406532, 1179.7640471905654, 1182.9704257393462, 1188.4295538141741, 1195.6114262864603, 1019.2304809868501, 1022.320157095538, 1036.5439206749006, 1036.7356324137297, 1043.4209013997154, 6304.0, 6468.191476065492, 6489.967550162219, 6540.41136297726, 6606.834829129214, 460.83179639431364, 1035.1977217656377, 1117.1342209787479, 1152.2683096233952, 1162.8488296521568, 4173.0, 4337.110892267077, 4364.803583838735, 4542.159700455426, 4642.837500687459, 11947.223430476992, 12021.699457513632, 12407.503699852085, 12412.62762117138, 12433.440495177738, 12134.696632584079, 12252.081093917992, 12256.877431225752, 12269.018478521697, 12380.690482738004, 8872.0, 8910.420822646507, 9226.0, 9273.072692730808, 9303.953480232556, 6240.438360547572, 6377.7767778127545, 6391.520635952229, 6427.807165785034, 6502.512311576556, 11038.779224415306, 11256.943715281372, 11300.604478843201, 11438.199326047097, 11532.538698452303, 10039.74900627461, 10406.323588966692, 11624.476898539755, 11693.88306116928, 11738.76522469563, 7829.412794040356, 8130.67477300925, 8158.551279679675, 8175.345972322081, 8182.590870456334, 9182.03588623185, 9241.445513269226, 9264.11990860867, 9267.86098208514, 9471.905280947103, 8222.712205072794, 8260.339172866035, 8353.83292334162, 8505.404621676424, 8527.53098579584, 7994.2405471480115, 8074.434789564674, 8529.317654587741, 8542.572871356531, 8649.30805535543, 7500.249975002456, 7588.8482230356185, 7591.62041897914, 7593.0, 7609.771706848804, 5156.61325400589, 5253.711045892513, 5355.25026496283, 5456.645318054314, 5526.447355264441, 5553.0, 5605.691686957257, 5708.771649134154, 5709.0, 5754.597178197486], "value": 4954.011031296824, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.35684233116893, 361.29006502223126, 361.76485284565024, 371.180881207231, 372.3316646619318, 5397.4062853086125, 5453.427390124039, 5485.0, 5538.639988400756, 5539.778408863645, 5068.518490743373, 5206.349206349207, 5229.398619158788, 5257.526822585966, 5396.703181325026, 4147.25349437102, 4198.622124008839, 4209.074003719192, 4260.850870219543, 4283.336082907154, 4208.684348673846, 4219.578042195772, 4221.345691417835, 4238.173556156536, 4242.0, 4135.635240370685, 4171.833126674945, 4234.597713217239, 4255.829766809324, 4260.297050986584, 4233.597808208216, 4234.513031001444, 4240.512341080769, 4246.214450326668, 4246.44796176495, 3812.80935953204, 3815.3704638734575, 3824.1586850893036, 3837.290101331235, 3858.189780146173, 5204.635675502716, 5258.7370631468275, 5271.258945348436, 5315.946840531623, 5326.334208223972, 4840.806367745286, 4844.32179494871, 4868.269759536098, 4870.756462176877, 4874.147024270774, 5573.442655734394, 5583.469570390806, 5639.295088113986, 5645.717714114319, 5663.660180389149, 4030.6372426481466, 4176.60322269384, 4176.62410383067, 4192.098698779747, 4200.201961627312, 5764.250647415809, 5795.217645617872, 5808.97095514524, 5814.186013958049, 5853.3561308256485, 4266.850660226877, 4282.164977829356, 4285.700000999963, 4308.418363520947, 4331.263685173528, 4090.6727461802984, 4323.589259020388, 4376.6498680105515, 4391.407160033418, 4397.274449715825, 4830.478399304208, 4832.806687732522, 4836.903261934742, 4840.419149702058, 4888.753367891165, 5685.943140568624, 5687.687177205294, 5688.9759843227785, 5723.942760572424, 5745.626534275342, 1453.3968403112801, 1461.744194765933, 1481.888858335629, 1489.53079779869, 1491.7991017231234, 910.4264313482464, 936.2884208001967, 949.5821838391086, 963.3882484622176, 985.0, 4900.191468407673, 4909.656324057353, 4931.3589233399425, 4938.419705694143, 4957.603391728725, 5137.51193636604, 5197.428282888879, 5213.817516386908, 5228.686278823283, 5232.39827419844, 4981.526754958273, 4987.0, 4993.70037797726, 5000.824971125993, 5221.216817477332, 3077.446059709274, 3123.4846250368887, 3131.9217019574694, 3134.6520995971496, 3137.262743255333, 6038.63768173911, 6039.969800150971, 6048.546359023091, 6065.363136870653, 6080.57435979486, 5644.97177514118, 5953.940460595425, 5964.463198312173, 5985.760569577168, 5994.22075130239, 6005.489533389711, 6028.849278767978, 6029.276486821609, 6078.209832721718, 6098.451139397475, 5603.719814009201, 5882.3529411764775, 5894.732632484037, 5959.828120315883, 5960.165576819336, 5752.68360240174, 5842.0, 5848.6783226921225, 5852.209951656472, 5852.4440178183, 6059.454649081604, 6060.2729321466095, 6089.33017368098, 6095.481884039729, 6112.8910525868505, 4036.172584620087, 4242.193983143193, 4269.850555230552, 4287.506936702319, 4323.783810809384, 5591.552675785846, 5735.340435849931, 5804.883902322014, 5853.882922341444, 5870.5010074142465, 5651.57613179005, 5691.3170419549915, 5717.828465146053, 5770.769169233352, 5806.925718742046, 5584.832455026356, 5664.235328230636, 5713.628614140068, 5714.9142762857755, 5719.31280272329, 5606.439356064361, 5676.858078548069, 5678.630888992202, 5679.346875109415, 5685.658860468385, 5714.657055592016, 5720.3707592164, 5724.454397312786, 5748.712564371681, 5768.278965129359, 5395.89384176235, 5526.419725928719, 5583.41374155724, 5594.636348637326, 5618.578606604603], "value": 4331.071349169763, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.5197847388434, 782.3858271257064, 822.2846123872231, 844.0673056272815, 866.8699695045744, 16909.661806763874, 18136.637267254646, 18498.815011849878, 18504.074796260185, 19040.33358832441, 17983.37058202963, 18027.909860450713, 18085.276588936427, 18118.365857195, 18124.45626631203, 733.1349008170357, 886.778305423644, 910.622091831889, 912.7124955638985, 912.885889263842, 849.2017503546678, 898.7348732124007, 905.7509184974119, 911.284641556375, 912.2747415804452, 941.0307383395079, 945.0927109974433, 947.8720372749659, 949.5347279832902, 950.8003319302954, 943.7876477792511, 943.981120377592, 944.9669261575846, 945.0785484152965, 945.4185675809397, 692.882210024297, 693.5942473652868, 693.8542905989748, 693.9444844412435, 694.1115372323434, 16065.678686426207, 16069.0, 16074.196290185444, 16075.276612552347, 16099.034057956444, 14858.925705371405, 14962.775558366633, 16196.109213993226, 16407.753883691756, 16737.581560460963, 13085.0, 13318.933405332911, 13506.459741610422, 14103.153810771284, 14402.639934001629, 4058.9594104059174, 4075.53131389891, 4113.958860411418, 4204.789760511962, 4262.147570485884, 84346.0, 85806.5709671454, 85921.14078859193, 87790.0, 87943.56028219817, 12213.145079844504, 12372.752544949231, 12614.684632884251, 12697.492100316069, 12746.0, 24113.758862411152, 24146.275611731675, 24522.14172503954, 24750.628740568907, 24919.50160996806, 8717.171868672465, 9002.774930626787, 9023.0, 9233.353665243369, 9352.345335826427, 4730.858074257778, 4761.809527618926, 4762.666613337103, 4803.5916947059195, 4887.34020907173, 3872.8063596820048, 3983.402489626579, 3999.2201520703184, 4026.9194616107516, 4045.4943132108488, 698.0297386632585, 700.1178515070945, 700.737223541172, 704.1339152842033, 708.5571517801374, 723.7141329175051, 790.8181118342814, 823.4400607586779, 829.531314807132, 851.4848516647463, 10502.10732087781, 10922.563097476012, 11049.834252486218, 11078.335299882034, 11649.76700465986, 825.9339252859756, 828.9502629842108, 833.0065181022485, 842.9957850210833, 845.3575282785005, 5287.70917599528, 5292.126799078109, 5313.601479889024, 5378.731063446812, 5474.562035037187, 74015.25984740192, 74210.25789742141, 74238.62880685562, 78529.60735196287, 121220.78779212094, 110562.0, 113467.0, 117220.41389793166, 117365.0, 117580.41209793897, 67242.0, 97727.51136244273, 97999.0, 98803.0, 99712.5014374938, 10133.13868321201, 10439.217058720475, 10452.947735261276, 10537.0, 10540.0, 57541.0, 57591.424085758605, 57892.71053644705, 59261.70369148126, 60250.0, 79400.0, 81973.0, 87970.0, 88008.0, 90488.54755726179, 13326.267055312052, 13515.526956556474, 14137.222452765196, 14363.56909292723, 15213.92393038072, 15424.228788560751, 15591.844081559038, 15703.450379236672, 16516.42192523256, 17929.82070179334, 15476.148811815458, 15566.766498502306, 15615.687686246438, 15821.446249381217, 15827.920860396085, 15100.471483498024, 15511.224438778241, 16209.756853646968, 16702.4154154604, 16844.747328789832, 15075.698486030436, 15174.696506070037, 15252.923735381695, 16063.357465701709, 16371.918140409221, 11939.701507462036, 12987.610371688865, 13019.934900325437, 13111.082224244212, 13521.256330901895, 13000.869991300344, 13097.738045238852, 13135.492083698124, 13201.471941122247, 13228.272445015231], "value": 8379.071085674894, "unit": "score"}], "name": "dromaeo_css", "value": 7716.074055565903, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 119.0, 111.0, 111.0, 111.0, 109.0, 109.0, 111.0, 110.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 146.0, 166.0, 115.0, 152.0, 145.0, 122.0, 179.0, 120.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 159.0, 161.0, 160.0, 161.0, 161.0, 159.0, 159.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 168.0, 76.0, 75.0, 73.0, 74.0, 176.0, 75.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 155.0, 83.0, 83.0, 83.0, 141.0, 83.0, 85.0, 150.0], "value": 102.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 116.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, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 49.0, 50.0, 51.0, 50.0, 50.0, 49.0, 48.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0, 61.0, 61.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 71.0, 72.0, 77.0, 71.0, 72.0, 72.0, 72.0, 73.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 119.0, 139.0, 117.0, 115.0, 133.0, 119.0, 115.0, 164.0, 133.0], "value": 127.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 153.0, 150.0, 152.0, 152.0, 151.0, 152.0, 150.0, 149.0, 153.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 62.0, 61.0, 65.0, 64.0, 60.0, 61.0, 61.0, 60.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1489.1999999999998, "alertThreshold": 2.0}]} 20:55:05 INFO - Return code: 0 20:55:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:55:06 INFO - # TBPL SUCCESS # 20:55:06 INFO - Running post-action listener: _resource_record_post_action 20:55:06 INFO - [mozharness: 2016-09-29 03:55:06.051863Z] Finished run-tests step (success) 20:55:06 INFO - Running post-run listener: _resource_record_post_run 20:55:06 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 278937600; Read time: 0; Write time: 158088 20:55:06 INFO - TinderboxPrint: CPU usage
12.9% 20:55:06 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 20:55:06 INFO - TinderboxPrint: I/O write bytes / time
278,937,600 / 158,088 20:55:06 INFO - TinderboxPrint: CPU idle
8,245.8 (87.1%) 20:55:06 INFO - TinderboxPrint: CPU user
1,179.6 (12.5%) 20:55:06 INFO - TinderboxPrint: Swap in / out
0 / 0 20:55:06 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70103040; Read time: 0; Write time: 60844 20:55:06 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 208834560; Read time: 0; Write time: 97244 20:55:06 INFO - Running post-run listener: _upload_blobber_files 20:55:06 INFO - Blob upload gear active. 20:55:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:55:06 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 20:55:06 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'] 20:55:06 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 20:55:07 INFO - (blobuploader) - INFO - Open directory for files ... 20:55:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:55:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:55:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:55:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:55:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:55:08 INFO - (blobuploader) - INFO - Done attempting. 20:55:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:55:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:55:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:55:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:55:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:55:08 INFO - (blobuploader) - INFO - Done attempting. 20:55:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:55:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:55:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:55:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:55:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:55:13 INFO - (blobuploader) - INFO - Done attempting. 20:55:13 INFO - (blobuploader) - INFO - Iteration through files over. 20:55:13 INFO - Return code: 0 20:55:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:55:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:55:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/68397385b922c3a5714fd8d4021da5e267b72b6944fcabddd83ea1666384c25e49e0de25e29d78d9c5d842a740987da75b1416dc9ee4c50e9225b97e22bc983b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0851116cf1e2865b80d70bbe824b707c263bccc41f13b152dd044636e091ab09143b6d6169c75bcce6dd522f51c133dd66cf2f21bfe8055009f66613295bb09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/69118af576a6f6acee6bc49d72c1528df74f167104e2fcbc7bb7c7185722d34a09b1e8addac6480abe8229ae1cd516398a1c4ae0365d61c9728ffe1e7664ab2b"} 20:55:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:55:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:55:13 INFO - Contents: 20:55:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/68397385b922c3a5714fd8d4021da5e267b72b6944fcabddd83ea1666384c25e49e0de25e29d78d9c5d842a740987da75b1416dc9ee4c50e9225b97e22bc983b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0851116cf1e2865b80d70bbe824b707c263bccc41f13b152dd044636e091ab09143b6d6169c75bcce6dd522f51c133dd66cf2f21bfe8055009f66613295bb09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/69118af576a6f6acee6bc49d72c1528df74f167104e2fcbc7bb7c7185722d34a09b1e8addac6480abe8229ae1cd516398a1c4ae0365d61c9728ffe1e7664ab2b"} 20:55:13 INFO - Running post-run listener: copy_logs_to_upload_dir 20:55:13 INFO - Copying logs to upload dir... 20:55:13 INFO - mkdir: /builds/slave/test/build/upload/logs 20:55:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.440382 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-09-28 20:55:12.651970) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 20:55:12.655971) ========= 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=bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972 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/68397385b922c3a5714fd8d4021da5e267b72b6944fcabddd83ea1666384c25e49e0de25e29d78d9c5d842a740987da75b1416dc9ee4c50e9225b97e22bc983b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0851116cf1e2865b80d70bbe824b707c263bccc41f13b152dd044636e091ab09143b6d6169c75bcce6dd522f51c133dd66cf2f21bfe8055009f66613295bb09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/69118af576a6f6acee6bc49d72c1528df74f167104e2fcbc7bb7c7185722d34a09b1e8addac6480abe8229ae1cd516398a1c4ae0365d61c9728ffe1e7664ab2b"} build_url:https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011372 build_url: 'https://queue.taskcluster.net/v1/task/J6JPVM9rRf6RRaq2UCAhvg/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/68397385b922c3a5714fd8d4021da5e267b72b6944fcabddd83ea1666384c25e49e0de25e29d78d9c5d842a740987da75b1416dc9ee4c50e9225b97e22bc983b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0851116cf1e2865b80d70bbe824b707c263bccc41f13b152dd044636e091ab09143b6d6169c75bcce6dd522f51c133dd66cf2f21bfe8055009f66613295bb09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/69118af576a6f6acee6bc49d72c1528df74f167104e2fcbc7bb7c7185722d34a09b1e8addac6480abe8229ae1cd516398a1c4ae0365d61c9728ffe1e7664ab2b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 20:55:12.683936) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 20:55:12.684493) ========= 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=bb9b54edd4a927dc735d112f000001d9-1475119636.194900-144908972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004506 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 20:55:12.765056) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 20:55:12.765392) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 20:55:12.765703) ========= ========= Total master_lag: 0.16 =========