builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-042 starttime: 1475019839.23 results: success (0) buildid: 20160927135419 builduid: cdd2de4f8dcd4a35b4806d67cceef574 revision: d1a2da10c74b2ff19d5e3add68c8fafa01bc1207 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 16:43:59.227042) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 16:43:59.227547) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 16:43:59.227869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 16:43:59.244730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 16:43:59.245168) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-27 16:43:59-- 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% 222M=0s 2016-09-27 16:44:00 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107158 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 16:43:59.372204) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 16:43:59.372561) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018741 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 16:43:59.426832) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 16:43:59.427211) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d1a2da10c74b2ff19d5e3add68c8fafa01bc1207 --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 d1a2da10c74b2ff19d5e3add68c8fafa01bc1207 --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=74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-27 16:44:00,192 truncating revision to first 12 chars 2016-09-27 16:44:00,192 Setting DEBUG logging. 2016-09-27 16:44:00,192 attempt 1/10 2016-09-27 16:44:00,192 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d1a2da10c74b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-27 16:44:00,506 unpacking tar archive at: mozilla-beta-d1a2da10c74b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.655061 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 16:44:00.096023) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 16:44:00.096358) ========= script_repo_revision: d1a2da10c74b2ff19d5e3add68c8fafa01bc1207 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 16:44:00.096807) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 16:44:00.097109) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 16:44:00.108348) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-09-27 16:44:00.108677) ========= /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=74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:44:00 INFO - MultiFileLogger online at 20160927 16:44:00 in /builds/slave/test 16:44:00 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 16:44:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:44:00 INFO - {'append_to_log': False, 16:44:00 INFO - 'base_work_dir': '/builds/slave/test', 16:44:00 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:44:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:44:00 INFO - 'branch': 'Mozilla-Beta', 16:44:00 INFO - 'buildbot_json_path': 'buildprops.json', 16:44:00 INFO - 'config_files': ('talos/linux_config.py',), 16:44:00 INFO - 'default_actions': ('clobber', 16:44:00 INFO - 'read-buildbot-config', 16:44:00 INFO - 'download-and-extract', 16:44:00 INFO - 'populate-webroot', 16:44:00 INFO - 'create-virtualenv', 16:44:00 INFO - 'install', 16:44:00 INFO - 'run-tests'), 16:44:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:44:00 INFO - 'download_minidump_stackwalk': True, 16:44:00 INFO - 'download_symbols': 'ondemand', 16:44:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:44:00 INFO - 'tooltool.py': '/tools/tooltool.py', 16:44:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:44:00 INFO - '/tools/misc-python/virtualenv.py')}, 16:44:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:44:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:44:00 INFO - 'installer_path': 'installer.exe', 16:44:00 INFO - 'log_level': 'info', 16:44:00 INFO - 'log_name': 'talos', 16:44:00 INFO - 'log_to_console': True, 16:44:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:44:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:44:00 INFO - 'opt_config_files': (), 16:44:00 INFO - 'pip_index': False, 16:44:00 INFO - 'sps_profile': False, 16:44:00 INFO - 'sps_profile_interval': 0, 16:44:00 INFO - 'suite': 'dromaeojs', 16:44:00 INFO - 'system_bits': '32', 16:44:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:44:00 INFO - 'title': 'talos-linux64-ix-042', 16:44:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:44:00 INFO - 'use_talos_json': True, 16:44:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:44:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:44:00 INFO - 'work_dir': 'build'} 16:44:00 INFO - [mozharness: 2016-09-27 23:44:00.964729Z] Running clobber step. 16:44:00 INFO - Running pre-action listener: _resource_record_pre_action 16:44:00 INFO - Running main action method: clobber 16:44:00 INFO - rmtree: /builds/slave/test/build 16:44:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:44:01 INFO - Running post-action listener: _resource_record_post_action 16:44:01 INFO - [mozharness: 2016-09-27 23:44:01.422729Z] Finished clobber step (success) 16:44:01 INFO - [mozharness: 2016-09-27 23:44:01.422834Z] Running read-buildbot-config step. 16:44:01 INFO - Running pre-action listener: _resource_record_pre_action 16:44:01 INFO - Running main action method: read_buildbot_config 16:44:01 INFO - Using buildbot properties: 16:44:01 INFO - { 16:44:01 INFO - "project": "", 16:44:01 INFO - "product": "firefox", 16:44:01 INFO - "script_repo_revision": "production", 16:44:01 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:44:01 INFO - "repository": "", 16:44:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:44:01 INFO - "buildid": "20160927135419", 16:44:01 INFO - "pgo_build": "True", 16:44:01 INFO - "basedir": "/builds/slave/test", 16:44:01 INFO - "buildnumber": 174, 16:44:01 INFO - "slavename": "talos-linux64-ix-042", 16:44:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:44:01 INFO - "platform": "ubuntu64_hw", 16:44:01 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:44:01 INFO - "branch": "mozilla-beta", 16:44:01 INFO - "repo_path": "releases/mozilla-beta", 16:44:01 INFO - "revision": "d1a2da10c74b2ff19d5e3add68c8fafa01bc1207", 16:44:01 INFO - "stage_platform": "linux64-pgo", 16:44:01 INFO - "builduid": "cdd2de4f8dcd4a35b4806d67cceef574", 16:44:01 INFO - "slavebuilddir": "test" 16:44:01 INFO - } 16:44:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 16:44:01 INFO - Running post-action listener: _resource_record_post_action 16:44:01 INFO - [mozharness: 2016-09-27 23:44:01.425291Z] Finished read-buildbot-config step (success) 16:44:01 INFO - [mozharness: 2016-09-27 23:44:01.425380Z] Running download-and-extract step. 16:44:01 INFO - Running pre-action listener: _resource_record_pre_action 16:44:01 INFO - Running main action method: download_and_extract 16:44:01 INFO - mkdir: /builds/slave/test/build/tests 16:44:01 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')]} 16:44:01 INFO - https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:44:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:44:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:44:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:44:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:44:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:44:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:44:01 INFO - retry: Failed, sleeping 30 seconds before retrying 16:44:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:44:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:44:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:44:31 INFO - retry: Failed, sleeping 60 seconds before retrying 16:45:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:45:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:45:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:45:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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! 16:45:31 INFO - Caught exception: HTTP Error 404: Not Found 16:45:31 INFO - Caught exception: HTTP Error 404: Not Found 16:45:31 INFO - Caught exception: HTTP Error 404: Not Found 16:45:31 INFO - trying https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:45:31 INFO - Downloading https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:45:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:45:32 INFO - Downloaded 1327 bytes. 16:45:32 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:45:32 INFO - Using the following test package requirements: 16:45:32 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:45:32 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:45:32 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:45:32 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:45:32 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 16:45:32 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:45:32 INFO - u'jsshell-linux-x86_64.zip'], 16:45:32 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:45:32 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:45:32 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:45:32 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:45:32 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 16:45:32 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:45:32 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 16:45:32 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:45:32 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:45:32 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:45:32 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:45:32 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:32 INFO - https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:45:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:45:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:45:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:45:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:45:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:45:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:45:32 INFO - retry: Failed, sleeping 30 seconds before retrying 16:46:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:46:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:46:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:46:02 INFO - retry: Failed, sleeping 60 seconds before retrying 16:47:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:47:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:47:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:47:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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! 16:47:02 INFO - Caught exception: HTTP Error 404: Not Found 16:47:02 INFO - Caught exception: HTTP Error 404: Not Found 16:47:02 INFO - Caught exception: HTTP Error 404: Not Found 16:47:02 INFO - trying https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:47:02 INFO - Downloading https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:47:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:47:03 INFO - Downloaded 21773398 bytes. 16:47:03 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'] 16:47:03 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 16:47:04 INFO - Return code: 0 16:47:04 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 16:47:04 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')]} 16:47:04 INFO - https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:47:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:47:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:47:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:47:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:47:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:47:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:47:04 INFO - retry: Failed, sleeping 30 seconds before retrying 16:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:47:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:47:34 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:47:34 INFO - retry: Failed, sleeping 60 seconds before retrying 16:48:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:48:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:48:34 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:48:34 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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! 16:48:34 INFO - Caught exception: HTTP Error 404: Not Found 16:48:34 INFO - Caught exception: HTTP Error 404: Not Found 16:48:34 INFO - Caught exception: HTTP Error 404: Not Found 16:48:34 INFO - trying https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:48:34 INFO - Downloading https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:48:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:48:35 INFO - Downloaded 21773398 bytes. 16:48:35 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'] 16:48:35 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 16:48:36 INFO - Return code: 0 16:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:48:36 INFO - https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:48:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:48:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:48:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:48:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:48:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:48:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:48:36 INFO - retry: Failed, sleeping 30 seconds before retrying 16:49:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:49:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:49:07 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:49:07 INFO - retry: Failed, sleeping 60 seconds before retrying 16:50:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:50:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:50:07 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:50:07 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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! 16:50:07 INFO - Caught exception: HTTP Error 404: Not Found 16:50:07 INFO - Caught exception: HTTP Error 404: Not Found 16:50:07 INFO - Caught exception: HTTP Error 404: Not Found 16:50:07 INFO - trying https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:50:07 INFO - Downloading https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:50:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:50:08 INFO - Downloaded 14413740 bytes. 16:50:08 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'] 16:50:08 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 16:50:08 INFO - Return code: 0 16:50: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')]} 16:50:08 INFO - https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:50:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:50:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:50:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:50:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:50:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:50:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:50:08 INFO - retry: Failed, sleeping 30 seconds before retrying 16:50:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:50:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:50:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:50:38 INFO - retry: Failed, sleeping 60 seconds before retrying 16:51:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:51:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:51:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:51:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHOF6WxETJCIWr5J2T8owQ/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! 16:51:38 INFO - Caught exception: HTTP Error 404: Not Found 16:51:38 INFO - Caught exception: HTTP Error 404: Not Found 16:51:38 INFO - Caught exception: HTTP Error 404: Not Found 16:51:38 INFO - trying https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:51:38 INFO - Downloading https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:51:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/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 16:51:39 INFO - Downloaded 56790303 bytes. 16:51:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:51:39 INFO - mkdir: /builds/slave/test/properties 16:51:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:51:39 INFO - Writing to file /builds/slave/test/properties/build_url 16:51:39 INFO - Contents: 16:51:39 INFO - build_url:https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:51:40 INFO - Running post-action listener: _resource_record_post_action 16:51:40 INFO - Running post-action listener: set_extra_try_arguments 16:51:40 INFO - [mozharness: 2016-09-27 23:51:40.373277Z] Finished download-and-extract step (success) 16:51:40 INFO - [mozharness: 2016-09-27 23:51:40.373513Z] Running populate-webroot step. 16:51:40 INFO - Running pre-action listener: _resource_record_pre_action 16:51:40 INFO - Running main action method: populate_webroot 16:51:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:51:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:51:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:51:40 INFO - '--tppagecycles', 16:51:40 INFO - '7'], 16:51:40 INFO - 'tests': ['tsvgm']}}, 16:51:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:51:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:51:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:51:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:51:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:51:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:51:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:40 INFO - 'talos_options': ['--disable-e10s'], 16:51:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:51:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:51:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:40 INFO - 'talos_options': ['--disable-e10s'], 16:51:40 INFO - 'tests': ['damp', 'tps']}, 16:51:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:40 INFO - 'tests': ['damp', 'tps']}, 16:51:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:51:40 INFO - 'tests': ['dromaeo_dom']}, 16:51:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:51:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:51:40 INFO - 'tests': ['basic_compositor_video']}, 16:51:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:51:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:51:40 INFO - 'tests': ['a11yr', 16:51:40 INFO - 'ts_paint', 16:51:40 INFO - 'tpaint', 16:51:40 INFO - 'sessionrestore', 16:51:40 INFO - 'sessionrestore_no_auto_restore', 16:51:40 INFO - 'tabpaint']}, 16:51:40 INFO - 'other-e10s': {'tests': ['a11yr', 16:51:40 INFO - 'ts_paint', 16:51:40 INFO - 'tpaint', 16:51:40 INFO - 'sessionrestore', 16:51:40 INFO - 'sessionrestore_no_auto_restore', 16:51:40 INFO - 'tabpaint']}, 16:51:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:51:40 INFO - 'ts_paint', 16:51:40 INFO - 'tpaint', 16:51:40 INFO - 'sessionrestore', 16:51:40 INFO - 'sessionrestore_no_auto_restore', 16:51:40 INFO - 'tabpaint']}, 16:51:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:51:40 INFO - 'ts_paint', 16:51:40 INFO - 'tpaint', 16:51:40 INFO - 'sessionrestore', 16:51:40 INFO - 'sessionrestore_no_auto_restore', 16:51:40 INFO - 'tabpaint']}, 16:51:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:51:40 INFO - 'tests': ['a11yr', 16:51:40 INFO - 'ts_paint', 16:51:40 INFO - 'tpaint', 16:51:40 INFO - 'sessionrestore', 16:51:40 INFO - 'sessionrestore_no_auto_restore', 16:51:40 INFO - 'tabpaint']}, 16:51:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:51:40 INFO - 'tests': ['a11yr', 16:51:40 INFO - 'ts_paint', 16:51:40 INFO - 'tpaint', 16:51:40 INFO - 'sessionrestore', 16:51:40 INFO - 'sessionrestore_no_auto_restore', 16:51:40 INFO - 'tabpaint']}, 16:51:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:51:40 INFO - 'tests': ['tsvgx', 16:51:40 INFO - 'tsvgr_opacity', 16:51:40 INFO - 'tart', 16:51:40 INFO - 'tscrollx', 16:51:40 INFO - 'cart']}, 16:51:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:51:40 INFO - 'tsvgr_opacity', 16:51:40 INFO - 'tart', 16:51:40 INFO - 'tscrollx', 16:51:40 INFO - 'cart']}, 16:51:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:40 INFO - 'talos_options': ['--disable-e10s'], 16:51:40 INFO - 'tests': ['tp5o']}, 16:51:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:40 INFO - 'tests': ['tp5o']}, 16:51:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:40 INFO - 'talos_options': ['--disable-e10s', 16:51:40 INFO - '--xperf_path', 16:51:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:51:40 INFO - 'tests': ['tp5n']}, 16:51:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:51:40 INFO - 'talos_options': ['--xperf_path', 16:51:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:51:40 INFO - 'tests': ['tp5n']}}, 16:51:40 INFO - 'talos.zip': {'path': '', 16:51:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:51:40 INFO - Running post-action listener: _resource_record_post_action 16:51:40 INFO - [mozharness: 2016-09-27 23:51:40.393219Z] Finished populate-webroot step (success) 16:51:40 INFO - [mozharness: 2016-09-27 23:51:40.393308Z] Running create-virtualenv step. 16:51:40 INFO - Running pre-action listener: _resource_record_pre_action 16:51:40 INFO - Running main action method: create_virtualenv 16:51:40 INFO - Creating virtualenv /builds/slave/test/build/venv 16:51:40 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 16:51:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:51:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:51:40 INFO - Using real prefix '/usr' 16:51:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:51:41 INFO - Installing distribute.............................................................................................................................................................................................done. 16:51:42 INFO - Installing pip.................done. 16:51:42 INFO - Return code: 0 16:51:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:51:42 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')]} 16:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:42 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')]} 16:51:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:42 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 0xe56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1117180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11153b0>, '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 0x11162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1116740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', '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 16:51:42 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 16:51:42 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 16:51:42 INFO - Using env: {'DISPLAY': ':0', 16:51:42 INFO - 'HOME': '/home/cltbld', 16:51:42 INFO - 'LANG': 'en_US.UTF-8', 16:51:42 INFO - 'LANGUAGE': 'en_US:en', 16:51:42 INFO - 'LOGNAME': 'cltbld', 16:51:42 INFO - 'MAIL': '/var/mail/cltbld', 16:51:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:42 INFO - 'MOZ_NO_REMOTE': '1', 16:51:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:42 INFO - 'NO_EM_RESTART': '1', 16:51:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:42 INFO - 'PWD': '/builds/slave/test', 16:51:42 INFO - 'SHELL': '/bin/bash', 16:51:42 INFO - 'SHLVL': '1', 16:51:42 INFO - 'TERM': 'linux', 16:51:42 INFO - 'TMOUT': '86400', 16:51:42 INFO - 'USER': 'cltbld', 16:51:42 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', 16:51:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:42 INFO - '_': '/tools/buildbot/bin/python'} 16:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:43 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 16:51:43 INFO - Cleaning up... 16:51:43 INFO - Return code: 0 16:51:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:43 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 0xe56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1117180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11153b0>, '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 0x11162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1116740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', '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 16:51:43 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 16:51:43 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 16:51:43 INFO - Using env: {'DISPLAY': ':0', 16:51:43 INFO - 'HOME': '/home/cltbld', 16:51:43 INFO - 'LANG': 'en_US.UTF-8', 16:51:43 INFO - 'LANGUAGE': 'en_US:en', 16:51:43 INFO - 'LOGNAME': 'cltbld', 16:51:43 INFO - 'MAIL': '/var/mail/cltbld', 16:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:43 INFO - 'MOZ_NO_REMOTE': '1', 16:51:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:43 INFO - 'NO_EM_RESTART': '1', 16:51:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:43 INFO - 'PWD': '/builds/slave/test', 16:51:43 INFO - 'SHELL': '/bin/bash', 16:51:43 INFO - 'SHLVL': '1', 16:51:43 INFO - 'TERM': 'linux', 16:51:43 INFO - 'TMOUT': '86400', 16:51:43 INFO - 'USER': 'cltbld', 16:51:43 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', 16:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:43 INFO - '_': '/tools/buildbot/bin/python'} 16:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:43 INFO - Downloading/unpacking psutil>=3.1.1 16:51:43 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 16:51:43 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 16:51:43 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 16:51:43 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 16:51:43 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 16:51:43 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 16:51:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:51:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:51:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:51:45 INFO - Installing collected packages: psutil 16:51:45 INFO - Running setup.py install for psutil 16:51:45 INFO - building 'psutil._psutil_linux' extension 16:51:45 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 16:51:45 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 16:51:45 INFO - building 'psutil._psutil_posix' extension 16:51:45 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 16:51:45 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 16:51:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:51:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:51:45 INFO - Successfully installed psutil 16:51:45 INFO - Cleaning up... 16:51:45 INFO - Return code: 0 16:51:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:51:45 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')]} 16:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:45 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')]} 16:51:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:45 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 0xe56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1117180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11153b0>, '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 0x11162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1116740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', '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 16:51:45 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 16:51:45 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 16:51:45 INFO - Using env: {'DISPLAY': ':0', 16:51:45 INFO - 'HOME': '/home/cltbld', 16:51:45 INFO - 'LANG': 'en_US.UTF-8', 16:51:45 INFO - 'LANGUAGE': 'en_US:en', 16:51:45 INFO - 'LOGNAME': 'cltbld', 16:51:45 INFO - 'MAIL': '/var/mail/cltbld', 16:51:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:45 INFO - 'MOZ_NO_REMOTE': '1', 16:51:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:45 INFO - 'NO_EM_RESTART': '1', 16:51:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:45 INFO - 'PWD': '/builds/slave/test', 16:51:45 INFO - 'SHELL': '/bin/bash', 16:51:45 INFO - 'SHLVL': '1', 16:51:45 INFO - 'TERM': 'linux', 16:51:45 INFO - 'TMOUT': '86400', 16:51:45 INFO - 'USER': 'cltbld', 16:51:45 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', 16:51:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:45 INFO - '_': '/tools/buildbot/bin/python'} 16:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:51:46 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 16:51:46 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 16:51:46 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 16:51:46 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 16:51:46 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 16:51:46 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 16:51:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:51:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:51:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:51:48 INFO - Installing collected packages: mozsystemmonitor 16:51:48 INFO - Running setup.py install for mozsystemmonitor 16:51:48 INFO - Successfully installed mozsystemmonitor 16:51:48 INFO - Cleaning up... 16:51:48 INFO - Return code: 0 16:51:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:51:48 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')]} 16:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:48 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')]} 16:51:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1117180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11153b0>, '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 0x11162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1116740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', '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 16:51:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:51:48 INFO - Using env: {'DISPLAY': ':0', 16:51:48 INFO - 'HOME': '/home/cltbld', 16:51:48 INFO - 'LANG': 'en_US.UTF-8', 16:51:48 INFO - 'LANGUAGE': 'en_US:en', 16:51:48 INFO - 'LOGNAME': 'cltbld', 16:51:48 INFO - 'MAIL': '/var/mail/cltbld', 16:51:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:48 INFO - 'MOZ_NO_REMOTE': '1', 16:51:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:48 INFO - 'NO_EM_RESTART': '1', 16:51:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:48 INFO - 'PWD': '/builds/slave/test', 16:51:48 INFO - 'SHELL': '/bin/bash', 16:51:48 INFO - 'SHLVL': '1', 16:51:48 INFO - 'TERM': 'linux', 16:51:48 INFO - 'TMOUT': '86400', 16:51:48 INFO - 'USER': 'cltbld', 16:51:48 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', 16:51:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:48 INFO - '_': '/tools/buildbot/bin/python'} 16:51:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:48 INFO - Downloading/unpacking jsonschema==2.5.1 16:51:48 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 16:51:48 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 16:51:48 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 16:51:48 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 16:51:48 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 16:51:48 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 16:51:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:51:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:51:50 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 16:51:50 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 16:51:50 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 16:51:50 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 16:51:50 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 16:51:50 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 16:51:50 INFO - Downloading functools32-3.2.3-2.tar.gz 16:51:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:51:50 INFO - warning: no files found matching '*.txt' 16:51:50 INFO - no previously-included directories found matching 'build' 16:51:50 INFO - no previously-included directories found matching 'dist' 16:51:50 INFO - no previously-included directories found matching '.git*' 16:51:50 INFO - Installing collected packages: jsonschema, functools32 16:51:50 INFO - Running setup.py install for functools32 16:51:50 INFO - warning: no files found matching '*.txt' 16:51:50 INFO - no previously-included directories found matching 'build' 16:51:50 INFO - no previously-included directories found matching 'dist' 16:51:50 INFO - no previously-included directories found matching '.git*' 16:51:50 INFO - Successfully installed jsonschema functools32 16:51:50 INFO - Cleaning up... 16:51:50 INFO - Return code: 0 16:51:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:51:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:50 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 0xe56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1117180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11153b0>, '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 0x11162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1116740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', '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 16:51:50 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 16:51:50 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 16:51:50 INFO - Using env: {'DISPLAY': ':0', 16:51:50 INFO - 'HOME': '/home/cltbld', 16:51:50 INFO - 'LANG': 'en_US.UTF-8', 16:51:50 INFO - 'LANGUAGE': 'en_US:en', 16:51:50 INFO - 'LOGNAME': 'cltbld', 16:51:50 INFO - 'MAIL': '/var/mail/cltbld', 16:51:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:50 INFO - 'MOZ_NO_REMOTE': '1', 16:51:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:50 INFO - 'NO_EM_RESTART': '1', 16:51:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:50 INFO - 'PWD': '/builds/slave/test', 16:51:50 INFO - 'SHELL': '/bin/bash', 16:51:50 INFO - 'SHLVL': '1', 16:51:50 INFO - 'TERM': 'linux', 16:51:50 INFO - 'TMOUT': '86400', 16:51:50 INFO - 'USER': 'cltbld', 16:51:50 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', 16:51:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:50 INFO - '_': '/tools/buildbot/bin/python'} 16:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:51:51 INFO - Cleaning up... 16:51:51 INFO - Return code: 0 16:51:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:51:51 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')]} 16:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:51 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')]} 16:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:51 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 0xe56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1117180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11153b0>, '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 0x11162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1116740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', '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 16:51:51 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 16:51:51 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 16:51:51 INFO - Using env: {'DISPLAY': ':0', 16:51:51 INFO - 'HOME': '/home/cltbld', 16:51:51 INFO - 'LANG': 'en_US.UTF-8', 16:51:51 INFO - 'LANGUAGE': 'en_US:en', 16:51:51 INFO - 'LOGNAME': 'cltbld', 16:51:51 INFO - 'MAIL': '/var/mail/cltbld', 16:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:51 INFO - 'MOZ_NO_REMOTE': '1', 16:51:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:51 INFO - 'NO_EM_RESTART': '1', 16:51:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:51 INFO - 'PWD': '/builds/slave/test', 16:51:51 INFO - 'SHELL': '/bin/bash', 16:51:51 INFO - 'SHLVL': '1', 16:51:51 INFO - 'TERM': 'linux', 16:51:51 INFO - 'TMOUT': '86400', 16:51:51 INFO - 'USER': 'cltbld', 16:51:51 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', 16:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:51 INFO - '_': '/tools/buildbot/bin/python'} 16:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:51 INFO - Downloading/unpacking blobuploader==1.2.4 16:51:51 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 16:51:51 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 16:51:51 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 16:51:51 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 16:51:51 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 16:51:51 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 16:51:53 INFO - Downloading blobuploader-1.2.4.tar.gz 16:51:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:51:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:51:53 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 16:51:53 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 16:51:53 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 16:51:53 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 16:51:53 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 16:51:53 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 16:51:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:51:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:51:53 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 16:51:53 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 16:51:53 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 16:51:53 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 16:51:53 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 16:51:53 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 16:51:53 INFO - Downloading docopt-0.6.1.tar.gz 16:51:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:51:53 INFO - Installing collected packages: blobuploader, requests, docopt 16:51:53 INFO - Running setup.py install for blobuploader 16:51:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:51:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:51:54 INFO - Running setup.py install for requests 16:51:54 INFO - Running setup.py install for docopt 16:51:54 INFO - Successfully installed blobuploader requests docopt 16:51:54 INFO - Cleaning up... 16:51:54 INFO - Return code: 0 16:51:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:51:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:54 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 0xe56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1117180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11153b0>, '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 0x11162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1116740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', '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 16:51:54 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 16:51:54 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 16:51:54 INFO - Using env: {'DISPLAY': ':0', 16:51:54 INFO - 'HOME': '/home/cltbld', 16:51:54 INFO - 'LANG': 'en_US.UTF-8', 16:51:54 INFO - 'LANGUAGE': 'en_US:en', 16:51:54 INFO - 'LOGNAME': 'cltbld', 16:51:54 INFO - 'MAIL': '/var/mail/cltbld', 16:51:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:54 INFO - 'MOZ_NO_REMOTE': '1', 16:51:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:54 INFO - 'NO_EM_RESTART': '1', 16:51:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:54 INFO - 'PWD': '/builds/slave/test', 16:51:54 INFO - 'SHELL': '/bin/bash', 16:51:54 INFO - 'SHLVL': '1', 16:51:54 INFO - 'TERM': 'linux', 16:51:54 INFO - 'TMOUT': '86400', 16:51:54 INFO - 'USER': 'cltbld', 16:51:54 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', 16:51:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:54 INFO - '_': '/tools/buildbot/bin/python'} 16:51:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:51:54 INFO - Running setup.py (path:/tmp/pip-f6Q5H2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:51:54 INFO - Running setup.py (path:/tmp/pip-F55eJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:51:54 INFO - Running setup.py (path:/tmp/pip-Y9YeFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:51:55 INFO - Running setup.py (path:/tmp/pip-hoYDf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:51:55 INFO - Running setup.py (path:/tmp/pip-KmEeWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:51:55 INFO - Running setup.py (path:/tmp/pip-HGS8gF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:51:55 INFO - Running setup.py (path:/tmp/pip-CXWaKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:51:55 INFO - Running setup.py (path:/tmp/pip-av8dfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:51:55 INFO - Running setup.py (path:/tmp/pip-tnIWT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:51:55 INFO - Running setup.py (path:/tmp/pip-J76gZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:51:55 INFO - Running setup.py (path:/tmp/pip-0S6wJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:51:55 INFO - Running setup.py (path:/tmp/pip-_xN1K2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:51:55 INFO - Running setup.py (path:/tmp/pip-3m5aKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:51:55 INFO - Running setup.py (path:/tmp/pip-DFM1ic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:51:55 INFO - Running setup.py (path:/tmp/pip-HeEJ1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:51:55 INFO - Running setup.py (path:/tmp/pip-dBH8Mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:51:55 INFO - Running setup.py (path:/tmp/pip-IIHMwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:51:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:51:56 INFO - Running setup.py install for manifestparser 16:51:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:51:56 INFO - Running setup.py install for mozcrash 16:51:56 INFO - Running setup.py install for mozdebug 16:51:56 INFO - Running setup.py install for mozdevice 16:51:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:51:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:51:56 INFO - Running setup.py install for mozfile 16:51:56 INFO - Running setup.py install for mozhttpd 16:51:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:51:56 INFO - Running setup.py install for mozinfo 16:51:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:51:56 INFO - Running setup.py install for mozInstall 16:51:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:51:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:51:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:51:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:51:56 INFO - Running setup.py install for mozleak 16:51:56 INFO - Running setup.py install for mozlog 16:51:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:51:57 INFO - Running setup.py install for moznetwork 16:51:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:51:57 INFO - Running setup.py install for mozprocess 16:51:57 INFO - Running setup.py install for mozprofile 16:51:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:51:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:51:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:51:57 INFO - Running setup.py install for mozrunner 16:51:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:51:57 INFO - Running setup.py install for mozscreenshot 16:51:57 INFO - Running setup.py install for moztest 16:51:57 INFO - Running setup.py install for mozversion 16:51:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:51:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:51:57 INFO - Cleaning up... 16:51:57 INFO - Return code: 0 16:51:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:51: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')]} 16:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:51:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51: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')]} 16:51:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:51:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:51:57 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 0xe56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1117180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11153b0>, '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 0x11162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1116740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', '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 16:51:57 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 16:51:57 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 16:51:57 INFO - Using env: {'DISPLAY': ':0', 16:51:57 INFO - 'HOME': '/home/cltbld', 16:51:57 INFO - 'LANG': 'en_US.UTF-8', 16:51:57 INFO - 'LANGUAGE': 'en_US:en', 16:51:57 INFO - 'LOGNAME': 'cltbld', 16:51:57 INFO - 'MAIL': '/var/mail/cltbld', 16:51:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:51:57 INFO - 'MOZ_NO_REMOTE': '1', 16:51:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:51:57 INFO - 'NO_EM_RESTART': '1', 16:51:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:51:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:51:57 INFO - 'PWD': '/builds/slave/test', 16:51:57 INFO - 'SHELL': '/bin/bash', 16:51:57 INFO - 'SHLVL': '1', 16:51:57 INFO - 'TERM': 'linux', 16:51:57 INFO - 'TMOUT': '86400', 16:51:57 INFO - 'USER': 'cltbld', 16:51:57 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', 16:51:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:51:57 INFO - '_': '/tools/buildbot/bin/python'} 16:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:51:58 INFO - Running setup.py (path:/tmp/pip-GoHuut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:51:58 INFO - Running setup.py (path:/tmp/pip-j4TYEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:51:58 INFO - Running setup.py (path:/tmp/pip-_1S274-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:51:58 INFO - Running setup.py (path:/tmp/pip-yeZUNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:51:58 INFO - Running setup.py (path:/tmp/pip-ciPG14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:51:58 INFO - Running setup.py (path:/tmp/pip-iMqW0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:51:58 INFO - Running setup.py (path:/tmp/pip-13_mC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:51:58 INFO - Running setup.py (path:/tmp/pip-XJUnOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:51:58 INFO - Running setup.py (path:/tmp/pip-bkOwiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:51:58 INFO - Running setup.py (path:/tmp/pip-Oie0V7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:51:58 INFO - Running setup.py (path:/tmp/pip-ew7bTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:51:58 INFO - Running setup.py (path:/tmp/pip-qQPZjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:51:58 INFO - Running setup.py (path:/tmp/pip-5Xxo02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:51:58 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)) 16:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:51:58 INFO - Running setup.py (path:/tmp/pip-VJuF1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:51:59 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)) 16:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:51:59 INFO - Running setup.py (path:/tmp/pip-XnTueY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:51:59 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)) 16:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:51:59 INFO - Running setup.py (path:/tmp/pip-WMdIzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:51:59 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)) 16:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:51:59 INFO - Running setup.py (path:/tmp/pip-9QK40H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:51:59 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)) 16:51:59 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)) 16:51:59 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)) 16:51:59 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)) 16:51:59 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)) 16:51:59 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)) 16:51:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:51: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 16:51: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 16:51: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 16:51: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 16:51: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 16:51: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 16:52:01 INFO - Downloading blessings-1.6.tar.gz 16:52:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:52:01 INFO - Installing collected packages: blessings 16:52:01 INFO - Running setup.py install for blessings 16:52:01 INFO - Successfully installed blessings 16:52:01 INFO - Cleaning up... 16:52:01 INFO - Return code: 0 16:52:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:52:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:52:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:52:01 INFO - Reading from file tmpfile_stdout 16:52:01 INFO - Current package versions: 16:52:01 INFO - argparse == 1.2.1 16:52:01 INFO - blessings == 1.6 16:52:01 INFO - blobuploader == 1.2.4 16:52:01 INFO - docopt == 0.6.1 16:52:01 INFO - functools32 == 3.2.3-2 16:52:01 INFO - jsonschema == 2.5.1 16:52:01 INFO - manifestparser == 1.1 16:52:01 INFO - mozInstall == 1.12 16:52:01 INFO - mozcrash == 0.17 16:52:01 INFO - mozdebug == 0.1 16:52:01 INFO - mozdevice == 0.48 16:52:01 INFO - mozfile == 1.2 16:52:01 INFO - mozhttpd == 0.7 16:52:01 INFO - mozinfo == 0.9 16:52:01 INFO - mozleak == 0.1 16:52:01 INFO - mozlog == 3.3 16:52:01 INFO - moznetwork == 0.27 16:52:01 INFO - mozprocess == 0.23 16:52:01 INFO - mozprofile == 0.28 16:52:01 INFO - mozrunner == 6.12 16:52:01 INFO - mozscreenshot == 0.1 16:52:01 INFO - mozsystemmonitor == 0.3 16:52:01 INFO - moztest == 0.7 16:52:01 INFO - mozversion == 1.4 16:52:01 INFO - psutil == 3.1.1 16:52:01 INFO - requests == 1.2.3 16:52:01 INFO - wsgiref == 0.1.2 16:52:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:52:01 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')]} 16:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:01 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')]} 16:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:01 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 0xe56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1117180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11153b0>, '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 0x11162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1116740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', '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 16:52:01 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 16:52:01 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 16:52:01 INFO - Using env: {'DISPLAY': ':0', 16:52:01 INFO - 'HOME': '/home/cltbld', 16:52:01 INFO - 'LANG': 'en_US.UTF-8', 16:52:01 INFO - 'LANGUAGE': 'en_US:en', 16:52:01 INFO - 'LOGNAME': 'cltbld', 16:52:01 INFO - 'MAIL': '/var/mail/cltbld', 16:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:01 INFO - 'MOZ_NO_REMOTE': '1', 16:52:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:52:01 INFO - 'NO_EM_RESTART': '1', 16:52:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:52:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:01 INFO - 'PWD': '/builds/slave/test', 16:52:01 INFO - 'SHELL': '/bin/bash', 16:52:01 INFO - 'SHLVL': '1', 16:52:01 INFO - 'TERM': 'linux', 16:52:01 INFO - 'TMOUT': '86400', 16:52:01 INFO - 'USER': 'cltbld', 16:52:01 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', 16:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:01 INFO - '_': '/tools/buildbot/bin/python'} 16:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:01 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)) 16:52:01 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)) 16:52:01 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)) 16:52:01 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)) 16:52:01 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)) 16:52:01 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)) 16:52:01 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)) 16:52:01 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)) 16:52:01 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)) 16:52:01 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)) 16:52:01 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)) 16:52:01 INFO - Cleaning up... 16:52:01 INFO - Return code: 0 16:52:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:52:01 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')]} 16:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:01 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')]} 16:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:01 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 0xe56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1117180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11153b0>, '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 0x11162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1116740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', '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 16:52:01 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 16:52:01 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 16:52:01 INFO - Using env: {'DISPLAY': ':0', 16:52:01 INFO - 'HOME': '/home/cltbld', 16:52:01 INFO - 'LANG': 'en_US.UTF-8', 16:52:01 INFO - 'LANGUAGE': 'en_US:en', 16:52:01 INFO - 'LOGNAME': 'cltbld', 16:52:01 INFO - 'MAIL': '/var/mail/cltbld', 16:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:01 INFO - 'MOZ_NO_REMOTE': '1', 16:52:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:52:01 INFO - 'NO_EM_RESTART': '1', 16:52:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:52:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:01 INFO - 'PWD': '/builds/slave/test', 16:52:01 INFO - 'SHELL': '/bin/bash', 16:52:01 INFO - 'SHLVL': '1', 16:52:01 INFO - 'TERM': 'linux', 16:52:01 INFO - 'TMOUT': '86400', 16:52:01 INFO - 'USER': 'cltbld', 16:52:01 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', 16:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:01 INFO - '_': '/tools/buildbot/bin/python'} 16:52:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:52:02 INFO - Cleaning up... 16:52:02 INFO - Return code: 0 16:52:02 INFO - Running post-action listener: _resource_record_post_action 16:52:02 INFO - Running post-action listener: _start_resource_monitoring 16:52:02 INFO - Starting resource monitoring. 16:52:02 INFO - [mozharness: 2016-09-27 23:52:02.105680Z] Finished create-virtualenv step (success) 16:52:02 INFO - [mozharness: 2016-09-27 23:52:02.106158Z] Running install step. 16:52:02 INFO - Running pre-action listener: _resource_record_pre_action 16:52:02 INFO - Running main action method: install 16:52:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:52:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:52:02 INFO - Reading from file tmpfile_stdout 16:52:02 INFO - Detecting whether we're running mozinstall >=1.0... 16:52:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:52:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:52:02 INFO - Reading from file tmpfile_stdout 16:52:02 INFO - Output received: 16:52:02 INFO - Usage: mozinstall [options] installer 16:52:02 INFO - Options: 16:52:02 INFO - -h, --help show this help message and exit 16:52:02 INFO - -d DEST, --destination=DEST 16:52:02 INFO - Directory to install application into. [default: 16:52:02 INFO - "/builds/slave/test"] 16:52:02 INFO - --app=APP Application being installed. [default: firefox] 16:52:02 INFO - mkdir: /builds/slave/test/build/application 16:52:02 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'] 16:52:02 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 16:52:15 INFO - Reading from file tmpfile_stdout 16:52:15 INFO - Output received: 16:52:15 INFO - /builds/slave/test/build/application/firefox/firefox 16:52:15 INFO - Running post-action listener: _resource_record_post_action 16:52:15 INFO - [mozharness: 2016-09-27 23:52:15.905374Z] Finished install step (success) 16:52:15 INFO - [mozharness: 2016-09-27 23:52:15.905654Z] Running run-tests step. 16:52:15 INFO - Running pre-action listener: _resource_record_pre_action 16:52:15 INFO - Running main action method: run_tests 16:52:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:52:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:52:15 INFO - Python 2.7.3 16:52:15 INFO - Return code: 0 16:52:15 INFO - grabbing minidump binary from tooltool 16:52:15 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')]} 16:52:15 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 0x11153b0>, '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 0x11162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1116740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:52:15 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 16:52:15 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 16:52:15 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 16:52:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:52:15 INFO - Return code: 0 16:52:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:52:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:52:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:52:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:52:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:52:15 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/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 16:52:15 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/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --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 16:52:15 INFO - Using env: {'DISPLAY': ':0', 16:52:15 INFO - 'HOME': '/home/cltbld', 16:52:15 INFO - 'LANG': 'en_US.UTF-8', 16:52:15 INFO - 'LANGUAGE': 'en_US:en', 16:52:15 INFO - 'LOGNAME': 'cltbld', 16:52:15 INFO - 'MAIL': '/var/mail/cltbld', 16:52:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:52:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:52:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:15 INFO - 'MOZ_NO_REMOTE': '1', 16:52:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:52:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:52:15 INFO - 'NO_EM_RESTART': '1', 16:52:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:52:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:15 INFO - 'PWD': '/builds/slave/test', 16:52:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:52:15 INFO - 'SHELL': '/bin/bash', 16:52:15 INFO - 'SHLVL': '1', 16:52:15 INFO - 'TERM': 'linux', 16:52:15 INFO - 'TMOUT': '86400', 16:52:15 INFO - 'USER': 'cltbld', 16:52:15 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340', 16:52:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:15 INFO - '_': '/tools/buildbot/bin/python'} 16:52:15 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/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 16:52:16 INFO - mozversion application_buildid: 20160927135419 16:52:16 INFO - mozversion application_changeset: d1a2da10c74b2ff19d5e3add68c8fafa01bc1207 16:52:16 INFO - mozversion application_display_name: Firefox 16:52:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:52:16 INFO - mozversion application_name: Firefox 16:52:16 INFO - mozversion application_remotingname: firefox 16:52:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:52:16 INFO - mozversion application_vendor: Mozilla 16:52:16 INFO - mozversion application_version: 50.0 16:52:16 INFO - mozversion platform_buildid: 20160927135419 16:52:16 INFO - mozversion platform_changeset: d1a2da10c74b2ff19d5e3add68c8fafa01bc1207 16:52:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:52:16 INFO - mozversion platform_version: 50.0 16:52:16 INFO - using testdate: 1475020336 16:52:16 INFO - actual date: 1475020336 16:52:16 INFO - starting webserver on 'localhost:41607' 16:52:16 INFO - SUITE-START | Running 2 tests 16:52:16 INFO - TEST-START | dromaeo_css 16:52:16 INFO - operating with platform_type : linux_ 16:52:16 INFO - Initialising browser for dromaeo_css test... 16:52:16 INFO - TEST-INFO | started process 9838 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy3Bh6l/profile http://localhost:41607/getInfo.html) 16:52:20 INFO - PROCESS | 9838 | __metrics Screen width/height:1600/1200 16:52:20 INFO - PROCESS | 9838 | colorDepth:24 16:52:20 INFO - PROCESS | 9838 | Browser inner width/height: 1024/697 16:52:20 INFO - PROCESS | 9838 | __metrics 16:52:20 INFO - PROCESS | 9838 | 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" 16:52:20 INFO - PROCESS | 9838 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:52:21 INFO - PROCESS | 9838 | console.error: PushService: 16:52:21 INFO - PROCESS | 9838 | stateChangeProcessEnqueue: Error transitioning state 16:52:21 INFO - PROCESS | 9838 | UnknownError 16:52:21 INFO - TEST-INFO | 9838: exit 0 16:52:21 INFO - Browser initialized. 16:52:21 INFO - Running cycle 1/1 for dromaeo_css test... 16:52:21 INFO - TEST-INFO | started process 9925 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy3Bh6l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:52:33 INFO - PROCESS | 9925 | RSS: Main: 143593472 16:52:33 INFO - PROCESS | 9925 | 16:53:25 INFO - PROCESS | 9925 | 1475020405329 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] 16:55:32 INFO - PROCESS | 9925 | Cycle 1(1): loaded http://localhost:41607/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41607/tests/dromaeo/cssquery-ext.html) 16:55:34 INFO - PROCESS | 9925 | RSS: Main: 223911936 16:55:34 INFO - PROCESS | 9925 | 16:58:36 INFO - PROCESS | 9925 | Cycle 1(1): loaded http://localhost:41607/tests/dromaeo/cssquery-ext.html (next: http://localhost:41607/tests/dromaeo/cssquery-jquery.html) 16:58:37 INFO - PROCESS | 9925 | RSS: Main: 237682688 16:58:37 INFO - PROCESS | 9925 | 17:01:38 INFO - PROCESS | 9925 | Cycle 1(1): loaded http://localhost:41607/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41607/tests/dromaeo/cssquery-mootools.html) 17:01:39 INFO - PROCESS | 9925 | RSS: Main: 230862848 17:01:39 INFO - PROCESS | 9925 | 17:04:41 INFO - PROCESS | 9925 | Cycle 1(1): loaded http://localhost:41607/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41607/tests/dromaeo/cssquery-prototype.html) 17:04:41 INFO - PROCESS | 9925 | RSS: Main: 176136192 17:04:41 INFO - PROCESS | 9925 | 17:07:44 INFO - PROCESS | 9925 | Cycle 1(1): loaded http://localhost:41607/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41607/tests/dromaeo/cssquery-yui.html) 17:07:45 INFO - PROCESS | 9925 | RSS: Main: 175919104 17:07:45 INFO - PROCESS | 9925 | 17:10:47 INFO - PROCESS | 9925 | Cycle 1(1): loaded http://localhost:41607/tests/dromaeo/cssquery-yui.html (next: http://localhost:41607/tests/dromaeo/cssquery-dojo.html) 17:10:47 INFO - PROCESS | 9925 | RSS: Main: 264642560 17:10:47 INFO - PROCESS | 9925 | 17:10:47 INFO - PROCESS | 9925 | __start_tp_report 17:10:47 INFO - PROCESS | 9925 | _x_x_mozilla_page_load 17:10:47 INFO - PROCESS | 9925 | _x_x_mozilla_page_load_details 17:10:47 INFO - PROCESS | 9925 | |i|pagename|runs| 17:10:47 INFO - PROCESS | 9925 | |0;dojo.html;3183.4428974929383;3192.4572822620153;3205.951910721338;3223.967760322396;3239.56265904103;5480.424555421678;5541.639793413436;5550.389457159709;5555.916661250085;5579.38626751057;5547.44525547445;5591.720413979295;5592.552595792337;5636.4927156555905;5640.407757185498;4962.528559786814;4989.376327959005;5077.619178561614;5097.133487307166;5172.793088276464;5028.949710502903;5117.411497677759;5124.461931497195;5124.871878203056;5129.12804823181;4953.257011448293;4980.701157930518;5002.1496345621335;5008.198688209887;5039.017391608638;5080.517350851663;5108.540231379176;5108.923366149511;5110.770015349318;5134.974325128369;4769.856904292842;4918.950810491885;4979.775910084055;5019.347484826949;5046.419661738873;5489.972550137264;5512.228288039678;5513.9448605514235;5515.641483303572;5538.446155384469;5992.520598352121;5992.550558708114;5993.790217342372;5997.760089596411;6003.27960644721;5914.852128696774;5958.656459616504;5973.581849270553;6027.246594175728;6033.245844269466;5584;5609.635373700738;5620.213170156181;5660.433956604348;5670.886582268332;5863.413658634103;6148.631082135086;6149.354317796657;6158.507319414436;6162.106494558354;5850.268716410449;5883.0881213411985;5886.676232807236;6000.519958403317;6000.579959402888;6053.3946605339115;6053.969730151321;6054.697265136726;6055.636661800306;6060.727267272895;5776.480116789509;6022.126791615191;6025.879482410327;6029.246344206974;6038.637681739022;5878.11828225761;5929.644221346733;5938.376470470625;5982.371850955587;6019.759209631566;4586.4725556561425;4587.770611469413;4591.724496530219;4592.517785632461;4616.215243408628;4019.4774679291504;4120.896977575585;4139.710220284612;4143.19207754485;4154.086101057763;5623.325200975909;5630.915536266959;5667.489925906688;5691.3170419549915;5705.315362156568;5977.402259774075;6008.609440386361;6016.067509536029;6016.789412370546;6025.15647809307;5501.367342755554;5709.914351284734;5716.914246286309;5723.828285151452;5725.713714314267;5039.092963266574;5091.618128640367;5097.46476619957;5123.846284611468;5136.4606716295;6282.120503129566;6287.465565426898;6292;6294.748210071546;6310.337414571496;5798.217240672624;6074.696265186634;6098.664573448503;6108.205933228652;6109.6639684817765;6120.632762034292;6131.040261761288;6136.917362303726;6184.5980011299125;6218.347073557211;5885.087811389241;5889.676067816311;5889.94110058894;5894.440028197314;5911.970440147772;5870.970645146747;5941.554383421347;6004.1294012368835;6015.1278064681255;6018.518518518596;5719.456651618089;5772.105323674838;5775.653460792366;5817.738201780845;5857.7949771757785;5799.449052340021;5801.071828507418;5812.21535092757;5812.360640329645;5812.94187058124;5672.801451949162;5788.363280039276;5794.739236734273;5796;5809.535237180931;5848.707564621837;5878.911816322673;5903.144044113666;5996.640201587918;6000.87998240024;5799.188113664006;5814.709264536672;5819.214406055128;5820.970895145667;5850.824475265749 17:10:47 INFO - PROCESS | 9925 | |1;ext.html;593.7298529169228;623.6071275096689;625.7090452939385;652.8824811533922;657.0079180437538;34836;38774.418383724194;38783.61216387835;38786.80606596963;38787;38904.22191556174;39618.20763584726;47853.76073119638;48768.26847597289;48868.51131488684;9276.304277179222;9392.530373481333;9400.482973436458;9407.764805879839;9412.952935235315;9235.492047937361;9341.95329023354;9342.813143737121;9344.345895787297;9347.6728314509;8679.652813887436;11278.323300601991;11492.425378731072;11524.769504609903;11899.58351457699;11334.546618135264;11401.600943967005;11421.086313094933;11427.885721142766;11432.256903301259;11171.49728262222;11222.102231821516;11247;11255.493502792395;11255.831162532568;45719.771401143116;45997.310040349425;46025.30962035572;46042.76978615119;46066.53933460656;4041.959580404188;4142.1922725068625;4263.509696384925;4415.977920110379;4755.548222918817;31929;32307.19232019195;36404.536975150375;36565.451518227244;36633.63366366329;4325.82696692132;4348.130373925196;4376.759278239696;4448.154850578412;4629.837955671535;360431;362079;362406;362423;367540;38901;58936.70531647314;59209.70395147997;59284;59337.406625934054;41647;41826.37260441096;41979.58020419818;42036.57963420388;42083.15833683309;17830.643387132186;17954.730679039825;17955.192016359295;17961.820381796275;17979.460616181535;15224.391024358902;15304.923475382551;15307.770383444256;15311.770323445156;15312.846871531365;8846.911530884738;9310.581023853943;9549.904500955041;9566.66516671913;9622.13400793932;3544.9822750886083;3573.714102871764;3575.3921833288405;3639.29033838399;3660.3411385950785;1051.952662130206;1128.1707944660668;1135.795556799784;1202.1044321980005;1233.2908577567935;35824.462633060524;35839.82080089583;36094.27811443756;36255;36410;1154.1574650505179;1238.158052524292;1241.8323526324011;1278.6291975327238;1309.5220244610648;17776;18784.906075469535;18842.71735923962;18952.81047189538;18985.33551325697;617370;636509;645751;645914;1004293;59673.10490342648;63673.36326636767;67335.66332168308;67899.3210067893;68072;1443.141330908108;1469.434267806902;1475.6310922269433;1486.0934829754058;1500.48983345663;33683.326333473684;35549.644503554635;35753.64246357503;35896.820515897256;35958.46062309015;56099;57238.14142787945;57429.138562922424;57459.42540574541;57537.13694294505;506676;512524;526200;541287;547252;48141.27788083111;51726.48273517217;51746.48253517417;52091.2186317198;52146.47853521416;56442;56645.71677141588;56926.43073569211;56941;57562;46735.29897051615;48644.513554863996;48917;49079.26381104358;49526;48880.51119488759;49578.50421495883;49849.50150498449;50030;50569.98860022705;46569.53430465652;47157.76421117873;47439.762801185774;48623.5137648619;48737.26894096662;40788.388174178006;42329.78835105805;42982.92542686333;45355.773221133684;46044.76977615224;31635.953515409245;31672.208194795316;31872.36255274928;31999;32084.67915320817 17:10:47 INFO - PROCESS | 9925 | |2;jquery.html;406.4614385938632;412.4411422522482;422.47612959929677;432.9848455304065;434.23141040358564;20474.89762551187;20767.584648307045;21296.041678124482;21643.891780541115;21822.345329640135;20296.492587685316;20696.586068278626;20944.37166884996;21102.261420850275;21103.788962110375;5843.47408733215;6121.387861213865;6127.540434467411;6128.448439640431;6140.478059364959;5909.704514774266;5938.832326200927;5993.85015374617;6010.8497287567725;6015.75936962521;5958.914693904002;6003.369646187153;6012.3085845127935;6015.669138197398;6022.488088512482;5880.353161152269;5957.374475680056;5986.281646202484;5991.999999999978;5997.190379298782;5340.706261155635;5378.300820893259;5382.757775900055;5384.0847056000575;5384.138537833927;24096.397590060213;24100.879495602585;24316;24348.513029739483;24535.509289814105;2488.8880000400027;2535;2547.6688030556093;2579.587266037425;2612.5166844133896;14793.96442249043;16504.917475412545;16714.247858846382;16766.497005089866;17300.913495432567;2170.848040637156;2212.214663794346;2216.357256395643;2217.0466699319286;2354.223106374909;93482;97457.51271243599;100842;103055.48472257666;103479;18658.34695785641;18859.35968964346;19279.4216173515;19488;19733.309334173235;34560;34763.478547821294;34928;35034.12414689653;35139.82430087833;10601.787964240671;10725.83911241332;10753.677389678322;10791.460426978776;11085.224034317682;9012.684556040638;9027.864582031274;9045.366824322235;9236.399634023766;9329.533523323942;9347;9696.0788725071;9819.705408837746;9837.80324393508;9949.855766586936;3333.966660333414;3418.5555877735737;3557.7509574330065;3806.4290356446754;3844.884653460401;491.03511599706746;943.8395472769646;1043.9895601043893;1068.625980906692;1112.799696054718;12027.278363298055;12756.93621531905;12796.232226066466;12800.10399272042;13036;1010.434156872154;1023.3348323589578;1031.0720351683394;1064.1486810551528;1098.9175811814146;11108.222424430212;11130.944345278222;11317.377544234982;11340.092792576574;12036.759264814655;491190;507368;509358;510870;514534;59607.40392596105;60355.396446034974;60433;92613.5369323149;98163;1054.5781687324977;1059.6503153959175;1080.9405482698369;1083.3337497439018;1109.678193323943;13006.934965325112;17384;17684.823151768833;18949.43151705451;19076.141573628942;44120;44265.77867110644;44719;44829;45409;54614;55387.44612553823;59310.40689593222;59783.70108149431;60402.69798651154;5681.0910254359105;6191.9690401547705;8941.061188575106;9141.708984765612;9312;11849.348285844362;14111.647208819862;14232;14237.430502779773;14262.928685356506;11765.352905590275;11946.163768536118;11966.521339146337;12372.81440778371;12887.517935437545;11606.419679016184;11651.76696466049;11706.88293117092;11713.88286117162;12005.279683219034;12047.638570842888;12161.115027170803;12422.440990155284;12622.810657839957;12674.873251267369;9118.270538357048;9253.814923701622;9312.534373281173;9354.625814967325;9405.43567385959;11084.833727493933;12112;12639;12743.426545805276;12869.485220591072 17:10:47 INFO - PROCESS | 9925 | |3;mootools.html;1576.5979675182828;1599.7520384340426;1788.40982475783;1831.7618709567755;1886.76415448069;9151.954240228797;9836.06557377048;9840.901590984087;9853.408795472276;9854.852177217348;8351.37364697647;9092.090790920905;9375.43747375158;9393.76515587109;9661;2515.7358477359935;2536.4293034067373;2539.873006349684;2539.949201015988;2542.1737935171145;2405.194259922928;2514.798816094708;2516.169664010872;2520.4707011527603;2530.3168144600945;2502.1117503286346;2513.7611926866916;2518.9874050629815;2530.607755797854;2532.645429639852;2428.1501474483875;2434.659147719312;2441.8412803167826;2500.5124000819847;2504.536660717773;2418.9274321770376;2447.5349683560066;2453.6319552067152;2456.6437866509254;2483.52812965536;9612.471314077722;9627.3260871739;9666.516674166334;9754.317197796157;9757.170640495566;5613.101903695389;5759.827205183851;5764.481196692276;5768.74040668171;5771.365149833515;3476.4959080933377;3568.4290513517712;3639.5450568678916;3654.7076233901225;3660.4875317455576;1161.8896204860525;1217.4643157010887;1241.6213055018216;1261.161327717067;1270.6950331920366;13755;13801.930990344985;13823.377947992365;13828.03203775736;13834.584962451143;4833.516648335209;4904.828331008398;5090.41460232078;5260.579153667697;5301.098813201765;10859.674209773719;10899.945500272448;10931.890681093246;10939.343639381503;10954.671359859218;5762.567807414461;5764.74058667361;5770.5095066919785;5775.104858746901;5776.624519406225;4268.573142685706;4673.369095172108;5000.4999500050435;5138.665986710852;5384.6769193848495;4107.740742591563;4108.815103320358;4110.856120035784;4144.668426525871;4188.99464128613;1202.4108186988312;1202.7353982123918;1213.1265488848014;1214.6841821126393;1227.8403807504967;933.1508327422014;1000.4047591682937;1005.607812952949;1007.9697609071593;1023.0383439566932;6185.103160041769;6196.287426945869;6302.432781049636;6333.240011198685;6574.309697481791;490.82821012645286;499.12652857499376;500.07486150621787;501.74160307441696;516.0736478021885;4227.894302642397;4364.367166760865;4441;4455.353973673799;4473.709208901476;11840.348780816965;12247.448864800926;12327.137100402886;12328.137030407965;12365.505379784709;11369.147313951322;11767.352795596153;11845.111616628787;12200.816987745191;12207.816882746765;8605.268552173135;8783.385163038654;8794.560271986504;8824.073472285447;8854.64581416749;6275.937240627718;6484.967575162094;6516.869662606627;6580.868382632416;6679.098321726505;10889.945550272198;11068.612598559012;11077.279976801483;11202.21584489078;11456.255343402652;10137.89862101399;10815;11468.770624587294;11592.420378980849;11653.708657283296;8054.194580541899;8229.135940726377;8696.304295656206;8733.475991440533;8744.78138046554;9355.15803577654;9580.66467673628;9608.95195524018;9610.903890961;9665;8486.151384861465;8563.229309362187;8564.357673174662;8660.566971651266;8667.263282621048;8122.458313861489;8156.265936065783;8281.29608983243;8333.708320208763;8675.652973880975;7800.84398312042;7804.84390312202;7813.218678132142;7818.6481608328895;7870.685172593032;5334.733263336895;5358;5397.082495975772;5516.255305533862;5519.172124181405;5438.782448702008;5486.588505862156;5514.862128446821;5537.197106419627;5543.695096769717 17:10:47 INFO - PROCESS | 9925 | |4;prototype.html;356.10439744043737;356.189668504153;359.3298498300669;360.29022825034673;364.47697554009983;5627.184058311553;5636.830895073149;5667.829965101043;5676.176954341623;5682.346530149034;5568.443155684429;5580.776768929238;5581.548797312697;5605.299337582802;5611.691356975365;4202.957970420302;4322.13557288542;4369.541198174194;4383.953152558973;4391.143726973241;4349.1947693737675;4357.564243575633;4368;4376.365427013081;4404.854737768172;4337.54455782143;4347.130573885235;4350.390945267665;4355.586219309161;4396.758178300192;4110.753354798707;4321.351797230441;4337.436133302651;4343.9348409773875;4397.824087036514;3817.351050321452;3817.9618203817886;3826.617338266179;3837;3845.8653947111716;5492.972535137299;5504.146857237135;5507.9449205507835;5508.614396992212;5538.169274608841;4859.611231101539;4914.238293064581;5011.448740638563;5031.270465782441;5035.295058691786;5744.167095771089;5752.741126649311;5763.6541807491685;5775.104858746859;5821.943479673163;4211.452511173558;4225.366195070733;4234.745915245096;4236.85171019016;4264.914701705949;5763.855903602402;5776.191333213354;5853.853653658607;6045.244344456943;6111.236095488064;4239.554846741109;4248.63886569645;4251;4266.530681625019;4282.507511636201;4343.67422443318;4344.066025804404;4358.542353052884;4358.760268185217;4360.084382279693;4797.409398924611;4869.829555965524;4937;4948.183549714328;4960.776765045582;5760.399750156153;5761.366249712528;5771;5801.593888427854;5815.82552523425;1484.1021182184838;1489.5233525271808;1498.2808252038865;1503.2183264702294;1506;928.1878356438117;970.5001924009141;970.5729479029204;979;982.1455333859587;4991.800327986913;5013.1227035268685;5018.146915024455;5018.197088465828;5029.220470827027;5220.608454365862;5220.765065571983;5251.872991438474;5254;5265.262863199156;4962.005585503632;5002.824901128516;5032;5279.260903473517;5697.287839020122;3154.558361829346;3187.5696780934277;3195.344954284366;3196.360727854438;3206.374756922378;6041.2750469943885;6219.47134493564;6235.282942461675;6236.282827474808;6236.908541005306;6121.265448146251;6124.938750612526;6138.447539721446;6147.969260153671;6148.385161483905;6162;6179.084483810033;6198.845028874224;6205.307841834101;6259.2488901333045;5830.416958304136;5999.250093738283;6008.369121242382;6027.849303767265;6034.185384973148;5931.733072011683;5949.1076338549565;5950.910736339045;5954.880902381841;5961.165436838759;5684.346300175533;5871.500922421642;6014.879702405839;6025.728842202199;6180.598261113013;4341.522432532356;4366.890827729332;4374;4394.824207031683;4397.384366188672;5749.626274292157;5951.672658003678;5989.341172471112;5991.940080599313;5995.280566332068;5716.79991200306;5723.971380143239;5730.942690573041;5749.9712501437225;5756.597038207286;5767.029979560613;5770.711464426678;5772.942270577409;5777.45114214149;5783.710814459344;5554.50009499147;5710.230538189058;5757.165211044459;5805.332386775575;5865.472107510344;5916.491294719819;5940.227770389822;5949.286085669747;5975.37258587842;5976.581639285208;5565.749541270571;5756.568257380797;5770.307563092456;5834.329052159056;5838.153467747238 17:10:47 INFO - PROCESS | 9925 | |5;yui.html;751.8421131562371;775.0389516998923;835.4235577451556;840.0549381945311;842.5618678287287;16542.586435339123;17792.377266795665;17799.82200177998;17805.109744512756;17839.286428542877;16409.917950410232;16849.494515164533;17026.233819478122;17766.378176763817;17776.64446711065;742.283696233135;887.4497811356968;906.4063038709622;909.2998391238775;909.8498747706653;845.898492180936;883.9955800221022;887.5207388010466;888.1296329596998;888.3781353052857;950.619752099159;953.1183655118987;953.1755031897394;954.5942974235921;954.8138113067954;946.1153821177221;948.5162567090764;948.6821914658598;949.3022628368144;949.3117489819859;691.1878542712293;691.7890043536719;692.0553444548156;692.6363659078974;693.2270518372029;16417.58956026097;16427.178641068018;16433.671326573403;16449.6710065798;16450.259738311805;14699.26503674812;15341.309641066178;15372.461963831212;15400;15743.606409839731;13388.732225355438;13526.729465410735;13565.118267312593;13906.096103753325;14279.500217492337;4075.4498142750645;4079.714419990602;4131.483564554431;4181.226473102486;4212.031232816439;94233.52883235608;94965.5251723737;94979.52510237404;95274.80713753584;98508.99999999929;11650.708732281591;11923.124612523248;12052.578159764365;12406.81389779136;12672.556460523838;24215.878920605635;24367.878160609085;24445.877770611034;24494.877525612614;24537.754622453904;9230.53847307632;9230.723078307661;9292.53537323131;9353.392029518196;9402.294827887936;5063.11395505785;5141.948580514222;5165.9483405165465;5348.1442969124755;5377.059014672417;4045.8179381927885;4143.482064741907;4175.16496700655;4177.582241775798;4179.582041795796;733.4719002318323;757.3978686943893;763.4007304266124;763.8319569694659;775.824625692077;636.4081404293959;647.9870402591922;690.1580072311772;712.2307907459931;730.9561426314439;11074.892510749021;11130;11199.272047316897;11707.180497365272;11953.342566158944;780.7930898311952;801.0867610923552;809.1382677448498;819.5082950229934;850.3154960257052;4882.7314497702255;5909.911351329733;5920.437558431942;5996.2504686914135;6177;79997;94314;131025;138040;139598;113453.43273283746;113992.43003784928;114487.42756286166;115360;119133.6606437541;79031;97562.51218743861;97696;99480;101147.49426252821;10284.177265818866;10582.412258576845;10850;10923.781524369628;10929.289596176222;58301.4169858296;59501.70249148727;60187;60779.69610152098;62210.066848998205;68502.65748671425;69199.03525671177;78156.60921695356;78941.21058789568;83340;14622.780658290347;14663.92668036653;14822.777658334919;14854;14870.405183792527;14734.410623574937;14974.176420296988;15172.772408414103;15314.61713457128;16458.83541164573;16004.359825607311;16044.358225670843;17043.23305451233;17092.48722538326;17262.741058884378;15436.536903892902;15836.92081539585;16402.507924762278;16407.917960410123;16906.40827571029;14955.626109347353;15050.774238386652;15355.462558810388;15641.921790390976;16470.42353517621;12591.93704031474;12771.233725976472;13036.217826930415;13573.321333933462;13961.930190348983;9923.305368624127;10242.48787560604;10665.253432259668;13338.666533336356;13668.863311367157 17:10:47 INFO - PROCESS | 9925 | __end_tp_report 17:10:47 INFO - PROCESS | 9925 | __start_cc_report 17:10:47 INFO - PROCESS | 9925 | _x_x_mozilla_cycle_collect,996 17:10:47 INFO - PROCESS | 9925 | __end_cc_report 17:10:47 INFO - PROCESS | 9925 | __startTimestamp1475021447735__endTimestamp 17:10:47 INFO - PROCESS | 9925 | 17:10:47 INFO - PROCESS | 9925 | ------- Summary: start ------- 17:10:47 INFO - PROCESS | 9925 | Number of tests: 6 17:10:47 INFO - PROCESS | 9925 | 17:10:47 INFO - PROCESS | 9925 | [#0] dojo.html Cycles:165 Average:5555.14 Median:5796.00 stddev:643.13 (11.1%) stddev-sans-first:617.60 17:10:47 INFO - PROCESS | 9925 | Values: 3183.4 3192.5 3206.0 3224.0 3239.6 5480.4 5541.6 5550.4 5555.9 5579.4 5547.4 5591.7 5592.6 5636.5 5640.4 4962.5 4989.4 5077.6 5097.1 5172.8 5028.9 5117.4 5124.5 5124.9 5129.1 4953.3 4980.7 5002.1 5008.2 5039.0 5080.5 5108.5 5108.9 5110.8 5135.0 4769.9 4919.0 4979.8 5019.3 5046.4 5490.0 5512.2 5513.9 5515.6 5538.4 5992.5 5992.6 5993.8 5997.8 6003.3 5914.9 5958.7 5973.6 6027.2 6033.2 5584.0 5609.6 5620.2 5660.4 5670.9 5863.4 6148.6 6149.4 6158.5 6162.1 5850.3 5883.1 5886.7 6000.5 6000.6 6053.4 6054.0 6054.7 6055.6 6060.7 5776.5 6022.1 6025.9 6029.2 6038.6 5878.1 5929.6 5938.4 5982.4 6019.8 4586.5 4587.8 4591.7 4592.5 4616.2 4019.5 4120.9 4139.7 4143.2 4154.1 5623.3 5630.9 5667.5 5691.3 5705.3 5977.4 6008.6 6016.1 6016.8 6025.2 5501.4 5709.9 5716.9 5723.8 5725.7 5039.1 5091.6 5097.5 5123.8 5136.5 6282.1 6287.5 6292.0 6294.7 6310.3 5798.2 6074.7 6098.7 6108.2 6109.7 6120.6 6131.0 6136.9 6184.6 6218.3 5885.1 5889.7 5889.9 5894.4 5912.0 5871.0 5941.6 6004.1 6015.1 6018.5 5719.5 5772.1 5775.7 5817.7 5857.8 5799.4 5801.1 5812.2 5812.4 5812.9 5672.8 5788.4 5794.7 5796.0 5809.5 5848.7 5878.9 5903.1 5996.6 6000.9 5799.2 5814.7 5819.2 5821.0 5850.8 17:10:47 INFO - PROCESS | 9925 | 17:10:47 INFO - PROCESS | 9925 | [#1] ext.html Cycles:180 Average:69838.30 Median:42206.47 stddev:149392.87 (354.0%) stddev-sans-first:149721.49 17:10:47 INFO - PROCESS | 9925 | Values: 593.7 623.6 625.7 652.9 657.0 34836.0 38774.4 38783.6 38786.8 38787.0 38904.2 39618.2 47853.8 48768.3 48868.5 9276.3 9392.5 9400.5 9407.8 9413.0 9235.5 9342.0 9342.8 9344.3 9347.7 8679.7 11278.3 11492.4 11524.8 11899.6 11334.5 11401.6 11421.1 11427.9 11432.3 11171.5 11222.1 11247.0 11255.5 11255.8 45719.8 45997.3 46025.3 46042.8 46066.5 4042.0 4142.2 4263.5 4416.0 4755.5 31929.0 32307.2 36404.5 36565.5 36633.6 4325.8 4348.1 4376.8 4448.2 4629.8 360431.0 362079.0 362406.0 362423.0 367540.0 38901.0 58936.7 59209.7 59284.0 59337.4 41647.0 41826.4 41979.6 42036.6 42083.2 17830.6 17954.7 17955.2 17961.8 17979.5 15224.4 15304.9 15307.8 15311.8 15312.8 8846.9 9310.6 9549.9 9566.7 9622.1 3545.0 3573.7 3575.4 3639.3 3660.3 1052.0 1128.2 1135.8 1202.1 1233.3 35824.5 35839.8 36094.3 36255.0 36410.0 1154.2 1238.2 1241.8 1278.6 1309.5 17776.0 18784.9 18842.7 18952.8 18985.3 617370.0 636509.0 645751.0 645914.0 1004293.0 59673.1 63673.4 67335.7 67899.3 68072.0 1443.1 1469.4 1475.6 1486.1 1500.5 33683.3 35549.6 35753.6 35896.8 35958.5 56099.0 57238.1 57429.1 57459.4 57537.1 506676.0 512524.0 526200.0 541287.0 547252.0 48141.3 51726.5 51746.5 52091.2 52146.5 56442.0 56645.7 56926.4 56941.0 57562.0 46735.3 48644.5 48917.0 49079.3 49526.0 48880.5 49578.5 49849.5 50030.0 50570.0 46569.5 47157.8 47439.8 48623.5 48737.3 40788.4 42329.8 42982.9 45355.8 46044.8 31636.0 31672.2 31872.4 31999.0 32084.7 17:10:47 INFO - PROCESS | 9925 | 17:10:47 INFO - PROCESS | 9925 | [#2] jquery.html Cycles:180 Average:30878.02 Median:13512.20 stddev:83383.53 (617.1%) stddev-sans-first:83586.05 17:10:47 INFO - PROCESS | 9925 | Values: 406.5 412.4 422.5 433.0 434.2 20474.9 20767.6 21296.0 21643.9 21822.3 20296.5 20696.6 20944.4 21102.3 21103.8 5843.5 6121.4 6127.5 6128.4 6140.5 5909.7 5938.8 5993.9 6010.8 6015.8 5958.9 6003.4 6012.3 6015.7 6022.5 5880.4 5957.4 5986.3 5992.0 5997.2 5340.7 5378.3 5382.8 5384.1 5384.1 24096.4 24100.9 24316.0 24348.5 24535.5 2488.9 2535.0 2547.7 2579.6 2612.5 14794.0 16504.9 16714.2 16766.5 17300.9 2170.8 2212.2 2216.4 2217.0 2354.2 93482.0 97457.5 100842.0 103055.5 103479.0 18658.3 18859.4 19279.4 19488.0 19733.3 34560.0 34763.5 34928.0 35034.1 35139.8 10601.8 10725.8 10753.7 10791.5 11085.2 9012.7 9027.9 9045.4 9236.4 9329.5 9347.0 9696.1 9819.7 9837.8 9949.9 3334.0 3418.6 3557.8 3806.4 3844.9 491.0 943.8 1044.0 1068.6 1112.8 12027.3 12756.9 12796.2 12800.1 13036.0 1010.4 1023.3 1031.1 1064.1 1098.9 11108.2 11130.9 11317.4 11340.1 12036.8 491190.0 507368.0 509358.0 510870.0 514534.0 59607.4 60355.4 60433.0 92613.5 98163.0 1054.6 1059.7 1080.9 1083.3 1109.7 13006.9 17384.0 17684.8 18949.4 19076.1 44120.0 44265.8 44719.0 44829.0 45409.0 54614.0 55387.4 59310.4 59783.7 60402.7 5681.1 6192.0 8941.1 9141.7 9312.0 11849.3 14111.6 14232.0 14237.4 14262.9 11765.4 11946.2 11966.5 12372.8 12887.5 11606.4 11651.8 11706.9 11713.9 12005.3 12047.6 12161.1 12422.4 12622.8 12674.9 9118.3 9253.8 9312.5 9354.6 9405.4 11084.8 12112.0 12639.0 12743.4 12869.5 17:10:47 INFO - PROCESS | 9925 | 17:10:47 INFO - PROCESS | 9925 | [#3] mootools.html Cycles:180 Average:6297.58 Median:4753.44 stddev:3677.50 (77.4%) stddev-sans-first:3670.70 17:10:47 INFO - PROCESS | 9925 | Values: 1576.6 1599.8 1788.4 1831.8 1886.8 9152.0 9836.1 9840.9 9853.4 9854.9 8351.4 9092.1 9375.4 9393.8 9661.0 2515.7 2536.4 2539.9 2539.9 2542.2 2405.2 2514.8 2516.2 2520.5 2530.3 2502.1 2513.8 2519.0 2530.6 2532.6 2428.2 2434.7 2441.8 2500.5 2504.5 2418.9 2447.5 2453.6 2456.6 2483.5 9612.5 9627.3 9666.5 9754.3 9757.2 5613.1 5759.8 5764.5 5768.7 5771.4 3476.5 3568.4 3639.5 3654.7 3660.5 1161.9 1217.5 1241.6 1261.2 1270.7 13755.0 13801.9 13823.4 13828.0 13834.6 4833.5 4904.8 5090.4 5260.6 5301.1 10859.7 10899.9 10931.9 10939.3 10954.7 5762.6 5764.7 5770.5 5775.1 5776.6 4268.6 4673.4 5000.5 5138.7 5384.7 4107.7 4108.8 4110.9 4144.7 4189.0 1202.4 1202.7 1213.1 1214.7 1227.8 933.2 1000.4 1005.6 1008.0 1023.0 6185.1 6196.3 6302.4 6333.2 6574.3 490.8 499.1 500.1 501.7 516.1 4227.9 4364.4 4441.0 4455.4 4473.7 11840.3 12247.4 12327.1 12328.1 12365.5 11369.1 11767.4 11845.1 12200.8 12207.8 8605.3 8783.4 8794.6 8824.1 8854.6 6275.9 6485.0 6516.9 6580.9 6679.1 10889.9 11068.6 11077.3 11202.2 11456.3 10137.9 10815.0 11468.8 11592.4 11653.7 8054.2 8229.1 8696.3 8733.5 8744.8 9355.2 9580.7 9609.0 9610.9 9665.0 8486.2 8563.2 8564.4 8660.6 8667.3 8122.5 8156.3 8281.3 8333.7 8675.7 7800.8 7804.8 7813.2 7818.6 7870.7 5334.7 5358.0 5397.1 5516.3 5519.2 5438.8 5486.6 5514.9 5537.2 5543.7 17:10:47 INFO - PROCESS | 9925 | 17:10:47 INFO - PROCESS | 9925 | [#4] prototype.html Cycles:180 Average:4868.73 Median:5567.10 stddev:1419.65 (25.5%) stddev-sans-first:1382.63 17:10:47 INFO - PROCESS | 9925 | Values: 356.1 356.2 359.3 360.3 364.5 5627.2 5636.8 5667.8 5676.2 5682.3 5568.4 5580.8 5581.5 5605.3 5611.7 4203.0 4322.1 4369.5 4384.0 4391.1 4349.2 4357.6 4368.0 4376.4 4404.9 4337.5 4347.1 4350.4 4355.6 4396.8 4110.8 4321.4 4337.4 4343.9 4397.8 3817.4 3818.0 3826.6 3837.0 3845.9 5493.0 5504.1 5507.9 5508.6 5538.2 4859.6 4914.2 5011.4 5031.3 5035.3 5744.2 5752.7 5763.7 5775.1 5821.9 4211.5 4225.4 4234.7 4236.9 4264.9 5763.9 5776.2 5853.9 6045.2 6111.2 4239.6 4248.6 4251.0 4266.5 4282.5 4343.7 4344.1 4358.5 4358.8 4360.1 4797.4 4869.8 4937.0 4948.2 4960.8 5760.4 5761.4 5771.0 5801.6 5815.8 1484.1 1489.5 1498.3 1503.2 1506.0 928.2 970.5 970.6 979.0 982.1 4991.8 5013.1 5018.1 5018.2 5029.2 5220.6 5220.8 5251.9 5254.0 5265.3 4962.0 5002.8 5032.0 5279.3 5697.3 3154.6 3187.6 3195.3 3196.4 3206.4 6041.3 6219.5 6235.3 6236.3 6236.9 6121.3 6124.9 6138.4 6148.0 6148.4 6162.0 6179.1 6198.8 6205.3 6259.2 5830.4 5999.3 6008.4 6027.8 6034.2 5931.7 5949.1 5950.9 5954.9 5961.2 5684.3 5871.5 6014.9 6025.7 6180.6 4341.5 4366.9 4374.0 4394.8 4397.4 5749.6 5951.7 5989.3 5991.9 5995.3 5716.8 5724.0 5730.9 5750.0 5756.6 5767.0 5770.7 5772.9 5777.5 5783.7 5554.5 5710.2 5757.2 5805.3 5865.5 5916.5 5940.2 5949.3 5975.4 5976.6 5565.7 5756.6 5770.3 5834.3 5838.2 17:10:47 INFO - PROCESS | 9925 | 17:10:47 INFO - PROCESS | 9925 | [#5] yui.html Cycles:180 Average:23232.86 Median:4105.60 stddev:33296.30 (811.0%) stddev-sans-first:33346.92 17:10:47 INFO - PROCESS | 9925 | Values: 751.8 775.0 835.4 840.1 842.6 16542.6 17792.4 17799.8 17805.1 17839.3 16409.9 16849.5 17026.2 17766.4 17776.6 742.3 887.4 906.4 909.3 909.8 845.9 884.0 887.5 888.1 888.4 950.6 953.1 953.2 954.6 954.8 946.1 948.5 948.7 949.3 949.3 691.2 691.8 692.1 692.6 693.2 16417.6 16427.2 16433.7 16449.7 16450.3 14699.3 15341.3 15372.5 15400.0 15743.6 13388.7 13526.7 13565.1 13906.1 14279.5 4075.4 4079.7 4131.5 4181.2 4212.0 94233.5 94965.5 94979.5 95274.8 98509.0 11650.7 11923.1 12052.6 12406.8 12672.6 24215.9 24367.9 24445.9 24494.9 24537.8 9230.5 9230.7 9292.5 9353.4 9402.3 5063.1 5141.9 5165.9 5348.1 5377.1 4045.8 4143.5 4175.2 4177.6 4179.6 733.5 757.4 763.4 763.8 775.8 636.4 648.0 690.2 712.2 731.0 11074.9 11130.0 11199.3 11707.2 11953.3 780.8 801.1 809.1 819.5 850.3 4882.7 5909.9 5920.4 5996.3 6177.0 79997.0 94314.0 131025.0 138040.0 139598.0 113453.4 113992.4 114487.4 115360.0 119133.7 79031.0 97562.5 97696.0 99480.0 101147.5 10284.2 10582.4 10850.0 10923.8 10929.3 58301.4 59501.7 60187.0 60779.7 62210.1 68502.7 69199.0 78156.6 78941.2 83340.0 14622.8 14663.9 14822.8 14854.0 14870.4 14734.4 14974.2 15172.8 15314.6 16458.8 16004.4 16044.4 17043.2 17092.5 17262.7 15436.5 15836.9 16402.5 16407.9 16906.4 14955.6 15050.8 15355.5 15641.9 16470.4 12591.9 12771.2 13036.2 13573.3 13961.9 9923.3 10242.5 10665.3 13338.7 13668.9 17:10:47 INFO - PROCESS | 9925 | -------- Summary: end -------- 17:10:47 INFO - PROCESS | 9925 | 17:10:48 INFO - TEST-INFO | 9925: exit 0 17:10:48 INFO - TEST-OK | dromaeo_css | took 1112214ms 17:10:48 INFO - TEST-START | kraken 17:10:48 INFO - operating with platform_type : linux_ 17:10:48 INFO - Initialising browser for kraken test... 17:10:48 INFO - TEST-INFO | started process 10119 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphmsoic/profile http://localhost:41607/getInfo.html) 17:10:52 INFO - PROCESS | 10119 | __metrics Screen width/height:1600/1200 17:10:52 INFO - PROCESS | 10119 | colorDepth:24 17:10:52 INFO - PROCESS | 10119 | Browser inner width/height: 1024/697 17:10:52 INFO - PROCESS | 10119 | __metrics 17:10:53 INFO - PROCESS | 10119 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:10:53 INFO - PROCESS | 10119 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:10:53 INFO - TEST-INFO | 10119: exit 0 17:10:53 INFO - Browser initialized. 17:10:53 INFO - Running cycle 1/1 for kraken test... 17:10:53 INFO - TEST-INFO | started process 10206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphmsoic/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:11:05 INFO - PROCESS | 10206 | RSS: Main: 143220736 17:11:05 INFO - PROCESS | 10206 | 17:11:08 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41607/tests/kraken/driver.html?testName=audio-beat-detection) 17:11:08 INFO - PROCESS | 10206 | RSS: Main: 198377472 17:11:08 INFO - PROCESS | 10206 | 17:11:11 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41607/tests/kraken/driver.html?testName=audio-dft) 17:11:12 INFO - PROCESS | 10206 | RSS: Main: 182554624 17:11:12 INFO - PROCESS | 10206 | 17:11:14 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41607/tests/kraken/driver.html?testName=audio-fft) 17:11:15 INFO - PROCESS | 10206 | RSS: Main: 273903616 17:11:15 INFO - PROCESS | 10206 | 17:11:17 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41607/tests/kraken/driver.html?testName=audio-oscillator) 17:11:18 INFO - PROCESS | 10206 | RSS: Main: 180101120 17:11:18 INFO - PROCESS | 10206 | 17:11:20 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41607/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:11:20 INFO - PROCESS | 10206 | RSS: Main: 178458624 17:11:20 INFO - PROCESS | 10206 | 17:11:23 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41607/tests/kraken/driver.html?testName=imaging-darkroom) 17:11:24 INFO - PROCESS | 10206 | RSS: Main: 214388736 17:11:24 INFO - PROCESS | 10206 | 17:11:27 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41607/tests/kraken/driver.html?testName=imaging-desaturate) 17:11:28 INFO - PROCESS | 10206 | RSS: Main: 213680128 17:11:28 INFO - PROCESS | 10206 | 17:11:31 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41607/tests/kraken/driver.html?testName=json-parse-financial) 17:11:31 INFO - PROCESS | 10206 | RSS: Main: 214020096 17:11:31 INFO - PROCESS | 10206 | 17:11:33 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41607/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:11:33 INFO - PROCESS | 10206 | RSS: Main: 175874048 17:11:33 INFO - PROCESS | 10206 | 17:11:35 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41607/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:11:36 INFO - PROCESS | 10206 | RSS: Main: 183558144 17:11:36 INFO - PROCESS | 10206 | 17:11:38 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41607/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:11:38 INFO - PROCESS | 10206 | RSS: Main: 185810944 17:11:38 INFO - PROCESS | 10206 | 17:11:41 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41607/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:11:41 INFO - PROCESS | 10206 | RSS: Main: 185438208 17:11:41 INFO - PROCESS | 10206 | 17:11:44 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41607/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:11:45 INFO - PROCESS | 10206 | RSS: Main: 182829056 17:11:45 INFO - PROCESS | 10206 | 17:11:46 INFO - PROCESS | 10206 | Cycle 1(1): loaded http://localhost:41607/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41607/tests/kraken/driver.html?testName=ai-astar) 17:11:47 INFO - PROCESS | 10206 | RSS: Main: 233984000 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | __start_tp_report 17:11:47 INFO - PROCESS | 10206 | _x_x_mozilla_page_load 17:11:47 INFO - PROCESS | 10206 | _x_x_mozilla_page_load_details 17:11:47 INFO - PROCESS | 10206 | |i|pagename|runs| 17:11:47 INFO - PROCESS | 10206 | |0;ai-astar;110;109;119;110;110;110;111;111;110;110 17:11:47 INFO - PROCESS | 10206 | |1;audio-beat-detection;147;114;142;177;115;149;138;117;169;116 17:11:47 INFO - PROCESS | 10206 | |2;audio-dft;161;159;159;159;159;159;162;161;160;161 17:11:47 INFO - PROCESS | 10206 | |3;audio-fft;77;75;74;172;75;75;76;77;163;75 17:11:47 INFO - PROCESS | 10206 | |4;audio-oscillator;83;84;171;82;84;85;139;82;83;145 17:11:47 INFO - PROCESS | 10206 | |5;imaging-gaussian-blur;117;116;116;118;117;116;117;117;117;117 17:11:47 INFO - PROCESS | 10206 | |6;imaging-darkroom;146;146;146;145;146;146;146;146;146;145 17:11:47 INFO - PROCESS | 10206 | |7;imaging-desaturate;95;94;95;95;95;95;94;94;95;94 17:11:47 INFO - PROCESS | 10206 | |8;json-parse-financial;51;53;53;52;52;54;52;51;53;52 17:11:47 INFO - PROCESS | 10206 | |9;json-stringify-tinderbox;59;60;60;60;60;60;60;60;59;60 17:11:47 INFO - PROCESS | 10206 | |10;stanford-crypto-aes;83;79;73;73;80;72;72;73;73;82 17:11:47 INFO - PROCESS | 10206 | |11;stanford-crypto-ccm;119;119;119;117;118;126;117;123;157;118 17:11:47 INFO - PROCESS | 10206 | |12;stanford-crypto-pbkdf2;156;158;158;156;152;154;153;153;152;157 17:11:47 INFO - PROCESS | 10206 | |13;stanford-crypto-sha256-iterative;65;64;62;62;61;62;62;62;60;63 17:11:47 INFO - PROCESS | 10206 | __end_tp_report 17:11:47 INFO - PROCESS | 10206 | __start_cc_report 17:11:47 INFO - PROCESS | 10206 | _x_x_mozilla_cycle_collect,1699 17:11:47 INFO - PROCESS | 10206 | __end_cc_report 17:11:47 INFO - PROCESS | 10206 | __startTimestamp1475021507027__endTimestamp 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | ------- Summary: start ------- 17:11:47 INFO - PROCESS | 10206 | Number of tests: 14 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#0] ai-astar Cycles:10 Average:111.00 Median:110.00 stddev:2.87 (2.6%) stddev-sans-first:3.02 17:11:47 INFO - PROCESS | 10206 | Values: 110.0 109.0 119.0 110.0 110.0 110.0 111.0 111.0 110.0 110.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:144.50 stddev:22.92 (15.9%) stddev-sans-first:24.10 17:11:47 INFO - PROCESS | 10206 | Values: 147.0 114.0 142.0 177.0 115.0 149.0 138.0 117.0 169.0 116.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.50 stddev:1.15 (0.7%) stddev-sans-first:1.17 17:11:47 INFO - PROCESS | 10206 | Values: 161.0 159.0 159.0 159.0 159.0 159.0 162.0 161.0 160.0 161.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#3] audio-fft Cycles:10 Average:93.90 Median:75.00 stddev:38.86 (51.8%) stddev-sans-first:40.73 17:11:47 INFO - PROCESS | 10206 | Values: 77.0 75.0 74.0 172.0 75.0 75.0 76.0 77.0 163.0 75.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#4] audio-oscillator Cycles:10 Average:103.80 Median:83.00 stddev:34.00 (41.0%) stddev-sans-first:35.22 17:11:47 INFO - PROCESS | 10206 | Values: 83.0 84.0 171.0 82.0 84.0 85.0 139.0 82.0 83.0 145.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 17:11:47 INFO - PROCESS | 10206 | Values: 117.0 116.0 116.0 118.0 117.0 116.0 117.0 117.0 117.0 117.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 17:11:47 INFO - PROCESS | 10206 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 145.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 17:11:47 INFO - PROCESS | 10206 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 94.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.50 stddev:0.95 (1.8%) stddev-sans-first:0.88 17:11:47 INFO - PROCESS | 10206 | Values: 51.0 53.0 53.0 52.0 52.0 54.0 52.0 51.0 53.0 52.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 17:11:47 INFO - PROCESS | 10206 | Values: 59.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 59.0 60.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:76.00 stddev:4.45 (5.9%) stddev-sans-first:3.93 17:11:47 INFO - PROCESS | 10206 | Values: 83.0 79.0 73.0 73.0 80.0 72.0 72.0 73.0 73.0 82.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#11] stanford-crypto-ccm Cycles:10 Average:123.30 Median:119.00 stddev:12.18 (10.2%) stddev-sans-first:12.81 17:11:47 INFO - PROCESS | 10206 | Values: 119.0 119.0 119.0 117.0 118.0 126.0 117.0 123.0 157.0 118.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.90 Median:156.00 stddev:2.38 (1.5%) stddev-sans-first:2.49 17:11:47 INFO - PROCESS | 10206 | Values: 156.0 158.0 158.0 156.0 152.0 154.0 153.0 153.0 152.0 157.0 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - PROCESS | 10206 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.00 stddev:1.42 (2.3%) stddev-sans-first:1.12 17:11:47 INFO - PROCESS | 10206 | Values: 65.0 64.0 62.0 62.0 61.0 62.0 62.0 62.0 60.0 63.0 17:11:47 INFO - PROCESS | 10206 | -------- Summary: end -------- 17:11:47 INFO - PROCESS | 10206 | 17:11:47 INFO - TEST-INFO | 10206: exit 0 17:11:48 INFO - TEST-OK | kraken | took 59613ms 17:11:48 INFO - SUITE-END | took 1171s 17:11:48 INFO - Completed test suite (00:19:32) 17:11:48 INFO - javascript benchmark 17:11:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3183.4428974929383, 3192.4572822620153, 3205.951910721338, 3223.967760322396, 3239.56265904103, 5480.424555421678, 5541.639793413436, 5550.389457159709, 5555.916661250085, 5579.38626751057, 5547.44525547445, 5591.720413979295, 5592.552595792337, 5636.4927156555905, 5640.407757185498, 4962.528559786814, 4989.376327959005, 5077.619178561614, 5097.133487307166, 5172.793088276464, 5028.949710502903, 5117.411497677759, 5124.461931497195, 5124.871878203056, 5129.12804823181, 4953.257011448293, 4980.701157930518, 5002.1496345621335, 5008.198688209887, 5039.017391608638, 5080.517350851663, 5108.540231379176, 5108.923366149511, 5110.770015349318, 5134.974325128369, 4769.856904292842, 4918.950810491885, 4979.775910084055, 5019.347484826949, 5046.419661738873, 5489.972550137264, 5512.228288039678, 5513.9448605514235, 5515.641483303572, 5538.446155384469, 5992.520598352121, 5992.550558708114, 5993.790217342372, 5997.760089596411, 6003.27960644721, 5914.852128696774, 5958.656459616504, 5973.581849270553, 6027.246594175728, 6033.245844269466, 5584.0, 5609.635373700738, 5620.213170156181, 5660.433956604348, 5670.886582268332, 5863.413658634103, 6148.631082135086, 6149.354317796657, 6158.507319414436, 6162.106494558354, 5850.268716410449, 5883.0881213411985, 5886.676232807236, 6000.519958403317, 6000.579959402888, 6053.3946605339115, 6053.969730151321, 6054.697265136726, 6055.636661800306, 6060.727267272895, 5776.480116789509, 6022.126791615191, 6025.879482410327, 6029.246344206974, 6038.637681739022, 5878.11828225761, 5929.644221346733, 5938.376470470625, 5982.371850955587, 6019.759209631566, 4586.4725556561425, 4587.770611469413, 4591.724496530219, 4592.517785632461, 4616.215243408628, 4019.4774679291504, 4120.896977575585, 4139.710220284612, 4143.19207754485, 4154.086101057763, 5623.325200975909, 5630.915536266959, 5667.489925906688, 5691.3170419549915, 5705.315362156568, 5977.402259774075, 6008.609440386361, 6016.067509536029, 6016.789412370546, 6025.15647809307, 5501.367342755554, 5709.914351284734, 5716.914246286309, 5723.828285151452, 5725.713714314267, 5039.092963266574, 5091.618128640367, 5097.46476619957, 5123.846284611468, 5136.4606716295, 6282.120503129566, 6287.465565426898, 6292.0, 6294.748210071546, 6310.337414571496, 5798.217240672624, 6074.696265186634, 6098.664573448503, 6108.205933228652, 6109.6639684817765, 6120.632762034292, 6131.040261761288, 6136.917362303726, 6184.5980011299125, 6218.347073557211, 5885.087811389241, 5889.676067816311, 5889.94110058894, 5894.440028197314, 5911.970440147772, 5870.970645146747, 5941.554383421347, 6004.1294012368835, 6015.1278064681255, 6018.518518518596, 5719.456651618089, 5772.105323674838, 5775.653460792366, 5817.738201780845, 5857.7949771757785, 5799.449052340021, 5801.071828507418, 5812.21535092757, 5812.360640329645, 5812.94187058124, 5672.801451949162, 5788.363280039276, 5794.739236734273, 5796.0, 5809.535237180931, 5848.707564621837, 5878.911816322673, 5903.144044113666, 5996.640201587918, 6000.87998240024, 5799.188113664006, 5814.709264536672, 5819.214406055128, 5820.970895145667, 5850.824475265749], "value": 5510.995515359269, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.7298529169228, 623.6071275096689, 625.7090452939385, 652.8824811533922, 657.0079180437538, 34836.0, 38774.418383724194, 38783.61216387835, 38786.80606596963, 38787.0, 38904.22191556174, 39618.20763584726, 47853.76073119638, 48768.26847597289, 48868.51131488684, 9276.304277179222, 9392.530373481333, 9400.482973436458, 9407.764805879839, 9412.952935235315, 9235.492047937361, 9341.95329023354, 9342.813143737121, 9344.345895787297, 9347.6728314509, 8679.652813887436, 11278.323300601991, 11492.425378731072, 11524.769504609903, 11899.58351457699, 11334.546618135264, 11401.600943967005, 11421.086313094933, 11427.885721142766, 11432.256903301259, 11171.49728262222, 11222.102231821516, 11247.0, 11255.493502792395, 11255.831162532568, 45719.771401143116, 45997.310040349425, 46025.30962035572, 46042.76978615119, 46066.53933460656, 4041.959580404188, 4142.1922725068625, 4263.509696384925, 4415.977920110379, 4755.548222918817, 31929.0, 32307.19232019195, 36404.536975150375, 36565.451518227244, 36633.63366366329, 4325.82696692132, 4348.130373925196, 4376.759278239696, 4448.154850578412, 4629.837955671535, 360431.0, 362079.0, 362406.0, 362423.0, 367540.0, 38901.0, 58936.70531647314, 59209.70395147997, 59284.0, 59337.406625934054, 41647.0, 41826.37260441096, 41979.58020419818, 42036.57963420388, 42083.15833683309, 17830.643387132186, 17954.730679039825, 17955.192016359295, 17961.820381796275, 17979.460616181535, 15224.391024358902, 15304.923475382551, 15307.770383444256, 15311.770323445156, 15312.846871531365, 8846.911530884738, 9310.581023853943, 9549.904500955041, 9566.66516671913, 9622.13400793932, 3544.9822750886083, 3573.714102871764, 3575.3921833288405, 3639.29033838399, 3660.3411385950785, 1051.952662130206, 1128.1707944660668, 1135.795556799784, 1202.1044321980005, 1233.2908577567935, 35824.462633060524, 35839.82080089583, 36094.27811443756, 36255.0, 36410.0, 1154.1574650505179, 1238.158052524292, 1241.8323526324011, 1278.6291975327238, 1309.5220244610648, 17776.0, 18784.906075469535, 18842.71735923962, 18952.81047189538, 18985.33551325697, 617370.0, 636509.0, 645751.0, 645914.0, 1004293.0, 59673.10490342648, 63673.36326636767, 67335.66332168308, 67899.3210067893, 68072.0, 1443.141330908108, 1469.434267806902, 1475.6310922269433, 1486.0934829754058, 1500.48983345663, 33683.326333473684, 35549.644503554635, 35753.64246357503, 35896.820515897256, 35958.46062309015, 56099.0, 57238.14142787945, 57429.138562922424, 57459.42540574541, 57537.13694294505, 506676.0, 512524.0, 526200.0, 541287.0, 547252.0, 48141.27788083111, 51726.48273517217, 51746.48253517417, 52091.2186317198, 52146.47853521416, 56442.0, 56645.71677141588, 56926.43073569211, 56941.0, 57562.0, 46735.29897051615, 48644.513554863996, 48917.0, 49079.26381104358, 49526.0, 48880.51119488759, 49578.50421495883, 49849.50150498449, 50030.0, 50569.98860022705, 46569.53430465652, 47157.76421117873, 47439.762801185774, 48623.5137648619, 48737.26894096662, 40788.388174178006, 42329.78835105805, 42982.92542686333, 45355.773221133684, 46044.76977615224, 31635.953515409245, 31672.208194795316, 31872.36255274928, 31999.0, 32084.67915320817], "value": 21561.252594221998, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.4614385938632, 412.4411422522482, 422.47612959929677, 432.9848455304065, 434.23141040358564, 20474.89762551187, 20767.584648307045, 21296.041678124482, 21643.891780541115, 21822.345329640135, 20296.492587685316, 20696.586068278626, 20944.37166884996, 21102.261420850275, 21103.788962110375, 5843.47408733215, 6121.387861213865, 6127.540434467411, 6128.448439640431, 6140.478059364959, 5909.704514774266, 5938.832326200927, 5993.85015374617, 6010.8497287567725, 6015.75936962521, 5958.914693904002, 6003.369646187153, 6012.3085845127935, 6015.669138197398, 6022.488088512482, 5880.353161152269, 5957.374475680056, 5986.281646202484, 5991.999999999978, 5997.190379298782, 5340.706261155635, 5378.300820893259, 5382.757775900055, 5384.0847056000575, 5384.138537833927, 24096.397590060213, 24100.879495602585, 24316.0, 24348.513029739483, 24535.509289814105, 2488.8880000400027, 2535.0, 2547.6688030556093, 2579.587266037425, 2612.5166844133896, 14793.96442249043, 16504.917475412545, 16714.247858846382, 16766.497005089866, 17300.913495432567, 2170.848040637156, 2212.214663794346, 2216.357256395643, 2217.0466699319286, 2354.223106374909, 93482.0, 97457.51271243599, 100842.0, 103055.48472257666, 103479.0, 18658.34695785641, 18859.35968964346, 19279.4216173515, 19488.0, 19733.309334173235, 34560.0, 34763.478547821294, 34928.0, 35034.12414689653, 35139.82430087833, 10601.787964240671, 10725.83911241332, 10753.677389678322, 10791.460426978776, 11085.224034317682, 9012.684556040638, 9027.864582031274, 9045.366824322235, 9236.399634023766, 9329.533523323942, 9347.0, 9696.0788725071, 9819.705408837746, 9837.80324393508, 9949.855766586936, 3333.966660333414, 3418.5555877735737, 3557.7509574330065, 3806.4290356446754, 3844.884653460401, 491.03511599706746, 943.8395472769646, 1043.9895601043893, 1068.625980906692, 1112.799696054718, 12027.278363298055, 12756.93621531905, 12796.232226066466, 12800.10399272042, 13036.0, 1010.434156872154, 1023.3348323589578, 1031.0720351683394, 1064.1486810551528, 1098.9175811814146, 11108.222424430212, 11130.944345278222, 11317.377544234982, 11340.092792576574, 12036.759264814655, 491190.0, 507368.0, 509358.0, 510870.0, 514534.0, 59607.40392596105, 60355.396446034974, 60433.0, 92613.5369323149, 98163.0, 1054.5781687324977, 1059.6503153959175, 1080.9405482698369, 1083.3337497439018, 1109.678193323943, 13006.934965325112, 17384.0, 17684.823151768833, 18949.43151705451, 19076.141573628942, 44120.0, 44265.77867110644, 44719.0, 44829.0, 45409.0, 54614.0, 55387.44612553823, 59310.40689593222, 59783.70108149431, 60402.69798651154, 5681.0910254359105, 6191.9690401547705, 8941.061188575106, 9141.708984765612, 9312.0, 11849.348285844362, 14111.647208819862, 14232.0, 14237.430502779773, 14262.928685356506, 11765.352905590275, 11946.163768536118, 11966.521339146337, 12372.81440778371, 12887.517935437545, 11606.419679016184, 11651.76696466049, 11706.88293117092, 11713.88286117162, 12005.279683219034, 12047.638570842888, 12161.115027170803, 12422.440990155284, 12622.810657839957, 12674.873251267369, 9118.270538357048, 9253.814923701622, 9312.534373281173, 9354.625814967325, 9405.43567385959, 11084.833727493933, 12112.0, 12639.0, 12743.426545805276, 12869.485220591072], "value": 10376.70590169778, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1576.5979675182828, 1599.7520384340426, 1788.40982475783, 1831.7618709567755, 1886.76415448069, 9151.954240228797, 9836.06557377048, 9840.901590984087, 9853.408795472276, 9854.852177217348, 8351.37364697647, 9092.090790920905, 9375.43747375158, 9393.76515587109, 9661.0, 2515.7358477359935, 2536.4293034067373, 2539.873006349684, 2539.949201015988, 2542.1737935171145, 2405.194259922928, 2514.798816094708, 2516.169664010872, 2520.4707011527603, 2530.3168144600945, 2502.1117503286346, 2513.7611926866916, 2518.9874050629815, 2530.607755797854, 2532.645429639852, 2428.1501474483875, 2434.659147719312, 2441.8412803167826, 2500.5124000819847, 2504.536660717773, 2418.9274321770376, 2447.5349683560066, 2453.6319552067152, 2456.6437866509254, 2483.52812965536, 9612.471314077722, 9627.3260871739, 9666.516674166334, 9754.317197796157, 9757.170640495566, 5613.101903695389, 5759.827205183851, 5764.481196692276, 5768.74040668171, 5771.365149833515, 3476.4959080933377, 3568.4290513517712, 3639.5450568678916, 3654.7076233901225, 3660.4875317455576, 1161.8896204860525, 1217.4643157010887, 1241.6213055018216, 1261.161327717067, 1270.6950331920366, 13755.0, 13801.930990344985, 13823.377947992365, 13828.03203775736, 13834.584962451143, 4833.516648335209, 4904.828331008398, 5090.41460232078, 5260.579153667697, 5301.098813201765, 10859.674209773719, 10899.945500272448, 10931.890681093246, 10939.343639381503, 10954.671359859218, 5762.567807414461, 5764.74058667361, 5770.5095066919785, 5775.104858746901, 5776.624519406225, 4268.573142685706, 4673.369095172108, 5000.4999500050435, 5138.665986710852, 5384.6769193848495, 4107.740742591563, 4108.815103320358, 4110.856120035784, 4144.668426525871, 4188.99464128613, 1202.4108186988312, 1202.7353982123918, 1213.1265488848014, 1214.6841821126393, 1227.8403807504967, 933.1508327422014, 1000.4047591682937, 1005.607812952949, 1007.9697609071593, 1023.0383439566932, 6185.103160041769, 6196.287426945869, 6302.432781049636, 6333.240011198685, 6574.309697481791, 490.82821012645286, 499.12652857499376, 500.07486150621787, 501.74160307441696, 516.0736478021885, 4227.894302642397, 4364.367166760865, 4441.0, 4455.353973673799, 4473.709208901476, 11840.348780816965, 12247.448864800926, 12327.137100402886, 12328.137030407965, 12365.505379784709, 11369.147313951322, 11767.352795596153, 11845.111616628787, 12200.816987745191, 12207.816882746765, 8605.268552173135, 8783.385163038654, 8794.560271986504, 8824.073472285447, 8854.64581416749, 6275.937240627718, 6484.967575162094, 6516.869662606627, 6580.868382632416, 6679.098321726505, 10889.945550272198, 11068.612598559012, 11077.279976801483, 11202.21584489078, 11456.255343402652, 10137.89862101399, 10815.0, 11468.770624587294, 11592.420378980849, 11653.708657283296, 8054.194580541899, 8229.135940726377, 8696.304295656206, 8733.475991440533, 8744.78138046554, 9355.15803577654, 9580.66467673628, 9608.95195524018, 9610.903890961, 9665.0, 8486.151384861465, 8563.229309362187, 8564.357673174662, 8660.566971651266, 8667.263282621048, 8122.458313861489, 8156.265936065783, 8281.29608983243, 8333.708320208763, 8675.652973880975, 7800.84398312042, 7804.84390312202, 7813.218678132142, 7818.6481608328895, 7870.685172593032, 5334.733263336895, 5358.0, 5397.082495975772, 5516.255305533862, 5519.172124181405, 5438.782448702008, 5486.588505862156, 5514.862128446821, 5537.197106419627, 5543.695096769717], "value": 4911.805479730848, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.10439744043737, 356.189668504153, 359.3298498300669, 360.29022825034673, 364.47697554009983, 5627.184058311553, 5636.830895073149, 5667.829965101043, 5676.176954341623, 5682.346530149034, 5568.443155684429, 5580.776768929238, 5581.548797312697, 5605.299337582802, 5611.691356975365, 4202.957970420302, 4322.13557288542, 4369.541198174194, 4383.953152558973, 4391.143726973241, 4349.1947693737675, 4357.564243575633, 4368.0, 4376.365427013081, 4404.854737768172, 4337.54455782143, 4347.130573885235, 4350.390945267665, 4355.586219309161, 4396.758178300192, 4110.753354798707, 4321.351797230441, 4337.436133302651, 4343.9348409773875, 4397.824087036514, 3817.351050321452, 3817.9618203817886, 3826.617338266179, 3837.0, 3845.8653947111716, 5492.972535137299, 5504.146857237135, 5507.9449205507835, 5508.614396992212, 5538.169274608841, 4859.611231101539, 4914.238293064581, 5011.448740638563, 5031.270465782441, 5035.295058691786, 5744.167095771089, 5752.741126649311, 5763.6541807491685, 5775.104858746859, 5821.943479673163, 4211.452511173558, 4225.366195070733, 4234.745915245096, 4236.85171019016, 4264.914701705949, 5763.855903602402, 5776.191333213354, 5853.853653658607, 6045.244344456943, 6111.236095488064, 4239.554846741109, 4248.63886569645, 4251.0, 4266.530681625019, 4282.507511636201, 4343.67422443318, 4344.066025804404, 4358.542353052884, 4358.760268185217, 4360.084382279693, 4797.409398924611, 4869.829555965524, 4937.0, 4948.183549714328, 4960.776765045582, 5760.399750156153, 5761.366249712528, 5771.0, 5801.593888427854, 5815.82552523425, 1484.1021182184838, 1489.5233525271808, 1498.2808252038865, 1503.2183264702294, 1506.0, 928.1878356438117, 970.5001924009141, 970.5729479029204, 979.0, 982.1455333859587, 4991.800327986913, 5013.1227035268685, 5018.146915024455, 5018.197088465828, 5029.220470827027, 5220.608454365862, 5220.765065571983, 5251.872991438474, 5254.0, 5265.262863199156, 4962.005585503632, 5002.824901128516, 5032.0, 5279.260903473517, 5697.287839020122, 3154.558361829346, 3187.5696780934277, 3195.344954284366, 3196.360727854438, 3206.374756922378, 6041.2750469943885, 6219.47134493564, 6235.282942461675, 6236.282827474808, 6236.908541005306, 6121.265448146251, 6124.938750612526, 6138.447539721446, 6147.969260153671, 6148.385161483905, 6162.0, 6179.084483810033, 6198.845028874224, 6205.307841834101, 6259.2488901333045, 5830.416958304136, 5999.250093738283, 6008.369121242382, 6027.849303767265, 6034.185384973148, 5931.733072011683, 5949.1076338549565, 5950.910736339045, 5954.880902381841, 5961.165436838759, 5684.346300175533, 5871.500922421642, 6014.879702405839, 6025.728842202199, 6180.598261113013, 4341.522432532356, 4366.890827729332, 4374.0, 4394.824207031683, 4397.384366188672, 5749.626274292157, 5951.672658003678, 5989.341172471112, 5991.940080599313, 5995.280566332068, 5716.79991200306, 5723.971380143239, 5730.942690573041, 5749.9712501437225, 5756.597038207286, 5767.029979560613, 5770.711464426678, 5772.942270577409, 5777.45114214149, 5783.710814459344, 5554.50009499147, 5710.230538189058, 5757.165211044459, 5805.332386775575, 5865.472107510344, 5916.491294719819, 5940.227770389822, 5949.286085669747, 5975.37258587842, 5976.581639285208, 5565.749541270571, 5756.568257380797, 5770.307563092456, 5834.329052159056, 5838.153467747238], "value": 4419.3867701974805, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.8421131562371, 775.0389516998923, 835.4235577451556, 840.0549381945311, 842.5618678287287, 16542.586435339123, 17792.377266795665, 17799.82200177998, 17805.109744512756, 17839.286428542877, 16409.917950410232, 16849.494515164533, 17026.233819478122, 17766.378176763817, 17776.64446711065, 742.283696233135, 887.4497811356968, 906.4063038709622, 909.2998391238775, 909.8498747706653, 845.898492180936, 883.9955800221022, 887.5207388010466, 888.1296329596998, 888.3781353052857, 950.619752099159, 953.1183655118987, 953.1755031897394, 954.5942974235921, 954.8138113067954, 946.1153821177221, 948.5162567090764, 948.6821914658598, 949.3022628368144, 949.3117489819859, 691.1878542712293, 691.7890043536719, 692.0553444548156, 692.6363659078974, 693.2270518372029, 16417.58956026097, 16427.178641068018, 16433.671326573403, 16449.6710065798, 16450.259738311805, 14699.26503674812, 15341.309641066178, 15372.461963831212, 15400.0, 15743.606409839731, 13388.732225355438, 13526.729465410735, 13565.118267312593, 13906.096103753325, 14279.500217492337, 4075.4498142750645, 4079.714419990602, 4131.483564554431, 4181.226473102486, 4212.031232816439, 94233.52883235608, 94965.5251723737, 94979.52510237404, 95274.80713753584, 98508.99999999929, 11650.708732281591, 11923.124612523248, 12052.578159764365, 12406.81389779136, 12672.556460523838, 24215.878920605635, 24367.878160609085, 24445.877770611034, 24494.877525612614, 24537.754622453904, 9230.53847307632, 9230.723078307661, 9292.53537323131, 9353.392029518196, 9402.294827887936, 5063.11395505785, 5141.948580514222, 5165.9483405165465, 5348.1442969124755, 5377.059014672417, 4045.8179381927885, 4143.482064741907, 4175.16496700655, 4177.582241775798, 4179.582041795796, 733.4719002318323, 757.3978686943893, 763.4007304266124, 763.8319569694659, 775.824625692077, 636.4081404293959, 647.9870402591922, 690.1580072311772, 712.2307907459931, 730.9561426314439, 11074.892510749021, 11130.0, 11199.272047316897, 11707.180497365272, 11953.342566158944, 780.7930898311952, 801.0867610923552, 809.1382677448498, 819.5082950229934, 850.3154960257052, 4882.7314497702255, 5909.911351329733, 5920.437558431942, 5996.2504686914135, 6177.0, 79997.0, 94314.0, 131025.0, 138040.0, 139598.0, 113453.43273283746, 113992.43003784928, 114487.42756286166, 115360.0, 119133.6606437541, 79031.0, 97562.51218743861, 97696.0, 99480.0, 101147.49426252821, 10284.177265818866, 10582.412258576845, 10850.0, 10923.781524369628, 10929.289596176222, 58301.4169858296, 59501.70249148727, 60187.0, 60779.69610152098, 62210.066848998205, 68502.65748671425, 69199.03525671177, 78156.60921695356, 78941.21058789568, 83340.0, 14622.780658290347, 14663.92668036653, 14822.777658334919, 14854.0, 14870.405183792527, 14734.410623574937, 14974.176420296988, 15172.772408414103, 15314.61713457128, 16458.83541164573, 16004.359825607311, 16044.358225670843, 17043.23305451233, 17092.48722538326, 17262.741058884378, 15436.536903892902, 15836.92081539585, 16402.507924762278, 16407.917960410123, 16906.40827571029, 14955.626109347353, 15050.774238386652, 15355.462558810388, 15641.921790390976, 16470.42353517621, 12591.93704031474, 12771.233725976472, 13036.217826930415, 13573.321333933462, 13961.930190348983, 9923.305368624127, 10242.48787560604, 10665.253432259668, 13338.666533336356, 13668.863311367157], "value": 8457.452967139048, "unit": "score"}], "name": "dromaeo_css", "value": 7806.839074789607, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 119.0, 110.0, 110.0, 110.0, 111.0, 111.0, 110.0, 110.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 142.0, 177.0, 115.0, 149.0, 138.0, 117.0, 169.0, 116.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 159.0, 159.0, 159.0, 159.0, 162.0, 161.0, 160.0, 161.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 74.0, 172.0, 75.0, 75.0, 76.0, 77.0, 163.0, 75.0], "value": 93.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 171.0, 82.0, 84.0, 85.0, 139.0, 82.0, 83.0, 145.0], "value": 103.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 53.0, 53.0, 52.0, 52.0, 54.0, 52.0, 51.0, 53.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 59.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 79.0, 73.0, 73.0, 80.0, 72.0, 72.0, 73.0, 73.0, 82.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 119.0, 117.0, 118.0, 126.0, 117.0, 123.0, 157.0, 118.0], "value": 123.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 158.0, 158.0, 156.0, 152.0, 154.0, 153.0, 153.0, 152.0, 157.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 60.0, 63.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1492.8999999999999, "alertThreshold": 2.0}]} 17:11:48 INFO - Return code: 0 17:11:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:11:48 INFO - # TBPL SUCCESS # 17:11:48 INFO - Running post-action listener: _resource_record_post_action 17:11:48 INFO - [mozharness: 2016-09-28 00:11:48.339273Z] Finished run-tests step (success) 17:11:48 INFO - Running post-run listener: _resource_record_post_run 17:11:48 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 291033088; Read time: 0; Write time: 180040 17:11:48 INFO - TinderboxPrint: CPU usage
12.9% 17:11:48 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 17:11:48 INFO - TinderboxPrint: I/O write bytes / time
291,033,088 / 180,040 17:11:48 INFO - TinderboxPrint: CPU idle
8,243.9 (87.1%) 17:11:48 INFO - TinderboxPrint: CPU user
1,181.1 (12.5%) 17:11:48 INFO - TinderboxPrint: Swap in / out
0 / 0 17:11:48 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70139904; Read time: 0; Write time: 65164 17:11:48 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 220893184; Read time: 0; Write time: 114876 17:11:48 INFO - Running post-run listener: _upload_blobber_files 17:11:48 INFO - Blob upload gear active. 17:11:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:11:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:11:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:11:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:11:49 INFO - (blobuploader) - INFO - Open directory for files ... 17:11:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:11:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:11:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:11:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:11:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:11:50 INFO - (blobuploader) - INFO - Done attempting. 17:11:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:11:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:11:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:11:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:11:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:11:51 INFO - (blobuploader) - INFO - Done attempting. 17:11:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:11:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:11:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:11:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:11:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:11:56 INFO - (blobuploader) - INFO - Done attempting. 17:11:56 INFO - (blobuploader) - INFO - Iteration through files over. 17:11:56 INFO - Return code: 0 17:11:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:11:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:11:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/708b8b787d5e5ef1c74e37f31fb62a6acfa98dd24c4dd48e8ec21d69b8677a3964a789b3def71f8f420312add92434d7a8313c5102e2fb021291842d02496c5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01c485c911ebf75264ac3bc88741026d79697022da0f7370ce27e7df9211f4609329ef10d7da8a98271ec66113bbf7d6da33d279035998f5f227a3ec4e68567e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19f9ead957b3b8633b5f28cd31f89375378438022b1589eb95a555602516245c294df4bddce78ccd81f0925c418dffa06424a05cb0a2b428769316902d185838"} 17:11:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:11:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:11:56 INFO - Contents: 17:11:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/708b8b787d5e5ef1c74e37f31fb62a6acfa98dd24c4dd48e8ec21d69b8677a3964a789b3def71f8f420312add92434d7a8313c5102e2fb021291842d02496c5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01c485c911ebf75264ac3bc88741026d79697022da0f7370ce27e7df9211f4609329ef10d7da8a98271ec66113bbf7d6da33d279035998f5f227a3ec4e68567e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19f9ead957b3b8633b5f28cd31f89375378438022b1589eb95a555602516245c294df4bddce78ccd81f0925c418dffa06424a05cb0a2b428769316902d185838"} 17:11:56 INFO - Running post-run listener: copy_logs_to_upload_dir 17:11:56 INFO - Copying logs to upload dir... 17:11:56 INFO - mkdir: /builds/slave/test/build/upload/logs 17:11:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1675.452847 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-09-27 17:11:55.564150) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 17:11:55.567597) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340 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/708b8b787d5e5ef1c74e37f31fb62a6acfa98dd24c4dd48e8ec21d69b8677a3964a789b3def71f8f420312add92434d7a8313c5102e2fb021291842d02496c5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01c485c911ebf75264ac3bc88741026d79697022da0f7370ce27e7df9211f4609329ef10d7da8a98271ec66113bbf7d6da33d279035998f5f227a3ec4e68567e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19f9ead957b3b8633b5f28cd31f89375378438022b1589eb95a555602516245c294df4bddce78ccd81f0925c418dffa06424a05cb0a2b428769316902d185838"} build_url:https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011636 build_url: 'https://queue.taskcluster.net/v1/task/fHOF6WxETJCIWr5J2T8owQ/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/708b8b787d5e5ef1c74e37f31fb62a6acfa98dd24c4dd48e8ec21d69b8677a3964a789b3def71f8f420312add92434d7a8313c5102e2fb021291842d02496c5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/01c485c911ebf75264ac3bc88741026d79697022da0f7370ce27e7df9211f4609329ef10d7da8a98271ec66113bbf7d6da33d279035998f5f227a3ec4e68567e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/19f9ead957b3b8633b5f28cd31f89375378438022b1589eb95a555602516245c294df4bddce78ccd81f0925c418dffa06424a05cb0a2b428769316902d185838"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 17:11:55.592967) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 17:11:55.593315) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1475017993.380046-601045340 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004438 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 17:11:55.676586) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 17:11:55.676878) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 17:11:55.679379) ========= ========= Total master_lag: 0.16 =========