builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0132 starttime: 1461564454.72 results: success (0) buildid: 20160424221650 builduid: 36cac20aebb0420f866366d653b76dc1 revision: 0225961ad7adbef25e670abf314ec67f5e6c224d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:34.722507) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:34.722949) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:34.723220) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:35.063991) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-24 23:07:35.064279) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RgPDTPc9Os/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-24 23:07:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-04-24 23:07:35 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.711043 ========= master_lag: 0.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-24 23:07:36.095955) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:36.096267) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RgPDTPc9Os/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020413 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:36.175464) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:36.175806) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0225961ad7adbef25e670abf314ec67f5e6c224d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0225961ad7adbef25e670abf314ec67f5e6c224d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RgPDTPc9Os/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-24 23:07:36,256 truncating revision to first 12 chars 2016-04-24 23:07:36,256 Setting DEBUG logging. 2016-04-24 23:07:36,256 attempt 1/10 2016-04-24 23:07:36,256 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0225961ad7ad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-24 23:07:36,933 unpacking tar archive at: fx-team-0225961ad7ad/testing/mozharness/ program finished with exit code 0 elapsedTime=0.922288 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:37.124558) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:37.124865) ========= script_repo_revision: 0225961ad7adbef25e670abf314ec67f5e6c224d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:37.125226) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:37.125489) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 23:07:37.139425) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 47 secs) (at 2016-04-24 23:07:37.139692) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RgPDTPc9Os/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 23:07:37 INFO - MultiFileLogger online at 20160424 23:07:37 in /builds/slave/test 23:07:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:07:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:07:37 INFO - {'append_to_log': False, 23:07:37 INFO - 'base_work_dir': '/builds/slave/test', 23:07:37 INFO - 'blob_upload_branch': 'Fx-Team', 23:07:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:07:37 INFO - 'branch': 'Fx-Team', 23:07:37 INFO - 'buildbot_json_path': 'buildprops.json', 23:07:37 INFO - 'config_files': ('talos/mac_config.py',), 23:07:37 INFO - 'default_actions': ('clobber', 23:07:37 INFO - 'read-buildbot-config', 23:07:37 INFO - 'download-and-extract', 23:07:37 INFO - 'populate-webroot', 23:07:37 INFO - 'create-virtualenv', 23:07:37 INFO - 'install', 23:07:37 INFO - 'run-tests'), 23:07:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:07:37 INFO - 'download_minidump_stackwalk': True, 23:07:37 INFO - 'download_symbols': 'ondemand', 23:07:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:07:37 INFO - 'tooltool.py': '/tools/tooltool.py', 23:07:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:07:37 INFO - '/tools/misc-python/virtualenv.py')}, 23:07:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:07:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:07:37 INFO - 'installer_path': 'installer.exe', 23:07:37 INFO - 'log_level': 'info', 23:07:37 INFO - 'log_name': 'talos', 23:07:37 INFO - 'log_to_console': True, 23:07:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:07:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:07:37 INFO - 'opt_config_files': (), 23:07:37 INFO - 'pip_index': False, 23:07:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:07:37 INFO - 'cmd': ('bash', 23:07:37 INFO - '-c', 23:07:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:07:37 INFO - 'enabled': True, 23:07:37 INFO - 'halt_on_failure': False, 23:07:37 INFO - 'name': 'check_screen_resolution'},), 23:07:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:07:37 INFO - 'cmd': ('bash', 23:07:37 INFO - '-c', 23:07:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:07:37 INFO - 'enabled': True, 23:07:37 INFO - 'halt_on_failure': False, 23:07:37 INFO - 'name': 'check_screen_resolution'},), 23:07:37 INFO - 'run_cmd_checks_enabled': True, 23:07:37 INFO - 'sps_profile': False, 23:07:37 INFO - 'sps_profile_interval': 0, 23:07:37 INFO - 'suite': 'dromaeojs', 23:07:37 INFO - 'system_bits': '32', 23:07:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:07:37 INFO - 'title': 't-yosemite-r7-0132', 23:07:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:07:37 INFO - 'use_talos_json': True, 23:07:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:07:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:07:37 INFO - 'work_dir': 'build'} 23:07:37 INFO - ##### 23:07:37 INFO - ##### Running clobber step. 23:07:37 INFO - ##### 23:07:37 INFO - Running pre-action listener: _resource_record_pre_action 23:07:37 INFO - Running main action method: clobber 23:07:37 INFO - rmtree: /builds/slave/test/build 23:07:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:07:38 INFO - Running post-action listener: _resource_record_post_action 23:07:38 INFO - ##### 23:07:38 INFO - ##### Running read-buildbot-config step. 23:07:38 INFO - ##### 23:07:38 INFO - Running pre-action listener: _resource_record_pre_action 23:07:38 INFO - Running main action method: read_buildbot_config 23:07:38 INFO - Using buildbot properties: 23:07:38 INFO - { 23:07:38 INFO - "project": "", 23:07:38 INFO - "product": "firefox", 23:07:38 INFO - "script_repo_revision": "production", 23:07:38 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 23:07:38 INFO - "repository": "", 23:07:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 23:07:38 INFO - "buildid": "20160424221650", 23:07:38 INFO - "pgo_build": "False", 23:07:38 INFO - "basedir": "/builds/slave/test", 23:07:38 INFO - "buildnumber": 662, 23:07:38 INFO - "slavename": "t-yosemite-r7-0132", 23:07:38 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 23:07:38 INFO - "platform": "yosemite_r7", 23:07:38 INFO - "scheduler": "tests-fx-team-macosx64-talos", 23:07:38 INFO - "branch": "fx-team", 23:07:38 INFO - "repo_path": "integration/fx-team", 23:07:38 INFO - "revision": "0225961ad7adbef25e670abf314ec67f5e6c224d", 23:07:38 INFO - "stage_platform": "macosx64", 23:07:38 INFO - "builduid": "36cac20aebb0420f866366d653b76dc1", 23:07:38 INFO - "slavebuilddir": "test" 23:07:38 INFO - } 23:07:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 23:07:38 INFO - Running post-action listener: _resource_record_post_action 23:07:38 INFO - ##### 23:07:38 INFO - ##### Running download-and-extract step. 23:07:38 INFO - ##### 23:07:38 INFO - Running pre-action listener: _resource_record_pre_action 23:07:38 INFO - Running main action method: download_and_extract 23:07:38 INFO - mkdir: /builds/slave/test/build/tests 23:07:38 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')]} 23:07:38 INFO - https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:07:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 23:07:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 23:07:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 23:07:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 23:07:40 INFO - Downloaded 1199 bytes. 23:07:40 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 23:07:40 INFO - Using the following test package requirements: 23:07:40 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 23:07:40 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 23:07:40 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 23:07:40 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 23:07:40 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 23:07:40 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 23:07:40 INFO - u'jsshell-mac.zip'], 23:07:40 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 23:07:40 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 23:07:40 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 23:07:40 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 23:07:40 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 23:07:40 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 23:07:40 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 23:07:40 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 23:07:40 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 23:07:40 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 23:07:40 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 23:07:40 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:40 INFO - https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:07:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 23:07:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 23:07:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 23:07:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:07:41 INFO - Downloaded 18109575 bytes. 23:07:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:07:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 23:07:45 INFO - Return code: 0 23:07:45 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:07: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')]} 23:07:45 INFO - https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:07:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 23:07:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 23:07:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 23:07:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:07:50 INFO - Downloaded 18109575 bytes. 23:07:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:07:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 23:07:53 INFO - Return code: 0 23:07:53 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')]} 23:07:53 INFO - https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:07:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 23:07:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 23:07:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 23:07:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:07:55 INFO - Downloaded 10840145 bytes. 23:07:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:07:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 23:07:55 INFO - Return code: 0 23:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:55 INFO - https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 23:07:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 23:07:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 23:07:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 23:07:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 23:08:00 INFO - Downloaded 110114897 bytes. 23:08:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 23:08:00 INFO - mkdir: /builds/slave/test/properties 23:08:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:08:00 INFO - Writing to file /builds/slave/test/properties/build_url 23:08:00 INFO - Contents: 23:08:00 INFO - build_url:https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 23:08:01 INFO - Running post-action listener: _resource_record_post_action 23:08:01 INFO - Running post-action listener: set_extra_try_arguments 23:08:01 INFO - ##### 23:08:01 INFO - ##### Running populate-webroot step. 23:08:01 INFO - ##### 23:08:01 INFO - Running pre-action listener: _resource_record_pre_action 23:08:01 INFO - Running main action method: populate_webroot 23:08:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:08:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:08:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:08:01 INFO - '--tppagecycles', 23:08:01 INFO - '7'], 23:08:01 INFO - 'tests': ['tsvgm']}}, 23:08:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:08:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:08:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:08:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:08:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:08:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:08:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:01 INFO - 'talos_options': ['--disable-e10s'], 23:08:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:08:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:08:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:01 INFO - 'talos_options': ['--disable-e10s'], 23:08:01 INFO - 'tests': ['damp', 'tps']}, 23:08:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:01 INFO - 'tests': ['damp', 'tps']}, 23:08:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:08:01 INFO - 'tests': ['dromaeo_dom']}, 23:08:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:08:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:08:01 INFO - 'tests': ['a11yr', 23:08:01 INFO - 'ts_paint', 23:08:01 INFO - 'tpaint', 23:08:01 INFO - 'sessionrestore', 23:08:01 INFO - 'sessionrestore_no_auto_restore', 23:08:01 INFO - 'tabpaint']}, 23:08:01 INFO - 'other-e10s': {'tests': ['a11yr', 23:08:01 INFO - 'ts_paint', 23:08:01 INFO - 'tpaint', 23:08:01 INFO - 'sessionrestore', 23:08:01 INFO - 'sessionrestore_no_auto_restore', 23:08:01 INFO - 'tabpaint']}, 23:08:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:08:01 INFO - 'ts_paint', 23:08:01 INFO - 'tpaint', 23:08:01 INFO - 'sessionrestore', 23:08:01 INFO - 'sessionrestore_no_auto_restore', 23:08:01 INFO - 'tabpaint']}, 23:08:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:08:01 INFO - 'ts_paint', 23:08:01 INFO - 'tpaint', 23:08:01 INFO - 'sessionrestore', 23:08:01 INFO - 'sessionrestore_no_auto_restore', 23:08:01 INFO - 'tabpaint']}, 23:08:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:08:01 INFO - 'tests': ['a11yr', 23:08:01 INFO - 'ts_paint', 23:08:01 INFO - 'tpaint', 23:08:01 INFO - 'sessionrestore', 23:08:01 INFO - 'sessionrestore_no_auto_restore', 23:08:01 INFO - 'tabpaint']}, 23:08:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:08:01 INFO - 'tests': ['a11yr', 23:08:01 INFO - 'ts_paint', 23:08:01 INFO - 'tpaint', 23:08:01 INFO - 'sessionrestore', 23:08:01 INFO - 'sessionrestore_no_auto_restore', 23:08:01 INFO - 'tabpaint']}, 23:08:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:08:01 INFO - 'tests': ['tsvgx', 23:08:01 INFO - 'tsvgr_opacity', 23:08:01 INFO - 'tart', 23:08:01 INFO - 'tscrollx', 23:08:01 INFO - 'cart']}, 23:08:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:08:01 INFO - 'tsvgr_opacity', 23:08:01 INFO - 'tart', 23:08:01 INFO - 'tscrollx', 23:08:01 INFO - 'cart']}, 23:08:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:01 INFO - 'talos_options': ['--disable-e10s'], 23:08:01 INFO - 'tests': ['tp5o']}, 23:08:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:01 INFO - 'tests': ['tp5o']}, 23:08:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:01 INFO - 'talos_options': ['--disable-e10s', 23:08:01 INFO - '--xperf_path', 23:08:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:08:01 INFO - 'tests': ['tp5n']}, 23:08:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:01 INFO - 'talos_options': ['--xperf_path', 23:08:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:08:01 INFO - 'tests': ['tp5n']}}, 23:08:01 INFO - 'talos.zip': {'path': '', 23:08:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:08:01 INFO - Running post-action listener: _resource_record_post_action 23:08:01 INFO - ##### 23:08:01 INFO - ##### Running create-virtualenv step. 23:08:01 INFO - ##### 23:08:01 INFO - Running pre-action listener: _resource_record_pre_action 23:08:01 INFO - Running main action method: create_virtualenv 23:08:01 INFO - Creating virtualenv /builds/slave/test/build/venv 23:08:01 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 23:08:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:08:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:08:01 INFO - Using real prefix '/tools/python27' 23:08:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:08:02 INFO - Installing distribute.............................................................................................................................................................................................done. 23:08:05 INFO - Installing pip.................done. 23:08:05 INFO - Return code: 0 23:08:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e32d6f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102148e00>, '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 0x101f71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102143b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:08:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:08:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:08:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 23:08:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:08:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:08:05 INFO - 'HOME': '/Users/cltbld', 23:08:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:08:05 INFO - 'LOGNAME': 'cltbld', 23:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:05 INFO - 'MOZ_NO_REMOTE': '1', 23:08:05 INFO - 'NO_EM_RESTART': '1', 23:08:05 INFO - 'PAGER': '/bin/cat', 23:08:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:05 INFO - 'PWD': '/builds/slave/test', 23:08:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:08:05 INFO - 'SHELL': '/bin/bash', 23:08:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 23:08:05 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 23:08:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:08:05 INFO - 'USER': 'cltbld', 23:08:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:08:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:05 INFO - 'XPC_FLAGS': '0x0', 23:08:05 INFO - 'XPC_SERVICE_NAME': '0', 23:08:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:08:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:05 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 23:08:05 INFO - Cleaning up... 23:08:05 INFO - Return code: 0 23:08:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e32d6f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102148e00>, '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 0x101f71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102143b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:08:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 23:08:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 23:08:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 23:08:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:08:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:08:05 INFO - 'HOME': '/Users/cltbld', 23:08:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:08:05 INFO - 'LOGNAME': 'cltbld', 23:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:05 INFO - 'MOZ_NO_REMOTE': '1', 23:08:05 INFO - 'NO_EM_RESTART': '1', 23:08:05 INFO - 'PAGER': '/bin/cat', 23:08:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:05 INFO - 'PWD': '/builds/slave/test', 23:08:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:08:05 INFO - 'SHELL': '/bin/bash', 23:08:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 23:08:05 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 23:08:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:08:05 INFO - 'USER': 'cltbld', 23:08:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:08:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:05 INFO - 'XPC_FLAGS': '0x0', 23:08:05 INFO - 'XPC_SERVICE_NAME': '0', 23:08:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:08:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:05 INFO - Downloading/unpacking psutil>=0.7.1 23:08:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:08:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:08:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:08:08 INFO - Installing collected packages: psutil 23:08:08 INFO - Running setup.py install for psutil 23:08:08 INFO - building 'psutil._psutil_osx' extension 23:08:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 23:08:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 23:08:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 23:08:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 23:08:09 INFO - building 'psutil._psutil_posix' extension 23:08:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 23:08:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:08:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:08:09 INFO - ^ 23:08:09 INFO - 1 warning generated. 23:08:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 23:08:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:08:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:08:09 INFO - Successfully installed psutil 23:08:09 INFO - Cleaning up... 23:08:09 INFO - Return code: 0 23:08:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:09 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e32d6f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102148e00>, '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 0x101f71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102143b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:08:09 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.0.0'] in /builds/slave/test/build 23:08:09 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.0.0 23:08:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 23:08:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:08:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:08:09 INFO - 'HOME': '/Users/cltbld', 23:08:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:08:09 INFO - 'LOGNAME': 'cltbld', 23:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:09 INFO - 'MOZ_NO_REMOTE': '1', 23:08:09 INFO - 'NO_EM_RESTART': '1', 23:08:09 INFO - 'PAGER': '/bin/cat', 23:08:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:09 INFO - 'PWD': '/builds/slave/test', 23:08:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:08:09 INFO - 'SHELL': '/bin/bash', 23:08:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 23:08:09 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 23:08:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:08:09 INFO - 'USER': 'cltbld', 23:08:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:08:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:09 INFO - 'XPC_FLAGS': '0x0', 23:08:09 INFO - 'XPC_SERVICE_NAME': '0', 23:08:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:08:09 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 23:08:09 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 23:08:09 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 23:08:09 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 23:08:09 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 23:08:09 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 23:08:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:08:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:08:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:08:12 INFO - Installing collected packages: mozsystemmonitor 23:08:12 INFO - Running setup.py install for mozsystemmonitor 23:08:12 INFO - Successfully installed mozsystemmonitor 23:08:12 INFO - Cleaning up... 23:08:12 INFO - Return code: 0 23:08:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:12 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 0x1022a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e32d6f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102148e00>, '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 0x101f71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102143b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:08:12 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 23:08:12 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 23:08:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 23:08:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:08:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:08:12 INFO - 'HOME': '/Users/cltbld', 23:08:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:08:12 INFO - 'LOGNAME': 'cltbld', 23:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:12 INFO - 'MOZ_NO_REMOTE': '1', 23:08:12 INFO - 'NO_EM_RESTART': '1', 23:08:12 INFO - 'PAGER': '/bin/cat', 23:08:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:12 INFO - 'PWD': '/builds/slave/test', 23:08:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:08:12 INFO - 'SHELL': '/bin/bash', 23:08:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 23:08:12 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 23:08:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:08:12 INFO - 'USER': 'cltbld', 23:08:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:08:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:12 INFO - 'XPC_FLAGS': '0x0', 23:08:12 INFO - 'XPC_SERVICE_NAME': '0', 23:08:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:12 INFO - Downloading/unpacking blobuploader==1.2.4 23:08:12 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 23:08:12 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 23:08:12 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 23:08:12 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 23:08:12 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 23:08:12 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 23:08:14 INFO - Downloading blobuploader-1.2.4.tar.gz 23:08:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:08:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:08:15 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 23:08:15 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 23:08:15 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 23:08:15 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 23:08:15 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 23:08:15 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 23:08:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:08:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:08:15 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 23:08:15 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 23:08:15 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 23:08:15 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 23:08:15 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 23:08:15 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 23:08:16 INFO - Downloading docopt-0.6.1.tar.gz 23:08:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:08:16 INFO - Installing collected packages: blobuploader, requests, docopt 23:08:16 INFO - Running setup.py install for blobuploader 23:08:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:08:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:08:16 INFO - Running setup.py install for requests 23:08:16 INFO - Running setup.py install for docopt 23:08:17 INFO - Successfully installed blobuploader requests docopt 23:08:17 INFO - Cleaning up... 23:08:17 INFO - Return code: 0 23:08:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:17 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 0x1022a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e32d6f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102148e00>, '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 0x101f71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102143b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:08:17 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 23:08:17 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 23:08:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 23:08:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:08:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:08:17 INFO - 'HOME': '/Users/cltbld', 23:08:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:08:17 INFO - 'LOGNAME': 'cltbld', 23:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:17 INFO - 'MOZ_NO_REMOTE': '1', 23:08:17 INFO - 'NO_EM_RESTART': '1', 23:08:17 INFO - 'PAGER': '/bin/cat', 23:08:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:17 INFO - 'PWD': '/builds/slave/test', 23:08:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:08:17 INFO - 'SHELL': '/bin/bash', 23:08:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 23:08:17 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 23:08:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:08:17 INFO - 'USER': 'cltbld', 23:08:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:08:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:17 INFO - 'XPC_FLAGS': '0x0', 23:08:17 INFO - 'XPC_SERVICE_NAME': '0', 23:08:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:08:17 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Tq1VOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:08:17 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-nmKFac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:08:17 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-gTAJj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:08:17 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-kDvSXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:08:17 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-agjKgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:08:17 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-CwnfRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:08:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-QNzC7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:08:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-OFd87X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:08:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-PbbEAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:08:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-OJgKnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:08:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-7FTA2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:08:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-q38q9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:08:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-jxolRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:08:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-pyhXSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:08:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-auzuOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:08:19 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-G51BZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:08:19 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-t6hRwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:08:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:08:19 INFO - Running setup.py install for manifestparser 23:08:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:08:19 INFO - Running setup.py install for mozcrash 23:08:19 INFO - Running setup.py install for mozdebug 23:08:19 INFO - Running setup.py install for mozdevice 23:08:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:08:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:08:19 INFO - Running setup.py install for mozfile 23:08:19 INFO - Running setup.py install for mozhttpd 23:08:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:08:20 INFO - Running setup.py install for mozinfo 23:08:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:08:20 INFO - Running setup.py install for mozInstall 23:08:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:08:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:08:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:08:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:08:20 INFO - Running setup.py install for mozleak 23:08:20 INFO - Running setup.py install for mozlog 23:08:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:08:20 INFO - Running setup.py install for moznetwork 23:08:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:08:20 INFO - Running setup.py install for mozprocess 23:08:21 INFO - Running setup.py install for mozprofile 23:08:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:08:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:08:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:08:21 INFO - Running setup.py install for mozrunner 23:08:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:08:21 INFO - Running setup.py install for mozscreenshot 23:08:21 INFO - Running setup.py install for moztest 23:08:21 INFO - Running setup.py install for mozversion 23:08:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:08:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:08:21 INFO - Cleaning up... 23:08:21 INFO - Return code: 0 23:08:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:08:21 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')]} 23:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:21 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')]} 23:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:21 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 0x1022a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e32d6f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102148e00>, '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 0x101f71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102143b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:08:21 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 23:08:21 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 23:08:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 23:08:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:08:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:08:21 INFO - 'HOME': '/Users/cltbld', 23:08:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:08:21 INFO - 'LOGNAME': 'cltbld', 23:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:21 INFO - 'MOZ_NO_REMOTE': '1', 23:08:21 INFO - 'NO_EM_RESTART': '1', 23:08:21 INFO - 'PAGER': '/bin/cat', 23:08:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:21 INFO - 'PWD': '/builds/slave/test', 23:08:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:08:21 INFO - 'SHELL': '/bin/bash', 23:08:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 23:08:21 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 23:08:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:08:21 INFO - 'USER': 'cltbld', 23:08:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:08:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:21 INFO - 'XPC_FLAGS': '0x0', 23:08:21 INFO - 'XPC_SERVICE_NAME': '0', 23:08:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:08:22 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-iVKjq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:08:22 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)) 23:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:08:22 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-SAKXfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:08:22 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)) 23:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:08:22 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-CHE1pZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:08:22 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)) 23:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:08:22 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-IcajVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:08:22 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)) 23:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:08:22 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-8mkm90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:08:22 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)) 23:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:08:22 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-oKXmI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:08:22 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)) 23:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:08:22 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-WkTFks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:08:22 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)) 23:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:08:22 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-EBqkmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:08:22 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)) 23:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:08:22 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-0ftHVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:08:22 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)) 23:08:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:08:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-wbf2aF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 23:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:08:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-TCx5xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:08:23 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)) 23:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:08:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Xr3Usy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 23:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:08:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-YPPsrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:08:23 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)) 23:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:08:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-bfRQJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 23:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:08:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-zjsxVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:08:23 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)) 23:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:08:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-80_WdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:08:23 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)) 23:08:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:08:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-vBJEWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:08:23 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)) 23:08:23 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)) 23:08:23 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)) 23:08:23 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)) 23:08:23 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)) 23:08:23 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)) 23:08:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:08:23 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 23:08:23 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 23:08:23 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 23:08:23 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 23:08:23 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 23:08:23 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 23:08:26 INFO - Downloading blessings-1.6.tar.gz 23:08:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:08:26 INFO - Installing collected packages: blessings 23:08:26 INFO - Running setup.py install for blessings 23:08:26 INFO - Successfully installed blessings 23:08:26 INFO - Cleaning up... 23:08:26 INFO - Return code: 0 23:08:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:08:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:08:26 INFO - Reading from file tmpfile_stdout 23:08:26 INFO - Current package versions: 23:08:26 INFO - blessings == 1.6 23:08:26 INFO - blobuploader == 1.2.4 23:08:26 INFO - docopt == 0.6.1 23:08:26 INFO - manifestparser == 1.1 23:08:26 INFO - mozInstall == 1.12 23:08:26 INFO - mozcrash == 0.17 23:08:26 INFO - mozdebug == 0.1 23:08:26 INFO - mozdevice == 0.48 23:08:26 INFO - mozfile == 1.2 23:08:26 INFO - mozhttpd == 0.7 23:08:26 INFO - mozinfo == 0.9 23:08:26 INFO - mozleak == 0.1 23:08:26 INFO - mozlog == 3.1 23:08:26 INFO - moznetwork == 0.27 23:08:26 INFO - mozprocess == 0.22 23:08:26 INFO - mozprofile == 0.28 23:08:26 INFO - mozrunner == 6.11 23:08:26 INFO - mozscreenshot == 0.1 23:08:26 INFO - mozsystemmonitor == 0.0 23:08:26 INFO - moztest == 0.7 23:08:26 INFO - mozversion == 1.4 23:08:26 INFO - psutil == 3.1.1 23:08:26 INFO - requests == 1.2.3 23:08:26 INFO - wsgiref == 0.1.2 23:08:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:08:26 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')]} 23:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:26 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')]} 23:08:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:26 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 0x1022a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e32d6f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102148e00>, '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 0x101f71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102143b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:08:26 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 23:08:26 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 23:08:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 23:08:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:08:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:08:26 INFO - 'HOME': '/Users/cltbld', 23:08:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:08:26 INFO - 'LOGNAME': 'cltbld', 23:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:26 INFO - 'MOZ_NO_REMOTE': '1', 23:08:26 INFO - 'NO_EM_RESTART': '1', 23:08:26 INFO - 'PAGER': '/bin/cat', 23:08:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:26 INFO - 'PWD': '/builds/slave/test', 23:08:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:08:26 INFO - 'SHELL': '/bin/bash', 23:08:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 23:08:26 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 23:08:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:08:26 INFO - 'USER': 'cltbld', 23:08:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:08:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:26 INFO - 'XPC_FLAGS': '0x0', 23:08:26 INFO - 'XPC_SERVICE_NAME': '0', 23:08:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:27 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)) 23:08:27 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)) 23:08:27 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)) 23:08:27 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)) 23:08:27 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)) 23:08:27 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)) 23:08:27 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)) 23:08:27 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)) 23:08:27 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)) 23:08:27 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)) 23:08:27 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)) 23:08:27 INFO - Cleaning up... 23:08:27 INFO - Return code: 0 23:08:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:08:27 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')]} 23:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:27 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')]} 23:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:27 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 0x1022a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e32d6f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102148e00>, '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 0x101f71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102143b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:08:27 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 23:08:27 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 23:08:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 23:08:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:08:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:08:27 INFO - 'HOME': '/Users/cltbld', 23:08:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:08:27 INFO - 'LOGNAME': 'cltbld', 23:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:27 INFO - 'MOZ_NO_REMOTE': '1', 23:08:27 INFO - 'NO_EM_RESTART': '1', 23:08:27 INFO - 'PAGER': '/bin/cat', 23:08:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:27 INFO - 'PWD': '/builds/slave/test', 23:08:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:08:27 INFO - 'SHELL': '/bin/bash', 23:08:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 23:08:27 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 23:08:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:08:27 INFO - 'USER': 'cltbld', 23:08:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:08:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:27 INFO - 'XPC_FLAGS': '0x0', 23:08:27 INFO - 'XPC_SERVICE_NAME': '0', 23:08:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:27 INFO - Downloading/unpacking jsonschema 23:08:27 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 23:08:27 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 23:08:27 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 23:08:27 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 23:08:27 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 23:08:27 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 23:08:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:08:29 INFO - Downloading/unpacking functools32 (from jsonschema) 23:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:30 INFO - Downloading functools32-3.2.3-2.tar.gz 23:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:08:30 INFO - warning: no files found matching '*.txt' 23:08:30 INFO - no previously-included directories found matching 'build' 23:08:30 INFO - no previously-included directories found matching 'dist' 23:08:30 INFO - no previously-included directories found matching '.git*' 23:08:30 INFO - Installing collected packages: jsonschema, functools32 23:08:30 INFO - Running setup.py install for functools32 23:08:30 INFO - warning: no files found matching '*.txt' 23:08:30 INFO - no previously-included directories found matching 'build' 23:08:30 INFO - no previously-included directories found matching 'dist' 23:08:30 INFO - no previously-included directories found matching '.git*' 23:08:30 INFO - Successfully installed jsonschema functools32 23:08:30 INFO - Cleaning up... 23:08:30 INFO - Return code: 0 23:08:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:08:30 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')]} 23:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:30 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')]} 23:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:30 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022b9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e32d6f370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102148e00>, '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 0x101f71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102143b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:08:30 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', 'flake8'] in /builds/slave/test/build 23:08:30 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 flake8 23:08:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 23:08:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:08:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:08:30 INFO - 'HOME': '/Users/cltbld', 23:08:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:08:30 INFO - 'LOGNAME': 'cltbld', 23:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:30 INFO - 'MOZ_NO_REMOTE': '1', 23:08:30 INFO - 'NO_EM_RESTART': '1', 23:08:30 INFO - 'PAGER': '/bin/cat', 23:08:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:30 INFO - 'PWD': '/builds/slave/test', 23:08:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:08:30 INFO - 'SHELL': '/bin/bash', 23:08:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 23:08:30 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 23:08:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:08:30 INFO - 'USER': 'cltbld', 23:08:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:08:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:30 INFO - 'XPC_FLAGS': '0x0', 23:08:30 INFO - 'XPC_SERVICE_NAME': '0', 23:08:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:30 INFO - Downloading/unpacking flake8 23:08:30 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 23:08:30 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 23:08:30 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 23:08:30 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 23:08:30 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 23:08:30 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 23:08:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:08:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:08:33 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 23:08:33 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 23:08:33 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 23:08:33 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 23:08:33 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 23:08:33 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 23:08:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:08:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:08:33 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 23:08:33 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 23:08:33 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 23:08:33 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 23:08:33 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 23:08:33 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 23:08:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:08:33 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 23:08:33 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 23:08:33 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 23:08:33 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 23:08:33 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 23:08:33 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 23:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:08:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:08:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:08:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:08:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:08:34 INFO - no previously-included directories found matching 'docs/_build' 23:08:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:08:34 INFO - Running setup.py install for pep8 23:08:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:08:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:08:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:08:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:08:34 INFO - no previously-included directories found matching 'docs/_build' 23:08:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:08:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:08:34 INFO - Cleaning up... 23:08:34 INFO - Return code: 0 23:08:34 INFO - Running post-action listener: _resource_record_post_action 23:08:34 INFO - Running post-action listener: _start_resource_monitoring 23:08:34 INFO - Starting resource monitoring. 23:08:34 INFO - ##### 23:08:34 INFO - ##### Running install step. 23:08:34 INFO - ##### 23:08:34 INFO - Running pre-action listener: _resource_record_pre_action 23:08:34 INFO - Running main action method: install 23:08:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:08:35 INFO - Reading from file tmpfile_stdout 23:08:35 INFO - Detecting whether we're running mozinstall >=1.0... 23:08:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:08:35 INFO - Reading from file tmpfile_stdout 23:08:35 INFO - Output received: 23:08:35 INFO - Usage: mozinstall [options] installer 23:08:35 INFO - Options: 23:08:35 INFO - -h, --help show this help message and exit 23:08:35 INFO - -d DEST, --destination=DEST 23:08:35 INFO - Directory to install application into. [default: 23:08:35 INFO - "/builds/slave/test"] 23:08:35 INFO - --app=APP Application being installed. [default: firefox] 23:08:35 INFO - mkdir: /builds/slave/test/build/application 23:08:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:09:08 INFO - Reading from file tmpfile_stdout 23:09:08 INFO - Output received: 23:09:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:09:08 INFO - Running post-action listener: _resource_record_post_action 23:09:08 INFO - ##### 23:09:08 INFO - ##### Running run-tests step. 23:09:08 INFO - ##### 23:09:08 INFO - Running pre-action listener: _resource_record_pre_action 23:09:08 INFO - Running main action method: run_tests 23:09:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:09:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:09:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:09:08 INFO - 2016-04-24 23:09:08.787 screenresolution[1926:16872] starting screenresolution argv=screenresolution get 23:09:08 INFO - 2016-04-24 23:09:08.789 screenresolution[1926:16872] Display 0: 1600x1200x32@60 23:09:08 INFO - 2016-04-24 23:09:08.795 screenresolution[1927:16874] starting screenresolution argv=screenresolution list 23:09:08 INFO - Available Modes on Display 0 23:09:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:09:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:09:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:09:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:09:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:09:09 INFO - Intel Iris: 23:09:09 INFO - Chipset Model: Intel Iris 23:09:09 INFO - Type: GPU 23:09:09 INFO - Bus: Built-In 23:09:09 INFO - VRAM (Dynamic, Max): 1536 MB 23:09:09 INFO - Vendor: Intel (0x8086) 23:09:09 INFO - Device ID: 0x0a2e 23:09:09 INFO - Revision ID: 0x0009 23:09:09 INFO - Displays: 23:09:09 INFO - Display: 23:09:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:09:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:09:09 INFO - Display Serial Number: 1600x1200 60 23:09:09 INFO - Main Display: Yes 23:09:09 INFO - Mirror: Off 23:09:09 INFO - Online: Yes 23:09:09 INFO - Rotation: Supported 23:09:09 INFO - Return code: 0 23:09:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:09:09 INFO - Python 2.7.3 23:09:09 INFO - Return code: 0 23:09:09 INFO - grabbing minidump binary from tooltool 23:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:09 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102148e00>, '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 0x101f71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102143b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:09:09 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:09:09 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 23:09:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:09:09 INFO - Return code: 0 23:09:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:09:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:09:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:09:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:09:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:09:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:09:10 INFO - Return code: 0 23:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--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 23:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0132 --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 23:09:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgPDTPc9Os/Render', 23:09:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:09:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:09:10 INFO - 'HOME': '/Users/cltbld', 23:09:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:09:10 INFO - 'LOGNAME': 'cltbld', 23:09:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:09:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:10 INFO - 'MOZ_NO_REMOTE': '1', 23:09:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:09:10 INFO - 'NO_EM_RESTART': '1', 23:09:10 INFO - 'PAGER': '/bin/cat', 23:09:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:10 INFO - 'PWD': '/builds/slave/test', 23:09:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:09:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:09:10 INFO - 'SHELL': '/bin/bash', 23:09:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners', 23:09:10 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 23:09:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:09:10 INFO - 'USER': 'cltbld', 23:09:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:09:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:10 INFO - 'XPC_FLAGS': '0x0', 23:09:10 INFO - 'XPC_SERVICE_NAME': '0', 23:09:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:09:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--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 23:09:10 INFO - mozversion application_buildid: 20160424221650 23:09:10 INFO - mozversion application_changeset: 0225961ad7adbef25e670abf314ec67f5e6c224d 23:09:10 INFO - mozversion application_display_name: Nightly 23:09:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:09:10 INFO - mozversion application_name: Firefox 23:09:10 INFO - mozversion application_remotingname: firefox 23:09:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:09:10 INFO - mozversion application_vendor: Mozilla 23:09:10 INFO - mozversion application_version: 48.0a1 23:09:10 INFO - mozversion platform_buildid: 20160424221650 23:09:10 INFO - mozversion platform_changeset: 0225961ad7adbef25e670abf314ec67f5e6c224d 23:09:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:09:10 INFO - mozversion platform_version: 48.0a1 23:09:10 INFO - using testdate: 1461564550 23:09:10 INFO - actual date: 1461564550 23:09:10 INFO - starting webserver on 'localhost:52272' 23:09:10 INFO - SUITE-START | Running 2 tests 23:09:10 INFO - TEST-START | dromaeo_css 23:09:10 INFO - operating with platform_type : mac_ 23:09:10 INFO - Initialising browser for dromaeo_css test... 23:09:10 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpLU0LVo/profile http://localhost:52272/getInfo.html) 23:09:12 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 23:09:12 INFO - PROCESS | 1945 | colorDepth:24 23:09:12 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 23:09:12 INFO - PROCESS | 1945 | __metrics 23:09:12 INFO - PROCESS | 1945 | 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" 23:09:12 ERROR - PROCESS | 1945 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:09:13 INFO - TEST-INFO | 1945: exit 0 23:09:13 INFO - Browser initialized. 23:09:13 INFO - Running cycle 1/1 for dromaeo_css test... 23:09:13 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpLU0LVo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:09:25 INFO - PROCESS | 1947 | RSS: Main: 239226880 23:09:25 INFO - PROCESS | 1947 | 23:10:16 INFO - PROCESS | 1947 | 1461564616794 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] 23:12:27 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52272/tests/dromaeo/cssquery-ext.html) 23:12:28 INFO - PROCESS | 1947 | RSS: Main: 355024896 23:12:28 INFO - PROCESS | 1947 | 23:15:31 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-ext.html (next: http://localhost:52272/tests/dromaeo/cssquery-jquery.html) 23:15:31 INFO - PROCESS | 1947 | RSS: Main: 367366144 23:15:31 INFO - PROCESS | 1947 | 23:18:34 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52272/tests/dromaeo/cssquery-mootools.html) 23:18:34 INFO - PROCESS | 1947 | RSS: Main: 370282496 23:18:34 INFO - PROCESS | 1947 | 23:21:36 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52272/tests/dromaeo/cssquery-prototype.html) 23:21:37 INFO - PROCESS | 1947 | RSS: Main: 372584448 23:21:37 INFO - PROCESS | 1947 | 23:24:40 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52272/tests/dromaeo/cssquery-yui.html) 23:24:41 INFO - PROCESS | 1947 | RSS: Main: 346161152 23:24:41 INFO - PROCESS | 1947 | 23:27:44 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-yui.html (next: http://localhost:52272/tests/dromaeo/cssquery-dojo.html) 23:27:44 INFO - PROCESS | 1947 | RSS: Main: 395464704 23:27:44 INFO - PROCESS | 1947 | 23:27:44 INFO - PROCESS | 1947 | __start_tp_report 23:27:44 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 23:27:44 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 23:27:44 INFO - PROCESS | 1947 | |i|pagename|runs| 23:27:44 INFO - PROCESS | 1947 | |0;dojo.html;3365.091425315165;3430.6912377885983;3461.948070778937;3467.531883195768;3502.8773992910255;5582.051051321275;5702.429757024295;5712.371639119699;5736.110902810065;5736.885262294757;5720.141978703186;5721.799737009205;5824;5842.240508733869;5855.677937713424;5126.512981266792;5172.818951336713;5195.506426889439;5209.7134657593815;5294.655847369928;5232.843014709565;5274.129768588178;5282.603804714642;5286.4449232830575;5338.492843179892;5113.130767769507;5125.256837758541;5203.089459344599;5205.453427390107;5229.8169564065265;5178.093833579109;5204.453532379083;5206.323177986876;5216.374035115772;5219.269302297681;5159.25190847325;5194.71429071401;5207.192885102815;5226.634135610509;5239.371275446971;5545.3622833374275;5572.359178694462;5634.408387119334;5646.632968857011;5670;6204.6587437690905;6222.502199824004;6251.187345645037;6253.968730156365;6259.906101408483;6248.187735594343;6291.7797877074545;6325.557210995232;6337.746490140389;6340.841478963017;5633.380328163898;5686.118651609007;5758.222639943596;5760.164776107441;5769.24999750034;6246.6876656167005;6299.275583307886;6302.1177035215105;6318.399752023551;6318.778842740482;6096.939030609726;6122.081687746873;6130.233720784902;6134.38656134383;6143.32423433422;6103.701349646989;6170.907436388457;6188.845278868064;6273.059041143773;6287.245530536365;6200;6207.751689932443;6223.190985171989;6225.097360882737;6232.59488133279;6227.346128656516;6245.344238854855;6261.060840873905;6272.278687950944;6278.215223097113;4425.601695847389;4742.312364707098;4922.237053256751;4940.481249468826;4976.353074100344;4344.131173765197;4424.292113261863;4427.247367947457;4454.997625534229;4474.552544745499;5610.13042978339;5631.2960879890015;5632.662040277596;5635.436456354414;5646;5054.620903432257;6247.937520624827;6281.7173227203975;6282.465990390777;6294.590851594632;5656.943430565724;5674.460926212003;5676.262085928803;5679.687617181012;5692.943070569324;5386.407495175527;5402.621816472809;5408.486193811582;5409;5420.403755586883;6207.193064901624;6248.250209974832;6299.275583307886;6318.115463835159;6327.050942358683;6283.8114856554375;6319.873602528016;6320.462760665211;6321.241451025906;6332.52506062038;6316.147320111726;6325.746970121144;6327.841803954938;6333.809985700436;6334.841628959313;6055.969720151371;6122.306673174842;6132.938670613236;6190.2571691397325;6213.9067913980425;5907.616004959664;6288.937110628835;6306.243250809932;6325.304216536086;6341.587796793194;5945.583809133492;6132.754689812357;6135.1717518136265;6139.171211886338;6151.0773383992755;5965.582409231312;6059.151718759289;6124.075808605272;6136.723847426966;6153.72308246139;5956.940430595639;5978.730957107027;5981.0729336952845;5984.700764961822;5988.341282459013;6109.7861574844665;6112.327643959072;6154.723037463235;6168.660723660242;6174.938250617436;5769.307683078057;5811.651300921958;5860.355360910211;5865.061590145727;5977.760889564369 23:27:44 INFO - PROCESS | 1947 | |1;ext.html;506.9163588007981;520.926890605353;534.2547146730311;585.9589828711986;713.8500914807892;30552.23619409516;30823.383532329368;31377.99999999997;31381.68618313816;31383.215419614466;42808.571914280845;43253.7837310813;43392;43458.130837383236;43538.782306088506;8530;9416.905830941689;9660.275479339043;9676;9684.903150968506;8703.390762646586;11492.13808964327;11803.114766392511;11831.40842957853;11835.704107397341;11521.423928803568;11646.650600481998;11651.893070158321;11654.650360489157;11663.766724665502;8609.913900860975;11513.251638643462;11566.59516916908;11666.708332291719;11838;11504.252223605523;11642.068634509304;11642.767144657144;11654.30074195551;11666.824997625043;39800.40299395483;40186;40224;40389.19221615551;40500.79749601262;3107.5183346581316;3219.7585181111276;3496.108492334464;3678.2275722098393;3989;29451.852740736373;29619.70380296191;29658.55512167319;29672.40655186906;29841.70158298411;2702.9729702702916;2747.986260068707;2749.793765467598;2762.337039110619;2811.712138988039;476283;476363;477775;478029;478387;49387.50612493901;62811;62897.685511573065;62939.370606294266;63088.369116309164;66886.33113668898;68858.3114168862;70933.93599096018;70942.64528677426;71084.80118394685;16835.915820420818;17123.400680976356;17167.91416042937;17175.65648687044;17175.742363864552;12151.817722734166;12349.19730217533;12371.876281237252;12381;13007.544735934385;17719.114044297992;18730.77971581467;21035.474113147295;21072.473188170417;21080.78919210788;6733.8653226935185;7987.44087913852;8045.9597702011115;8062.234087761653;8135.3491720662205;991.6330957545754;1014.0366651680932;1044;1080.8756992945755;1089.716673664837;29753.10740677783;30037.849810750806;30323.393532129234;30509.483079211604;30683.079507614806;1074.2050882347164;1094.813881640123;1128.700894263021;1204.722913729847;1280.1934781087855;15381.00023498469;15707.68584628301;15803.683926321639;15871.920640396724;15987.36050557988;574142;580276.0986195042;581183;581476;581649;61585.69207153936;62416.06375904365;62484;62560.74878502404;62653.43366415876;1363.2502123831853;1479.4895760962452;1505.9247037648074;1512.070076902719;1521.3306145296035;22216.55566888621;25770.96916123409;28712.856435717687;28934.842606296355;29592.40815183641;39624;48093.75953120212;48905;48939.5106048935;48947.51052489572;386043;387150;387545;387776;388151.1184888115;22891.084356626216;23511;27129;27367.72632273652;27433.31416714598;42708;44085.77957110194;45368;45464.77267613641;45605.54394456013;35443;36210.64920160672;36224;36609.45085823768;37179.6282037187;35091.473627895095;35697.28605427929;35934.8203258982;36609.816950915076;36701.44947825732;37038.074048149014;37464;37945.81027094847;38112.237755245296;38273.04317392087;31573.210669733442;33191.83404082964;33268.1682957928;33271.83364083164;33617.32765344631;18847.623047539248;24222.909969051787;24674.87662561676;25074.24777256685;25100.12149574756 23:27:44 INFO - PROCESS | 1947 | |2;jquery.html;334.4114358728637;391.5067015560394;396.09689907012006;396.67274498538706;412.56886553551533;19677.803221967777;20154.093065812038;21337.67993480097;21339.933003349808;21347.893260533696;12124.93937530311;12459.68850778731;12466.688332791684;13359;20456.386308410736;5300.204969254604;5477.863053423656;5513.641613295123;5522.171674248874;5524.779008839661;3788.867389641363;4095.89760255993;5131.255967884655;6110.327863934964;6128.63228206309;6000.75996960121;6071.392860713915;6090.756369745205;6099.329073801879;6147.508199344042;6577.309382514863;6582.6050436973455;6591.044298576703;6593.3736295051895;6621.106150669671;3772.792496412696;3955.6835453163967;3999.3601023836045;5428.805662754187;5787.623804452739;12896;13368.86631133686;13501.324933753272;13503.864961350358;13689.31553422325;1921.5580416504142;1946.0950657944124;2099.601075795609;2336.9250144933353;2447.6940382452194;9939;10020.85807526869;10088.495575221283;10459.895401045967;15366.923165384213;1656.7432048032574;1668.121617239922;1687.451578237078;1788.534980904974;1821.6174603333184;108337.45831270794;109208.61870719376;109285.45357273243;109530.45234773855;117348.41325793316;11615.245009074382;11649.301041937511;11692.35692036946;11750.412479375997;11858.822117668242;15379.92310038465;15384.692306153815;15440.305186266645;15447.227638618024;15449.382024718885;8689.956550217208;8810.691625793195;8811.822932890278;8842.911570884338;8918.241949434241;8767.727576673942;8768.123187681309;8811.603477843513;8911.598978046004;8918.821623567492;4625.722456652612;4639.097969832353;4639.907201856012;4641.8647422185495;4717.174494463455;1504.751715966875;1652.396875140565;1721.552396376926;1757.07338954823;1840.3006857394105;928.8560273157672;953.7186529973675;966.5553845231132;990.960361585543;1002.5939494504737;19748.802511974984;20257.29099481511;20392;20595.58808823815;20678.48303792387;880.506916126971;904.9321300902459;956.4357029352576;957.5978089202551;974.9951250243703;8370.916290837136;10784.245102842726;10797.190210734227;10815.78368432627;10906.945465272624;578439.6468105963;583739;588044;589581;594888;116379;116822;117900;118024;121297;900.4912224593083;967.5742673223759;1030.2685093583489;1045.2630895218851;1050.175612144463;11651.47568359409;17807.19867605935;18215.908920455313;18411;18814.623707526047;47400.7629961848;48466;48487.51512484971;49550.3107080554;51890.74054629854;63894.3610563907;67460.66269668816;67623.66188169028;67678.66160669165;67680.6615966917;7712.92287077122;7962.283394494523;8061.717839875576;8157;8562.74311770648;8091.676332946617;9047.502387368495;9699.369540979813;10036.949815250877;12019.75960480803;8986.14631609996;10370.740731481472;10427.947860260954;10727.624533141303;10751.548540946873;10589.841152382567;10635.20235982289;10780.676579702622;10988;11145.331280122895;10723.571057157626;10903.454827258764;10915.617953371593;10970.232083754061;11101.444927753419;8049.597520124087;8143.959280203561;8213.835723285381;8474.322054235523;8587.699430519902;10487.842682359618;10561.947190264;10616.203784716023;10871.456427178768;10892.346459212473 23:27:44 INFO - PROCESS | 1947 | |3;mootools.html;1236.8453943257093;1590.1969505399773;1599.8160211575669;1869.1308541528194;2003.9198432062708;7953;8648.956755216223;8888.822223555526;8890.688825891095;8893.68872089477;8489.193526614978;8714.651413943435;8737.606807693654;8740.388172827905;8770.956145219265;2431.234161239213;2468.419921318489;2469.777720005199;2471.0733474946896;2472.208893154191;2408.903643854244;2469.3085935937875;2470.518248941457;2470.555300045991;2471.678681771376;2430.501747141832;2452.6566280720713;2477.55404027275;2477.962830557543;2479.987600061997;2418.794402475783;2437.2079074300837;2449.608062709967;2458.6926634170727;2459.262221333611;2398.8080953523677;2428.0409238350753;2435.135526887947;2435.439848834778;2436.378723425515;8673.13268673134;8729.301655867579;8750;8809.42738721981;8821.294296456268;5299.417064122943;5472.097103977839;5476.7535460904355;5494.2857428534435;5522.723863806794;3442.6557344265625;3551.6803487686234;3617.0595645132194;3617.89146325608;3626.510421093145;997.1922742578535;1017.2319898476677;1021.3769600543662;1029.3360782295379;1029.6036026129918;11789.528418863236;11908.34504102274;11911.463984120735;11914.344711040889;11916.046716262677;4492.707973981681;4493.236149854532;4669.906601867943;4695.835645752382;4707.882302942454;9256.629734810667;9269.212116969999;9271.026542213105;9271.814563708824;9273.814523709489;5313.123334649817;5321.388040375329;5327.520523152857;5336.62643614951;5345.679259244458;4402.789232960961;4416.160929423463;4424.867253982386;4909.582685471775;4931.235658472942;3120.781545291853;3219.4687876500116;3783.564890037626;3838.9424158637644;3870.0905287257474;1213.9028877689764;1226.6810629236284;1228.3858070964518;1238.2879844089684;1240.0637518673343;922.8154369126202;923.9029901860342;927.2998885841147;947.6920000999564;1002.0931057393071;5622;5966.343702192756;6006.639601623917;6106.175666285084;6147.754089836357;963.5808423335872;977.5356705564942;977.731123940922;983.7393090830935;1016.2225897188656;3850.863995121446;4008.2985477541315;4048.7773172475795;4095.795210239476;4113.794310284534;10544.947275263574;10882.40146791934;10990.17573681961;11007.394593297446;11009.119270458345;10806.94596527028;10949.671509854717;10951.616693415692;10959.06847917936;10969.451527423596;8037.115917249098;8128.065272493621;8133.877991830245;8214.671413143407;8224.013118425828;5741.25363702716;6015.1278064681255;6021.036634138692;6136.693165341626;6227.19046523949;9470.431774093577;9608.327417080738;10050.99490050989;10127.59489620407;10218.131458825786;9866.358686685342;9970.102690757576;10094.141997929964;10098.192144628265;10106.494675266355;7496.325330720261;7767.650455729618;8006;8382.413231073766;8394.748157555283;8355;8403.579821008803;8424.620892059995;8501.617427215666;8517.95741021291;6831.795046148623;7100.147982242164;7470.140933792466;7493.063367079115;7651.732189373345;7291.963540182265;7306.59813710251;7322.853542929005;7453.888191677021;7455.291747283999;6188.783392581238;7185.964070179615;7267.200607933229;7291.052163218747;7301.561906285639;4458.108378324283;4648.302754586839;4885.853424397274;5078.314427552233;5085.56772674312;4976.5023497649945;5003.274525193754;5026;5078.822241221539;5144.176931690911 23:27:44 INFO - PROCESS | 1947 | |4;prototype.html;260.9399838037251;304.17720067218175;318.6097031136858;320.7866768598882;330.0560397263824;5889.352171261163;5900.256380782935;5910.3203131639875;5915.858898770862;5934.5845790794665;5137.768800403981;5436.966976274505;5563.8887222255535;5603.825150263049;5799.536037117031;3562.9513030007784;4040.8483582179083;4220.683448741342;4332.631726303268;4394;3242.4325742994997;4247.87256382309;4329.805158767863;4398.64810815134;4412.183746006999;3929.017745563609;4351.5648435156545;4407.016842420908;4424.208195680754;4441.777911104448;4161.3549899765585;4184.811683474251;4447.332900064984;4458.9554104459185;4475.39582156408;3291.572095627553;3392.6082112110903;3466.4280393735003;3533.8691618682033;3734.8692795752017;4286.635635970946;5679.938843726755;5686.668465928949;5725.513331366838;5746.396628354005;4881.414230292352;4973.4280557735965;5085.186370180791;5135.768890399941;5164.793408263665;5498.202760599731;6085;6278.905816412757;6288.937110628881;6314.368563143695;3832.022834177295;4280.293751530994;4290.592393722623;4293.840663020998;4304.397384366192;5938.613990090674;5950.285965684102;6382.022022822206;6614.404703576702;6656.67316244445;4315.719478233905;4360.41134446852;4367.0174210802315;4371.846985355497;4388.626966707866;4134.73124246923;4426.889327766831;4439.933400998988;4449.7330160190495;4452.621527170227;4185.183889141588;4987.526185012418;4989.201727723546;5024.6231532634465;5040.621953353513;5475.397706252309;6298.653574053471;6306;6333.809985700436;6352.650604216812;1338.0232430325723;1359.5785306555;1412.8587141285996;1450.2168828832523;1479.4022455747768;901.553730903194;925.0379605210641;927.5408672707053;939.9718008459757;941.1764705882352;4762.928556071662;4950.979902259619;5667.234923285304;5688.544916406678;5731.885362292731;5699.202111704365;6418.74325027003;6467.676616169267;6556.934430655728;6571.638559879157;5689.231953686282;5854.414558544197;5868.765249390062;5891.05743081105;5921.387953348454;3376.5610470638658;3402.217489977319;3421.3997362005057;3422.3155368926314;3429.1880657860315;5716.9137863805745;6584.9257483892425;6788.626625535643;6809.863802723918;6827.6244806536115;5850.2102216200265;6110.725017374229;6693.665316734144;6739.764108256286;6755.6622168892345;5855.653199764078;6025.758969641253;6497.610143391412;6799.388055075067;6810.727570897108;5329.413764485981;6477.805665830033;6488.837779055372;6505.51208659343;6555.311692272243;5903.509735945612;5947.405259474018;6425.690769253744;6444.935550644621;6468.70096250906;6046.516278697605;6598.736050557924;6694.899576506454;6795.72817087311;6805.217399999064;4179.707420480538;4194.4337514435165;4210.6841986850495;4216.7259128156575;4225.260579398654;5682.602217844877;6091.8781624366375;6187.288461826947;6194.907076393948;6518.380753828379;5501.8721162162465;6123.775244950939;6300.889202117342;6352.872942541031;6364.936350636434;5534.944650553604;6360;6362.968185159045;6363.96818015907;6381.329960354282;4492.438445194351;5164.870878228075;5766.3368712598585;5952.166696662384;6185.907211391923;6178.752849885954;6300.0234963580715;6314.842128946813;6351.396617321347;6382.93617063842;5610.747516361692;5996.730147143302;6023.247094113236;6165.32181460048;6196.287426945959 23:27:44 INFO - PROCESS | 1947 | |5;yui.html;641.3682522715125;650.2489624483721;735.0811485642946;751.0611735330837;755.4447481101391;14820.925895370521;15228.695426091472;15244.237788110591;15269.770953435707;15276.541703748877;13477.595672129852;13526.526571569972;13840.100393474417;13874.167549946986;13885.097468664555;905.4929239625798;907.9818403631923;911.3848152497071;912.0514664748687;912.7581190984395;869.3783944479695;881.7178502879082;886.3175354976664;886.6542048601051;888.8000199955023;717.5730440387987;756.8788993761;972.8394814855541;976.7851072763981;980.0346658541366;935.8315503209419;993.3443927008212;993.4089216915924;994.3785134291104;995.552001599276;712.2877122877123;716.7276434954736;716.7455553278563;716.9211386747454;718.7448455798221;10882.727931801768;15006.324715387835;15087.321070551852;15520.844791552054;15557;12410.255384676948;12657.493700251978;12838.935805321007;12948.0288978327;12976.416061277265;8405.865208196876;10069.446180460072;10217;10218.437985910772;10525.578976840918;3479.7738147020614;3483.198864261234;3516.1033936346066;3588.6411358864166;3600.819959002066;75131.24868751297;75557.62221188915;75823.62088189578;76232.23767762307;77107.61446192733;9642.180414664832;9695.806083878424;9760.170385517311;10053.748656283651;10396.380054418767;22703.545929081327;22781.31656050321;22803.087876485086;22814.20150294732;22854.771452285597;6330.208723909512;7502.799552071751;7551.320381165611;7714.5756983366455;7853.41099417546;4065.9390109148385;4154.896127596774;4156.147989662112;4163.271427500236;4166.604172603597;3628.6552777486095;3699.611540788232;3707.962920370762;3712.1090938174643;3731.1045349116016;623.3548277532732;632.424493710721;643.7650257655922;648.4844548583887;657.2376043789277;497.73122513842844;567.6706118614379;582.1326223926319;606.0817860940679;615.5014438304987;6555.3772391622715;7464.365528929993;7800.9219907801335;7927.643256053491;8475.449095808754;570.2956848292356;572.0018567599483;579.7709904587664;580.6690186593601;608.4007252856022;3815.3704638734853;3919.1769728356794;3935.822887970048;3980.8208630611693;4030.1133750574827;80996.19003810005;82953.74886273794;84345;84355;86421;70552.64723676452;70618.9407158893;70964;73311;75102;51562.74218628958;67252.66373668198;69041;69204;70338.29661703318;7742.496737712031;7756.883646745417;7837.843243134991;7871.881921771293;7929.5242285463055;43358.69923902288;43374;43526;43529.78235108804;43626;63187.6840615794;63284;64063;64489.387765306245;64613.67693161504;10324.380537167794;10379.584816607252;10413.062824345845;10556.683299501028;10689.839652405064;10135.341202821794;10695.304805187638;11177.16171287173;11316.038136758467;11634.884717750348;10349.689509314732;10503.422311772925;10566.841497377392;10600.522976465923;11109.566322730556;10503.317284376491;10608.469576521298;10723.24937254416;10724.463776811284;11469.885301147215;10305.84480271993;10384.792304154025;10460.424676642857;10813.459327033459;11310.94344528272;8842.690505832265;8921.97397299293;9224.446533208029;9243.2143267823;9926.404415735078;9029.819403612022;9039.548022598712;9095.575300619417;9099.772505687146;9111.134442227978 23:27:44 INFO - PROCESS | 1947 | __end_tp_report 23:27:44 INFO - PROCESS | 1947 | __start_cc_report 23:27:44 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,1145 23:27:44 INFO - PROCESS | 1947 | __end_cc_report 23:27:44 INFO - PROCESS | 1947 | __startTimestamp1461565664830__endTimestamp 23:27:44 INFO - PROCESS | 1947 | 23:27:44 INFO - PROCESS | 1947 | ------- Summary: start ------- 23:27:44 INFO - PROCESS | 1947 | Number of tests: 6 23:27:44 INFO - PROCESS | 1947 | 23:27:44 INFO - PROCESS | 1947 | [#0] dojo.html Cycles:165 Average:5740.12 Median:5956.94 stddev:640.68 (10.8%) stddev-sans-first:614.96 23:27:44 INFO - PROCESS | 1947 | Values: 3365.1 3430.7 3461.9 3467.5 3502.9 5582.1 5702.4 5712.4 5736.1 5736.9 5720.1 5721.8 5824.0 5842.2 5855.7 5126.5 5172.8 5195.5 5209.7 5294.7 5232.8 5274.1 5282.6 5286.4 5338.5 5113.1 5125.3 5203.1 5205.5 5229.8 5178.1 5204.5 5206.3 5216.4 5219.3 5159.3 5194.7 5207.2 5226.6 5239.4 5545.4 5572.4 5634.4 5646.6 5670.0 6204.7 6222.5 6251.2 6254.0 6259.9 6248.2 6291.8 6325.6 6337.7 6340.8 5633.4 5686.1 5758.2 5760.2 5769.2 6246.7 6299.3 6302.1 6318.4 6318.8 6096.9 6122.1 6130.2 6134.4 6143.3 6103.7 6170.9 6188.8 6273.1 6287.2 6200.0 6207.8 6223.2 6225.1 6232.6 6227.3 6245.3 6261.1 6272.3 6278.2 4425.6 4742.3 4922.2 4940.5 4976.4 4344.1 4424.3 4427.2 4455.0 4474.6 5610.1 5631.3 5632.7 5635.4 5646.0 5054.6 6247.9 6281.7 6282.5 6294.6 5656.9 5674.5 5676.3 5679.7 5692.9 5386.4 5402.6 5408.5 5409.0 5420.4 6207.2 6248.3 6299.3 6318.1 6327.1 6283.8 6319.9 6320.5 6321.2 6332.5 6316.1 6325.7 6327.8 6333.8 6334.8 6056.0 6122.3 6132.9 6190.3 6213.9 5907.6 6288.9 6306.2 6325.3 6341.6 5945.6 6132.8 6135.2 6139.2 6151.1 5965.6 6059.2 6124.1 6136.7 6153.7 5956.9 5978.7 5981.1 5984.7 5988.3 6109.8 6112.3 6154.7 6168.7 6174.9 5769.3 5811.7 5860.4 5865.1 5977.8 23:27:44 INFO - PROCESS | 1947 | 23:27:44 INFO - PROCESS | 1947 | [#1] ext.html Cycles:180 Average:62793.01 Median:33230.00 stddev:129872.01 (390.8%) stddev-sans-first:130152.14 23:27:44 INFO - PROCESS | 1947 | Values: 506.9 520.9 534.3 586.0 713.9 30552.2 30823.4 31378.0 31381.7 31383.2 42808.6 43253.8 43392.0 43458.1 43538.8 8530.0 9416.9 9660.3 9676.0 9684.9 8703.4 11492.1 11803.1 11831.4 11835.7 11521.4 11646.7 11651.9 11654.7 11663.8 8609.9 11513.3 11566.6 11666.7 11838.0 11504.3 11642.1 11642.8 11654.3 11666.8 39800.4 40186.0 40224.0 40389.2 40500.8 3107.5 3219.8 3496.1 3678.2 3989.0 29451.9 29619.7 29658.6 29672.4 29841.7 2703.0 2748.0 2749.8 2762.3 2811.7 476283.0 476363.0 477775.0 478029.0 478387.0 49387.5 62811.0 62897.7 62939.4 63088.4 66886.3 68858.3 70933.9 70942.6 71084.8 16835.9 17123.4 17167.9 17175.7 17175.7 12151.8 12349.2 12371.9 12381.0 13007.5 17719.1 18730.8 21035.5 21072.5 21080.8 6733.9 7987.4 8046.0 8062.2 8135.3 991.6 1014.0 1044.0 1080.9 1089.7 29753.1 30037.8 30323.4 30509.5 30683.1 1074.2 1094.8 1128.7 1204.7 1280.2 15381.0 15707.7 15803.7 15871.9 15987.4 574142.0 580276.1 581183.0 581476.0 581649.0 61585.7 62416.1 62484.0 62560.7 62653.4 1363.3 1479.5 1505.9 1512.1 1521.3 22216.6 25771.0 28712.9 28934.8 29592.4 39624.0 48093.8 48905.0 48939.5 48947.5 386043.0 387150.0 387545.0 387776.0 388151.1 22891.1 23511.0 27129.0 27367.7 27433.3 42708.0 44085.8 45368.0 45464.8 45605.5 35443.0 36210.6 36224.0 36609.5 37179.6 35091.5 35697.3 35934.8 36609.8 36701.4 37038.1 37464.0 37945.8 38112.2 38273.0 31573.2 33191.8 33268.2 33271.8 33617.3 18847.6 24222.9 24674.9 25074.2 25100.1 23:27:44 INFO - PROCESS | 1947 | 23:27:44 INFO - PROCESS | 1947 | [#2] jquery.html Cycles:180 Average:33163.45 Median:11842.41 stddev:97629.64 (824.4%) stddev-sans-first:97872.40 23:27:44 INFO - PROCESS | 1947 | Values: 334.4 391.5 396.1 396.7 412.6 19677.8 20154.1 21337.7 21339.9 21347.9 12124.9 12459.7 12466.7 13359.0 20456.4 5300.2 5477.9 5513.6 5522.2 5524.8 3788.9 4095.9 5131.3 6110.3 6128.6 6000.8 6071.4 6090.8 6099.3 6147.5 6577.3 6582.6 6591.0 6593.4 6621.1 3772.8 3955.7 3999.4 5428.8 5787.6 12896.0 13368.9 13501.3 13503.9 13689.3 1921.6 1946.1 2099.6 2336.9 2447.7 9939.0 10020.9 10088.5 10459.9 15366.9 1656.7 1668.1 1687.5 1788.5 1821.6 108337.5 109208.6 109285.5 109530.5 117348.4 11615.2 11649.3 11692.4 11750.4 11858.8 15379.9 15384.7 15440.3 15447.2 15449.4 8690.0 8810.7 8811.8 8842.9 8918.2 8767.7 8768.1 8811.6 8911.6 8918.8 4625.7 4639.1 4639.9 4641.9 4717.2 1504.8 1652.4 1721.6 1757.1 1840.3 928.9 953.7 966.6 991.0 1002.6 19748.8 20257.3 20392.0 20595.6 20678.5 880.5 904.9 956.4 957.6 975.0 8370.9 10784.2 10797.2 10815.8 10906.9 578439.6 583739.0 588044.0 589581.0 594888.0 116379.0 116822.0 117900.0 118024.0 121297.0 900.5 967.6 1030.3 1045.3 1050.2 11651.5 17807.2 18215.9 18411.0 18814.6 47400.8 48466.0 48487.5 49550.3 51890.7 63894.4 67460.7 67623.7 67678.7 67680.7 7712.9 7962.3 8061.7 8157.0 8562.7 8091.7 9047.5 9699.4 10036.9 12019.8 8986.1 10370.7 10427.9 10727.6 10751.5 10589.8 10635.2 10780.7 10988.0 11145.3 10723.6 10903.5 10915.6 10970.2 11101.4 8049.6 8144.0 8213.8 8474.3 8587.7 10487.8 10561.9 10616.2 10871.5 10892.3 23:27:44 INFO - PROCESS | 1947 | 23:27:44 INFO - PROCESS | 1947 | [#3] mootools.html Cycles:180 Average:5734.86 Median:4989.89 stddev:3203.16 (64.2%) stddev-sans-first:3194.30 23:27:44 INFO - PROCESS | 1947 | Values: 1236.8 1590.2 1599.8 1869.1 2003.9 7953.0 8649.0 8888.8 8890.7 8893.7 8489.2 8714.7 8737.6 8740.4 8771.0 2431.2 2468.4 2469.8 2471.1 2472.2 2408.9 2469.3 2470.5 2470.6 2471.7 2430.5 2452.7 2477.6 2478.0 2480.0 2418.8 2437.2 2449.6 2458.7 2459.3 2398.8 2428.0 2435.1 2435.4 2436.4 8673.1 8729.3 8750.0 8809.4 8821.3 5299.4 5472.1 5476.8 5494.3 5522.7 3442.7 3551.7 3617.1 3617.9 3626.5 997.2 1017.2 1021.4 1029.3 1029.6 11789.5 11908.3 11911.5 11914.3 11916.0 4492.7 4493.2 4669.9 4695.8 4707.9 9256.6 9269.2 9271.0 9271.8 9273.8 5313.1 5321.4 5327.5 5336.6 5345.7 4402.8 4416.2 4424.9 4909.6 4931.2 3120.8 3219.5 3783.6 3838.9 3870.1 1213.9 1226.7 1228.4 1238.3 1240.1 922.8 923.9 927.3 947.7 1002.1 5622.0 5966.3 6006.6 6106.2 6147.8 963.6 977.5 977.7 983.7 1016.2 3850.9 4008.3 4048.8 4095.8 4113.8 10544.9 10882.4 10990.2 11007.4 11009.1 10806.9 10949.7 10951.6 10959.1 10969.5 8037.1 8128.1 8133.9 8214.7 8224.0 5741.3 6015.1 6021.0 6136.7 6227.2 9470.4 9608.3 10051.0 10127.6 10218.1 9866.4 9970.1 10094.1 10098.2 10106.5 7496.3 7767.7 8006.0 8382.4 8394.7 8355.0 8403.6 8424.6 8501.6 8518.0 6831.8 7100.1 7470.1 7493.1 7651.7 7292.0 7306.6 7322.9 7453.9 7455.3 6188.8 7186.0 7267.2 7291.1 7301.6 4458.1 4648.3 4885.9 5078.3 5085.6 4976.5 5003.3 5026.0 5078.8 5144.2 23:27:44 INFO - PROCESS | 1947 | 23:27:44 INFO - PROCESS | 1947 | [#4] prototype.html Cycles:180 Average:5032.44 Median:5694.22 stddev:1595.16 (28.0%) stddev-sans-first:1558.91 23:27:44 INFO - PROCESS | 1947 | Values: 260.9 304.2 318.6 320.8 330.1 5889.4 5900.3 5910.3 5915.9 5934.6 5137.8 5437.0 5563.9 5603.8 5799.5 3563.0 4040.8 4220.7 4332.6 4394.0 3242.4 4247.9 4329.8 4398.6 4412.2 3929.0 4351.6 4407.0 4424.2 4441.8 4161.4 4184.8 4447.3 4459.0 4475.4 3291.6 3392.6 3466.4 3533.9 3734.9 4286.6 5679.9 5686.7 5725.5 5746.4 4881.4 4973.4 5085.2 5135.8 5164.8 5498.2 6085.0 6278.9 6288.9 6314.4 3832.0 4280.3 4290.6 4293.8 4304.4 5938.6 5950.3 6382.0 6614.4 6656.7 4315.7 4360.4 4367.0 4371.8 4388.6 4134.7 4426.9 4439.9 4449.7 4452.6 4185.2 4987.5 4989.2 5024.6 5040.6 5475.4 6298.7 6306.0 6333.8 6352.7 1338.0 1359.6 1412.9 1450.2 1479.4 901.6 925.0 927.5 940.0 941.2 4762.9 4951.0 5667.2 5688.5 5731.9 5699.2 6418.7 6467.7 6556.9 6571.6 5689.2 5854.4 5868.8 5891.1 5921.4 3376.6 3402.2 3421.4 3422.3 3429.2 5716.9 6584.9 6788.6 6809.9 6827.6 5850.2 6110.7 6693.7 6739.8 6755.7 5855.7 6025.8 6497.6 6799.4 6810.7 5329.4 6477.8 6488.8 6505.5 6555.3 5903.5 5947.4 6425.7 6444.9 6468.7 6046.5 6598.7 6694.9 6795.7 6805.2 4179.7 4194.4 4210.7 4216.7 4225.3 5682.6 6091.9 6187.3 6194.9 6518.4 5501.9 6123.8 6300.9 6352.9 6364.9 5534.9 6360.0 6363.0 6364.0 6381.3 4492.4 5164.9 5766.3 5952.2 6185.9 6178.8 6300.0 6314.8 6351.4 6382.9 5610.7 5996.7 6023.2 6165.3 6196.3 23:27:44 INFO - PROCESS | 1947 | 23:27:44 INFO - PROCESS | 1947 | [#5] yui.html Cycles:180 Average:17231.75 Median:611.95 stddev:23705.59 (3873.8%) stddev-sans-first:23739.35 23:27:44 INFO - PROCESS | 1947 | Values: 641.4 650.2 735.1 751.1 755.4 14820.9 15228.7 15244.2 15269.8 15276.5 13477.6 13526.5 13840.1 13874.2 13885.1 905.5 908.0 911.4 912.1 912.8 869.4 881.7 886.3 886.7 888.8 717.6 756.9 972.8 976.8 980.0 935.8 993.3 993.4 994.4 995.6 712.3 716.7 716.7 716.9 718.7 10882.7 15006.3 15087.3 15520.8 15557.0 12410.3 12657.5 12838.9 12948.0 12976.4 8405.9 10069.4 10217.0 10218.4 10525.6 3479.8 3483.2 3516.1 3588.6 3600.8 75131.2 75557.6 75823.6 76232.2 77107.6 9642.2 9695.8 9760.2 10053.7 10396.4 22703.5 22781.3 22803.1 22814.2 22854.8 6330.2 7502.8 7551.3 7714.6 7853.4 4065.9 4154.9 4156.1 4163.3 4166.6 3628.7 3699.6 3708.0 3712.1 3731.1 623.4 632.4 643.8 648.5 657.2 497.7 567.7 582.1 606.1 615.5 6555.4 7464.4 7800.9 7927.6 8475.4 570.3 572.0 579.8 580.7 608.4 3815.4 3919.2 3935.8 3980.8 4030.1 80996.2 82953.7 84345.0 84355.0 86421.0 70552.6 70618.9 70964.0 73311.0 75102.0 51562.7 67252.7 69041.0 69204.0 70338.3 7742.5 7756.9 7837.8 7871.9 7929.5 43358.7 43374.0 43526.0 43529.8 43626.0 63187.7 63284.0 64063.0 64489.4 64613.7 10324.4 10379.6 10413.1 10556.7 10689.8 10135.3 10695.3 11177.2 11316.0 11634.9 10349.7 10503.4 10566.8 10600.5 11109.6 10503.3 10608.5 10723.2 10724.5 11469.9 10305.8 10384.8 10460.4 10813.5 11310.9 8842.7 8922.0 9224.4 9243.2 9926.4 9029.8 9039.5 9095.6 9099.8 9111.1 23:27:44 INFO - PROCESS | 1947 | -------- Summary: end -------- 23:27:44 INFO - PROCESS | 1947 | 23:27:45 INFO - TEST-INFO | 1947: exit 0 23:27:45 INFO - TEST-OK | dromaeo_css | took 1114950ms 23:27:45 INFO - TEST-START | kraken 23:27:45 INFO - operating with platform_type : mac_ 23:27:45 INFO - Initialising browser for kraken test... 23:27:45 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpNDm_vy/profile http://localhost:52272/getInfo.html) 23:27:47 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 23:27:47 INFO - PROCESS | 1951 | colorDepth:24 23:27:47 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 23:27:47 INFO - PROCESS | 1951 | __metrics 23:27:47 INFO - PROCESS | 1951 | 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" 23:27:47 ERROR - PROCESS | 1951 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:27:48 INFO - TEST-INFO | 1951: exit 0 23:27:48 INFO - Browser initialized. 23:27:48 INFO - Running cycle 1/1 for kraken test... 23:27:48 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpNDm_vy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:28:00 INFO - PROCESS | 1953 | RSS: Main: 239337472 23:28:00 INFO - PROCESS | 1953 | 23:28:02 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52272/tests/kraken/driver.html?testName=audio-beat-detection) 23:28:03 INFO - PROCESS | 1953 | RSS: Main: 287350784 23:28:03 INFO - PROCESS | 1953 | 23:28:06 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52272/tests/kraken/driver.html?testName=audio-dft) 23:28:07 INFO - PROCESS | 1953 | RSS: Main: 300339200 23:28:07 INFO - PROCESS | 1953 | 23:28:10 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52272/tests/kraken/driver.html?testName=audio-fft) 23:28:11 INFO - PROCESS | 1953 | RSS: Main: 388956160 23:28:11 INFO - PROCESS | 1953 | 23:28:13 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52272/tests/kraken/driver.html?testName=audio-oscillator) 23:28:13 INFO - PROCESS | 1953 | RSS: Main: 287436800 23:28:13 INFO - PROCESS | 1953 | 23:28:16 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:28:16 INFO - PROCESS | 1953 | RSS: Main: 292913152 23:28:16 INFO - PROCESS | 1953 | 23:28:19 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52272/tests/kraken/driver.html?testName=imaging-darkroom) 23:28:20 INFO - PROCESS | 1953 | RSS: Main: 329121792 23:28:20 INFO - PROCESS | 1953 | 23:28:22 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52272/tests/kraken/driver.html?testName=imaging-desaturate) 23:28:23 INFO - PROCESS | 1953 | RSS: Main: 331120640 23:28:23 INFO - PROCESS | 1953 | 23:28:26 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52272/tests/kraken/driver.html?testName=json-parse-financial) 23:28:26 INFO - PROCESS | 1953 | RSS: Main: 330760192 23:28:26 INFO - PROCESS | 1953 | 23:28:28 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:28:29 INFO - PROCESS | 1953 | RSS: Main: 321896448 23:28:29 INFO - PROCESS | 1953 | 23:28:31 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:28:31 INFO - PROCESS | 1953 | RSS: Main: 329072640 23:28:31 INFO - PROCESS | 1953 | 23:28:33 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:28:34 INFO - PROCESS | 1953 | RSS: Main: 330452992 23:28:34 INFO - PROCESS | 1953 | 23:28:37 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:28:38 INFO - PROCESS | 1953 | RSS: Main: 335556608 23:28:38 INFO - PROCESS | 1953 | 23:28:41 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:28:41 INFO - PROCESS | 1953 | RSS: Main: 333021184 23:28:41 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52272/tests/kraken/driver.html?testName=ai-astar) 23:28:43 INFO - PROCESS | 1953 | RSS: Main: 398462976 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | __start_tp_report 23:28:43 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 23:28:43 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 23:28:43 INFO - PROCESS | 1953 | |i|pagename|runs| 23:28:43 INFO - PROCESS | 1953 | |0;ai-astar;111;112;108;113;112;117;160;166;107;111 23:28:43 INFO - PROCESS | 1953 | |1;audio-beat-detection;157;120;146;170;118;177;171;118;171;117 23:28:43 INFO - PROCESS | 1953 | |2;audio-dft;186;188;185;184;185;187;183;186;183;183 23:28:43 INFO - PROCESS | 1953 | |3;audio-fft;71;73;88;82;57;83;85;121;86;55 23:28:43 INFO - PROCESS | 1953 | |4;audio-oscillator;93;94;128;74;108;117;169;86;77;130 23:28:43 INFO - PROCESS | 1953 | |5;imaging-gaussian-blur;88;85;88;85;87;86;88;85;87;83 23:28:43 INFO - PROCESS | 1953 | |6;imaging-darkroom;93;92;93;93;92;92;93;92;103;147 23:28:43 INFO - PROCESS | 1953 | |7;imaging-desaturate;85;83;91;85;84;83;86;86;87;85 23:28:43 INFO - PROCESS | 1953 | |8;json-parse-financial;59;59;60;60;58;57;59;78;60;58 23:28:43 INFO - PROCESS | 1953 | |9;json-stringify-tinderbox;62;63;77;74;70;70;67;66;68;68 23:28:43 INFO - PROCESS | 1953 | |10;stanford-crypto-aes;84;83;95;105;106;107;102;82;83;83 23:28:43 INFO - PROCESS | 1953 | |11;stanford-crypto-ccm;139;143;153;154;140;139;141;131;177;139 23:28:43 INFO - PROCESS | 1953 | |12;stanford-crypto-pbkdf2;172;172;171;172;173;174;172;170;173;169 23:28:43 INFO - PROCESS | 1953 | |13;stanford-crypto-sha256-iterative;68;68;69;69;69;70;70;70;68;63 23:28:43 INFO - PROCESS | 1953 | __end_tp_report 23:28:43 INFO - PROCESS | 1953 | __start_cc_report 23:28:43 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,2006 23:28:43 INFO - PROCESS | 1953 | __end_cc_report 23:28:43 INFO - PROCESS | 1953 | __startTimestamp1461565723617__endTimestamp 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | ------- Summary: start ------- 23:28:43 INFO - PROCESS | 1953 | Number of tests: 14 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#0] ai-astar Cycles:10 Average:121.70 Median:112.50 stddev:21.98 (19.5%) stddev-sans-first:22.97 23:28:43 INFO - PROCESS | 1953 | Values: 111.0 112.0 108.0 113.0 112.0 117.0 160.0 166.0 107.0 111.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#1] audio-beat-detection Cycles:10 Average:146.50 Median:163.50 stddev:25.80 (15.8%) stddev-sans-first:27.08 23:28:43 INFO - PROCESS | 1953 | Values: 157.0 120.0 146.0 170.0 118.0 177.0 171.0 118.0 171.0 117.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.50 stddev:1.76 (1.0%) stddev-sans-first:1.83 23:28:43 INFO - PROCESS | 1953 | Values: 186.0 188.0 185.0 184.0 185.0 187.0 183.0 186.0 183.0 183.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#3] audio-fft Cycles:10 Average:80.10 Median:82.50 stddev:18.57 (22.5%) stddev-sans-first:19.40 23:28:43 INFO - PROCESS | 1953 | Values: 71.0 73.0 88.0 82.0 57.0 83.0 85.0 121.0 86.0 55.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#4] audio-oscillator Cycles:10 Average:107.60 Median:75.50 stddev:29.26 (38.8%) stddev-sans-first:30.56 23:28:43 INFO - PROCESS | 1953 | Values: 93.0 94.0 128.0 74.0 108.0 117.0 169.0 86.0 77.0 130.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:1.69 (1.9%) stddev-sans-first:1.66 23:28:43 INFO - PROCESS | 1953 | Values: 88.0 85.0 88.0 85.0 87.0 86.0 88.0 85.0 87.0 83.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#6] imaging-darkroom Cycles:10 Average:99.00 Median:92.50 stddev:17.19 (18.6%) stddev-sans-first:18.10 23:28:43 INFO - PROCESS | 1953 | Values: 93.0 92.0 93.0 93.0 92.0 92.0 93.0 92.0 103.0 147.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:85.50 stddev:2.32 (2.7%) stddev-sans-first:2.46 23:28:43 INFO - PROCESS | 1953 | Values: 85.0 83.0 91.0 85.0 84.0 83.0 86.0 86.0 87.0 85.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:59.50 stddev:6.12 (10.3%) stddev-sans-first:6.46 23:28:43 INFO - PROCESS | 1953 | Values: 59.0 59.0 60.0 60.0 58.0 57.0 59.0 78.0 60.0 58.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:69.00 stddev:4.58 (6.6%) stddev-sans-first:4.21 23:28:43 INFO - PROCESS | 1953 | Values: 62.0 63.0 77.0 74.0 70.0 70.0 67.0 66.0 68.0 68.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:83.00 stddev:11.04 (13.3%) stddev-sans-first:11.21 23:28:43 INFO - PROCESS | 1953 | Values: 84.0 83.0 95.0 105.0 106.0 107.0 102.0 82.0 83.0 83.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:142.00 stddev:12.97 (9.1%) stddev-sans-first:13.54 23:28:43 INFO - PROCESS | 1953 | Values: 139.0 143.0 153.0 154.0 140.0 139.0 141.0 131.0 177.0 139.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:172.00 stddev:1.48 (0.9%) stddev-sans-first:1.56 23:28:43 INFO - PROCESS | 1953 | Values: 172.0 172.0 171.0 172.0 173.0 174.0 172.0 170.0 173.0 169.0 23:28:43 INFO - PROCESS | 1953 | 23:28:43 INFO - PROCESS | 1953 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.00 stddev:2.07 (3.0%) stddev-sans-first:2.19 23:28:43 INFO - PROCESS | 1953 | Values: 68.0 68.0 69.0 69.0 69.0 70.0 70.0 70.0 68.0 63.0 23:28:43 INFO - PROCESS | 1953 | -------- Summary: end -------- 23:28:43 INFO - PROCESS | 1953 | 23:28:44 INFO - TEST-INFO | 1953: exit 0 23:28:44 INFO - TEST-OK | kraken | took 59063ms 23:28:44 INFO - SUITE-END | took 1174s 23:28:44 INFO - Completed test suite (00:19:34) 23:28:44 INFO - javascript benchmark 23:28:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3365.091425315165, 3430.6912377885983, 3461.948070778937, 3467.531883195768, 3502.8773992910255, 5582.051051321275, 5702.429757024295, 5712.371639119699, 5736.110902810065, 5736.885262294757, 5720.141978703186, 5721.799737009205, 5824.0, 5842.240508733869, 5855.677937713424, 5126.512981266792, 5172.818951336713, 5195.506426889439, 5209.7134657593815, 5294.655847369928, 5232.843014709565, 5274.129768588178, 5282.603804714642, 5286.4449232830575, 5338.492843179892, 5113.130767769507, 5125.256837758541, 5203.089459344599, 5205.453427390107, 5229.8169564065265, 5178.093833579109, 5204.453532379083, 5206.323177986876, 5216.374035115772, 5219.269302297681, 5159.25190847325, 5194.71429071401, 5207.192885102815, 5226.634135610509, 5239.371275446971, 5545.3622833374275, 5572.359178694462, 5634.408387119334, 5646.632968857011, 5670.0, 6204.6587437690905, 6222.502199824004, 6251.187345645037, 6253.968730156365, 6259.906101408483, 6248.187735594343, 6291.7797877074545, 6325.557210995232, 6337.746490140389, 6340.841478963017, 5633.380328163898, 5686.118651609007, 5758.222639943596, 5760.164776107441, 5769.24999750034, 6246.6876656167005, 6299.275583307886, 6302.1177035215105, 6318.399752023551, 6318.778842740482, 6096.939030609726, 6122.081687746873, 6130.233720784902, 6134.38656134383, 6143.32423433422, 6103.701349646989, 6170.907436388457, 6188.845278868064, 6273.059041143773, 6287.245530536365, 6200.0, 6207.751689932443, 6223.190985171989, 6225.097360882737, 6232.59488133279, 6227.346128656516, 6245.344238854855, 6261.060840873905, 6272.278687950944, 6278.215223097113, 4425.601695847389, 4742.312364707098, 4922.237053256751, 4940.481249468826, 4976.353074100344, 4344.131173765197, 4424.292113261863, 4427.247367947457, 4454.997625534229, 4474.552544745499, 5610.13042978339, 5631.2960879890015, 5632.662040277596, 5635.436456354414, 5646.0, 5054.620903432257, 6247.937520624827, 6281.7173227203975, 6282.465990390777, 6294.590851594632, 5656.943430565724, 5674.460926212003, 5676.262085928803, 5679.687617181012, 5692.943070569324, 5386.407495175527, 5402.621816472809, 5408.486193811582, 5409.0, 5420.403755586883, 6207.193064901624, 6248.250209974832, 6299.275583307886, 6318.115463835159, 6327.050942358683, 6283.8114856554375, 6319.873602528016, 6320.462760665211, 6321.241451025906, 6332.52506062038, 6316.147320111726, 6325.746970121144, 6327.841803954938, 6333.809985700436, 6334.841628959313, 6055.969720151371, 6122.306673174842, 6132.938670613236, 6190.2571691397325, 6213.9067913980425, 5907.616004959664, 6288.937110628835, 6306.243250809932, 6325.304216536086, 6341.587796793194, 5945.583809133492, 6132.754689812357, 6135.1717518136265, 6139.171211886338, 6151.0773383992755, 5965.582409231312, 6059.151718759289, 6124.075808605272, 6136.723847426966, 6153.72308246139, 5956.940430595639, 5978.730957107027, 5981.0729336952845, 5984.700764961822, 5988.341282459013, 6109.7861574844665, 6112.327643959072, 6154.723037463235, 6168.660723660242, 6174.938250617436, 5769.307683078057, 5811.651300921958, 5860.355360910211, 5865.061590145727, 5977.760889564369], "value": 5699.4732338433105, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.9163588007981, 520.926890605353, 534.2547146730311, 585.9589828711986, 713.8500914807892, 30552.23619409516, 30823.383532329368, 31377.99999999997, 31381.68618313816, 31383.215419614466, 42808.571914280845, 43253.7837310813, 43392.0, 43458.130837383236, 43538.782306088506, 8530.0, 9416.905830941689, 9660.275479339043, 9676.0, 9684.903150968506, 8703.390762646586, 11492.13808964327, 11803.114766392511, 11831.40842957853, 11835.704107397341, 11521.423928803568, 11646.650600481998, 11651.893070158321, 11654.650360489157, 11663.766724665502, 8609.913900860975, 11513.251638643462, 11566.59516916908, 11666.708332291719, 11838.0, 11504.252223605523, 11642.068634509304, 11642.767144657144, 11654.30074195551, 11666.824997625043, 39800.40299395483, 40186.0, 40224.0, 40389.19221615551, 40500.79749601262, 3107.5183346581316, 3219.7585181111276, 3496.108492334464, 3678.2275722098393, 3989.0, 29451.852740736373, 29619.70380296191, 29658.55512167319, 29672.40655186906, 29841.70158298411, 2702.9729702702916, 2747.986260068707, 2749.793765467598, 2762.337039110619, 2811.712138988039, 476283.0, 476363.0, 477775.0, 478029.0, 478387.0, 49387.50612493901, 62811.0, 62897.685511573065, 62939.370606294266, 63088.369116309164, 66886.33113668898, 68858.3114168862, 70933.93599096018, 70942.64528677426, 71084.80118394685, 16835.915820420818, 17123.400680976356, 17167.91416042937, 17175.65648687044, 17175.742363864552, 12151.817722734166, 12349.19730217533, 12371.876281237252, 12381.0, 13007.544735934385, 17719.114044297992, 18730.77971581467, 21035.474113147295, 21072.473188170417, 21080.78919210788, 6733.8653226935185, 7987.44087913852, 8045.9597702011115, 8062.234087761653, 8135.3491720662205, 991.6330957545754, 1014.0366651680932, 1044.0, 1080.8756992945755, 1089.716673664837, 29753.10740677783, 30037.849810750806, 30323.393532129234, 30509.483079211604, 30683.079507614806, 1074.2050882347164, 1094.813881640123, 1128.700894263021, 1204.722913729847, 1280.1934781087855, 15381.00023498469, 15707.68584628301, 15803.683926321639, 15871.920640396724, 15987.36050557988, 574142.0, 580276.0986195042, 581183.0, 581476.0, 581649.0, 61585.69207153936, 62416.06375904365, 62484.0, 62560.74878502404, 62653.43366415876, 1363.2502123831853, 1479.4895760962452, 1505.9247037648074, 1512.070076902719, 1521.3306145296035, 22216.55566888621, 25770.96916123409, 28712.856435717687, 28934.842606296355, 29592.40815183641, 39624.0, 48093.75953120212, 48905.0, 48939.5106048935, 48947.51052489572, 386043.0, 387150.0, 387545.0, 387776.0, 388151.1184888115, 22891.084356626216, 23511.0, 27129.0, 27367.72632273652, 27433.31416714598, 42708.0, 44085.77957110194, 45368.0, 45464.77267613641, 45605.54394456013, 35443.0, 36210.64920160672, 36224.0, 36609.45085823768, 37179.6282037187, 35091.473627895095, 35697.28605427929, 35934.8203258982, 36609.816950915076, 36701.44947825732, 37038.074048149014, 37464.0, 37945.81027094847, 38112.237755245296, 38273.04317392087, 31573.210669733442, 33191.83404082964, 33268.1682957928, 33271.83364083164, 33617.32765344631, 18847.623047539248, 24222.909969051787, 24674.87662561676, 25074.24777256685, 25100.12149574756], "value": 19935.127796441393, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.4114358728637, 391.5067015560394, 396.09689907012006, 396.67274498538706, 412.56886553551533, 19677.803221967777, 20154.093065812038, 21337.67993480097, 21339.933003349808, 21347.893260533696, 12124.93937530311, 12459.68850778731, 12466.688332791684, 13359.0, 20456.386308410736, 5300.204969254604, 5477.863053423656, 5513.641613295123, 5522.171674248874, 5524.779008839661, 3788.867389641363, 4095.89760255993, 5131.255967884655, 6110.327863934964, 6128.63228206309, 6000.75996960121, 6071.392860713915, 6090.756369745205, 6099.329073801879, 6147.508199344042, 6577.309382514863, 6582.6050436973455, 6591.044298576703, 6593.3736295051895, 6621.106150669671, 3772.792496412696, 3955.6835453163967, 3999.3601023836045, 5428.805662754187, 5787.623804452739, 12896.0, 13368.86631133686, 13501.324933753272, 13503.864961350358, 13689.31553422325, 1921.5580416504142, 1946.0950657944124, 2099.601075795609, 2336.9250144933353, 2447.6940382452194, 9939.0, 10020.85807526869, 10088.495575221283, 10459.895401045967, 15366.923165384213, 1656.7432048032574, 1668.121617239922, 1687.451578237078, 1788.534980904974, 1821.6174603333184, 108337.45831270794, 109208.61870719376, 109285.45357273243, 109530.45234773855, 117348.41325793316, 11615.245009074382, 11649.301041937511, 11692.35692036946, 11750.412479375997, 11858.822117668242, 15379.92310038465, 15384.692306153815, 15440.305186266645, 15447.227638618024, 15449.382024718885, 8689.956550217208, 8810.691625793195, 8811.822932890278, 8842.911570884338, 8918.241949434241, 8767.727576673942, 8768.123187681309, 8811.603477843513, 8911.598978046004, 8918.821623567492, 4625.722456652612, 4639.097969832353, 4639.907201856012, 4641.8647422185495, 4717.174494463455, 1504.751715966875, 1652.396875140565, 1721.552396376926, 1757.07338954823, 1840.3006857394105, 928.8560273157672, 953.7186529973675, 966.5553845231132, 990.960361585543, 1002.5939494504737, 19748.802511974984, 20257.29099481511, 20392.0, 20595.58808823815, 20678.48303792387, 880.506916126971, 904.9321300902459, 956.4357029352576, 957.5978089202551, 974.9951250243703, 8370.916290837136, 10784.245102842726, 10797.190210734227, 10815.78368432627, 10906.945465272624, 578439.6468105963, 583739.0, 588044.0, 589581.0, 594888.0, 116379.0, 116822.0, 117900.0, 118024.0, 121297.0, 900.4912224593083, 967.5742673223759, 1030.2685093583489, 1045.2630895218851, 1050.175612144463, 11651.47568359409, 17807.19867605935, 18215.908920455313, 18411.0, 18814.623707526047, 47400.7629961848, 48466.0, 48487.51512484971, 49550.3107080554, 51890.74054629854, 63894.3610563907, 67460.66269668816, 67623.66188169028, 67678.66160669165, 67680.6615966917, 7712.92287077122, 7962.283394494523, 8061.717839875576, 8157.0, 8562.74311770648, 8091.676332946617, 9047.502387368495, 9699.369540979813, 10036.949815250877, 12019.75960480803, 8986.14631609996, 10370.740731481472, 10427.947860260954, 10727.624533141303, 10751.548540946873, 10589.841152382567, 10635.20235982289, 10780.676579702622, 10988.0, 11145.331280122895, 10723.571057157626, 10903.454827258764, 10915.617953371593, 10970.232083754061, 11101.444927753419, 8049.597520124087, 8143.959280203561, 8213.835723285381, 8474.322054235523, 8587.699430519902, 10487.842682359618, 10561.947190264, 10616.203784716023, 10871.456427178768, 10892.346459212473], "value": 9023.925554317662, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.8453943257093, 1590.1969505399773, 1599.8160211575669, 1869.1308541528194, 2003.9198432062708, 7953.0, 8648.956755216223, 8888.822223555526, 8890.688825891095, 8893.68872089477, 8489.193526614978, 8714.651413943435, 8737.606807693654, 8740.388172827905, 8770.956145219265, 2431.234161239213, 2468.419921318489, 2469.777720005199, 2471.0733474946896, 2472.208893154191, 2408.903643854244, 2469.3085935937875, 2470.518248941457, 2470.555300045991, 2471.678681771376, 2430.501747141832, 2452.6566280720713, 2477.55404027275, 2477.962830557543, 2479.987600061997, 2418.794402475783, 2437.2079074300837, 2449.608062709967, 2458.6926634170727, 2459.262221333611, 2398.8080953523677, 2428.0409238350753, 2435.135526887947, 2435.439848834778, 2436.378723425515, 8673.13268673134, 8729.301655867579, 8750.0, 8809.42738721981, 8821.294296456268, 5299.417064122943, 5472.097103977839, 5476.7535460904355, 5494.2857428534435, 5522.723863806794, 3442.6557344265625, 3551.6803487686234, 3617.0595645132194, 3617.89146325608, 3626.510421093145, 997.1922742578535, 1017.2319898476677, 1021.3769600543662, 1029.3360782295379, 1029.6036026129918, 11789.528418863236, 11908.34504102274, 11911.463984120735, 11914.344711040889, 11916.046716262677, 4492.707973981681, 4493.236149854532, 4669.906601867943, 4695.835645752382, 4707.882302942454, 9256.629734810667, 9269.212116969999, 9271.026542213105, 9271.814563708824, 9273.814523709489, 5313.123334649817, 5321.388040375329, 5327.520523152857, 5336.62643614951, 5345.679259244458, 4402.789232960961, 4416.160929423463, 4424.867253982386, 4909.582685471775, 4931.235658472942, 3120.781545291853, 3219.4687876500116, 3783.564890037626, 3838.9424158637644, 3870.0905287257474, 1213.9028877689764, 1226.6810629236284, 1228.3858070964518, 1238.2879844089684, 1240.0637518673343, 922.8154369126202, 923.9029901860342, 927.2998885841147, 947.6920000999564, 1002.0931057393071, 5622.0, 5966.343702192756, 6006.639601623917, 6106.175666285084, 6147.754089836357, 963.5808423335872, 977.5356705564942, 977.731123940922, 983.7393090830935, 1016.2225897188656, 3850.863995121446, 4008.2985477541315, 4048.7773172475795, 4095.795210239476, 4113.794310284534, 10544.947275263574, 10882.40146791934, 10990.17573681961, 11007.394593297446, 11009.119270458345, 10806.94596527028, 10949.671509854717, 10951.616693415692, 10959.06847917936, 10969.451527423596, 8037.115917249098, 8128.065272493621, 8133.877991830245, 8214.671413143407, 8224.013118425828, 5741.25363702716, 6015.1278064681255, 6021.036634138692, 6136.693165341626, 6227.19046523949, 9470.431774093577, 9608.327417080738, 10050.99490050989, 10127.59489620407, 10218.131458825786, 9866.358686685342, 9970.102690757576, 10094.141997929964, 10098.192144628265, 10106.494675266355, 7496.325330720261, 7767.650455729618, 8006.0, 8382.413231073766, 8394.748157555283, 8355.0, 8403.579821008803, 8424.620892059995, 8501.617427215666, 8517.95741021291, 6831.795046148623, 7100.147982242164, 7470.140933792466, 7493.063367079115, 7651.732189373345, 7291.963540182265, 7306.59813710251, 7322.853542929005, 7453.888191677021, 7455.291747283999, 6188.783392581238, 7185.964070179615, 7267.200607933229, 7291.052163218747, 7301.561906285639, 4458.108378324283, 4648.302754586839, 4885.853424397274, 5078.314427552233, 5085.56772674312, 4976.5023497649945, 5003.274525193754, 5026.0, 5078.822241221539, 5144.176931690911], "value": 4621.279296397956, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.9399838037251, 304.17720067218175, 318.6097031136858, 320.7866768598882, 330.0560397263824, 5889.352171261163, 5900.256380782935, 5910.3203131639875, 5915.858898770862, 5934.5845790794665, 5137.768800403981, 5436.966976274505, 5563.8887222255535, 5603.825150263049, 5799.536037117031, 3562.9513030007784, 4040.8483582179083, 4220.683448741342, 4332.631726303268, 4394.0, 3242.4325742994997, 4247.87256382309, 4329.805158767863, 4398.64810815134, 4412.183746006999, 3929.017745563609, 4351.5648435156545, 4407.016842420908, 4424.208195680754, 4441.777911104448, 4161.3549899765585, 4184.811683474251, 4447.332900064984, 4458.9554104459185, 4475.39582156408, 3291.572095627553, 3392.6082112110903, 3466.4280393735003, 3533.8691618682033, 3734.8692795752017, 4286.635635970946, 5679.938843726755, 5686.668465928949, 5725.513331366838, 5746.396628354005, 4881.414230292352, 4973.4280557735965, 5085.186370180791, 5135.768890399941, 5164.793408263665, 5498.202760599731, 6085.0, 6278.905816412757, 6288.937110628881, 6314.368563143695, 3832.022834177295, 4280.293751530994, 4290.592393722623, 4293.840663020998, 4304.397384366192, 5938.613990090674, 5950.285965684102, 6382.022022822206, 6614.404703576702, 6656.67316244445, 4315.719478233905, 4360.41134446852, 4367.0174210802315, 4371.846985355497, 4388.626966707866, 4134.73124246923, 4426.889327766831, 4439.933400998988, 4449.7330160190495, 4452.621527170227, 4185.183889141588, 4987.526185012418, 4989.201727723546, 5024.6231532634465, 5040.621953353513, 5475.397706252309, 6298.653574053471, 6306.0, 6333.809985700436, 6352.650604216812, 1338.0232430325723, 1359.5785306555, 1412.8587141285996, 1450.2168828832523, 1479.4022455747768, 901.553730903194, 925.0379605210641, 927.5408672707053, 939.9718008459757, 941.1764705882352, 4762.928556071662, 4950.979902259619, 5667.234923285304, 5688.544916406678, 5731.885362292731, 5699.202111704365, 6418.74325027003, 6467.676616169267, 6556.934430655728, 6571.638559879157, 5689.231953686282, 5854.414558544197, 5868.765249390062, 5891.05743081105, 5921.387953348454, 3376.5610470638658, 3402.217489977319, 3421.3997362005057, 3422.3155368926314, 3429.1880657860315, 5716.9137863805745, 6584.9257483892425, 6788.626625535643, 6809.863802723918, 6827.6244806536115, 5850.2102216200265, 6110.725017374229, 6693.665316734144, 6739.764108256286, 6755.6622168892345, 5855.653199764078, 6025.758969641253, 6497.610143391412, 6799.388055075067, 6810.727570897108, 5329.413764485981, 6477.805665830033, 6488.837779055372, 6505.51208659343, 6555.311692272243, 5903.509735945612, 5947.405259474018, 6425.690769253744, 6444.935550644621, 6468.70096250906, 6046.516278697605, 6598.736050557924, 6694.899576506454, 6795.72817087311, 6805.217399999064, 4179.707420480538, 4194.4337514435165, 4210.6841986850495, 4216.7259128156575, 4225.260579398654, 5682.602217844877, 6091.8781624366375, 6187.288461826947, 6194.907076393948, 6518.380753828379, 5501.8721162162465, 6123.775244950939, 6300.889202117342, 6352.872942541031, 6364.936350636434, 5534.944650553604, 6360.0, 6362.968185159045, 6363.96818015907, 6381.329960354282, 4492.438445194351, 5164.870878228075, 5766.3368712598585, 5952.166696662384, 6185.907211391923, 6178.752849885954, 6300.0234963580715, 6314.842128946813, 6351.396617321347, 6382.93617063842, 5610.747516361692, 5996.730147143302, 6023.247094113236, 6165.32181460048, 6196.287426945959], "value": 4508.446309260374, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.3682522715125, 650.2489624483721, 735.0811485642946, 751.0611735330837, 755.4447481101391, 14820.925895370521, 15228.695426091472, 15244.237788110591, 15269.770953435707, 15276.541703748877, 13477.595672129852, 13526.526571569972, 13840.100393474417, 13874.167549946986, 13885.097468664555, 905.4929239625798, 907.9818403631923, 911.3848152497071, 912.0514664748687, 912.7581190984395, 869.3783944479695, 881.7178502879082, 886.3175354976664, 886.6542048601051, 888.8000199955023, 717.5730440387987, 756.8788993761, 972.8394814855541, 976.7851072763981, 980.0346658541366, 935.8315503209419, 993.3443927008212, 993.4089216915924, 994.3785134291104, 995.552001599276, 712.2877122877123, 716.7276434954736, 716.7455553278563, 716.9211386747454, 718.7448455798221, 10882.727931801768, 15006.324715387835, 15087.321070551852, 15520.844791552054, 15557.0, 12410.255384676948, 12657.493700251978, 12838.935805321007, 12948.0288978327, 12976.416061277265, 8405.865208196876, 10069.446180460072, 10217.0, 10218.437985910772, 10525.578976840918, 3479.7738147020614, 3483.198864261234, 3516.1033936346066, 3588.6411358864166, 3600.819959002066, 75131.24868751297, 75557.62221188915, 75823.62088189578, 76232.23767762307, 77107.61446192733, 9642.180414664832, 9695.806083878424, 9760.170385517311, 10053.748656283651, 10396.380054418767, 22703.545929081327, 22781.31656050321, 22803.087876485086, 22814.20150294732, 22854.771452285597, 6330.208723909512, 7502.799552071751, 7551.320381165611, 7714.5756983366455, 7853.41099417546, 4065.9390109148385, 4154.896127596774, 4156.147989662112, 4163.271427500236, 4166.604172603597, 3628.6552777486095, 3699.611540788232, 3707.962920370762, 3712.1090938174643, 3731.1045349116016, 623.3548277532732, 632.424493710721, 643.7650257655922, 648.4844548583887, 657.2376043789277, 497.73122513842844, 567.6706118614379, 582.1326223926319, 606.0817860940679, 615.5014438304987, 6555.3772391622715, 7464.365528929993, 7800.9219907801335, 7927.643256053491, 8475.449095808754, 570.2956848292356, 572.0018567599483, 579.7709904587664, 580.6690186593601, 608.4007252856022, 3815.3704638734853, 3919.1769728356794, 3935.822887970048, 3980.8208630611693, 4030.1133750574827, 80996.19003810005, 82953.74886273794, 84345.0, 84355.0, 86421.0, 70552.64723676452, 70618.9407158893, 70964.0, 73311.0, 75102.0, 51562.74218628958, 67252.66373668198, 69041.0, 69204.0, 70338.29661703318, 7742.496737712031, 7756.883646745417, 7837.843243134991, 7871.881921771293, 7929.5242285463055, 43358.69923902288, 43374.0, 43526.0, 43529.78235108804, 43626.0, 63187.6840615794, 63284.0, 64063.0, 64489.387765306245, 64613.67693161504, 10324.380537167794, 10379.584816607252, 10413.062824345845, 10556.683299501028, 10689.839652405064, 10135.341202821794, 10695.304805187638, 11177.16171287173, 11316.038136758467, 11634.884717750348, 10349.689509314732, 10503.422311772925, 10566.841497377392, 10600.522976465923, 11109.566322730556, 10503.317284376491, 10608.469576521298, 10723.24937254416, 10724.463776811284, 11469.885301147215, 10305.84480271993, 10384.792304154025, 10460.424676642857, 10813.459327033459, 11310.94344528272, 8842.690505832265, 8921.97397299293, 9224.446533208029, 9243.2143267823, 9926.404415735078, 9029.819403612022, 9039.548022598712, 9095.575300619417, 9099.772505687146, 9111.134442227978], "value": 6718.127334807961, "unit": "score"}], "name": "dromaeo_css", "value": 7235.822776607929, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 108.0, 113.0, 112.0, 117.0, 160.0, 166.0, 107.0, 111.0], "value": 121.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 120.0, 146.0, 170.0, 118.0, 177.0, 171.0, 118.0, 171.0, 117.0], "value": 146.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 188.0, 185.0, 184.0, 185.0, 187.0, 183.0, 186.0, 183.0, 183.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 88.0, 82.0, 57.0, 83.0, 85.0, 121.0, 86.0, 55.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 128.0, 74.0, 108.0, 117.0, 169.0, 86.0, 77.0, 130.0], "value": 107.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 87.0, 86.0, 88.0, 85.0, 87.0, 83.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 92.0, 92.0, 93.0, 92.0, 103.0, 147.0], "value": 99.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 91.0, 85.0, 84.0, 83.0, 86.0, 86.0, 87.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 60.0, 58.0, 57.0, 59.0, 78.0, 60.0, 58.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 77.0, 74.0, 70.0, 70.0, 67.0, 66.0, 68.0, 68.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 95.0, 105.0, 106.0, 107.0, 102.0, 82.0, 83.0, 83.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 143.0, 153.0, 154.0, 140.0, 139.0, 141.0, 131.0, 177.0, 139.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 171.0, 172.0, 173.0, 174.0, 172.0, 170.0, 173.0, 169.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 69.0, 69.0, 70.0, 70.0, 70.0, 68.0, 63.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1519.7, "alertThreshold": 2.0}]} 23:28:44 INFO - Return code: 0 23:28:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:28:44 ERROR - # TBPL SUCCESS # 23:28:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:28:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:28:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:28:44 INFO - 2016-04-24 23:28:44.899 screenresolution[1957:18898] starting screenresolution argv=screenresolution get 23:28:44 INFO - 2016-04-24 23:28:44.901 screenresolution[1957:18898] Display 0: 1600x1200x32@60 23:28:44 INFO - 2016-04-24 23:28:44.907 screenresolution[1958:18901] starting screenresolution argv=screenresolution list 23:28:44 INFO - Available Modes on Display 0 23:28:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:28:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:28:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:28:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:28:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:28:45 INFO - Intel Iris: 23:28:45 INFO - Chipset Model: Intel Iris 23:28:45 INFO - Type: GPU 23:28:45 INFO - Bus: Built-In 23:28:45 INFO - VRAM (Dynamic, Max): 1536 MB 23:28:45 INFO - Vendor: Intel (0x8086) 23:28:45 INFO - Device ID: 0x0a2e 23:28:45 INFO - Revision ID: 0x0009 23:28:45 INFO - Displays: 23:28:45 INFO - Display: 23:28:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:28:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:28:45 INFO - Display Serial Number: 1600x1200 60 23:28:45 INFO - Main Display: Yes 23:28:45 INFO - Mirror: Off 23:28:45 INFO - Online: Yes 23:28:45 INFO - Rotation: Supported 23:28:45 INFO - Return code: 0 23:28:45 INFO - Running post-action listener: _resource_record_post_action 23:28:45 INFO - Running post-run listener: _resource_record_post_run 23:28:46 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 704512; Write bytes: 694002688; Read time: 8; Write time: 2062 23:28:46 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 373783040; Write bytes: 368763392; Read time: 28269; Write time: 649 23:28:46 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 704512; Write bytes: 295793152; Read time: 8; Write time: 1360 23:28:46 INFO - Running post-run listener: _upload_blobber_files 23:28:46 INFO - Blob upload gear active. 23:28:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:28:46 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 23:28:46 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:28:46 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:28:46 INFO - (blobuploader) - INFO - Open directory for files ... 23:28:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:28:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:28:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:28:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:28:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:28:47 INFO - (blobuploader) - INFO - Done attempting. 23:28:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:28:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:28:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:28:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:28:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:28:48 INFO - (blobuploader) - INFO - Done attempting. 23:28:48 INFO - (blobuploader) - INFO - Iteration through files over. 23:28:48 INFO - Return code: 0 23:28:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:28:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:28:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64311eb35f17a7da19b7bac883fc3022eae95ec198b3fd25932c9fb8f5442294da33b74a676c2ae5cc730b749fa38343e97bc810c7153098a2d52f5496f6c728", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b70108da35bfddddf38f97dd61460ae0bbbd56beb9f949b0694b507ced20ff5fb3120ff338383a55e377683e3cec1f7d7c0cc16c5582c92af6dbceb2cfc54b47"} 23:28:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:28:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:28:48 INFO - Contents: 23:28:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64311eb35f17a7da19b7bac883fc3022eae95ec198b3fd25932c9fb8f5442294da33b74a676c2ae5cc730b749fa38343e97bc810c7153098a2d52f5496f6c728", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b70108da35bfddddf38f97dd61460ae0bbbd56beb9f949b0694b507ced20ff5fb3120ff338383a55e377683e3cec1f7d7c0cc16c5582c92af6dbceb2cfc54b47"} 23:28:48 INFO - Running post-run listener: copy_logs_to_upload_dir 23:28:48 INFO - Copying logs to upload dir... 23:28:48 INFO - mkdir: /builds/slave/test/build/upload/logs 23:28:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.270792 ========= master_lag: 215.96 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 47 secs) (at 2016-04-24 23:32:24.372762) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 23:32:24.376215) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RgPDTPc9Os/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64311eb35f17a7da19b7bac883fc3022eae95ec198b3fd25932c9fb8f5442294da33b74a676c2ae5cc730b749fa38343e97bc810c7153098a2d52f5496f6c728", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b70108da35bfddddf38f97dd61460ae0bbbd56beb9f949b0694b507ced20ff5fb3120ff338383a55e377683e3cec1f7d7c0cc16c5582c92af6dbceb2cfc54b47"} build_url:https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011380 build_url: 'https://queue.taskcluster.net/v1/task/dxU45NLuT1uqMv23Aw3nWQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64311eb35f17a7da19b7bac883fc3022eae95ec198b3fd25932c9fb8f5442294da33b74a676c2ae5cc730b749fa38343e97bc810c7153098a2d52f5496f6c728", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b70108da35bfddddf38f97dd61460ae0bbbd56beb9f949b0694b507ced20ff5fb3120ff338383a55e377683e3cec1f7d7c0cc16c5582c92af6dbceb2cfc54b47"}' ========= master_lag: 0.50 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 23:32:24.883702) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 19 secs) (at 2016-04-24 23:32:24.884535) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RgPDTPc9Os/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.PPTbjaztsX/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005080 ========= master_lag: 19.42 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 19 secs) (at 2016-04-24 23:32:44.304842) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 23:32:44.305148) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 23:32:44.423387) ========= ========= Total master_lag: 236.28 =========