builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1474374906.51 results: success (0) buildid: 20160920024415 builduid: 012fd9caafcb4a72b023bb9b7cc6cd32 revision: 846d7032d2e0da6910cab171d4fef0f47a44450b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:06.514071) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:06.514479) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:06.516558) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:06.528467) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:06.528762) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 05:35:06-- 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% 21.9M=0.001s 2016-09-20 05:35:06 (21.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113675 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:06.657012) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:06.657338) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015004 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:06.707474) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:06.710881) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 846d7032d2e0da6910cab171d4fef0f47a44450b --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 846d7032d2e0da6910cab171d4fef0f47a44450b --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=7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 05:35:06,970 truncating revision to first 12 chars 2016-09-20 05:35:06,970 Setting DEBUG logging. 2016-09-20 05:35:06,970 attempt 1/10 2016-09-20 05:35:06,971 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/846d7032d2e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 05:35:07,265 unpacking tar archive at: mozilla-beta-846d7032d2e0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.665705 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:07.390013) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:07.390344) ========= script_repo_revision: 846d7032d2e0da6910cab171d4fef0f47a44450b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:07.390742) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:07.391007) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 05:35:07.399570) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 8 secs) (at 2016-09-20 05:35:07.399862) ========= /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=7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:35:07 INFO - MultiFileLogger online at 20160920 05:35:07 in /builds/slave/test 05:35:07 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 05:35:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:35:07 INFO - {'append_to_log': False, 05:35:07 INFO - 'base_work_dir': '/builds/slave/test', 05:35:07 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:35:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:35:07 INFO - 'branch': 'Mozilla-Beta', 05:35:07 INFO - 'buildbot_json_path': 'buildprops.json', 05:35:07 INFO - 'config_files': ('talos/linux_config.py',), 05:35:07 INFO - 'default_actions': ('clobber', 05:35:07 INFO - 'read-buildbot-config', 05:35:07 INFO - 'download-and-extract', 05:35:07 INFO - 'populate-webroot', 05:35:07 INFO - 'create-virtualenv', 05:35:07 INFO - 'install', 05:35:07 INFO - 'run-tests'), 05:35:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:35:07 INFO - 'download_minidump_stackwalk': True, 05:35:07 INFO - 'download_symbols': 'ondemand', 05:35:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:35:07 INFO - 'tooltool.py': '/tools/tooltool.py', 05:35:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:35:07 INFO - '/tools/misc-python/virtualenv.py')}, 05:35:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:35:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:35:07 INFO - 'installer_path': 'installer.exe', 05:35:07 INFO - 'log_level': 'info', 05:35:07 INFO - 'log_name': 'talos', 05:35:07 INFO - 'log_to_console': True, 05:35:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:35:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:35:07 INFO - 'opt_config_files': (), 05:35:07 INFO - 'pip_index': False, 05:35:07 INFO - 'sps_profile': False, 05:35:07 INFO - 'sps_profile_interval': 0, 05:35:07 INFO - 'suite': 'dromaeojs', 05:35:07 INFO - 'system_bits': '32', 05:35:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:35:07 INFO - 'title': 'talos-linux64-ix-005', 05:35:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:35:07 INFO - 'use_talos_json': True, 05:35:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:35:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:35:07 INFO - 'work_dir': 'build'} 05:35:07 INFO - [mozharness: 2016-09-20 12:35:07.753586Z] Running clobber step. 05:35:07 INFO - Running pre-action listener: _resource_record_pre_action 05:35:07 INFO - Running main action method: clobber 05:35:07 INFO - rmtree: /builds/slave/test/build 05:35:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:35:08 INFO - Running post-action listener: _resource_record_post_action 05:35:08 INFO - [mozharness: 2016-09-20 12:35:08.728325Z] Finished clobber step (success) 05:35:08 INFO - [mozharness: 2016-09-20 12:35:08.728428Z] Running read-buildbot-config step. 05:35:08 INFO - Running pre-action listener: _resource_record_pre_action 05:35:08 INFO - Running main action method: read_buildbot_config 05:35:08 INFO - Using buildbot properties: 05:35:08 INFO - { 05:35:08 INFO - "project": "", 05:35:08 INFO - "product": "firefox", 05:35:08 INFO - "script_repo_revision": "production", 05:35:08 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:35:08 INFO - "repository": "", 05:35:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 05:35:08 INFO - "buildid": "20160920024415", 05:35:08 INFO - "pgo_build": "True", 05:35:08 INFO - "basedir": "/builds/slave/test", 05:35:08 INFO - "buildnumber": 247, 05:35:08 INFO - "slavename": "talos-linux64-ix-005", 05:35:08 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:35:08 INFO - "platform": "ubuntu64_hw", 05:35:08 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 05:35:08 INFO - "branch": "mozilla-beta", 05:35:08 INFO - "repo_path": "releases/mozilla-beta", 05:35:08 INFO - "revision": "846d7032d2e0da6910cab171d4fef0f47a44450b", 05:35:08 INFO - "stage_platform": "linux64-pgo", 05:35:08 INFO - "builduid": "012fd9caafcb4a72b023bb9b7cc6cd32", 05:35:08 INFO - "slavebuilddir": "test" 05:35:08 INFO - } 05:35:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 05:35:08 INFO - Running post-action listener: _resource_record_post_action 05:35:08 INFO - [mozharness: 2016-09-20 12:35:08.730898Z] Finished read-buildbot-config step (success) 05:35:08 INFO - [mozharness: 2016-09-20 12:35:08.730989Z] Running download-and-extract step. 05:35:08 INFO - Running pre-action listener: _resource_record_pre_action 05:35:08 INFO - Running main action method: download_and_extract 05:35:08 INFO - mkdir: /builds/slave/test/build/tests 05: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')]} 05:35:08 INFO - https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:35:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:35:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:35:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:35:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:35:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:35:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:35:08 INFO - retry: Failed, sleeping 30 seconds before retrying 05:35:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:35:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:35:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:35:38 INFO - retry: Failed, sleeping 60 seconds before retrying 05:36:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:36:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:36:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:36:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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! 05:36:38 INFO - Caught exception: HTTP Error 404: Not Found 05:36:38 INFO - Caught exception: HTTP Error 404: Not Found 05:36:38 INFO - Caught exception: HTTP Error 404: Not Found 05:36:38 INFO - trying https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:36:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:36:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:36:39 INFO - Downloaded 1327 bytes. 05:36:39 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 05:36:39 INFO - Using the following test package requirements: 05:36:39 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 05:36:39 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:36:39 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 05:36:39 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:36:39 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 05:36:39 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:36:39 INFO - u'jsshell-linux-x86_64.zip'], 05:36:39 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:36:39 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 05:36:39 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 05:36:39 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:36:39 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 05:36:39 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:36:39 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 05:36:39 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:36:39 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 05:36:39 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 05:36:39 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 05:36:39 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:36:39 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')]} 05:36:39 INFO - https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:36:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:36:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:36:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:36:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:36:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:36:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:36:39 INFO - retry: Failed, sleeping 30 seconds before retrying 05:37:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:37:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:37:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:37:09 INFO - retry: Failed, sleeping 60 seconds before retrying 05:38:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:38:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:38:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:38:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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! 05:38:09 INFO - Caught exception: HTTP Error 404: Not Found 05:38:09 INFO - Caught exception: HTTP Error 404: Not Found 05:38:09 INFO - Caught exception: HTTP Error 404: Not Found 05:38:09 INFO - trying https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:38:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:38:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:38:10 INFO - Downloaded 21771330 bytes. 05:38:10 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'] 05:38:10 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 05:38:11 INFO - Return code: 0 05:38:11 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 05:38: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')]} 05:38:11 INFO - https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:38:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:38:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:38:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:38:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:38:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:38:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:38:11 INFO - retry: Failed, sleeping 30 seconds before retrying 05:38:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:38:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:38:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:38:41 INFO - retry: Failed, sleeping 60 seconds before retrying 05:39:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:39:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:39:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:39:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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! 05:39:41 INFO - Caught exception: HTTP Error 404: Not Found 05:39:41 INFO - Caught exception: HTTP Error 404: Not Found 05:39:41 INFO - Caught exception: HTTP Error 404: Not Found 05:39:41 INFO - trying https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 05:39:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:39:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:39:42 INFO - Downloaded 21771330 bytes. 05:39:42 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'] 05:39:42 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 05:39:44 INFO - Return code: 0 05:39:44 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')]} 05:39:44 INFO - https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:39:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:39:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:39:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:39:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:39:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:39:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:39:44 INFO - retry: Failed, sleeping 30 seconds before retrying 05:40:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:40:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:40:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:40:14 INFO - retry: Failed, sleeping 60 seconds before retrying 05:41:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:41:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:41:14 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:41:14 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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! 05:41:14 INFO - Caught exception: HTTP Error 404: Not Found 05:41:14 INFO - Caught exception: HTTP Error 404: Not Found 05:41:14 INFO - Caught exception: HTTP Error 404: Not Found 05:41:14 INFO - trying https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 05:41:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:41:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:41:15 INFO - Downloaded 14413740 bytes. 05:41:15 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'] 05:41:15 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 05:41:16 INFO - Return code: 0 05:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:41:16 INFO - https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:41:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:41:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:41:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:41:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:41:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:41:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:41:16 INFO - retry: Failed, sleeping 30 seconds before retrying 05:41:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:41:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:41:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:41:46 INFO - retry: Failed, sleeping 60 seconds before retrying 05:42:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:42:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:42:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:42:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ec8xzhJkSyKx710JIJYnOg/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! 05:42:46 INFO - Caught exception: HTTP Error 404: Not Found 05:42:46 INFO - Caught exception: HTTP Error 404: Not Found 05:42:46 INFO - Caught exception: HTTP Error 404: Not Found 05:42:46 INFO - trying https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:42:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:42:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/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 05:42:48 INFO - Downloaded 56797156 bytes. 05:42:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:42:48 INFO - mkdir: /builds/slave/test/properties 05:42:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:42:48 INFO - Writing to file /builds/slave/test/properties/build_url 05:42:48 INFO - Contents: 05:42:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 05:42:49 INFO - Running post-action listener: _resource_record_post_action 05:42:49 INFO - Running post-action listener: set_extra_try_arguments 05:42:49 INFO - [mozharness: 2016-09-20 12:42:49.657460Z] Finished download-and-extract step (success) 05:42:49 INFO - [mozharness: 2016-09-20 12:42:49.657695Z] Running populate-webroot step. 05:42:49 INFO - Running pre-action listener: _resource_record_pre_action 05:42:49 INFO - Running main action method: populate_webroot 05:42:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:42:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:42:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:42:49 INFO - '--tppagecycles', 05:42:49 INFO - '7'], 05:42:49 INFO - 'tests': ['tsvgm']}}, 05:42:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:42:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:42:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:42:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:42:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:42:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:42:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:49 INFO - 'talos_options': ['--disable-e10s'], 05:42:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:42:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:42:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:49 INFO - 'talos_options': ['--disable-e10s'], 05:42:49 INFO - 'tests': ['damp', 'tps']}, 05:42:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:49 INFO - 'tests': ['damp', 'tps']}, 05:42:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:42:49 INFO - 'tests': ['dromaeo_dom']}, 05:42:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:42:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:42:49 INFO - 'tests': ['basic_compositor_video']}, 05:42:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:42:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:42:49 INFO - 'tests': ['a11yr', 05:42:49 INFO - 'ts_paint', 05:42:49 INFO - 'tpaint', 05:42:49 INFO - 'sessionrestore', 05:42:49 INFO - 'sessionrestore_no_auto_restore', 05:42:49 INFO - 'tabpaint']}, 05:42:49 INFO - 'other-e10s': {'tests': ['a11yr', 05:42:49 INFO - 'ts_paint', 05:42:49 INFO - 'tpaint', 05:42:49 INFO - 'sessionrestore', 05:42:49 INFO - 'sessionrestore_no_auto_restore', 05:42:49 INFO - 'tabpaint']}, 05:42:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:42:49 INFO - 'ts_paint', 05:42:49 INFO - 'tpaint', 05:42:49 INFO - 'sessionrestore', 05:42:49 INFO - 'sessionrestore_no_auto_restore', 05:42:49 INFO - 'tabpaint']}, 05:42:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:42:49 INFO - 'ts_paint', 05:42:49 INFO - 'tpaint', 05:42:49 INFO - 'sessionrestore', 05:42:49 INFO - 'sessionrestore_no_auto_restore', 05:42:49 INFO - 'tabpaint']}, 05:42:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:42:49 INFO - 'tests': ['a11yr', 05:42:49 INFO - 'ts_paint', 05:42:49 INFO - 'tpaint', 05:42:49 INFO - 'sessionrestore', 05:42:49 INFO - 'sessionrestore_no_auto_restore', 05:42:49 INFO - 'tabpaint']}, 05:42:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:42:49 INFO - 'tests': ['a11yr', 05:42:49 INFO - 'ts_paint', 05:42:49 INFO - 'tpaint', 05:42:49 INFO - 'sessionrestore', 05:42:49 INFO - 'sessionrestore_no_auto_restore', 05:42:49 INFO - 'tabpaint']}, 05:42:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:42:49 INFO - 'tests': ['tsvgx', 05:42:49 INFO - 'tsvgr_opacity', 05:42:49 INFO - 'tart', 05:42:49 INFO - 'tscrollx', 05:42:49 INFO - 'cart']}, 05:42:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:42:49 INFO - 'tsvgr_opacity', 05:42:49 INFO - 'tart', 05:42:49 INFO - 'tscrollx', 05:42:49 INFO - 'cart']}, 05:42:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:49 INFO - 'talos_options': ['--disable-e10s'], 05:42:49 INFO - 'tests': ['tp5o']}, 05:42:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:49 INFO - 'tests': ['tp5o']}, 05:42:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:49 INFO - 'talos_options': ['--disable-e10s', 05:42:49 INFO - '--xperf_path', 05:42:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:42:49 INFO - 'tests': ['tp5n']}, 05:42:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:49 INFO - 'talos_options': ['--xperf_path', 05:42:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:42:49 INFO - 'tests': ['tp5n']}}, 05:42:49 INFO - 'talos.zip': {'path': '', 05:42:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:42:49 INFO - Running post-action listener: _resource_record_post_action 05:42:49 INFO - [mozharness: 2016-09-20 12:42:49.677683Z] Finished populate-webroot step (success) 05:42:49 INFO - [mozharness: 2016-09-20 12:42:49.677773Z] Running create-virtualenv step. 05:42:49 INFO - Running pre-action listener: _resource_record_pre_action 05:42:49 INFO - Running main action method: create_virtualenv 05:42:49 INFO - Creating virtualenv /builds/slave/test/build/venv 05:42:49 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 05:42:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:42:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:42:49 INFO - Using real prefix '/usr' 05:42:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:42:50 INFO - Installing distribute.............................................................................................................................................................................................done. 05:42:52 INFO - Installing pip.................done. 05:42:52 INFO - Return code: 0 05:42:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:52 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 0xff4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b33b0>, '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 0x12b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b4740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', '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 05:42:52 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 05:42:52 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 05:42:52 INFO - Using env: {'DISPLAY': ':0', 05:42:52 INFO - 'HOME': '/home/cltbld', 05:42:52 INFO - 'LANG': 'en_US.UTF-8', 05:42:52 INFO - 'LANGUAGE': 'en_US:en', 05:42:52 INFO - 'LOGNAME': 'cltbld', 05:42:52 INFO - 'MAIL': '/var/mail/cltbld', 05:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:52 INFO - 'MOZ_NO_REMOTE': '1', 05:42:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:52 INFO - 'NO_EM_RESTART': '1', 05:42:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:52 INFO - 'PWD': '/builds/slave/test', 05:42:52 INFO - 'SHELL': '/bin/bash', 05:42:52 INFO - 'SHLVL': '1', 05:42:52 INFO - 'TERM': 'linux', 05:42:52 INFO - 'TMOUT': '86400', 05:42:52 INFO - 'USER': 'cltbld', 05:42:52 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', 05:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:52 INFO - '_': '/tools/buildbot/bin/python'} 05:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:52 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 05:42:52 INFO - Cleaning up... 05:42:52 INFO - Return code: 0 05:42:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:52 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 0xff4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b33b0>, '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 0x12b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b4740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', '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 05:42:52 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 05:42:52 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 05:42:52 INFO - Using env: {'DISPLAY': ':0', 05:42:52 INFO - 'HOME': '/home/cltbld', 05:42:52 INFO - 'LANG': 'en_US.UTF-8', 05:42:52 INFO - 'LANGUAGE': 'en_US:en', 05:42:52 INFO - 'LOGNAME': 'cltbld', 05:42:52 INFO - 'MAIL': '/var/mail/cltbld', 05:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:52 INFO - 'MOZ_NO_REMOTE': '1', 05:42:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:52 INFO - 'NO_EM_RESTART': '1', 05:42:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:52 INFO - 'PWD': '/builds/slave/test', 05:42:52 INFO - 'SHELL': '/bin/bash', 05:42:52 INFO - 'SHLVL': '1', 05:42:52 INFO - 'TERM': 'linux', 05:42:52 INFO - 'TMOUT': '86400', 05:42:52 INFO - 'USER': 'cltbld', 05:42:52 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', 05:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:52 INFO - '_': '/tools/buildbot/bin/python'} 05:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:52 INFO - Downloading/unpacking psutil>=3.1.1 05:42:52 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 05:42:52 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 05:42:52 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 05:42:52 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 05:42:52 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 05:42:52 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 05:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:42:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:42:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:42:54 INFO - Installing collected packages: psutil 05:42:54 INFO - Running setup.py install for psutil 05:42:54 INFO - building 'psutil._psutil_linux' extension 05:42:54 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 05:42:54 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 05:42:54 INFO - building 'psutil._psutil_posix' extension 05:42:54 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 05:42:55 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 05:42:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:42:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:42:55 INFO - Successfully installed psutil 05:42:55 INFO - Cleaning up... 05:42:55 INFO - Return code: 0 05:42:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:55 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 0xff4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b33b0>, '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 0x12b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b4740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', '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 05:42:55 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 05:42:55 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 05:42:55 INFO - Using env: {'DISPLAY': ':0', 05:42:55 INFO - 'HOME': '/home/cltbld', 05:42:55 INFO - 'LANG': 'en_US.UTF-8', 05:42:55 INFO - 'LANGUAGE': 'en_US:en', 05:42:55 INFO - 'LOGNAME': 'cltbld', 05:42:55 INFO - 'MAIL': '/var/mail/cltbld', 05:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:55 INFO - 'MOZ_NO_REMOTE': '1', 05:42:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:55 INFO - 'NO_EM_RESTART': '1', 05:42:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:55 INFO - 'PWD': '/builds/slave/test', 05:42:55 INFO - 'SHELL': '/bin/bash', 05:42:55 INFO - 'SHLVL': '1', 05:42:55 INFO - 'TERM': 'linux', 05:42:55 INFO - 'TMOUT': '86400', 05:42:55 INFO - 'USER': 'cltbld', 05:42:55 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', 05:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:55 INFO - '_': '/tools/buildbot/bin/python'} 05:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:42:55 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 05:42:55 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 05:42:55 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 05:42:55 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 05:42:55 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 05:42:55 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 05:42:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:42:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:42:57 INFO - Installing collected packages: mozsystemmonitor 05:42:57 INFO - Running setup.py install for mozsystemmonitor 05:42:57 INFO - Successfully installed mozsystemmonitor 05:42:57 INFO - Cleaning up... 05:42:57 INFO - Return code: 0 05:42:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:42:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xff4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b33b0>, '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 0x12b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b4740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', '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 05:42:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:42:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:42:57 INFO - Using env: {'DISPLAY': ':0', 05:42:57 INFO - 'HOME': '/home/cltbld', 05:42:57 INFO - 'LANG': 'en_US.UTF-8', 05:42:57 INFO - 'LANGUAGE': 'en_US:en', 05:42:57 INFO - 'LOGNAME': 'cltbld', 05:42:57 INFO - 'MAIL': '/var/mail/cltbld', 05:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:57 INFO - 'MOZ_NO_REMOTE': '1', 05:42:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:57 INFO - 'NO_EM_RESTART': '1', 05:42:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:57 INFO - 'PWD': '/builds/slave/test', 05:42:57 INFO - 'SHELL': '/bin/bash', 05:42:57 INFO - 'SHLVL': '1', 05:42:57 INFO - 'TERM': 'linux', 05:42:57 INFO - 'TMOUT': '86400', 05:42:57 INFO - 'USER': 'cltbld', 05:42:57 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', 05:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:57 INFO - '_': '/tools/buildbot/bin/python'} 05:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:57 INFO - Downloading/unpacking jsonschema==2.5.1 05:42:57 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 05:42:57 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 05:42:57 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 05:42:57 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 05:42:57 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 05:42:57 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 05:42:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:42:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:42:59 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 05:42:59 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 05:42:59 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 05:42:59 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 05:42:59 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 05:42:59 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 05:42:59 INFO - Downloading functools32-3.2.3-2.tar.gz 05:42:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:42:59 INFO - warning: no files found matching '*.txt' 05:42:59 INFO - no previously-included directories found matching 'build' 05:42:59 INFO - no previously-included directories found matching 'dist' 05:42:59 INFO - no previously-included directories found matching '.git*' 05:42:59 INFO - Installing collected packages: jsonschema, functools32 05:42:59 INFO - Running setup.py install for functools32 05:43:00 INFO - warning: no files found matching '*.txt' 05:43:00 INFO - no previously-included directories found matching 'build' 05:43:00 INFO - no previously-included directories found matching 'dist' 05:43:00 INFO - no previously-included directories found matching '.git*' 05:43:00 INFO - Successfully installed jsonschema functools32 05:43:00 INFO - Cleaning up... 05:43:00 INFO - Return code: 0 05:43:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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', '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 0xff4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b33b0>, '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 0x12b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b4740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', '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 05:43: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:43: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 functools32==3.2.3-2 05:43:00 INFO - Using env: {'DISPLAY': ':0', 05:43:00 INFO - 'HOME': '/home/cltbld', 05:43:00 INFO - 'LANG': 'en_US.UTF-8', 05:43:00 INFO - 'LANGUAGE': 'en_US:en', 05:43:00 INFO - 'LOGNAME': 'cltbld', 05:43:00 INFO - 'MAIL': '/var/mail/cltbld', 05:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:00 INFO - 'MOZ_NO_REMOTE': '1', 05:43:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:00 INFO - 'NO_EM_RESTART': '1', 05:43:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:00 INFO - 'PWD': '/builds/slave/test', 05:43:00 INFO - 'SHELL': '/bin/bash', 05:43:00 INFO - 'SHLVL': '1', 05:43:00 INFO - 'TERM': 'linux', 05:43:00 INFO - 'TMOUT': '86400', 05:43:00 INFO - 'USER': 'cltbld', 05:43:00 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', 05:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:00 INFO - '_': '/tools/buildbot/bin/python'} 05:43:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:43:00 INFO - Cleaning up... 05:43:00 INFO - Return code: 0 05:43:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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', '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 0xff4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b33b0>, '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 0x12b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b4740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', '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 05:43: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:43: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 blobuploader==1.2.4 05:43:00 INFO - Using env: {'DISPLAY': ':0', 05:43:00 INFO - 'HOME': '/home/cltbld', 05:43:00 INFO - 'LANG': 'en_US.UTF-8', 05:43:00 INFO - 'LANGUAGE': 'en_US:en', 05:43:00 INFO - 'LOGNAME': 'cltbld', 05:43:00 INFO - 'MAIL': '/var/mail/cltbld', 05:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:00 INFO - 'MOZ_NO_REMOTE': '1', 05:43:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:00 INFO - 'NO_EM_RESTART': '1', 05:43:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:00 INFO - 'PWD': '/builds/slave/test', 05:43:00 INFO - 'SHELL': '/bin/bash', 05:43:00 INFO - 'SHLVL': '1', 05:43:00 INFO - 'TERM': 'linux', 05:43:00 INFO - 'TMOUT': '86400', 05:43:00 INFO - 'USER': 'cltbld', 05:43:00 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', 05:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:00 INFO - '_': '/tools/buildbot/bin/python'} 05:43:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:00 INFO - Downloading/unpacking blobuploader==1.2.4 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43:02 INFO - Downloading blobuploader-1.2.4.tar.gz 05:43:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:43:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:43:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:03 INFO - Downloading docopt-0.6.1.tar.gz 05:43:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:43:03 INFO - Installing collected packages: blobuploader, requests, docopt 05:43:03 INFO - Running setup.py install for blobuploader 05:43:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:43:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:43:03 INFO - Running setup.py install for requests 05:43:03 INFO - Running setup.py install for docopt 05:43:03 INFO - Successfully installed blobuploader requests docopt 05:43:03 INFO - Cleaning up... 05:43:03 INFO - Return code: 0 05:43:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:03 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 0xff4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b33b0>, '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 0x12b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b4740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', '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 05:43:03 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 05:43:03 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 05:43:03 INFO - Using env: {'DISPLAY': ':0', 05:43:03 INFO - 'HOME': '/home/cltbld', 05:43:03 INFO - 'LANG': 'en_US.UTF-8', 05:43:03 INFO - 'LANGUAGE': 'en_US:en', 05:43:03 INFO - 'LOGNAME': 'cltbld', 05:43:03 INFO - 'MAIL': '/var/mail/cltbld', 05:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:03 INFO - 'MOZ_NO_REMOTE': '1', 05:43:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:03 INFO - 'NO_EM_RESTART': '1', 05:43:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:03 INFO - 'PWD': '/builds/slave/test', 05:43:03 INFO - 'SHELL': '/bin/bash', 05:43:03 INFO - 'SHLVL': '1', 05:43:03 INFO - 'TERM': 'linux', 05:43:03 INFO - 'TMOUT': '86400', 05:43:03 INFO - 'USER': 'cltbld', 05:43:03 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', 05:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:03 INFO - '_': '/tools/buildbot/bin/python'} 05:43:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:43:04 INFO - Running setup.py (path:/tmp/pip-lx8dET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:43:04 INFO - Running setup.py (path:/tmp/pip-UBJjeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:43:04 INFO - Running setup.py (path:/tmp/pip-cfuXDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:43:04 INFO - Running setup.py (path:/tmp/pip-ijlZ8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:43:04 INFO - Running setup.py (path:/tmp/pip-OvaMqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:43:04 INFO - Running setup.py (path:/tmp/pip-CQYHLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:43:04 INFO - Running setup.py (path:/tmp/pip-Av3L74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:43:04 INFO - Running setup.py (path:/tmp/pip-RCO5IM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:43:04 INFO - Running setup.py (path:/tmp/pip-gA9h5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:43:04 INFO - Running setup.py (path:/tmp/pip-2tdKpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:43:04 INFO - Running setup.py (path:/tmp/pip-F98jY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:43:04 INFO - Running setup.py (path:/tmp/pip-EkQsx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:43:04 INFO - Running setup.py (path:/tmp/pip-MSS9ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:43:04 INFO - Running setup.py (path:/tmp/pip-JJvhSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:05 INFO - Running setup.py (path:/tmp/pip-hfYDix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:43:05 INFO - Running setup.py (path:/tmp/pip-gaZ0fR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:43:05 INFO - Running setup.py (path:/tmp/pip-nymlgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:43:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:43:05 INFO - Running setup.py install for manifestparser 05:43:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:43:05 INFO - Running setup.py install for mozcrash 05:43:05 INFO - Running setup.py install for mozdebug 05:43:05 INFO - Running setup.py install for mozdevice 05:43:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:43:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:43:05 INFO - Running setup.py install for mozfile 05:43:05 INFO - Running setup.py install for mozhttpd 05:43:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:43:05 INFO - Running setup.py install for mozinfo 05:43:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:43:05 INFO - Running setup.py install for mozInstall 05:43:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Running setup.py install for mozleak 05:43:06 INFO - Running setup.py install for mozlog 05:43:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Running setup.py install for moznetwork 05:43:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Running setup.py install for mozprocess 05:43:06 INFO - Running setup.py install for mozprofile 05:43:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Running setup.py install for mozrunner 05:43:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Running setup.py install for mozscreenshot 05:43:06 INFO - Running setup.py install for moztest 05:43:06 INFO - Running setup.py install for mozversion 05:43:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:43:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:43:07 INFO - Cleaning up... 05:43:07 INFO - Return code: 0 05:43:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:07 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 0xff4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b33b0>, '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 0x12b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b4740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', '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 05:43:07 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 05:43:07 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 05:43:07 INFO - Using env: {'DISPLAY': ':0', 05:43:07 INFO - 'HOME': '/home/cltbld', 05:43:07 INFO - 'LANG': 'en_US.UTF-8', 05:43:07 INFO - 'LANGUAGE': 'en_US:en', 05:43:07 INFO - 'LOGNAME': 'cltbld', 05:43:07 INFO - 'MAIL': '/var/mail/cltbld', 05:43:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:07 INFO - 'MOZ_NO_REMOTE': '1', 05:43:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:07 INFO - 'NO_EM_RESTART': '1', 05:43:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:07 INFO - 'PWD': '/builds/slave/test', 05:43:07 INFO - 'SHELL': '/bin/bash', 05:43:07 INFO - 'SHLVL': '1', 05:43:07 INFO - 'TERM': 'linux', 05:43:07 INFO - 'TMOUT': '86400', 05:43:07 INFO - 'USER': 'cltbld', 05:43:07 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', 05:43:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:07 INFO - '_': '/tools/buildbot/bin/python'} 05:43:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:43:07 INFO - Running setup.py (path:/tmp/pip-6m_MgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:43:07 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)) 05:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:43:07 INFO - Running setup.py (path:/tmp/pip-Cjcxvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:43:07 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)) 05:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:43:07 INFO - Running setup.py (path:/tmp/pip-tGN5W6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:43:07 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)) 05:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:43:07 INFO - Running setup.py (path:/tmp/pip-MKjwoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:43:07 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)) 05:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:43:07 INFO - Running setup.py (path:/tmp/pip-lynhja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:43:07 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)) 05:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:43:07 INFO - Running setup.py (path:/tmp/pip-_Z4m2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:43:07 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)) 05:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:43:07 INFO - Running setup.py (path:/tmp/pip-24r0bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:43:07 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)) 05:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:43:07 INFO - Running setup.py (path:/tmp/pip-GU5g6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:43:07 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)) 05:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:43:07 INFO - Running setup.py (path:/tmp/pip-2ybdVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:43:07 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)) 05:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:43:07 INFO - Running setup.py (path:/tmp/pip-du5757-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:43:07 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)) 05:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:43:07 INFO - Running setup.py (path:/tmp/pip-pCmiEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:43:08 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)) 05:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:43:08 INFO - Running setup.py (path:/tmp/pip-ne3ldS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:43:08 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)) 05:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:43:08 INFO - Running setup.py (path:/tmp/pip-bnLfWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:43:08 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)) 05:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:43:08 INFO - Running setup.py (path:/tmp/pip-EB7IFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:43:08 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)) 05:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:08 INFO - Running setup.py (path:/tmp/pip-8AwdN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:08 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)) 05:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:43:08 INFO - Running setup.py (path:/tmp/pip-mMqRoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:43:08 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)) 05:43:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:43:08 INFO - Running setup.py (path:/tmp/pip-0p7UEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:43:08 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)) 05:43:08 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)) 05:43:08 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)) 05:43:08 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)) 05:43:08 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)) 05:43:08 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)) 05:43:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43:10 INFO - Downloading blessings-1.6.tar.gz 05:43:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:43:10 INFO - Installing collected packages: blessings 05:43:10 INFO - Running setup.py install for blessings 05:43:10 INFO - Successfully installed blessings 05:43:10 INFO - Cleaning up... 05:43:10 INFO - Return code: 0 05:43:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:43:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:43:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:43:10 INFO - Reading from file tmpfile_stdout 05:43:10 INFO - Current package versions: 05:43:10 INFO - argparse == 1.2.1 05:43:10 INFO - blessings == 1.6 05:43:10 INFO - blobuploader == 1.2.4 05:43:10 INFO - docopt == 0.6.1 05:43:10 INFO - functools32 == 3.2.3-2 05:43:10 INFO - jsonschema == 2.5.1 05:43:10 INFO - manifestparser == 1.1 05:43:10 INFO - mozInstall == 1.12 05:43:10 INFO - mozcrash == 0.17 05:43:10 INFO - mozdebug == 0.1 05:43:10 INFO - mozdevice == 0.48 05:43:10 INFO - mozfile == 1.2 05:43:10 INFO - mozhttpd == 0.7 05:43:10 INFO - mozinfo == 0.9 05:43:10 INFO - mozleak == 0.1 05:43:10 INFO - mozlog == 3.3 05:43:10 INFO - moznetwork == 0.27 05:43:10 INFO - mozprocess == 0.23 05:43:10 INFO - mozprofile == 0.28 05:43:10 INFO - mozrunner == 6.12 05:43:10 INFO - mozscreenshot == 0.1 05:43:10 INFO - mozsystemmonitor == 0.3 05:43:10 INFO - moztest == 0.7 05:43:10 INFO - mozversion == 1.4 05:43:10 INFO - psutil == 3.1.1 05:43:10 INFO - requests == 1.2.3 05:43:10 INFO - wsgiref == 0.1.2 05:43:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:10 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 0xff4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b33b0>, '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 0x12b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b4740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', '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 05:43:10 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 05:43:10 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 05:43:10 INFO - Using env: {'DISPLAY': ':0', 05:43:10 INFO - 'HOME': '/home/cltbld', 05:43:10 INFO - 'LANG': 'en_US.UTF-8', 05:43:10 INFO - 'LANGUAGE': 'en_US:en', 05:43:10 INFO - 'LOGNAME': 'cltbld', 05:43:10 INFO - 'MAIL': '/var/mail/cltbld', 05:43:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:10 INFO - 'MOZ_NO_REMOTE': '1', 05:43:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:10 INFO - 'NO_EM_RESTART': '1', 05:43:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:10 INFO - 'PWD': '/builds/slave/test', 05:43:10 INFO - 'SHELL': '/bin/bash', 05:43:10 INFO - 'SHLVL': '1', 05:43:10 INFO - 'TERM': 'linux', 05:43:10 INFO - 'TMOUT': '86400', 05:43:10 INFO - 'USER': 'cltbld', 05:43:10 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', 05:43:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:10 INFO - '_': '/tools/buildbot/bin/python'} 05:43:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 INFO - Cleaning up... 05:43:11 INFO - Return code: 0 05:43:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:11 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 0xff4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b33b0>, '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 0x12b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b4740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', '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 05:43:11 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 05:43:11 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 05:43:11 INFO - Using env: {'DISPLAY': ':0', 05:43:11 INFO - 'HOME': '/home/cltbld', 05:43:11 INFO - 'LANG': 'en_US.UTF-8', 05:43:11 INFO - 'LANGUAGE': 'en_US:en', 05:43:11 INFO - 'LOGNAME': 'cltbld', 05:43:11 INFO - 'MAIL': '/var/mail/cltbld', 05:43:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:11 INFO - 'MOZ_NO_REMOTE': '1', 05:43:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:11 INFO - 'NO_EM_RESTART': '1', 05:43:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:11 INFO - 'PWD': '/builds/slave/test', 05:43:11 INFO - 'SHELL': '/bin/bash', 05:43:11 INFO - 'SHLVL': '1', 05:43:11 INFO - 'TERM': 'linux', 05:43:11 INFO - 'TMOUT': '86400', 05:43:11 INFO - 'USER': 'cltbld', 05:43:11 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', 05:43:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:11 INFO - '_': '/tools/buildbot/bin/python'} 05:43:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:43:11 INFO - Cleaning up... 05:43:11 INFO - Return code: 0 05:43:11 INFO - Running post-action listener: _resource_record_post_action 05:43:11 INFO - Running post-action listener: _start_resource_monitoring 05:43:11 INFO - Starting resource monitoring. 05:43:11 INFO - [mozharness: 2016-09-20 12:43:11.285193Z] Finished create-virtualenv step (success) 05:43:11 INFO - [mozharness: 2016-09-20 12:43:11.285671Z] Running install step. 05:43:11 INFO - Running pre-action listener: _resource_record_pre_action 05:43:11 INFO - Running main action method: install 05:43:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:43:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:43:11 INFO - Reading from file tmpfile_stdout 05:43:11 INFO - Detecting whether we're running mozinstall >=1.0... 05:43:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:43:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:43:11 INFO - Reading from file tmpfile_stdout 05:43:11 INFO - Output received: 05:43:11 INFO - Usage: mozinstall [options] installer 05:43:11 INFO - Options: 05:43:11 INFO - -h, --help show this help message and exit 05:43:11 INFO - -d DEST, --destination=DEST 05:43:11 INFO - Directory to install application into. [default: 05:43:11 INFO - "/builds/slave/test"] 05:43:11 INFO - --app=APP Application being installed. [default: firefox] 05:43:11 INFO - mkdir: /builds/slave/test/build/application 05:43:11 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'] 05:43:11 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 05:43:25 INFO - Reading from file tmpfile_stdout 05:43:25 INFO - Output received: 05:43:25 INFO - /builds/slave/test/build/application/firefox/firefox 05:43:25 INFO - Running post-action listener: _resource_record_post_action 05:43:25 INFO - [mozharness: 2016-09-20 12:43:25.655512Z] Finished install step (success) 05:43:25 INFO - [mozharness: 2016-09-20 12:43:25.655862Z] Running run-tests step. 05:43:25 INFO - Running pre-action listener: _resource_record_pre_action 05:43:25 INFO - Running main action method: run_tests 05:43:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:43:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:43:25 INFO - Python 2.7.3 05:43:25 INFO - Return code: 0 05:43:25 INFO - grabbing minidump binary from tooltool 05:43: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')]} 05:43:25 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 0x12b33b0>, '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 0x12b42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b4740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:43:25 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 05:43:25 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 05:43:25 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 05:43:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:43:25 INFO - Return code: 0 05:43:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:43:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:43:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:43:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:43:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:43:25 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/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 05:43:25 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/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 05:43:25 INFO - Using env: {'DISPLAY': ':0', 05:43:25 INFO - 'HOME': '/home/cltbld', 05:43:25 INFO - 'LANG': 'en_US.UTF-8', 05:43:25 INFO - 'LANGUAGE': 'en_US:en', 05:43:25 INFO - 'LOGNAME': 'cltbld', 05:43:25 INFO - 'MAIL': '/var/mail/cltbld', 05:43:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:43:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:25 INFO - 'MOZ_NO_REMOTE': '1', 05:43:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:43:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:25 INFO - 'NO_EM_RESTART': '1', 05:43:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:25 INFO - 'PWD': '/builds/slave/test', 05:43:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:43:25 INFO - 'SHELL': '/bin/bash', 05:43:25 INFO - 'SHLVL': '1', 05:43:25 INFO - 'TERM': 'linux', 05:43:25 INFO - 'TMOUT': '86400', 05:43:25 INFO - 'USER': 'cltbld', 05:43:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812', 05:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:25 INFO - '_': '/tools/buildbot/bin/python'} 05:43:25 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/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 05:43:25 INFO - mozversion application_buildid: 20160920024415 05:43:25 INFO - mozversion application_changeset: 846d7032d2e0da6910cab171d4fef0f47a44450b 05:43:25 INFO - mozversion application_display_name: Firefox 05:43:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:43:25 INFO - mozversion application_name: Firefox 05:43:25 INFO - mozversion application_remotingname: firefox 05:43:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:43:25 INFO - mozversion application_vendor: Mozilla 05:43:25 INFO - mozversion application_version: 50.0 05:43:25 INFO - mozversion platform_buildid: 20160920024415 05:43:25 INFO - mozversion platform_changeset: 846d7032d2e0da6910cab171d4fef0f47a44450b 05:43:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:43:25 INFO - mozversion platform_version: 50.0 05:43:25 INFO - using testdate: 1474375405 05:43:25 INFO - actual date: 1474375405 05:43:25 INFO - starting webserver on 'localhost:43581' 05:43:25 INFO - SUITE-START | Running 2 tests 05:43:25 INFO - TEST-START | dromaeo_css 05:43:25 INFO - operating with platform_type : linux_ 05:43:25 INFO - Initialising browser for dromaeo_css test... 05:43:25 INFO - TEST-INFO | started process 29979 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdq_u5n/profile http://localhost:43581/getInfo.html) 05:43:29 INFO - PROCESS | 29979 | __metrics Screen width/height:1600/1200 05:43:29 INFO - PROCESS | 29979 | colorDepth:24 05:43:29 INFO - PROCESS | 29979 | Browser inner width/height: 1024/697 05:43:29 INFO - PROCESS | 29979 | __metrics 05:43:30 INFO - PROCESS | 29979 | 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" 05:43:30 INFO - PROCESS | 29979 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:43:31 INFO - TEST-INFO | 29979: exit 0 05:43:31 INFO - Browser initialized. 05:43:31 INFO - Running cycle 1/1 for dromaeo_css test... 05:43:31 INFO - TEST-INFO | started process 30069 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdq_u5n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:43 INFO - PROCESS | 30069 | RSS: Main: 143609856 05:43:43 INFO - PROCESS | 30069 | 05:44:34 INFO - PROCESS | 30069 | 1474375474544 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] 05:46:42 INFO - PROCESS | 30069 | Cycle 1(1): loaded http://localhost:43581/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43581/tests/dromaeo/cssquery-ext.html) 05:46:43 INFO - PROCESS | 30069 | RSS: Main: 217645056 05:46:43 INFO - PROCESS | 30069 | 05:49:45 INFO - PROCESS | 30069 | Cycle 1(1): loaded http://localhost:43581/tests/dromaeo/cssquery-ext.html (next: http://localhost:43581/tests/dromaeo/cssquery-jquery.html) 05:49:46 INFO - PROCESS | 30069 | RSS: Main: 234336256 05:49:46 INFO - PROCESS | 30069 | 05:52:48 INFO - PROCESS | 30069 | Cycle 1(1): loaded http://localhost:43581/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43581/tests/dromaeo/cssquery-mootools.html) 05:52:48 INFO - PROCESS | 30069 | RSS: Main: 177172480 05:52:48 INFO - PROCESS | 30069 | 05:55:50 INFO - PROCESS | 30069 | Cycle 1(1): loaded http://localhost:43581/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43581/tests/dromaeo/cssquery-prototype.html) 05:55:51 INFO - PROCESS | 30069 | RSS: Main: 175841280 05:55:51 INFO - PROCESS | 30069 | 05:58:53 INFO - PROCESS | 30069 | Cycle 1(1): loaded http://localhost:43581/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43581/tests/dromaeo/cssquery-yui.html) 05:58:54 INFO - PROCESS | 30069 | RSS: Main: 172236800 05:58:54 INFO - PROCESS | 30069 | 06:01:56 INFO - PROCESS | 30069 | Cycle 1(1): loaded http://localhost:43581/tests/dromaeo/cssquery-yui.html (next: http://localhost:43581/tests/dromaeo/cssquery-dojo.html) 06:01:57 INFO - PROCESS | 30069 | RSS: Main: 258748416 06:01:57 INFO - PROCESS | 30069 | 06:01:57 INFO - PROCESS | 30069 | __start_tp_report 06:01:57 INFO - PROCESS | 30069 | _x_x_mozilla_page_load 06:01:57 INFO - PROCESS | 30069 | _x_x_mozilla_page_load_details 06:01:57 INFO - PROCESS | 30069 | |i|pagename|runs| 06:01:57 INFO - PROCESS | 30069 | |0;dojo.html;3209.9679003209976;3228;3239.0768630940174;3272.280098378359;3272.9345413091737;5516.917246241304;5584.357798853133;5610.074337734279;5614.466625670555;5625.296837895263;5555.527780138693;5600.467955544227;5624.803131890385;5640.971795141028;5647.802326918559;4997.075541024904;5006.1739812931;5066.6706664066805;5106.540411362977;5166.121759300928;5009.824656137036;5045;5050.242463630448;5070.138076527;5076.543111119999;5166.63833531651;5168.173092305232;5172.612054095939;5180.948190518103;5181.352330958612;5163.535281824654;5172.715500647463;5177.4822517747925;5180.663256888328;5209.348831396075;5015.899682006376;5057.924131138036;5069.138246498067;5078.771455284485;5082.567981721558;5387.73061346935;5433.9675461662155;5435.537979271767;5527.419620939824;5662.320521537401;5902.911456328158;5906.911396329058;5944.673042982634;5957.553183511211;5964.403559644044;5930.733117009745;5938.732757025944;5948.167256584082;5961.970190149022;5981.700914954278;5480.095784195603;5497.47773961481;5498.395176530578;5504.284443022421;5522.420145884705;6100.847478813065;6101.816945491642;6106.908396374058;6110.175126357884;6115.4190351916495;5729.255196824386;5870.295564532284;5870.5010074144175;5923.466887980103;5927.525797936155;6076.908846367308;6082.513398928075;6084.300305464928;6098.26820781509;6100.69496525172;6066.545009124334;6072.666003369857;6074.331823499411;6076.361981991916;6081.847953801191;5775.220345253337;5816.011278082736;5867.3839246879315;5897.174395584621;5928.170056192136;4625.861224163281;4627.838025669085;4632.884177895579;4633.536646335339;4660.650451216173;4130.938035929464;4136.400221967798;4151.481064866892;4152.626263636287;4175.68682348825;5628.943710562924;5658.74535645897;5699.658020518782;5706.3722990471015;5798.333191682926;6091.573589848668;6091.847703807441;6095.756169753249;6100.298465676416;6100.572959892854;5749.080147176432;5785.074388097884;5796.913046304308;5801.883962320814;5805.970970145122;5069.062223488666;5111.386633603991;5125.077486052472;5146.716930568779;5213.817516386908;6162.445379915829;6164.4760195383;6164.537659675542;6167.136600875791;6172.321044685081;5920.79277225295;5994.160817485643;5995.250593675791;5996.2504686914135;5997.250343707036;6151.723172457339;6239.812805615838;6246.156768836149;6290.49676025926;6298.559100863079;6018.518518518596;6036.154938308552;6037.154798328325;6042.305134909366;6082.178905847831;6081.939180608314;6137.785177518944;6144.662043587646;6150.200473938359;6160.353162918008;5672.376038635664;5861.062230043172;5905.940940590539;5938.61399009063;6034.125051867542;5947.524198064058;5958.433948774859;5989.850253743692;5990.341062483036;5996.760129594767;5875.588708790515;5907.527397808251;5908.852278693067;5925.140854576149;5930.733117009831;5746.166805813217;5776.653400795965;5818.272715910512;5824.097264923944;5887.116932460165;5721.485066344049;5729.08334666468;5732.283464566929;5734.283214598176;5773.91339129905 06:01:57 INFO - PROCESS | 30069 | |1;ext.html;605.2102006881018;629.5844742469969;654.0810161722782;660.5145218264572;666.7266420767486;33252.33495330095;44918.32622510665;45744.77127614361;45846.31230531545;45858.312125318145;47151;47405;47536.524634753645;47601.285980710316;47753.52246477534;8509.064002959669;9139.314551408654;9288.21050210733;9301.534923253845;10911.508982095786;10751.354918704863;11204.831927521094;11216.831747523795;11217.887821121807;11231.26996745213;11235.764065952739;11267.211295209298;11294.491747871325;11321.433928303593;11325.886741132606;11112.722181945435;11223.494942727597;11251.887481125248;11257.605983790529;11262.324260544312;10812.243142979994;10818.404987725671;10856.620018299322;10871.728206794814;10876.728081797939;45136.77431612821;45417.09165816698;45455.318170227474;45603.77198113988;45612.315815262795;3624.963750362489;3673.0266479382985;3765.6799172070405;3975.344068228768;4048.1903619276263;34612.82693586516;34911.82544087289;35188.82405587981;35427.29145417077;35842.82078589616;4603.723776573416;4625.907481850378;4652.534746525321;4678.1813182692895;4760.523947605247;360481;361128.9999999974;361897;362899;363713;54110;54313;54410.72794636081;61649;61701.38298617046;40679.38980915289;41514.792426037675;41570;41608.791956040026;41725;18130.63738725218;18347.90826045888;18396.908015459838;18438.446846594623;18453.1696073677;12463.626091217278;13381.732365352833;13749.587512374444;13763.174209547458;13779.862201377859;20430.18279268842;20530.486737831376;20747.68878466824;20760.377188684364;20795.1681932721;7200.315969982843;7541.396688264926;7542.434317426215;7626.8093297668;7660.463767536219;970.1705042188968;1050.1913526584487;1171.5196769324536;1212.078820096733;1215.617080619602;33780;34351.31297374039;34617.65382346144;34623.65376346204;34649.653503464644;1111.2943281016612;1198.5026214121042;1201.3092471828559;1215.0620156957748;1269.2004037456343;17984.910075449538;18161;18179;18390.26438942454;18520.536986575444;600148;605899;605940;606336;608447;62479.6876015617;63172.684136579024;63228.367716322246;63267.68366158232;63304.36695633077;1325.9469621215237;1390.2492653966744;1422.7723564229673;1450.8973180382984;1468.77968304753;34174.14564635904;35679.64320356763;35770.463443047855;35867.64132358643;36021;54431.72784136054;55315.72342138264;55473.44526554683;55555;55561.444385557246;438365;439257;440659;441808;441837;29988.70011299859;30872;30986.69013309928;31103.68896311008;31136.53295200619;51585.7420712894;52859.471405287;53643.7317813424;53662;53760.73119634377;42243.1551368977;42447.5755242456;42791;43807.34288985604;44463.55536444594;42895.7855210722;43931;44022.339664905696;44550.55449445594;44792;42269.36595951002;42634;43333.56666433295;43385;43417.78291108524;36169.63830361663;38166.61919823397;38980.63567775114;39154.41268381033;39168.6083139165;28456.430871382872;28606.856965715036;28646.140615781558;28692.282692932844;28740 06:01:57 INFO - PROCESS | 30069 | |2;jquery.html;404.2864344432078;406.9866033576392;422.3074158380256;426.7866066966517;433.15102399297376;19429;20682.276120335773;20702.27542036029;20884.89557552214;20974.37076887694;19604.901975490102;19760.802391976074;20171.596568068668;20792;20927.790722092774;5854.443827836349;6098.237720284965;6099.085137229406;6112.816615501542;6115.052166914135;5985.4912332451795;6022;6040.552729012303;6072.149899014142;6073.210482637251;5864.794732184374;5988.550858685594;5997.160397544348;6000.579959402844;6000.8499787505225;5812.041013232812;5870.501007414375;5895;5907.202527658754;5926.051831706906;5274.05067087924;5325.707086110262;5328.813491527778;5332.733363331818;5334.866628334284;23703.170389036473;24043.39891502709;24242.87878560596;24341.756582434304;24667.136650217337;2422.2248880358293;2494.4387512809653;2536.746325367467;2550.3241640965157;2568.589025755889;14562.635934101627;15026.69946601062;15540.456084037005;15598.688026239524;15813.762793558106;1943.5626983928503;1994.3602924662548;2038.7655419626785;2091.5712278982774;2129.4782778219437;82436.17563824345;83938.58030709869;84756;85616;86084.56957715235;10782.622608208674;11296.152788540892;11397.772044559062;11449.198556100993;11585;16078.517644470685;16090.919545402197;16095;16201.756973645404;16205.756913646304;8158.836823263501;8584.914150858536;8639.6976105836;8695.217430431292;8806.295496360404;8372.665093396196;8382.287505562095;8407.873881891777;8515.02077261123;8588.65645374178;6827.863442731117;6925.272846351161;6946.305369463013;7016.859662806715;7024.700430420388;2031.502281940934;2059.4542446251753;2078.7089807426973;2081.8230450411584;2130.1585873579847;959.9184069354183;1019.3323373190572;1043.436544266103;1093.3494570730402;1130.1693255457233;20661.793382065985;20959.8952005239;20973.68539471909;21203.36389908305;21256.681149782766;1010.4788552561406;1047.9423631700329;1064.6752740414247;1087.396494945297;1131.1798945764256;11630.6510804676;11908.52365905354;11953.40232988347;12028.819567706492;12142.271463712206;474282;476833;480589;484977;486904;82897;84681;85526.57236713776;86272.56863715641;86913;1059.1791361694595;1083.6315652678127;1106.25880659958;1113.1929351220301;1125.3472985668293;17806.643867122842;18350;18568.674961474193;18726.063696815378;18803;42245.36631950585;42330;42533.78733106315;43266.783666081465;44043.33934990913;54483.455165447835;55428.72285638546;55428.72285638546;55795.72102139625;55911.16133258086;8172;8252.850768468023;8574.485530868167;8686.305095592465;8990.775230619025;13746.725065498835;13895.23576203278;14040.438382464587;14045.227512486912;14444;11411.942940285246;11492.425378730863;11506.424678766196;11747.47136378848;11911.82132268034;11837.526498940291;12085.093617978862;12094.637160885188;12282.140250182401;12638.810417843555;11480.311181329147;11692;11707.648770536898;11713.238639488405;11735.295882247092;9238.168564829197;9296.256299496159;9418.905810941802;9444.261164262287;9465.384749991228;11819.763604727685;11825.290482570728;12304.323262220305;12384.938075309567;12606.49574017029 06:01:57 INFO - PROCESS | 30069 | |3;mootools.html;1351.9662008449793;1508.2006536535632;1554.3005647458644;1721.7847769028872;1873.1102726205054;9233.445993240402;9594.760130996729;9596.040395960401;9871.210303175747;9913.900860991387;9724;9838.901610983907;9842.655507057254;9875.456849873253;9895.307328486992;2486.4902694947536;2491.1032028469735;2496.400863792686;2506.774390304872;2517.5342561626067;2395.4370722880108;2467.222824810179;2482.615194644824;2482.6276058591175;2489.7634724701124;2328.580855446019;2380.6310021946624;2415.4444477770126;2416.6979127609047;2420.987895060522;2364.2788949370433;2394.150076722756;2398.3884109552127;2401;2409.5301416223756;2310.5378924215056;2316.1082983051647;2321.3268152236;2322.512272422793;2335.1126571902737;9557.092076252746;9649.565769540388;9740.659076932345;9763.658271960518;9775.90224097764;5564.944350556483;5806.042003069489;5811.651300921958;5814.447627475383;5815.970920145414;3496.7027802636553;3530.5939816921127;3543.450765131383;3550.6626870447267;3623.365910965597;1206.6561030106466;1211.3216598704666;1212.5937810833382;1226.2642414551199;1251.2054845173288;14091.71816563663;14151.150930944177;14158.221297828615;14177.291135443187;14217.43130274798;4783.832565860178;4874.95125048745;4881.316615673737;4973.403191617029;5031.169856973632;10983.725406864893;10984.121270298518;10997.505112269968;11000.834987475195;11004.559817607205;5695.686737229409;5696.601237913389;5700.543956483471;5703.08750599902;5706.400827913092;4642.442906851199;4713.6464765142755;5061.1902095665255;5071.8985620287385;5318.175682769177;4131.5248746393945;4135.524414692289;4162.299156024668;4170.311898536768;4198.181354635817;1267.0497127154633;1272.9490820367266;1287.2791236907362;1302.3293004102895;1310.4758096761182;1074.409075008742;1082.4533610526635;1096.9341839489657;1112.593903225317;1116.944152792357;6576.210854697467;6703.932960670429;6912.135983002125;6972.51192416526;7065.540739851893;486.58640155867937;509.30479894943875;513.9511746384162;514.4289838279507;517.901598696254;4708.364370809965;4801.447833499123;4822.614190864722;4823.686460380064;4955.430125535538;11950.641480755592;12561;12587.685307867376;12592.81110783339;12610;12172.330521821208;12233.877661223452;12412.503499859904;12469.563565275172;12543.811842822364;8908.643654253887;8917.464952102764;8971.147740964598;9073.31950103745;9074.909250907538;6537.836554085996;6666.666666666744;6845.897311540231;6913.239543650094;6922.06552115458;11171.329720216489;11188.66434006981;11342.546298148312;11347.205695601559;11365.886341136813;9673.177779888789;10033.598656053677;11192.496337664987;11528.711782205512;11585.246958947642;7871.094824095302;7934.166912474107;7939.682412703658;7940.722074727357;7942.245486678757;9368.110029547182;9402.95298523503;9637.951810240904;9639.132478077006;9646.710598682052;8591.355648326526;8614.741557753277;8684.956575217084;8750.081241469554;8882.955585222033;8394.412391132562;8433.746987590383;8554.059053504234;8618.52598107085;8694.130586941255;7445;7525.6989720410575;7534.472586918863;7563.621818909142;7646;6030.246219222598;6078.7264573093435;6080.817575472743;6115.449609534985;6134.6625935573975;5959.314678811992;6070.3929607038945;6128.509719222362;6182.443580077815;6262.467690246198 06:01:57 INFO - PROCESS | 30069 | |4;prototype.html;342.6761710183877;348.5834427858709;349.3815945775978;352.3305719133647;352.4554563199856;5448.782048718052;5484.424135465773;5498.367687715914;5521.641093328931;5561.110222364423;5387.434319396466;5395.514403703666;5433.293671822667;5460.754266058027;5496.780128794853;3812.9618703812885;4071.552129265786;4182.916341673164;4187.748735075906;4324.84863029794;4285.914281714379;4303.139372125572;4309.525952145262;4310.849120280791;4316.503602085753;4299.3550967354995;4309.332053531708;4310.590493903074;4318.870433886988;4344.45694288214;4090.325096359097;4226.731980405897;4326.329418940054;4333.2200203963275;4339.132173565268;3767.773933563995;3774.320622287987;3793.2603142387247;3794.924101517954;3826.559945606263;5266.1574148136115;5315.973420132913;5332.626716129873;5341.679499230058;5347.438518955492;4794.712317260941;4866.902661946777;4899.730514821684;4906.460289368167;4915.238138088602;5587.860303492445;5620.622947377878;5656.0101982152955;5672.34768001681;5697.6011679182475;4140.958590414088;4155.439015732868;4177.12280421112;4201.886500077482;4209.6000879916355;5565.66606003637;5791.768329266824;5965.313988891289;5971.865345584369;6005.159277701126;4217.683673724421;4228.9788551057045;4240.978795106066;4255.319148936217;4260.595243451867;4326.351047342924;4328.826846926151;4331.956680433218;4332.090261045216;4335.371371151228;4749.715017098985;4866.586340161055;4880.804767809319;4908.680935739166;4927.457979622239;5640.802571909963;5661.678711728172;5662;5672.688002159838;5677.886442271131;1465.2234315812636;1473.6516087779776;1475.7933889255512;1491.5749011531877;1501.6396064944554;931.2782593490099;969;970.8203982263304;973.4743238651049;988.2193067476757;4951.282064100686;4961.404631444249;4981.526754958273;4988.975055124701;5009.44896061433;5165.251038599382;5168.328117344721;5171.870703232374;5193.610479214074;5213.921791173136;4641.396618439581;4946.707023484909;4970.254461830682;4987.301777751118;4988.55103040728;2737.1533605050527;2737.1660987138666;2740.9588856167175;2749.505089083984;2776.4169524400104;5273.630845840831;5333.733313334318;5343.65266257692;5415.214793854948;5426.972865135649;5043.445221025684;5255.061780188439;5260.316158899319;5271.815486457956;5300.973495132577;5342.679439233659;5357.7589008494715;5381.730913454312;5397.217403476474;5399.757010934517;5142.742862856768;5145.408278047923;5211.723127833553;5226.56964511504;5233.493559315192;4788.3327941066955;5075.416327122428;5120.283160357479;5138.3833939927445;5175.689458632494;5393.379761327497;5846.912296315644;5976.850578735566;6089.48239399656;6117;4235.97882010588;4252.383404406281;4257.233697934402;4260.785676082425;4289.592488713567;5695.829125126253;5934.376890426443;5952.375000624872;5960.344362120077;5964.612300200473;5698.857528561819;5703.62926409782;5712.942870571241;5715.371309155907;5723.141528770718;5705.058665320175;5726.656400615976;5726.971365143314;5728.684922329312;5764.308283006066;5444.210589464426;5623.409541998195;5654.38849927785;5659.377468478382;5660.773569057192;5695.180885461723;5722.4277572242445;5726.914096288642;5733.365990692632;5744.023516002206;5596.4123767003875;5598.300212473441;5624.35319938196;5665.376808550974;5667.688277144796 06:01:57 INFO - PROCESS | 30069 | |5;yui.html;678.4368973751787;716.3946465236876;729.3326606155368;807.3339494916694;863.499170481121;15361.231938403078;16291.83708162918;16297;16298.429554965549;16313.429029983952;13668.384922678479;13717.245551494663;15739;16610.169491525405;16616;844.6368061733467;870.995645021774;871.1375738019346;874.5321253129576;875.1991927386434;856.7172832965138;858.935579831512;859.0464584311409;859.0979471554842;859.6432480520576;915.6062892956027;916.0198587511366;916.5829547555846;916.7799728065283;916.9541522923861;906.2251774732574;914.8033172867865;916.5417291354323;916.734147097341;916.8716379706813;668.7124536449325;670.9161354830646;671.6977360187886;672.0658284983876;672.0758956434902;15208.46770363032;15359.846401535953;15362.769558456632;15369;15384.461543846024;12241.755164896653;13111.082224244306;13216.73566528664;13241.470341186341;13786.931065344608;12533.310667913262;12589.24464532131;12770.169938953939;12992.220466772023;13046.608601741962;4092.1815636872743;4147.212029714375;4147.6474499667565;4150.066235097109;4287.506936702288;55236.72381638066;58881.41118588802;78194.60902695508;78283.60858195729;87666;12135.87864121365;12301.630951071304;12340.259584424963;12486.250824950532;12504.562340318227;19224.038798060043;19356.516087097654;19362.515937101685;19408.22367105328;19444.125014374386;9083.18251357381;9200.493972831424;9223.492707900996;9353;9449.811003779885;4991.276264941564;5153.123968925292;5161.200013997836;5228.895422091537;5343;3658.8902332930056;3801.068738159169;3807.4669546263544;3809.3714537101623;3809.771413715186;655.4232275597444;660.9372109649576;661.9338066193342;665.6139439125295;692.9792106236821;613.7759717703095;674.639068098565;723.392350425645;741.3772431157853;745.9589722565311;9804.950975245221;9850.965648606936;10026.598936042623;10139.93530679268;10376.221783366278;674.0967104080555;721.3904250907974;788.6766425765409;791.5527726834321;799.3645052183529;4787.736674482937;4894.412670479565;5069.188929771219;5074.746262686851;5150.742462876841;105617;115178;122574.38712806378;127921.36039319744;132606;69324.6533767338;84985.57507212424;88712.5564372187;89403;104712;86048;87154.56422717973;89940.30179094638;89991.55004224936;92478.53760731153;9761.804763904824;9870.506474676382;9895.703128906145;9930.553125109243;10196.796064078826;56427.43572564386;56584;57088;57110.428895712175;57195.09582666952;73257.6337118311;75876;79366.60316698611;79915;80350.59824700838;11712.590059348222;11821.70445738829;11840.28958262507;12148.21036632616;12150.75698486043;13623.86376136226;14328.211948342941;14613.415463381345;14706.338214780575;14779.113253204841;13476.663083422993;13986;14019.50931717385;14542.636434089232;15056.62358441004;13758.243296618783;14243.21662308583;14310.49913253031;14398.496052638526;15122.697546048797;13697.520586779416;13824.861751382357;14415.639609009439;14615.780763288347;15373.601077872332;9408.858867116862;9607.711768646952;9664.275179361717;9784.85322720174;9811.705648830546;9768.013412735281;9848.507574621384;9858.457784821903;9943;9989.150922171406 06:01:57 INFO - PROCESS | 30069 | __end_tp_report 06:01:57 INFO - PROCESS | 30069 | __start_cc_report 06:01:57 INFO - PROCESS | 30069 | _x_x_mozilla_cycle_collect,1033 06:01:57 INFO - PROCESS | 30069 | __end_cc_report 06:01:57 INFO - PROCESS | 30069 | __startTimestamp1474376517221__endTimestamp 06:01:57 INFO - PROCESS | 30069 | 06:01:57 INFO - PROCESS | 30069 | ------- Summary: start ------- 06:01:57 INFO - PROCESS | 30069 | Number of tests: 6 06:01:57 INFO - PROCESS | 30069 | 06:01:57 INFO - PROCESS | 30069 | [#0] dojo.html Cycles:165 Average:5583.04 Median:5798.33 stddev:635.13 (11.0%) stddev-sans-first:609.18 06:01:57 INFO - PROCESS | 30069 | Values: 3210.0 3228.0 3239.1 3272.3 3272.9 5516.9 5584.4 5610.1 5614.5 5625.3 5555.5 5600.5 5624.8 5641.0 5647.8 4997.1 5006.2 5066.7 5106.5 5166.1 5009.8 5045.0 5050.2 5070.1 5076.5 5166.6 5168.2 5172.6 5180.9 5181.4 5163.5 5172.7 5177.5 5180.7 5209.3 5015.9 5057.9 5069.1 5078.8 5082.6 5387.7 5434.0 5435.5 5527.4 5662.3 5902.9 5906.9 5944.7 5957.6 5964.4 5930.7 5938.7 5948.2 5962.0 5981.7 5480.1 5497.5 5498.4 5504.3 5522.4 6100.8 6101.8 6106.9 6110.2 6115.4 5729.3 5870.3 5870.5 5923.5 5927.5 6076.9 6082.5 6084.3 6098.3 6100.7 6066.5 6072.7 6074.3 6076.4 6081.8 5775.2 5816.0 5867.4 5897.2 5928.2 4625.9 4627.8 4632.9 4633.5 4660.7 4130.9 4136.4 4151.5 4152.6 4175.7 5628.9 5658.7 5699.7 5706.4 5798.3 6091.6 6091.8 6095.8 6100.3 6100.6 5749.1 5785.1 5796.9 5801.9 5806.0 5069.1 5111.4 5125.1 5146.7 5213.8 6162.4 6164.5 6164.5 6167.1 6172.3 5920.8 5994.2 5995.3 5996.3 5997.3 6151.7 6239.8 6246.2 6290.5 6298.6 6018.5 6036.2 6037.2 6042.3 6082.2 6081.9 6137.8 6144.7 6150.2 6160.4 5672.4 5861.1 5905.9 5938.6 6034.1 5947.5 5958.4 5989.9 5990.3 5996.8 5875.6 5907.5 5908.9 5925.1 5930.7 5746.2 5776.7 5818.3 5824.1 5887.1 5721.5 5729.1 5732.3 5734.3 5773.9 06:01:57 INFO - PROCESS | 30069 | 06:01:57 INFO - PROCESS | 30069 | [#1] ext.html Cycles:180 Average:63666.10 Median:38573.63 stddev:127409.07 (330.3%) stddev-sans-first:127678.51 06:01:57 INFO - PROCESS | 30069 | Values: 605.2 629.6 654.1 660.5 666.7 33252.3 44918.3 45744.8 45846.3 45858.3 47151.0 47405.0 47536.5 47601.3 47753.5 8509.1 9139.3 9288.2 9301.5 10911.5 10751.4 11204.8 11216.8 11217.9 11231.3 11235.8 11267.2 11294.5 11321.4 11325.9 11112.7 11223.5 11251.9 11257.6 11262.3 10812.2 10818.4 10856.6 10871.7 10876.7 45136.8 45417.1 45455.3 45603.8 45612.3 3625.0 3673.0 3765.7 3975.3 4048.2 34612.8 34911.8 35188.8 35427.3 35842.8 4603.7 4625.9 4652.5 4678.2 4760.5 360481.0 361129.0 361897.0 362899.0 363713.0 54110.0 54313.0 54410.7 61649.0 61701.4 40679.4 41514.8 41570.0 41608.8 41725.0 18130.6 18347.9 18396.9 18438.4 18453.2 12463.6 13381.7 13749.6 13763.2 13779.9 20430.2 20530.5 20747.7 20760.4 20795.2 7200.3 7541.4 7542.4 7626.8 7660.5 970.2 1050.2 1171.5 1212.1 1215.6 33780.0 34351.3 34617.7 34623.7 34649.7 1111.3 1198.5 1201.3 1215.1 1269.2 17984.9 18161.0 18179.0 18390.3 18520.5 600148.0 605899.0 605940.0 606336.0 608447.0 62479.7 63172.7 63228.4 63267.7 63304.4 1325.9 1390.2 1422.8 1450.9 1468.8 34174.1 35679.6 35770.5 35867.6 36021.0 54431.7 55315.7 55473.4 55555.0 55561.4 438365.0 439257.0 440659.0 441808.0 441837.0 29988.7 30872.0 30986.7 31103.7 31136.5 51585.7 52859.5 53643.7 53662.0 53760.7 42243.2 42447.6 42791.0 43807.3 44463.6 42895.8 43931.0 44022.3 44550.6 44792.0 42269.4 42634.0 43333.6 43385.0 43417.8 36169.6 38166.6 38980.6 39154.4 39168.6 28456.4 28606.9 28646.1 28692.3 28740.0 06:01:57 INFO - PROCESS | 30069 | 06:01:57 INFO - PROCESS | 30069 | [#2] jquery.html Cycles:180 Average:29196.30 Median:13232.81 stddev:79106.92 (597.8%) stddev-sans-first:79299.30 06:01:57 INFO - PROCESS | 30069 | Values: 404.3 407.0 422.3 426.8 433.2 19429.0 20682.3 20702.3 20884.9 20974.4 19604.9 19760.8 20171.6 20792.0 20927.8 5854.4 6098.2 6099.1 6112.8 6115.1 5985.5 6022.0 6040.6 6072.1 6073.2 5864.8 5988.6 5997.2 6000.6 6000.8 5812.0 5870.5 5895.0 5907.2 5926.1 5274.1 5325.7 5328.8 5332.7 5334.9 23703.2 24043.4 24242.9 24341.8 24667.1 2422.2 2494.4 2536.7 2550.3 2568.6 14562.6 15026.7 15540.5 15598.7 15813.8 1943.6 1994.4 2038.8 2091.6 2129.5 82436.2 83938.6 84756.0 85616.0 86084.6 10782.6 11296.2 11397.8 11449.2 11585.0 16078.5 16090.9 16095.0 16201.8 16205.8 8158.8 8584.9 8639.7 8695.2 8806.3 8372.7 8382.3 8407.9 8515.0 8588.7 6827.9 6925.3 6946.3 7016.9 7024.7 2031.5 2059.5 2078.7 2081.8 2130.2 959.9 1019.3 1043.4 1093.3 1130.2 20661.8 20959.9 20973.7 21203.4 21256.7 1010.5 1047.9 1064.7 1087.4 1131.2 11630.7 11908.5 11953.4 12028.8 12142.3 474282.0 476833.0 480589.0 484977.0 486904.0 82897.0 84681.0 85526.6 86272.6 86913.0 1059.2 1083.6 1106.3 1113.2 1125.3 17806.6 18350.0 18568.7 18726.1 18803.0 42245.4 42330.0 42533.8 43266.8 44043.3 54483.5 55428.7 55428.7 55795.7 55911.2 8172.0 8252.9 8574.5 8686.3 8990.8 13746.7 13895.2 14040.4 14045.2 14444.0 11411.9 11492.4 11506.4 11747.5 11911.8 11837.5 12085.1 12094.6 12282.1 12638.8 11480.3 11692.0 11707.6 11713.2 11735.3 9238.2 9296.3 9418.9 9444.3 9465.4 11819.8 11825.3 12304.3 12384.9 12606.5 06:01:57 INFO - PROCESS | 30069 | 06:01:57 INFO - PROCESS | 30069 | [#3] mootools.html Cycles:180 Average:6387.88 Median:2655.14 stddev:3740.03 (140.9%) stddev-sans-first:3731.37 06:01:57 INFO - PROCESS | 30069 | Values: 1352.0 1508.2 1554.3 1721.8 1873.1 9233.4 9594.8 9596.0 9871.2 9913.9 9724.0 9838.9 9842.7 9875.5 9895.3 2486.5 2491.1 2496.4 2506.8 2517.5 2395.4 2467.2 2482.6 2482.6 2489.8 2328.6 2380.6 2415.4 2416.7 2421.0 2364.3 2394.2 2398.4 2401.0 2409.5 2310.5 2316.1 2321.3 2322.5 2335.1 9557.1 9649.6 9740.7 9763.7 9775.9 5564.9 5806.0 5811.7 5814.4 5816.0 3496.7 3530.6 3543.5 3550.7 3623.4 1206.7 1211.3 1212.6 1226.3 1251.2 14091.7 14151.2 14158.2 14177.3 14217.4 4783.8 4875.0 4881.3 4973.4 5031.2 10983.7 10984.1 10997.5 11000.8 11004.6 5695.7 5696.6 5700.5 5703.1 5706.4 4642.4 4713.6 5061.2 5071.9 5318.2 4131.5 4135.5 4162.3 4170.3 4198.2 1267.0 1272.9 1287.3 1302.3 1310.5 1074.4 1082.5 1096.9 1112.6 1116.9 6576.2 6703.9 6912.1 6972.5 7065.5 486.6 509.3 514.0 514.4 517.9 4708.4 4801.4 4822.6 4823.7 4955.4 11950.6 12561.0 12587.7 12592.8 12610.0 12172.3 12233.9 12412.5 12469.6 12543.8 8908.6 8917.5 8971.1 9073.3 9074.9 6537.8 6666.7 6845.9 6913.2 6922.1 11171.3 11188.7 11342.5 11347.2 11365.9 9673.2 10033.6 11192.5 11528.7 11585.2 7871.1 7934.2 7939.7 7940.7 7942.2 9368.1 9403.0 9638.0 9639.1 9646.7 8591.4 8614.7 8685.0 8750.1 8883.0 8394.4 8433.7 8554.1 8618.5 8694.1 7445.0 7525.7 7534.5 7563.6 7646.0 6030.2 6078.7 6080.8 6115.4 6134.7 5959.3 6070.4 6128.5 6182.4 6262.5 06:01:57 INFO - PROCESS | 30069 | 06:01:57 INFO - PROCESS | 30069 | [#4] prototype.html Cycles:180 Average:4666.33 Median:5184.65 stddev:1337.61 (25.8%) stddev-sans-first:1301.40 06:01:57 INFO - PROCESS | 30069 | Values: 342.7 348.6 349.4 352.3 352.5 5448.8 5484.4 5498.4 5521.6 5561.1 5387.4 5395.5 5433.3 5460.8 5496.8 3813.0 4071.6 4182.9 4187.7 4324.8 4285.9 4303.1 4309.5 4310.8 4316.5 4299.4 4309.3 4310.6 4318.9 4344.5 4090.3 4226.7 4326.3 4333.2 4339.1 3767.8 3774.3 3793.3 3794.9 3826.6 5266.2 5316.0 5332.6 5341.7 5347.4 4794.7 4866.9 4899.7 4906.5 4915.2 5587.9 5620.6 5656.0 5672.3 5697.6 4141.0 4155.4 4177.1 4201.9 4209.6 5565.7 5791.8 5965.3 5971.9 6005.2 4217.7 4229.0 4241.0 4255.3 4260.6 4326.4 4328.8 4332.0 4332.1 4335.4 4749.7 4866.6 4880.8 4908.7 4927.5 5640.8 5661.7 5662.0 5672.7 5677.9 1465.2 1473.7 1475.8 1491.6 1501.6 931.3 969.0 970.8 973.5 988.2 4951.3 4961.4 4981.5 4989.0 5009.4 5165.3 5168.3 5171.9 5193.6 5213.9 4641.4 4946.7 4970.3 4987.3 4988.6 2737.2 2737.2 2741.0 2749.5 2776.4 5273.6 5333.7 5343.7 5415.2 5427.0 5043.4 5255.1 5260.3 5271.8 5301.0 5342.7 5357.8 5381.7 5397.2 5399.8 5142.7 5145.4 5211.7 5226.6 5233.5 4788.3 5075.4 5120.3 5138.4 5175.7 5393.4 5846.9 5976.9 6089.5 6117.0 4236.0 4252.4 4257.2 4260.8 4289.6 5695.8 5934.4 5952.4 5960.3 5964.6 5698.9 5703.6 5712.9 5715.4 5723.1 5705.1 5726.7 5727.0 5728.7 5764.3 5444.2 5623.4 5654.4 5659.4 5660.8 5695.2 5722.4 5726.9 5733.4 5744.0 5596.4 5598.3 5624.4 5665.4 5667.7 06:01:57 INFO - PROCESS | 30069 | 06:01:57 INFO - PROCESS | 30069 | [#5] yui.html Cycles:180 Average:20868.31 Median:55832.08 stddev:30094.55 (53.9%) stddev-sans-first:30140.79 06:01:57 INFO - PROCESS | 30069 | Values: 678.4 716.4 729.3 807.3 863.5 15361.2 16291.8 16297.0 16298.4 16313.4 13668.4 13717.2 15739.0 16610.2 16616.0 844.6 871.0 871.1 874.5 875.2 856.7 858.9 859.0 859.1 859.6 915.6 916.0 916.6 916.8 917.0 906.2 914.8 916.5 916.7 916.9 668.7 670.9 671.7 672.1 672.1 15208.5 15359.8 15362.8 15369.0 15384.5 12241.8 13111.1 13216.7 13241.5 13786.9 12533.3 12589.2 12770.2 12992.2 13046.6 4092.2 4147.2 4147.6 4150.1 4287.5 55236.7 58881.4 78194.6 78283.6 87666.0 12135.9 12301.6 12340.3 12486.3 12504.6 19224.0 19356.5 19362.5 19408.2 19444.1 9083.2 9200.5 9223.5 9353.0 9449.8 4991.3 5153.1 5161.2 5228.9 5343.0 3658.9 3801.1 3807.5 3809.4 3809.8 655.4 660.9 661.9 665.6 693.0 613.8 674.6 723.4 741.4 746.0 9805.0 9851.0 10026.6 10139.9 10376.2 674.1 721.4 788.7 791.6 799.4 4787.7 4894.4 5069.2 5074.7 5150.7 105617.0 115178.0 122574.4 127921.4 132606.0 69324.7 84985.6 88712.6 89403.0 104712.0 86048.0 87154.6 89940.3 89991.6 92478.5 9761.8 9870.5 9895.7 9930.6 10196.8 56427.4 56584.0 57088.0 57110.4 57195.1 73257.6 75876.0 79366.6 79915.0 80350.6 11712.6 11821.7 11840.3 12148.2 12150.8 13623.9 14328.2 14613.4 14706.3 14779.1 13476.7 13986.0 14019.5 14542.6 15056.6 13758.2 14243.2 14310.5 14398.5 15122.7 13697.5 13824.9 14415.6 14615.8 15373.6 9408.9 9607.7 9664.3 9784.9 9811.7 9768.0 9848.5 9858.5 9943.0 9989.2 06:01:57 INFO - PROCESS | 30069 | -------- Summary: end -------- 06:01:57 INFO - PROCESS | 30069 | 06:01:57 INFO - TEST-INFO | 30069: exit 0 06:01:57 INFO - TEST-OK | dromaeo_css | took 1112066ms 06:01:58 INFO - TEST-START | kraken 06:01:58 INFO - operating with platform_type : linux_ 06:01:58 INFO - Initialising browser for kraken test... 06:01:58 INFO - TEST-INFO | started process 30256 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXQTqrX/profile http://localhost:43581/getInfo.html) 06:02:01 INFO - PROCESS | 30256 | __metrics Screen width/height:1600/1200 06:02:01 INFO - PROCESS | 30256 | colorDepth:24 06:02:01 INFO - PROCESS | 30256 | Browser inner width/height: 1024/697 06:02:01 INFO - PROCESS | 30256 | __metrics 06:02:02 INFO - PROCESS | 30256 | 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" 06:02:02 INFO - PROCESS | 30256 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:02:02 INFO - PROCESS | 30256 | console.error: PushService: 06:02:02 INFO - PROCESS | 30256 | stateChangeProcessEnqueue: Error transitioning state 06:02:02 INFO - PROCESS | 30256 | UnknownError 06:02:03 INFO - TEST-INFO | 30256: exit 0 06:02:03 INFO - Browser initialized. 06:02:03 INFO - Running cycle 1/1 for kraken test... 06:02:03 INFO - TEST-INFO | started process 30348 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXQTqrX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:02:14 INFO - PROCESS | 30348 | RSS: Main: 143777792 06:02:14 INFO - PROCESS | 30348 | 06:02:17 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43581/tests/kraken/driver.html?testName=audio-beat-detection) 06:02:18 INFO - PROCESS | 30348 | RSS: Main: 198397952 06:02:18 INFO - PROCESS | 30348 | 06:02:20 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43581/tests/kraken/driver.html?testName=audio-dft) 06:02:21 INFO - PROCESS | 30348 | RSS: Main: 183263232 06:02:21 INFO - PROCESS | 30348 | 06:02:24 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43581/tests/kraken/driver.html?testName=audio-fft) 06:02:24 INFO - PROCESS | 30348 | RSS: Main: 274440192 06:02:24 INFO - PROCESS | 30348 | 06:02:26 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43581/tests/kraken/driver.html?testName=audio-oscillator) 06:02:27 INFO - PROCESS | 30348 | RSS: Main: 180064256 06:02:27 INFO - PROCESS | 30348 | 06:02:29 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43581/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:02:30 INFO - PROCESS | 30348 | RSS: Main: 178737152 06:02:30 INFO - PROCESS | 30348 | 06:02:33 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43581/tests/kraken/driver.html?testName=imaging-darkroom) 06:02:33 INFO - PROCESS | 30348 | RSS: Main: 214757376 06:02:33 INFO - PROCESS | 30348 | 06:02:37 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43581/tests/kraken/driver.html?testName=imaging-desaturate) 06:02:37 INFO - PROCESS | 30348 | RSS: Main: 213970944 06:02:37 INFO - PROCESS | 30348 | 06:02:40 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43581/tests/kraken/driver.html?testName=json-parse-financial) 06:02:41 INFO - PROCESS | 30348 | RSS: Main: 213225472 06:02:41 INFO - PROCESS | 30348 | 06:02:42 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43581/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:02:43 INFO - PROCESS | 30348 | RSS: Main: 176320512 06:02:43 INFO - PROCESS | 30348 | 06:02:44 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43581/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:02:45 INFO - PROCESS | 30348 | RSS: Main: 182538240 06:02:45 INFO - PROCESS | 30348 | 06:02:47 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43581/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:02:48 INFO - PROCESS | 30348 | RSS: Main: 185528320 06:02:48 INFO - PROCESS | 30348 | 06:02:50 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43581/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:02:51 INFO - PROCESS | 30348 | RSS: Main: 186077184 06:02:51 INFO - PROCESS | 30348 | 06:02:53 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43581/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:02:54 INFO - PROCESS | 30348 | RSS: Main: 183681024 06:02:54 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | Cycle 1(1): loaded http://localhost:43581/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43581/tests/kraken/driver.html?testName=ai-astar) 06:02:56 INFO - PROCESS | 30348 | RSS: Main: 234688512 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | __start_tp_report 06:02:56 INFO - PROCESS | 30348 | _x_x_mozilla_page_load 06:02:56 INFO - PROCESS | 30348 | _x_x_mozilla_page_load_details 06:02:56 INFO - PROCESS | 30348 | |i|pagename|runs| 06:02:56 INFO - PROCESS | 30348 | |0;ai-astar;111;111;121;112;109;112;111;110;111;112 06:02:56 INFO - PROCESS | 30348 | |1;audio-beat-detection;142;116;141;169;116;146;141;120;191;120 06:02:56 INFO - PROCESS | 30348 | |2;audio-dft;160;160;160;159;159;160;160;159;160;160 06:02:56 INFO - PROCESS | 30348 | |3;audio-fft;74;74;75;176;75;76;73;74;163;76 06:02:56 INFO - PROCESS | 30348 | |4;audio-oscillator;83;83;157;84;82;83;142;84;84;143 06:02:56 INFO - PROCESS | 30348 | |5;imaging-gaussian-blur;116;116;116;117;117;116;116;116;117;116 06:02:56 INFO - PROCESS | 30348 | |6;imaging-darkroom;146;145;146;145;145;146;146;146;145;147 06:02:56 INFO - PROCESS | 30348 | |7;imaging-desaturate;95;95;95;95;95;95;95;94;94;94 06:02:56 INFO - PROCESS | 30348 | |8;json-parse-financial;52;52;53;51;52;51;53;52;51;52 06:02:56 INFO - PROCESS | 30348 | |9;json-stringify-tinderbox;69;61;59;59;60;60;59;63;59;59 06:02:56 INFO - PROCESS | 30348 | |10;stanford-crypto-aes;71;72;72;81;76;75;75;73;72;80 06:02:56 INFO - PROCESS | 30348 | |11;stanford-crypto-ccm;128;116;123;118;123;116;114;114;167;125 06:02:56 INFO - PROCESS | 30348 | |12;stanford-crypto-pbkdf2;148;149;153;147;148;148;149;149;148;148 06:02:56 INFO - PROCESS | 30348 | |13;stanford-crypto-sha256-iterative;63;63;61;60;60;60;61;60;58;63 06:02:56 INFO - PROCESS | 30348 | __end_tp_report 06:02:56 INFO - PROCESS | 30348 | __start_cc_report 06:02:56 INFO - PROCESS | 30348 | _x_x_mozilla_cycle_collect,1693 06:02:56 INFO - PROCESS | 30348 | __end_cc_report 06:02:56 INFO - PROCESS | 30348 | __startTimestamp1474376576318__endTimestamp 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | ------- Summary: start ------- 06:02:56 INFO - PROCESS | 30348 | Number of tests: 14 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#0] ai-astar Cycles:10 Average:112.00 Median:111.50 stddev:3.30 (3.0%) stddev-sans-first:3.48 06:02:56 INFO - PROCESS | 30348 | Values: 111.0 111.0 121.0 112.0 109.0 112.0 111.0 110.0 111.0 112.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:141.50 stddev:24.58 (17.4%) stddev-sans-first:26.06 06:02:56 INFO - PROCESS | 30348 | Values: 142.0 116.0 141.0 169.0 116.0 146.0 141.0 120.0 191.0 120.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#2] audio-dft Cycles:10 Average:159.70 Median:160.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 06:02:56 INFO - PROCESS | 30348 | Values: 160.0 160.0 160.0 159.0 159.0 160.0 160.0 159.0 160.0 160.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#3] audio-fft Cycles:10 Average:93.60 Median:74.50 stddev:40.13 (53.9%) stddev-sans-first:41.93 06:02:56 INFO - PROCESS | 30348 | Values: 74.0 74.0 75.0 176.0 75.0 76.0 73.0 74.0 163.0 76.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:83.00 stddev:31.20 (37.6%) stddev-sans-first:32.28 06:02:56 INFO - PROCESS | 30348 | Values: 83.0 83.0 157.0 84.0 82.0 83.0 142.0 84.0 84.0 143.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:116.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 06:02:56 INFO - PROCESS | 30348 | Values: 116.0 116.0 116.0 117.0 117.0 116.0 116.0 116.0 117.0 116.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 06:02:56 INFO - PROCESS | 30348 | Values: 146.0 145.0 146.0 145.0 145.0 146.0 146.0 146.0 145.0 147.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 06:02:56 INFO - PROCESS | 30348 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 94.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:0.74 (1.4%) stddev-sans-first:0.78 06:02:56 INFO - PROCESS | 30348 | Values: 52.0 52.0 53.0 51.0 52.0 51.0 53.0 52.0 51.0 52.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:60.00 stddev:3.16 (5.3%) stddev-sans-first:1.36 06:02:56 INFO - PROCESS | 30348 | Values: 69.0 61.0 59.0 59.0 60.0 60.0 59.0 63.0 59.0 59.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:75.00 stddev:3.47 (4.6%) stddev-sans-first:3.41 06:02:56 INFO - PROCESS | 30348 | Values: 71.0 72.0 72.0 81.0 76.0 75.0 75.0 73.0 72.0 80.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:123.00 stddev:15.74 (12.8%) stddev-sans-first:16.64 06:02:56 INFO - PROCESS | 30348 | Values: 128.0 116.0 123.0 118.0 123.0 116.0 114.0 114.0 167.0 125.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.70 Median:148.50 stddev:1.64 (1.1%) stddev-sans-first:1.72 06:02:56 INFO - PROCESS | 30348 | Values: 148.0 149.0 153.0 147.0 148.0 148.0 149.0 149.0 148.0 148.0 06:02:56 INFO - PROCESS | 30348 | 06:02:56 INFO - PROCESS | 30348 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:61.00 stddev:1.66 (2.7%) stddev-sans-first:1.58 06:02:56 INFO - PROCESS | 30348 | Values: 63.0 63.0 61.0 60.0 60.0 60.0 61.0 60.0 58.0 63.0 06:02:56 INFO - PROCESS | 30348 | -------- Summary: end -------- 06:02:56 INFO - PROCESS | 30348 | 06:02:57 INFO - TEST-INFO | 30348: exit 0 06:02:57 INFO - TEST-OK | kraken | took 59254ms 06:02:57 INFO - SUITE-END | took 1171s 06:02:57 INFO - Completed test suite (00:19:31) 06:02:57 INFO - javascript benchmark 06:02:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3209.9679003209976, 3228.0, 3239.0768630940174, 3272.280098378359, 3272.9345413091737, 5516.917246241304, 5584.357798853133, 5610.074337734279, 5614.466625670555, 5625.296837895263, 5555.527780138693, 5600.467955544227, 5624.803131890385, 5640.971795141028, 5647.802326918559, 4997.075541024904, 5006.1739812931, 5066.6706664066805, 5106.540411362977, 5166.121759300928, 5009.824656137036, 5045.0, 5050.242463630448, 5070.138076527, 5076.543111119999, 5166.63833531651, 5168.173092305232, 5172.612054095939, 5180.948190518103, 5181.352330958612, 5163.535281824654, 5172.715500647463, 5177.4822517747925, 5180.663256888328, 5209.348831396075, 5015.899682006376, 5057.924131138036, 5069.138246498067, 5078.771455284485, 5082.567981721558, 5387.73061346935, 5433.9675461662155, 5435.537979271767, 5527.419620939824, 5662.320521537401, 5902.911456328158, 5906.911396329058, 5944.673042982634, 5957.553183511211, 5964.403559644044, 5930.733117009745, 5938.732757025944, 5948.167256584082, 5961.970190149022, 5981.700914954278, 5480.095784195603, 5497.47773961481, 5498.395176530578, 5504.284443022421, 5522.420145884705, 6100.847478813065, 6101.816945491642, 6106.908396374058, 6110.175126357884, 6115.4190351916495, 5729.255196824386, 5870.295564532284, 5870.5010074144175, 5923.466887980103, 5927.525797936155, 6076.908846367308, 6082.513398928075, 6084.300305464928, 6098.26820781509, 6100.69496525172, 6066.545009124334, 6072.666003369857, 6074.331823499411, 6076.361981991916, 6081.847953801191, 5775.220345253337, 5816.011278082736, 5867.3839246879315, 5897.174395584621, 5928.170056192136, 4625.861224163281, 4627.838025669085, 4632.884177895579, 4633.536646335339, 4660.650451216173, 4130.938035929464, 4136.400221967798, 4151.481064866892, 4152.626263636287, 4175.68682348825, 5628.943710562924, 5658.74535645897, 5699.658020518782, 5706.3722990471015, 5798.333191682926, 6091.573589848668, 6091.847703807441, 6095.756169753249, 6100.298465676416, 6100.572959892854, 5749.080147176432, 5785.074388097884, 5796.913046304308, 5801.883962320814, 5805.970970145122, 5069.062223488666, 5111.386633603991, 5125.077486052472, 5146.716930568779, 5213.817516386908, 6162.445379915829, 6164.4760195383, 6164.537659675542, 6167.136600875791, 6172.321044685081, 5920.79277225295, 5994.160817485643, 5995.250593675791, 5996.2504686914135, 5997.250343707036, 6151.723172457339, 6239.812805615838, 6246.156768836149, 6290.49676025926, 6298.559100863079, 6018.518518518596, 6036.154938308552, 6037.154798328325, 6042.305134909366, 6082.178905847831, 6081.939180608314, 6137.785177518944, 6144.662043587646, 6150.200473938359, 6160.353162918008, 5672.376038635664, 5861.062230043172, 5905.940940590539, 5938.61399009063, 6034.125051867542, 5947.524198064058, 5958.433948774859, 5989.850253743692, 5990.341062483036, 5996.760129594767, 5875.588708790515, 5907.527397808251, 5908.852278693067, 5925.140854576149, 5930.733117009831, 5746.166805813217, 5776.653400795965, 5818.272715910512, 5824.097264923944, 5887.116932460165, 5721.485066344049, 5729.08334666468, 5732.283464566929, 5734.283214598176, 5773.91339129905], "value": 5540.099587209193, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.2102006881018, 629.5844742469969, 654.0810161722782, 660.5145218264572, 666.7266420767486, 33252.33495330095, 44918.32622510665, 45744.77127614361, 45846.31230531545, 45858.312125318145, 47151.0, 47405.0, 47536.524634753645, 47601.285980710316, 47753.52246477534, 8509.064002959669, 9139.314551408654, 9288.21050210733, 9301.534923253845, 10911.508982095786, 10751.354918704863, 11204.831927521094, 11216.831747523795, 11217.887821121807, 11231.26996745213, 11235.764065952739, 11267.211295209298, 11294.491747871325, 11321.433928303593, 11325.886741132606, 11112.722181945435, 11223.494942727597, 11251.887481125248, 11257.605983790529, 11262.324260544312, 10812.243142979994, 10818.404987725671, 10856.620018299322, 10871.728206794814, 10876.728081797939, 45136.77431612821, 45417.09165816698, 45455.318170227474, 45603.77198113988, 45612.315815262795, 3624.963750362489, 3673.0266479382985, 3765.6799172070405, 3975.344068228768, 4048.1903619276263, 34612.82693586516, 34911.82544087289, 35188.82405587981, 35427.29145417077, 35842.82078589616, 4603.723776573416, 4625.907481850378, 4652.534746525321, 4678.1813182692895, 4760.523947605247, 360481.0, 361128.9999999974, 361897.0, 362899.0, 363713.0, 54110.0, 54313.0, 54410.72794636081, 61649.0, 61701.38298617046, 40679.38980915289, 41514.792426037675, 41570.0, 41608.791956040026, 41725.0, 18130.63738725218, 18347.90826045888, 18396.908015459838, 18438.446846594623, 18453.1696073677, 12463.626091217278, 13381.732365352833, 13749.587512374444, 13763.174209547458, 13779.862201377859, 20430.18279268842, 20530.486737831376, 20747.68878466824, 20760.377188684364, 20795.1681932721, 7200.315969982843, 7541.396688264926, 7542.434317426215, 7626.8093297668, 7660.463767536219, 970.1705042188968, 1050.1913526584487, 1171.5196769324536, 1212.078820096733, 1215.617080619602, 33780.0, 34351.31297374039, 34617.65382346144, 34623.65376346204, 34649.653503464644, 1111.2943281016612, 1198.5026214121042, 1201.3092471828559, 1215.0620156957748, 1269.2004037456343, 17984.910075449538, 18161.0, 18179.0, 18390.26438942454, 18520.536986575444, 600148.0, 605899.0, 605940.0, 606336.0, 608447.0, 62479.6876015617, 63172.684136579024, 63228.367716322246, 63267.68366158232, 63304.36695633077, 1325.9469621215237, 1390.2492653966744, 1422.7723564229673, 1450.8973180382984, 1468.77968304753, 34174.14564635904, 35679.64320356763, 35770.463443047855, 35867.64132358643, 36021.0, 54431.72784136054, 55315.72342138264, 55473.44526554683, 55555.0, 55561.444385557246, 438365.0, 439257.0, 440659.0, 441808.0, 441837.0, 29988.70011299859, 30872.0, 30986.69013309928, 31103.68896311008, 31136.53295200619, 51585.7420712894, 52859.471405287, 53643.7317813424, 53662.0, 53760.73119634377, 42243.1551368977, 42447.5755242456, 42791.0, 43807.34288985604, 44463.55536444594, 42895.7855210722, 43931.0, 44022.339664905696, 44550.55449445594, 44792.0, 42269.36595951002, 42634.0, 43333.56666433295, 43385.0, 43417.78291108524, 36169.63830361663, 38166.61919823397, 38980.63567775114, 39154.41268381033, 39168.6083139165, 28456.430871382872, 28606.856965715036, 28646.140615781558, 28692.282692932844, 28740.0], "value": 21562.604448407335, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.2864344432078, 406.9866033576392, 422.3074158380256, 426.7866066966517, 433.15102399297376, 19429.0, 20682.276120335773, 20702.27542036029, 20884.89557552214, 20974.37076887694, 19604.901975490102, 19760.802391976074, 20171.596568068668, 20792.0, 20927.790722092774, 5854.443827836349, 6098.237720284965, 6099.085137229406, 6112.816615501542, 6115.052166914135, 5985.4912332451795, 6022.0, 6040.552729012303, 6072.149899014142, 6073.210482637251, 5864.794732184374, 5988.550858685594, 5997.160397544348, 6000.579959402844, 6000.8499787505225, 5812.041013232812, 5870.501007414375, 5895.0, 5907.202527658754, 5926.051831706906, 5274.05067087924, 5325.707086110262, 5328.813491527778, 5332.733363331818, 5334.866628334284, 23703.170389036473, 24043.39891502709, 24242.87878560596, 24341.756582434304, 24667.136650217337, 2422.2248880358293, 2494.4387512809653, 2536.746325367467, 2550.3241640965157, 2568.589025755889, 14562.635934101627, 15026.69946601062, 15540.456084037005, 15598.688026239524, 15813.762793558106, 1943.5626983928503, 1994.3602924662548, 2038.7655419626785, 2091.5712278982774, 2129.4782778219437, 82436.17563824345, 83938.58030709869, 84756.0, 85616.0, 86084.56957715235, 10782.622608208674, 11296.152788540892, 11397.772044559062, 11449.198556100993, 11585.0, 16078.517644470685, 16090.919545402197, 16095.0, 16201.756973645404, 16205.756913646304, 8158.836823263501, 8584.914150858536, 8639.6976105836, 8695.217430431292, 8806.295496360404, 8372.665093396196, 8382.287505562095, 8407.873881891777, 8515.02077261123, 8588.65645374178, 6827.863442731117, 6925.272846351161, 6946.305369463013, 7016.859662806715, 7024.700430420388, 2031.502281940934, 2059.4542446251753, 2078.7089807426973, 2081.8230450411584, 2130.1585873579847, 959.9184069354183, 1019.3323373190572, 1043.436544266103, 1093.3494570730402, 1130.1693255457233, 20661.793382065985, 20959.8952005239, 20973.68539471909, 21203.36389908305, 21256.681149782766, 1010.4788552561406, 1047.9423631700329, 1064.6752740414247, 1087.396494945297, 1131.1798945764256, 11630.6510804676, 11908.52365905354, 11953.40232988347, 12028.819567706492, 12142.271463712206, 474282.0, 476833.0, 480589.0, 484977.0, 486904.0, 82897.0, 84681.0, 85526.57236713776, 86272.56863715641, 86913.0, 1059.1791361694595, 1083.6315652678127, 1106.25880659958, 1113.1929351220301, 1125.3472985668293, 17806.643867122842, 18350.0, 18568.674961474193, 18726.063696815378, 18803.0, 42245.36631950585, 42330.0, 42533.78733106315, 43266.783666081465, 44043.33934990913, 54483.455165447835, 55428.72285638546, 55428.72285638546, 55795.72102139625, 55911.16133258086, 8172.0, 8252.850768468023, 8574.485530868167, 8686.305095592465, 8990.775230619025, 13746.725065498835, 13895.23576203278, 14040.438382464587, 14045.227512486912, 14444.0, 11411.942940285246, 11492.425378730863, 11506.424678766196, 11747.47136378848, 11911.82132268034, 11837.526498940291, 12085.093617978862, 12094.637160885188, 12282.140250182401, 12638.810417843555, 11480.311181329147, 11692.0, 11707.648770536898, 11713.238639488405, 11735.295882247092, 9238.168564829197, 9296.256299496159, 9418.905810941802, 9444.261164262287, 9465.384749991228, 11819.763604727685, 11825.290482570728, 12304.323262220305, 12384.938075309567, 12606.49574017029], "value": 9785.98732115224, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.9662008449793, 1508.2006536535632, 1554.3005647458644, 1721.7847769028872, 1873.1102726205054, 9233.445993240402, 9594.760130996729, 9596.040395960401, 9871.210303175747, 9913.900860991387, 9724.0, 9838.901610983907, 9842.655507057254, 9875.456849873253, 9895.307328486992, 2486.4902694947536, 2491.1032028469735, 2496.400863792686, 2506.774390304872, 2517.5342561626067, 2395.4370722880108, 2467.222824810179, 2482.615194644824, 2482.6276058591175, 2489.7634724701124, 2328.580855446019, 2380.6310021946624, 2415.4444477770126, 2416.6979127609047, 2420.987895060522, 2364.2788949370433, 2394.150076722756, 2398.3884109552127, 2401.0, 2409.5301416223756, 2310.5378924215056, 2316.1082983051647, 2321.3268152236, 2322.512272422793, 2335.1126571902737, 9557.092076252746, 9649.565769540388, 9740.659076932345, 9763.658271960518, 9775.90224097764, 5564.944350556483, 5806.042003069489, 5811.651300921958, 5814.447627475383, 5815.970920145414, 3496.7027802636553, 3530.5939816921127, 3543.450765131383, 3550.6626870447267, 3623.365910965597, 1206.6561030106466, 1211.3216598704666, 1212.5937810833382, 1226.2642414551199, 1251.2054845173288, 14091.71816563663, 14151.150930944177, 14158.221297828615, 14177.291135443187, 14217.43130274798, 4783.832565860178, 4874.95125048745, 4881.316615673737, 4973.403191617029, 5031.169856973632, 10983.725406864893, 10984.121270298518, 10997.505112269968, 11000.834987475195, 11004.559817607205, 5695.686737229409, 5696.601237913389, 5700.543956483471, 5703.08750599902, 5706.400827913092, 4642.442906851199, 4713.6464765142755, 5061.1902095665255, 5071.8985620287385, 5318.175682769177, 4131.5248746393945, 4135.524414692289, 4162.299156024668, 4170.311898536768, 4198.181354635817, 1267.0497127154633, 1272.9490820367266, 1287.2791236907362, 1302.3293004102895, 1310.4758096761182, 1074.409075008742, 1082.4533610526635, 1096.9341839489657, 1112.593903225317, 1116.944152792357, 6576.210854697467, 6703.932960670429, 6912.135983002125, 6972.51192416526, 7065.540739851893, 486.58640155867937, 509.30479894943875, 513.9511746384162, 514.4289838279507, 517.901598696254, 4708.364370809965, 4801.447833499123, 4822.614190864722, 4823.686460380064, 4955.430125535538, 11950.641480755592, 12561.0, 12587.685307867376, 12592.81110783339, 12610.0, 12172.330521821208, 12233.877661223452, 12412.503499859904, 12469.563565275172, 12543.811842822364, 8908.643654253887, 8917.464952102764, 8971.147740964598, 9073.31950103745, 9074.909250907538, 6537.836554085996, 6666.666666666744, 6845.897311540231, 6913.239543650094, 6922.06552115458, 11171.329720216489, 11188.66434006981, 11342.546298148312, 11347.205695601559, 11365.886341136813, 9673.177779888789, 10033.598656053677, 11192.496337664987, 11528.711782205512, 11585.246958947642, 7871.094824095302, 7934.166912474107, 7939.682412703658, 7940.722074727357, 7942.245486678757, 9368.110029547182, 9402.95298523503, 9637.951810240904, 9639.132478077006, 9646.710598682052, 8591.355648326526, 8614.741557753277, 8684.956575217084, 8750.081241469554, 8882.955585222033, 8394.412391132562, 8433.746987590383, 8554.059053504234, 8618.52598107085, 8694.130586941255, 7445.0, 7525.6989720410575, 7534.472586918863, 7563.621818909142, 7646.0, 6030.246219222598, 6078.7264573093435, 6080.817575472743, 6115.449609534985, 6134.6625935573975, 5959.314678811992, 6070.3929607038945, 6128.509719222362, 6182.443580077815, 6262.467690246198], "value": 4969.139767541446, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.6761710183877, 348.5834427858709, 349.3815945775978, 352.3305719133647, 352.4554563199856, 5448.782048718052, 5484.424135465773, 5498.367687715914, 5521.641093328931, 5561.110222364423, 5387.434319396466, 5395.514403703666, 5433.293671822667, 5460.754266058027, 5496.780128794853, 3812.9618703812885, 4071.552129265786, 4182.916341673164, 4187.748735075906, 4324.84863029794, 4285.914281714379, 4303.139372125572, 4309.525952145262, 4310.849120280791, 4316.503602085753, 4299.3550967354995, 4309.332053531708, 4310.590493903074, 4318.870433886988, 4344.45694288214, 4090.325096359097, 4226.731980405897, 4326.329418940054, 4333.2200203963275, 4339.132173565268, 3767.773933563995, 3774.320622287987, 3793.2603142387247, 3794.924101517954, 3826.559945606263, 5266.1574148136115, 5315.973420132913, 5332.626716129873, 5341.679499230058, 5347.438518955492, 4794.712317260941, 4866.902661946777, 4899.730514821684, 4906.460289368167, 4915.238138088602, 5587.860303492445, 5620.622947377878, 5656.0101982152955, 5672.34768001681, 5697.6011679182475, 4140.958590414088, 4155.439015732868, 4177.12280421112, 4201.886500077482, 4209.6000879916355, 5565.66606003637, 5791.768329266824, 5965.313988891289, 5971.865345584369, 6005.159277701126, 4217.683673724421, 4228.9788551057045, 4240.978795106066, 4255.319148936217, 4260.595243451867, 4326.351047342924, 4328.826846926151, 4331.956680433218, 4332.090261045216, 4335.371371151228, 4749.715017098985, 4866.586340161055, 4880.804767809319, 4908.680935739166, 4927.457979622239, 5640.802571909963, 5661.678711728172, 5662.0, 5672.688002159838, 5677.886442271131, 1465.2234315812636, 1473.6516087779776, 1475.7933889255512, 1491.5749011531877, 1501.6396064944554, 931.2782593490099, 969.0, 970.8203982263304, 973.4743238651049, 988.2193067476757, 4951.282064100686, 4961.404631444249, 4981.526754958273, 4988.975055124701, 5009.44896061433, 5165.251038599382, 5168.328117344721, 5171.870703232374, 5193.610479214074, 5213.921791173136, 4641.396618439581, 4946.707023484909, 4970.254461830682, 4987.301777751118, 4988.55103040728, 2737.1533605050527, 2737.1660987138666, 2740.9588856167175, 2749.505089083984, 2776.4169524400104, 5273.630845840831, 5333.733313334318, 5343.65266257692, 5415.214793854948, 5426.972865135649, 5043.445221025684, 5255.061780188439, 5260.316158899319, 5271.815486457956, 5300.973495132577, 5342.679439233659, 5357.7589008494715, 5381.730913454312, 5397.217403476474, 5399.757010934517, 5142.742862856768, 5145.408278047923, 5211.723127833553, 5226.56964511504, 5233.493559315192, 4788.3327941066955, 5075.416327122428, 5120.283160357479, 5138.3833939927445, 5175.689458632494, 5393.379761327497, 5846.912296315644, 5976.850578735566, 6089.48239399656, 6117.0, 4235.97882010588, 4252.383404406281, 4257.233697934402, 4260.785676082425, 4289.592488713567, 5695.829125126253, 5934.376890426443, 5952.375000624872, 5960.344362120077, 5964.612300200473, 5698.857528561819, 5703.62926409782, 5712.942870571241, 5715.371309155907, 5723.141528770718, 5705.058665320175, 5726.656400615976, 5726.971365143314, 5728.684922329312, 5764.308283006066, 5444.210589464426, 5623.409541998195, 5654.38849927785, 5659.377468478382, 5660.773569057192, 5695.180885461723, 5722.4277572242445, 5726.914096288642, 5733.365990692632, 5744.023516002206, 5596.4123767003875, 5598.300212473441, 5624.35319938196, 5665.376808550974, 5667.688277144796], "value": 4246.09725978043, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.4368973751787, 716.3946465236876, 729.3326606155368, 807.3339494916694, 863.499170481121, 15361.231938403078, 16291.83708162918, 16297.0, 16298.429554965549, 16313.429029983952, 13668.384922678479, 13717.245551494663, 15739.0, 16610.169491525405, 16616.0, 844.6368061733467, 870.995645021774, 871.1375738019346, 874.5321253129576, 875.1991927386434, 856.7172832965138, 858.935579831512, 859.0464584311409, 859.0979471554842, 859.6432480520576, 915.6062892956027, 916.0198587511366, 916.5829547555846, 916.7799728065283, 916.9541522923861, 906.2251774732574, 914.8033172867865, 916.5417291354323, 916.734147097341, 916.8716379706813, 668.7124536449325, 670.9161354830646, 671.6977360187886, 672.0658284983876, 672.0758956434902, 15208.46770363032, 15359.846401535953, 15362.769558456632, 15369.0, 15384.461543846024, 12241.755164896653, 13111.082224244306, 13216.73566528664, 13241.470341186341, 13786.931065344608, 12533.310667913262, 12589.24464532131, 12770.169938953939, 12992.220466772023, 13046.608601741962, 4092.1815636872743, 4147.212029714375, 4147.6474499667565, 4150.066235097109, 4287.506936702288, 55236.72381638066, 58881.41118588802, 78194.60902695508, 78283.60858195729, 87666.0, 12135.87864121365, 12301.630951071304, 12340.259584424963, 12486.250824950532, 12504.562340318227, 19224.038798060043, 19356.516087097654, 19362.515937101685, 19408.22367105328, 19444.125014374386, 9083.18251357381, 9200.493972831424, 9223.492707900996, 9353.0, 9449.811003779885, 4991.276264941564, 5153.123968925292, 5161.200013997836, 5228.895422091537, 5343.0, 3658.8902332930056, 3801.068738159169, 3807.4669546263544, 3809.3714537101623, 3809.771413715186, 655.4232275597444, 660.9372109649576, 661.9338066193342, 665.6139439125295, 692.9792106236821, 613.7759717703095, 674.639068098565, 723.392350425645, 741.3772431157853, 745.9589722565311, 9804.950975245221, 9850.965648606936, 10026.598936042623, 10139.93530679268, 10376.221783366278, 674.0967104080555, 721.3904250907974, 788.6766425765409, 791.5527726834321, 799.3645052183529, 4787.736674482937, 4894.412670479565, 5069.188929771219, 5074.746262686851, 5150.742462876841, 105617.0, 115178.0, 122574.38712806378, 127921.36039319744, 132606.0, 69324.6533767338, 84985.57507212424, 88712.5564372187, 89403.0, 104712.0, 86048.0, 87154.56422717973, 89940.30179094638, 89991.55004224936, 92478.53760731153, 9761.804763904824, 9870.506474676382, 9895.703128906145, 9930.553125109243, 10196.796064078826, 56427.43572564386, 56584.0, 57088.0, 57110.428895712175, 57195.09582666952, 73257.6337118311, 75876.0, 79366.60316698611, 79915.0, 80350.59824700838, 11712.590059348222, 11821.70445738829, 11840.28958262507, 12148.21036632616, 12150.75698486043, 13623.86376136226, 14328.211948342941, 14613.415463381345, 14706.338214780575, 14779.113253204841, 13476.663083422993, 13986.0, 14019.50931717385, 14542.636434089232, 15056.62358441004, 13758.243296618783, 14243.21662308583, 14310.49913253031, 14398.496052638526, 15122.697546048797, 13697.520586779416, 13824.861751382357, 14415.639609009439, 14615.780763288347, 15373.601077872332, 9408.858867116862, 9607.711768646952, 9664.275179361717, 9784.85322720174, 9811.705648830546, 9768.013412735281, 9848.507574621384, 9858.457784821903, 9943.0, 9989.150922171406], "value": 7725.817663789157, "unit": "score"}], "name": "dromaeo_css", "value": 7586.021272519835, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 121.0, 112.0, 109.0, 112.0, 111.0, 110.0, 111.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 141.0, 169.0, 116.0, 146.0, 141.0, 120.0, 191.0, 120.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 159.0, 159.0, 160.0, 160.0, 159.0, 160.0, 160.0], "value": 159.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 176.0, 75.0, 76.0, 73.0, 74.0, 163.0, 76.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 157.0, 84.0, 82.0, 83.0, 142.0, 84.0, 84.0, 143.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0], "value": 116.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 147.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 51.0, 52.0, 51.0, 53.0, 52.0, 51.0, 52.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 61.0, 59.0, 59.0, 60.0, 60.0, 59.0, 63.0, 59.0, 59.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 72.0, 81.0, 76.0, 75.0, 75.0, 73.0, 72.0, 80.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 116.0, 123.0, 118.0, 123.0, 116.0, 114.0, 114.0, 167.0, 125.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 153.0, 147.0, 148.0, 148.0, 149.0, 149.0, 148.0, 148.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 61.0, 60.0, 60.0, 60.0, 61.0, 60.0, 58.0, 63.0], "value": 60.9, "unit": "score"}], "name": "kraken", "value": 1486.1000000000004, "alertThreshold": 2.0}]} 06:02:57 INFO - Return code: 0 06:02:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:02:57 INFO - # TBPL SUCCESS # 06:02:57 INFO - Running post-action listener: _resource_record_post_action 06:02:57 INFO - [mozharness: 2016-09-20 13:02:57.626937Z] Finished run-tests step (success) 06:02:57 INFO - Running post-run listener: _resource_record_post_run 06:02:57 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292061184; Read time: 4; Write time: 175308 06:02:57 INFO - TinderboxPrint: CPU usage
13.0% 06:02:57 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 06:02:57 INFO - TinderboxPrint: I/O write bytes / time
292,061,184 / 175,308 06:02:57 INFO - TinderboxPrint: CPU idle
8,239.9 (87.0%) 06:02:57 INFO - TinderboxPrint: CPU user
1,184.4 (12.5%) 06:02:57 INFO - TinderboxPrint: Swap in / out
0 / 0 06:02:57 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 67661824; Read time: 0; Write time: 56812 06:02:57 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 223948800; Read time: 0; Write time: 118492 06:02:58 INFO - Running post-run listener: _upload_blobber_files 06:02:58 INFO - Blob upload gear active. 06:02:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:02:58 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 06:02:58 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'] 06:02:58 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 06:02:58 INFO - (blobuploader) - INFO - Open directory for files ... 06:02:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:02:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:02:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:02:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:02:59 INFO - (blobuploader) - INFO - Done attempting. 06:02:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:02:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:03:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:03:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:03:00 INFO - (blobuploader) - INFO - Done attempting. 06:03:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:03:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:03:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:03:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:03:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:03:04 INFO - (blobuploader) - INFO - Done attempting. 06:03:04 INFO - (blobuploader) - INFO - Iteration through files over. 06:03:04 INFO - Return code: 0 06:03:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:03:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:03:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09daab236b3ed158882baf0cf61e078a52f19095e61d733dc7552050de1c6518d1a7a26283e51d9ea4cac07f0eda58c870e5d3bf26ddb4e71959aa45ad8bda31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3c616721ee4a07be88bddb92c5b5748bbc0a572dbe58753f0aa0368f61b678121f4ad73644cc5cc3f6736e7ec388355fa9ff5f3fe90063bbbe165812fdeb55e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cce58188a71abfc05311dac0245c3317930040cdedb5f954cb0f1a274c18a462e6ceece409d320f9d726eb814e4b60f577aa4290761b7d4c97e353a367d407f8"} 06:03:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:03:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:03:04 INFO - Contents: 06:03:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09daab236b3ed158882baf0cf61e078a52f19095e61d733dc7552050de1c6518d1a7a26283e51d9ea4cac07f0eda58c870e5d3bf26ddb4e71959aa45ad8bda31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3c616721ee4a07be88bddb92c5b5748bbc0a572dbe58753f0aa0368f61b678121f4ad73644cc5cc3f6736e7ec388355fa9ff5f3fe90063bbbe165812fdeb55e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cce58188a71abfc05311dac0245c3317930040cdedb5f954cb0f1a274c18a462e6ceece409d320f9d726eb814e4b60f577aa4290761b7d4c97e353a367d407f8"} 06:03:04 INFO - Running post-run listener: copy_logs_to_upload_dir 06:03:04 INFO - Copying logs to upload dir... 06:03:04 INFO - mkdir: /builds/slave/test/build/upload/logs 06:03:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.882637 ========= master_lag: 11.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 8 secs) (at 2016-09-20 06:03:15.445453) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 06:03:15.449204) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812 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/09daab236b3ed158882baf0cf61e078a52f19095e61d733dc7552050de1c6518d1a7a26283e51d9ea4cac07f0eda58c870e5d3bf26ddb4e71959aa45ad8bda31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3c616721ee4a07be88bddb92c5b5748bbc0a572dbe58753f0aa0368f61b678121f4ad73644cc5cc3f6736e7ec388355fa9ff5f3fe90063bbbe165812fdeb55e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cce58188a71abfc05311dac0245c3317930040cdedb5f954cb0f1a274c18a462e6ceece409d320f9d726eb814e4b60f577aa4290761b7d4c97e353a367d407f8"} build_url:https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011225 build_url: 'https://queue.taskcluster.net/v1/task/Ec8xzhJkSyKx710JIJYnOg/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/09daab236b3ed158882baf0cf61e078a52f19095e61d733dc7552050de1c6518d1a7a26283e51d9ea4cac07f0eda58c870e5d3bf26ddb4e71959aa45ad8bda31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3c616721ee4a07be88bddb92c5b5748bbc0a572dbe58753f0aa0368f61b678121f4ad73644cc5cc3f6736e7ec388355fa9ff5f3fe90063bbbe165812fdeb55e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cce58188a71abfc05311dac0245c3317930040cdedb5f954cb0f1a274c18a462e6ceece409d320f9d726eb814e4b60f577aa4290761b7d4c97e353a367d407f8"}' ========= master_lag: 0.16 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 06:03:15.619309) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 mins, 9 secs) (at 2016-09-20 06:03:15.619623) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474374905.819859-1974987812 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 69.48 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 mins, 9 secs) (at 2016-09-20 06:04:25.104847) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-09-20 06:04:25.105311) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-09-20 06:04:26.275776) ========= ========= Total master_lag: 80.87 =========