builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0113 starttime: 1461891705.32 results: success (0) buildid: 20160428161042 builduid: 835df724bdae4035a563f9d7616ad8c3 revision: 98aa054d9e2757e43158b0c962be9cbab784e9e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:45.322703) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:45.323093) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:45.323376) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:45.403214) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-28 18:01:45.403508) ========= 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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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-28 18:01:45-- 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% 13.7M=0.001s 2016-04-28 18:01:45 (13.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132740 ========= master_lag: 5.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-28 18:01:50.745628) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:50.745960) ========= 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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.020390 ========= master_lag: 0.42 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:51.187586) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 18:01:51.187948) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 98aa054d9e2757e43158b0c962be9cbab784e9e2 --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 98aa054d9e2757e43158b0c962be9cbab784e9e2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Dt72AdlzAt/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.UbbU4UIn1f/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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-28 18:01:51,271 truncating revision to first 12 chars 2016-04-28 18:01:51,271 Setting DEBUG logging. 2016-04-28 18:01:51,271 attempt 1/10 2016-04-28 18:01:51,271 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/98aa054d9e27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 18:01:51,523 unpacking tar archive at: fx-team-98aa054d9e27/testing/mozharness/ program finished with exit code 0 elapsedTime=0.501736 ========= master_lag: 1.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 18:01:52.983851) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:52.988228) ========= script_repo_revision: 98aa054d9e2757e43158b0c962be9cbab784e9e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:52.988691) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:52.988984) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:53.025485) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-28 18:01:53.025894) ========= /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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:01:53 INFO - MultiFileLogger online at 20160428 18:01:53 in /builds/slave/test 18:01:53 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 18:01:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:01:53 INFO - {'append_to_log': False, 18:01:53 INFO - 'base_work_dir': '/builds/slave/test', 18:01:53 INFO - 'blob_upload_branch': 'Fx-Team', 18:01:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:01:53 INFO - 'branch': 'Fx-Team', 18:01:53 INFO - 'buildbot_json_path': 'buildprops.json', 18:01:53 INFO - 'config_files': ('talos/mac_config.py',), 18:01:53 INFO - 'default_actions': ('clobber', 18:01:53 INFO - 'read-buildbot-config', 18:01:53 INFO - 'download-and-extract', 18:01:53 INFO - 'populate-webroot', 18:01:53 INFO - 'create-virtualenv', 18:01:53 INFO - 'install', 18:01:53 INFO - 'run-tests'), 18:01:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:01:53 INFO - 'download_minidump_stackwalk': True, 18:01:53 INFO - 'download_symbols': 'ondemand', 18:01:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:01:53 INFO - 'tooltool.py': '/tools/tooltool.py', 18:01:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:01:53 INFO - '/tools/misc-python/virtualenv.py')}, 18:01:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:01:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:01:53 INFO - 'installer_path': 'installer.exe', 18:01:53 INFO - 'log_level': 'info', 18:01:53 INFO - 'log_name': 'talos', 18:01:53 INFO - 'log_to_console': True, 18:01:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:01:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:01:53 INFO - 'opt_config_files': (), 18:01:53 INFO - 'pip_index': False, 18:01:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:53 INFO - 'cmd': ('bash', 18:01:53 INFO - '-c', 18:01:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:53 INFO - 'enabled': True, 18:01:53 INFO - 'halt_on_failure': False, 18:01:53 INFO - 'name': 'check_screen_resolution'},), 18:01:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:53 INFO - 'cmd': ('bash', 18:01:53 INFO - '-c', 18:01:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:53 INFO - 'enabled': True, 18:01:53 INFO - 'halt_on_failure': False, 18:01:53 INFO - 'name': 'check_screen_resolution'},), 18:01:53 INFO - 'run_cmd_checks_enabled': True, 18:01:53 INFO - 'sps_profile': False, 18:01:53 INFO - 'sps_profile_interval': 0, 18:01:53 INFO - 'suite': 'dromaeojs', 18:01:53 INFO - 'system_bits': '32', 18:01:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:01:53 INFO - 'title': 't-yosemite-r7-0113', 18:01:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:01:53 INFO - 'use_talos_json': True, 18:01:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:01:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:01:53 INFO - 'work_dir': 'build'} 18:01:53 INFO - ##### 18:01:53 INFO - ##### Running clobber step. 18:01:53 INFO - ##### 18:01:53 INFO - Running pre-action listener: _resource_record_pre_action 18:01:53 INFO - Running main action method: clobber 18:01:53 INFO - rmtree: /builds/slave/test/build 18:01:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:01:55 INFO - Running post-action listener: _resource_record_post_action 18:01:55 INFO - ##### 18:01:55 INFO - ##### Running read-buildbot-config step. 18:01:55 INFO - ##### 18:01:55 INFO - Running pre-action listener: _resource_record_pre_action 18:01:55 INFO - Running main action method: read_buildbot_config 18:01:55 INFO - Using buildbot properties: 18:01:55 INFO - { 18:01:55 INFO - "project": "", 18:01:55 INFO - "product": "firefox", 18:01:55 INFO - "script_repo_revision": "production", 18:01:55 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:01:55 INFO - "repository": "", 18:01:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:01:55 INFO - "buildid": "20160428161042", 18:01:55 INFO - "pgo_build": "False", 18:01:55 INFO - "basedir": "/builds/slave/test", 18:01:55 INFO - "buildnumber": 790, 18:01:55 INFO - "slavename": "t-yosemite-r7-0113", 18:01:55 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:01:55 INFO - "platform": "yosemite_r7", 18:01:55 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:01:55 INFO - "branch": "fx-team", 18:01:55 INFO - "repo_path": "integration/fx-team", 18:01:55 INFO - "revision": "98aa054d9e2757e43158b0c962be9cbab784e9e2", 18:01:55 INFO - "stage_platform": "macosx64", 18:01:55 INFO - "builduid": "835df724bdae4035a563f9d7616ad8c3", 18:01:55 INFO - "slavebuilddir": "test" 18:01:55 INFO - } 18:01:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:01:55 INFO - Running post-action listener: _resource_record_post_action 18:01:55 INFO - ##### 18:01:55 INFO - ##### Running download-and-extract step. 18:01:55 INFO - ##### 18:01:55 INFO - Running pre-action listener: _resource_record_pre_action 18:01:55 INFO - Running main action method: download_and_extract 18:01:55 INFO - mkdir: /builds/slave/test/build/tests 18:01: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')]} 18:01:55 INFO - https://queue.taskcluster.net/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:01:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:01:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:01:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:01:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 18:01:57 INFO - Downloaded 1199 bytes. 18:01:57 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:01:57 INFO - Using the following test package requirements: 18:01:57 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:01:57 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:57 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:01:57 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:57 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:01:57 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:57 INFO - u'jsshell-mac.zip'], 18:01:57 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:57 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:01:57 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:01:57 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:57 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:01:57 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:57 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:01:57 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:57 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:01:57 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:01:57 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:01:57 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:57 INFO - https://queue.taskcluster.net/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:01:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:01:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:01:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:01:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:01:58 INFO - Downloaded 18124360 bytes. 18:01:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:01:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:02:01 INFO - Return code: 0 18:02:01 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:01 INFO - https://queue.taskcluster.net/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:02:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:02:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:02:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:02:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:02:03 INFO - Downloaded 18124360 bytes. 18:02:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:02:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:02:07 INFO - Return code: 0 18:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:07 INFO - https://queue.taskcluster.net/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:02:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:02:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:02:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:02:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:02:08 INFO - Downloaded 10840145 bytes. 18:02:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:02:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:02:08 INFO - Return code: 0 18:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:08 INFO - https://queue.taskcluster.net/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:02:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:02:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:02:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:02:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 18:02:11 INFO - Downloaded 110273903 bytes. 18:02:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:02:11 INFO - mkdir: /builds/slave/test/properties 18:02:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:02:11 INFO - Writing to file /builds/slave/test/properties/build_url 18:02:11 INFO - Contents: 18:02:11 INFO - build_url:https://queue.taskcluster.net/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:02:13 INFO - Running post-action listener: _resource_record_post_action 18:02:13 INFO - Running post-action listener: set_extra_try_arguments 18:02:13 INFO - ##### 18:02:13 INFO - ##### Running populate-webroot step. 18:02:13 INFO - ##### 18:02:13 INFO - Running pre-action listener: _resource_record_pre_action 18:02:13 INFO - Running main action method: populate_webroot 18:02:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:02:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:02:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:02:13 INFO - '--tppagecycles', 18:02:13 INFO - '7'], 18:02:13 INFO - 'tests': ['tsvgm']}}, 18:02:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:02:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:02:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:02:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:02:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:02:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:02:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:02:13 INFO - 'talos_options': ['--disable-e10s'], 18:02:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:02:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:02:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:02:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:02:13 INFO - 'talos_options': ['--disable-e10s'], 18:02:13 INFO - 'tests': ['damp', 'tps']}, 18:02:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:02:13 INFO - 'tests': ['damp', 'tps']}, 18:02:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:02:13 INFO - 'tests': ['dromaeo_dom']}, 18:02:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:02:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:02:13 INFO - 'tests': ['a11yr', 18:02:13 INFO - 'ts_paint', 18:02:13 INFO - 'tpaint', 18:02:13 INFO - 'sessionrestore', 18:02:13 INFO - 'sessionrestore_no_auto_restore', 18:02:13 INFO - 'tabpaint']}, 18:02:13 INFO - 'other-e10s': {'tests': ['a11yr', 18:02:13 INFO - 'ts_paint', 18:02:13 INFO - 'tpaint', 18:02:13 INFO - 'sessionrestore', 18:02:13 INFO - 'sessionrestore_no_auto_restore', 18:02:13 INFO - 'tabpaint']}, 18:02:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:02:13 INFO - 'ts_paint', 18:02:13 INFO - 'tpaint', 18:02:13 INFO - 'sessionrestore', 18:02:13 INFO - 'sessionrestore_no_auto_restore', 18:02:13 INFO - 'tabpaint']}, 18:02:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:02:13 INFO - 'ts_paint', 18:02:13 INFO - 'tpaint', 18:02:13 INFO - 'sessionrestore', 18:02:13 INFO - 'sessionrestore_no_auto_restore', 18:02:13 INFO - 'tabpaint']}, 18:02:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:02:13 INFO - 'tests': ['a11yr', 18:02:13 INFO - 'ts_paint', 18:02:13 INFO - 'tpaint', 18:02:13 INFO - 'sessionrestore', 18:02:13 INFO - 'sessionrestore_no_auto_restore', 18:02:13 INFO - 'tabpaint']}, 18:02:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:02:13 INFO - 'tests': ['a11yr', 18:02:13 INFO - 'ts_paint', 18:02:13 INFO - 'tpaint', 18:02:13 INFO - 'sessionrestore', 18:02:13 INFO - 'sessionrestore_no_auto_restore', 18:02:13 INFO - 'tabpaint']}, 18:02:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:02:13 INFO - 'tests': ['tsvgx', 18:02:13 INFO - 'tsvgr_opacity', 18:02:13 INFO - 'tart', 18:02:13 INFO - 'tscrollx', 18:02:13 INFO - 'cart']}, 18:02:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:02:13 INFO - 'tsvgr_opacity', 18:02:13 INFO - 'tart', 18:02:13 INFO - 'tscrollx', 18:02:13 INFO - 'cart']}, 18:02:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:02:13 INFO - 'talos_options': ['--disable-e10s'], 18:02:13 INFO - 'tests': ['tp5o']}, 18:02:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:02:13 INFO - 'tests': ['tp5o']}, 18:02:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:02:13 INFO - 'talos_options': ['--disable-e10s', 18:02:13 INFO - '--xperf_path', 18:02:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:02:13 INFO - 'tests': ['tp5n']}, 18:02:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:02:13 INFO - 'talos_options': ['--xperf_path', 18:02:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:02:13 INFO - 'tests': ['tp5n']}}, 18:02:13 INFO - 'talos.zip': {'path': '', 18:02:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:02:13 INFO - Running post-action listener: _resource_record_post_action 18:02:13 INFO - ##### 18:02:13 INFO - ##### Running create-virtualenv step. 18:02:13 INFO - ##### 18:02:13 INFO - Running pre-action listener: _resource_record_pre_action 18:02:13 INFO - Running main action method: create_virtualenv 18:02:13 INFO - Creating virtualenv /builds/slave/test/build/venv 18:02:13 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 18:02:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:02:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:02:13 INFO - Using real prefix '/tools/python27' 18:02:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:02:13 INFO - Installing distribute.............................................................................................................................................................................................done. 18:02:16 INFO - Installing pip.................done. 18:02:16 INFO - Return code: 0 18:02:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:16 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 0x10c3eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3fa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8910475ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c289e00>, '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 0x10c0b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c284b20>, '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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:02:16 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 18:02:16 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 18:02:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dt72AdlzAt/Render', 18:02:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:16 INFO - 'HOME': '/Users/cltbld', 18:02:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:16 INFO - 'LOGNAME': 'cltbld', 18:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:16 INFO - 'MOZ_NO_REMOTE': '1', 18:02:16 INFO - 'NO_EM_RESTART': '1', 18:02:16 INFO - 'PAGER': '/bin/cat', 18:02:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:16 INFO - 'PWD': '/builds/slave/test', 18:02:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:16 INFO - 'SHELL': '/bin/bash', 18:02:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UbbU4UIn1f/Listeners', 18:02:16 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:02:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:16 INFO - 'USER': 'cltbld', 18:02:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:16 INFO - 'XPC_FLAGS': '0x0', 18:02:16 INFO - 'XPC_SERVICE_NAME': '0', 18:02:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:17 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 18:02:17 INFO - Cleaning up... 18:02:17 INFO - Return code: 0 18:02:17 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:02: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')]} 18:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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')]} 18:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:17 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 0x10c3eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3fa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8910475ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c289e00>, '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 0x10c0b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c284b20>, '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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:02:17 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 18:02:17 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 18:02:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dt72AdlzAt/Render', 18:02:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:17 INFO - 'HOME': '/Users/cltbld', 18:02:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:17 INFO - 'LOGNAME': 'cltbld', 18:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:17 INFO - 'MOZ_NO_REMOTE': '1', 18:02:17 INFO - 'NO_EM_RESTART': '1', 18:02:17 INFO - 'PAGER': '/bin/cat', 18:02:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:17 INFO - 'PWD': '/builds/slave/test', 18:02:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:17 INFO - 'SHELL': '/bin/bash', 18:02:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UbbU4UIn1f/Listeners', 18:02:17 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:02:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:17 INFO - 'USER': 'cltbld', 18:02:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:17 INFO - 'XPC_FLAGS': '0x0', 18:02:17 INFO - 'XPC_SERVICE_NAME': '0', 18:02:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:17 INFO - Downloading/unpacking psutil>=0.7.1 18:02:17 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 18:02:17 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 18:02:17 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 18:02:17 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 18:02:17 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 18:02:17 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 18:02:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:02:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:20 INFO - Installing collected packages: psutil 18:02:20 INFO - Running setup.py install for psutil 18:02:20 INFO - building 'psutil._psutil_osx' extension 18:02:20 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 18:02:20 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 18:02:20 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 18:02:20 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 18:02:20 INFO - building 'psutil._psutil_posix' extension 18:02:20 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 18:02:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:02:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:02:20 INFO - ^ 18:02:21 INFO - 1 warning generated. 18:02:21 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 18:02:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:21 INFO - Successfully installed psutil 18:02:21 INFO - Cleaning up... 18:02:21 INFO - Return code: 0 18:02:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:02: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')]} 18:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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')]} 18:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:21 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 0x10c3eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3fa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8910475ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c289e00>, '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 0x10c0b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c284b20>, '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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:02:21 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 18:02:21 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 18:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dt72AdlzAt/Render', 18:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:21 INFO - 'HOME': '/Users/cltbld', 18:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:21 INFO - 'LOGNAME': 'cltbld', 18:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:21 INFO - 'MOZ_NO_REMOTE': '1', 18:02:21 INFO - 'NO_EM_RESTART': '1', 18:02:21 INFO - 'PAGER': '/bin/cat', 18:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:21 INFO - 'PWD': '/builds/slave/test', 18:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:21 INFO - 'SHELL': '/bin/bash', 18:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UbbU4UIn1f/Listeners', 18:02:21 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:21 INFO - 'USER': 'cltbld', 18:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:21 INFO - 'XPC_FLAGS': '0x0', 18:02:21 INFO - 'XPC_SERVICE_NAME': '0', 18:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:02:21 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 18:02:21 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 18:02:21 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 18:02:21 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 18:02:21 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 18:02:21 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 18:02:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:02:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:02:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:02:24 INFO - Installing collected packages: mozsystemmonitor 18:02:24 INFO - Running setup.py install for mozsystemmonitor 18:02:24 INFO - Successfully installed mozsystemmonitor 18:02:24 INFO - Cleaning up... 18:02:24 INFO - Return code: 0 18:02:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:02:24 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')]} 18:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:24 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')]} 18:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:24 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 0x10c3eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3fa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8910475ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c289e00>, '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 0x10c0b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c284b20>, '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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:02:24 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 18:02:24 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 18:02:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dt72AdlzAt/Render', 18:02:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:24 INFO - 'HOME': '/Users/cltbld', 18:02:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:24 INFO - 'LOGNAME': 'cltbld', 18:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:24 INFO - 'MOZ_NO_REMOTE': '1', 18:02:24 INFO - 'NO_EM_RESTART': '1', 18:02:24 INFO - 'PAGER': '/bin/cat', 18:02:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:24 INFO - 'PWD': '/builds/slave/test', 18:02:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:24 INFO - 'SHELL': '/bin/bash', 18:02:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UbbU4UIn1f/Listeners', 18:02:24 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:02:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:24 INFO - 'USER': 'cltbld', 18:02:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:24 INFO - 'XPC_FLAGS': '0x0', 18:02:24 INFO - 'XPC_SERVICE_NAME': '0', 18:02:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:24 INFO - Downloading/unpacking blobuploader==1.2.4 18:02:24 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 18:02:24 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 18:02:24 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 18:02:24 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 18:02:24 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 18:02:24 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 18:02:26 INFO - Downloading blobuploader-1.2.4.tar.gz 18:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:02:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:02:26 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 18:02:26 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 18:02:26 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 18:02:26 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 18:02:26 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 18:02:26 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 18:02:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:02:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02: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 18:02:28 INFO - Downloading docopt-0.6.1.tar.gz 18:02:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:02:28 INFO - Installing collected packages: blobuploader, requests, docopt 18:02:28 INFO - Running setup.py install for blobuploader 18:02:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:02:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:02:28 INFO - Running setup.py install for requests 18:02:28 INFO - Running setup.py install for docopt 18:02:28 INFO - Successfully installed blobuploader requests docopt 18:02:28 INFO - Cleaning up... 18:02:29 INFO - Return code: 0 18:02:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:29 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 0x10c3eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3fa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8910475ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c289e00>, '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 0x10c0b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c284b20>, '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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:02:29 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 18:02:29 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 18:02:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dt72AdlzAt/Render', 18:02:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:29 INFO - 'HOME': '/Users/cltbld', 18:02:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:29 INFO - 'LOGNAME': 'cltbld', 18:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:29 INFO - 'MOZ_NO_REMOTE': '1', 18:02:29 INFO - 'NO_EM_RESTART': '1', 18:02:29 INFO - 'PAGER': '/bin/cat', 18:02:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:29 INFO - 'PWD': '/builds/slave/test', 18:02:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:29 INFO - 'SHELL': '/bin/bash', 18:02:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UbbU4UIn1f/Listeners', 18:02:29 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:02:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:29 INFO - 'USER': 'cltbld', 18:02:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:29 INFO - 'XPC_FLAGS': '0x0', 18:02:29 INFO - 'XPC_SERVICE_NAME': '0', 18:02:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:29 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-IZCQrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:29 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-F7Ku1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:29 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-TpOGI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-l0gdiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ZtTPA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ui3I3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ABN7ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-1sJxk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-IV5yO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-nXFtzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-2tt1hU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ijzY0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-5ncPcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Arg4Jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-VbsjrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-0wY7PT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-af02Zt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:02:31 INFO - Running setup.py install for manifestparser 18:02:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:02:31 INFO - Running setup.py install for mozcrash 18:02:31 INFO - Running setup.py install for mozdebug 18:02:31 INFO - Running setup.py install for mozdevice 18:02:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:02:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:02:32 INFO - Running setup.py install for mozfile 18:02:32 INFO - Running setup.py install for mozhttpd 18:02:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:02:32 INFO - Running setup.py install for mozinfo 18:02:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:02:32 INFO - Running setup.py install for mozInstall 18:02:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:02:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:02:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:02:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:02:32 INFO - Running setup.py install for mozleak 18:02:32 INFO - Running setup.py install for mozlog 18:02:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:02:32 INFO - Running setup.py install for moznetwork 18:02:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:02:33 INFO - Running setup.py install for mozprocess 18:02:33 INFO - Running setup.py install for mozprofile 18:02:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:02:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:02:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:02:33 INFO - Running setup.py install for mozrunner 18:02:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:02:33 INFO - Running setup.py install for mozscreenshot 18:02:33 INFO - Running setup.py install for moztest 18:02:33 INFO - Running setup.py install for mozversion 18:02:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:02:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:02:34 INFO - Cleaning up... 18:02:34 INFO - Return code: 0 18:02:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:34 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 0x10c3eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3fa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8910475ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c289e00>, '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 0x10c0b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c284b20>, '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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:02:34 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 18:02:34 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 18:02:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dt72AdlzAt/Render', 18:02:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:34 INFO - 'HOME': '/Users/cltbld', 18:02:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:34 INFO - 'LOGNAME': 'cltbld', 18:02:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:34 INFO - 'MOZ_NO_REMOTE': '1', 18:02:34 INFO - 'NO_EM_RESTART': '1', 18:02:34 INFO - 'PAGER': '/bin/cat', 18:02:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:34 INFO - 'PWD': '/builds/slave/test', 18:02:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:34 INFO - 'SHELL': '/bin/bash', 18:02:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UbbU4UIn1f/Listeners', 18:02:34 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:02:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:34 INFO - 'USER': 'cltbld', 18:02:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:34 INFO - 'XPC_FLAGS': '0x0', 18:02:34 INFO - 'XPC_SERVICE_NAME': '0', 18:02:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-mN1RBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:34 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)) 18:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-wDhla1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:34 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)) 18:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-mkmxpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:34 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)) 18:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-90tnHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:34 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)) 18:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-aP96Bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:34 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)) 18:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:34 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-YNgNQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:35 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)) 18:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-bZ6pjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:35 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)) 18:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Dyc1TA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:35 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)) 18:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-fTEssF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:35 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)) 18:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-4cMrWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:35 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)) 18:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-l2zu0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:35 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)) 18:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-6zYV5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:35 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)) 18:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-pSAQOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:35 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)) 18:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-W4NgpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:35 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)) 18:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:35 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-giMlat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:36 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)) 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:36 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-OoU00Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:36 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)) 18:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:36 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-JePPel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:36 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)) 18:02:36 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)) 18:02:36 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)) 18:02:36 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)) 18:02:36 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)) 18:02:36 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)) 18:02:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:38 INFO - Downloading blessings-1.6.tar.gz 18:02:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:02:38 INFO - Installing collected packages: blessings 18:02:38 INFO - Running setup.py install for blessings 18:02:38 INFO - Successfully installed blessings 18:02:38 INFO - Cleaning up... 18:02:39 INFO - Return code: 0 18:02:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:02:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:39 INFO - Reading from file tmpfile_stdout 18:02:39 INFO - Current package versions: 18:02:39 INFO - blessings == 1.6 18:02:39 INFO - blobuploader == 1.2.4 18:02:39 INFO - docopt == 0.6.1 18:02:39 INFO - manifestparser == 1.1 18:02:39 INFO - mozInstall == 1.12 18:02:39 INFO - mozcrash == 0.17 18:02:39 INFO - mozdebug == 0.1 18:02:39 INFO - mozdevice == 0.48 18:02:39 INFO - mozfile == 1.2 18:02:39 INFO - mozhttpd == 0.7 18:02:39 INFO - mozinfo == 0.9 18:02:39 INFO - mozleak == 0.1 18:02:39 INFO - mozlog == 3.1 18:02:39 INFO - moznetwork == 0.27 18:02:39 INFO - mozprocess == 0.22 18:02:39 INFO - mozprofile == 0.28 18:02:39 INFO - mozrunner == 6.11 18:02:39 INFO - mozscreenshot == 0.1 18:02:39 INFO - mozsystemmonitor == 0.0 18:02:39 INFO - moztest == 0.7 18:02:39 INFO - mozversion == 1.4 18:02:39 INFO - psutil == 3.1.1 18:02:39 INFO - requests == 1.2.3 18:02:39 INFO - wsgiref == 0.1.2 18:02:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:39 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 0x10c3eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3fa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8910475ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c289e00>, '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 0x10c0b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c284b20>, '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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:02:39 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 18:02:39 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 18:02:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dt72AdlzAt/Render', 18:02:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:39 INFO - 'HOME': '/Users/cltbld', 18:02:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:39 INFO - 'LOGNAME': 'cltbld', 18:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:39 INFO - 'MOZ_NO_REMOTE': '1', 18:02:39 INFO - 'NO_EM_RESTART': '1', 18:02:39 INFO - 'PAGER': '/bin/cat', 18:02:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:39 INFO - 'PWD': '/builds/slave/test', 18:02:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:39 INFO - 'SHELL': '/bin/bash', 18:02:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UbbU4UIn1f/Listeners', 18:02:39 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:02:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:39 INFO - 'USER': 'cltbld', 18:02:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:39 INFO - 'XPC_FLAGS': '0x0', 18:02:39 INFO - 'XPC_SERVICE_NAME': '0', 18:02:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:39 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)) 18:02:39 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)) 18:02:39 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)) 18:02:39 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)) 18:02:39 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)) 18:02:39 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)) 18:02:39 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)) 18:02:39 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)) 18:02:39 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)) 18:02:39 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)) 18:02:39 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)) 18:02:39 INFO - Cleaning up... 18:02:39 INFO - Return code: 0 18:02:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:39 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 0x10c3eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3fa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8910475ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c289e00>, '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 0x10c0b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c284b20>, '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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:02:39 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 18:02:39 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 18:02:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dt72AdlzAt/Render', 18:02:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:39 INFO - 'HOME': '/Users/cltbld', 18:02:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:39 INFO - 'LOGNAME': 'cltbld', 18:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:39 INFO - 'MOZ_NO_REMOTE': '1', 18:02:39 INFO - 'NO_EM_RESTART': '1', 18:02:39 INFO - 'PAGER': '/bin/cat', 18:02:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:39 INFO - 'PWD': '/builds/slave/test', 18:02:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:39 INFO - 'SHELL': '/bin/bash', 18:02:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UbbU4UIn1f/Listeners', 18:02:39 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:02:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:39 INFO - 'USER': 'cltbld', 18:02:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:39 INFO - 'XPC_FLAGS': '0x0', 18:02:39 INFO - 'XPC_SERVICE_NAME': '0', 18:02:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:39 INFO - Downloading/unpacking jsonschema 18:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:02:42 INFO - Downloading/unpacking functools32 (from jsonschema) 18:02:42 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 18:02:42 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 18:02:42 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 18:02:42 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 18:02:42 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 18:02:42 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 18:02:42 INFO - Downloading functools32-3.2.3-2.tar.gz 18:02:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:02:42 INFO - warning: no files found matching '*.txt' 18:02:42 INFO - no previously-included directories found matching 'build' 18:02:42 INFO - no previously-included directories found matching 'dist' 18:02:42 INFO - no previously-included directories found matching '.git*' 18:02:42 INFO - Installing collected packages: jsonschema, functools32 18:02:42 INFO - Running setup.py install for functools32 18:02:42 INFO - warning: no files found matching '*.txt' 18:02:42 INFO - no previously-included directories found matching 'build' 18:02:42 INFO - no previously-included directories found matching 'dist' 18:02:42 INFO - no previously-included directories found matching '.git*' 18:02:42 INFO - Successfully installed jsonschema functools32 18:02:42 INFO - Cleaning up... 18:02:43 INFO - Return code: 0 18:02:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c3eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3fa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8910475ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c289e00>, '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 0x10c0b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c284b20>, '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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:02:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dt72AdlzAt/Render', 18:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:43 INFO - 'HOME': '/Users/cltbld', 18:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:43 INFO - 'LOGNAME': 'cltbld', 18:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:43 INFO - 'MOZ_NO_REMOTE': '1', 18:02:43 INFO - 'NO_EM_RESTART': '1', 18:02:43 INFO - 'PAGER': '/bin/cat', 18:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:43 INFO - 'PWD': '/builds/slave/test', 18:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:43 INFO - 'SHELL': '/bin/bash', 18:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UbbU4UIn1f/Listeners', 18:02:43 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:43 INFO - 'USER': 'cltbld', 18:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:43 INFO - 'XPC_FLAGS': '0x0', 18:02:43 INFO - 'XPC_SERVICE_NAME': '0', 18:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:43 INFO - Downloading/unpacking flake8 18:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:02:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:02:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:02:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:02:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:02:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:02:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:02:46 INFO - no previously-included directories found matching 'docs/_build' 18:02:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:02:47 INFO - Running setup.py install for pep8 18:02:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:02:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:02:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:02:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:02:47 INFO - no previously-included directories found matching 'docs/_build' 18:02:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:02:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:02:47 INFO - Cleaning up... 18:02:47 INFO - Return code: 0 18:02:47 INFO - Running post-action listener: _resource_record_post_action 18:02:47 INFO - Running post-action listener: _start_resource_monitoring 18:02:47 INFO - Starting resource monitoring. 18:02:47 INFO - ##### 18:02:47 INFO - ##### Running install step. 18:02:47 INFO - ##### 18:02:47 INFO - Running pre-action listener: _resource_record_pre_action 18:02:47 INFO - Running main action method: install 18:02:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:47 INFO - Reading from file tmpfile_stdout 18:02:47 INFO - Detecting whether we're running mozinstall >=1.0... 18:02:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:02:47 INFO - Reading from file tmpfile_stdout 18:02:47 INFO - Output received: 18:02:47 INFO - Usage: mozinstall [options] installer 18:02:47 INFO - Options: 18:02:47 INFO - -h, --help show this help message and exit 18:02:47 INFO - -d DEST, --destination=DEST 18:02:47 INFO - Directory to install application into. [default: 18:02:47 INFO - "/builds/slave/test"] 18:02:47 INFO - --app=APP Application being installed. [default: firefox] 18:02:47 INFO - mkdir: /builds/slave/test/build/application 18:02:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:03:20 INFO - Reading from file tmpfile_stdout 18:03:20 INFO - Output received: 18:03:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:03:20 INFO - Running post-action listener: _resource_record_post_action 18:03:20 INFO - ##### 18:03:20 INFO - ##### Running run-tests step. 18:03:20 INFO - ##### 18:03:20 INFO - Running pre-action listener: _resource_record_pre_action 18:03:20 INFO - Running main action method: run_tests 18:03:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:03:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:03:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:03:20 INFO - 2016-04-28 18:03:20.972 screenresolution[2757:31437] starting screenresolution argv=screenresolution get 18:03:20 INFO - 2016-04-28 18:03:20.975 screenresolution[2757:31437] Display 0: 1600x1200x32@60 18:03:20 INFO - 2016-04-28 18:03:20.981 screenresolution[2758:31439] starting screenresolution argv=screenresolution list 18:03:20 INFO - Available Modes on Display 0 18:03:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:03:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:03:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:03:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:03:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:03:21 INFO - Intel Iris: 18:03:21 INFO - Chipset Model: Intel Iris 18:03:21 INFO - Type: GPU 18:03:21 INFO - Bus: Built-In 18:03:21 INFO - VRAM (Dynamic, Max): 1536 MB 18:03:21 INFO - Vendor: Intel (0x8086) 18:03:21 INFO - Device ID: 0x0a2e 18:03:21 INFO - Revision ID: 0x0009 18:03:21 INFO - Displays: 18:03:21 INFO - Display: 18:03:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:03:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:03:21 INFO - Display Serial Number: 1600x1200 60 18:03:21 INFO - Main Display: Yes 18:03:21 INFO - Mirror: Off 18:03:21 INFO - Online: Yes 18:03:21 INFO - Rotation: Supported 18:03:21 INFO - Return code: 0 18:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:03:21 INFO - Python 2.7.3 18:03:21 INFO - Return code: 0 18:03:21 INFO - grabbing minidump binary from tooltool 18:03: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')]} 18:03:21 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 0x10c289e00>, '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 0x10c0b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c284b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:03:21 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 18:03:21 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 18:03:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:03:21 INFO - Return code: 0 18:03:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:03:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:03:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:03:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:03:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:03:22 INFO - Return code: 0 18:03:22 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/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 18:03:22 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/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0113 --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 18:03:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dt72AdlzAt/Render', 18:03:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:22 INFO - 'HOME': '/Users/cltbld', 18:03:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:22 INFO - 'LOGNAME': 'cltbld', 18:03:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:03:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:22 INFO - 'MOZ_NO_REMOTE': '1', 18:03:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:03:22 INFO - 'NO_EM_RESTART': '1', 18:03:22 INFO - 'PAGER': '/bin/cat', 18:03:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:22 INFO - 'PWD': '/builds/slave/test', 18:03:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:03:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:22 INFO - 'SHELL': '/bin/bash', 18:03:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UbbU4UIn1f/Listeners', 18:03:22 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:03:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:22 INFO - 'USER': 'cltbld', 18:03:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:22 INFO - 'XPC_FLAGS': '0x0', 18:03:22 INFO - 'XPC_SERVICE_NAME': '0', 18:03:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:22 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/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 18:03:22 INFO - mozversion application_buildid: 20160428161042 18:03:22 INFO - mozversion application_changeset: 98aa054d9e2757e43158b0c962be9cbab784e9e2 18:03:22 INFO - mozversion application_display_name: Nightly 18:03:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:03:22 INFO - mozversion application_name: Firefox 18:03:22 INFO - mozversion application_remotingname: firefox 18:03:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:03:22 INFO - mozversion application_vendor: Mozilla 18:03:22 INFO - mozversion application_version: 49.0a1 18:03:22 INFO - mozversion platform_buildid: 20160428161042 18:03:22 INFO - mozversion platform_changeset: 98aa054d9e2757e43158b0c962be9cbab784e9e2 18:03:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:03:22 INFO - mozversion platform_version: 49.0a1 18:03:22 INFO - using testdate: 1461891802 18:03:22 INFO - actual date: 1461891802 18:03:22 INFO - starting webserver on 'localhost:61754' 18:03:22 INFO - SUITE-START | Running 2 tests 18:03:22 INFO - TEST-START | dromaeo_css 18:03:22 INFO - operating with platform_type : mac_ 18:03:22 INFO - Initialising browser for dromaeo_css test... 18:03:22 INFO - TEST-INFO | started process 2776 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmp1RUurN/profile http://localhost:61754/getInfo.html) 18:03:24 INFO - PROCESS | 2776 | __metrics Screen width/height:1600/1200 18:03:24 INFO - PROCESS | 2776 | colorDepth:24 18:03:24 INFO - PROCESS | 2776 | Browser inner width/height: 1024/689 18:03:24 INFO - PROCESS | 2776 | __metrics 18:03:24 INFO - PROCESS | 2776 | 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" 18:03:24 ERROR - PROCESS | 2776 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:03:25 INFO - TEST-INFO | 2776: exit 0 18:03:25 INFO - Browser initialized. 18:03:25 INFO - Running cycle 1/1 for dromaeo_css test... 18:03:25 INFO - TEST-INFO | started process 2778 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmp1RUurN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:03:37 INFO - PROCESS | 2778 | RSS: Main: 239960064 18:03:37 INFO - PROCESS | 2778 | 18:04:28 INFO - PROCESS | 2778 | 1461891868818 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] 18:06:39 INFO - PROCESS | 2778 | Cycle 1(1): loaded http://localhost:61754/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61754/tests/dromaeo/cssquery-ext.html) 18:06:40 INFO - PROCESS | 2778 | RSS: Main: 348209152 18:06:40 INFO - PROCESS | 2778 | 18:09:44 INFO - PROCESS | 2778 | Cycle 1(1): loaded http://localhost:61754/tests/dromaeo/cssquery-ext.html (next: http://localhost:61754/tests/dromaeo/cssquery-jquery.html) 18:09:44 INFO - PROCESS | 2778 | RSS: Main: 373342208 18:09:44 INFO - PROCESS | 2778 | 18:12:46 INFO - PROCESS | 2778 | Cycle 1(1): loaded http://localhost:61754/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61754/tests/dromaeo/cssquery-mootools.html) 18:12:47 INFO - PROCESS | 2778 | RSS: Main: 369434624 18:12:47 INFO - PROCESS | 2778 | 18:15:49 INFO - PROCESS | 2778 | Cycle 1(1): loaded http://localhost:61754/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61754/tests/dromaeo/cssquery-prototype.html) 18:15:50 INFO - PROCESS | 2778 | RSS: Main: 359415808 18:15:50 INFO - PROCESS | 2778 | 18:18:53 INFO - PROCESS | 2778 | Cycle 1(1): loaded http://localhost:61754/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61754/tests/dromaeo/cssquery-yui.html) 18:18:54 INFO - PROCESS | 2778 | RSS: Main: 338509824 18:18:54 INFO - PROCESS | 2778 | 18:21:57 INFO - PROCESS | 2778 | Cycle 1(1): loaded http://localhost:61754/tests/dromaeo/cssquery-yui.html (next: http://localhost:61754/tests/dromaeo/cssquery-dojo.html) 18:21:57 INFO - PROCESS | 2778 | RSS: Main: 378474496 18:21:57 INFO - PROCESS | 2778 | 18:21:57 INFO - PROCESS | 2778 | __start_tp_report 18:21:57 INFO - PROCESS | 2778 | _x_x_mozilla_page_load 18:21:57 INFO - PROCESS | 2778 | _x_x_mozilla_page_load_details 18:21:57 INFO - PROCESS | 2778 | |i|pagename|runs| 18:21:57 INFO - PROCESS | 2778 | |0;dojo.html;3278.541004259404;3333.399988002159;3342.766006379554;3366.141633883359;3405.6424075472073;5476.561875049996;5546.69493177875;5565.554755619546;5608.214849921009;5619.1852181433715;4704.482506924235;5525.944740552593;5567.47109024642;5606.383297837235;5670.121131224657;5023.522765337287;5058.747062646871;5088.465711100337;5107.795688172488;5195.9480405195845;5143.254228136918;5152.768125434364;5171.043356978952;5195.324607801;5243.475652434745;5037.395512538482;5075.593952483792;5132.717700526469;5137.691738495683;5141.53726164647;5098.515641014098;5102.132637451642;5110;5158.484151584849;5192.299039629639;5075.264086707407;5088.23676448532;5115.8209462669;5124.897502049976;5142.280080788692;4282.100758840647;5207.765650545734;5536.778528858841;5537.640053396557;5537.667739935617;6042.335343112254;6077.662914158878;6105.267367915834;6114.996965385497;6120.938790612081;6119.143319935212;6197.845053873644;6198.969005154945;6207.006878899399;6222.937770622281;5534.557235421156;5636.2954630671165;5713.628614140068;5715.142728590745;5771.942280577183;6151;6247.125402443661;6265.8120256392385;6278.905816412757;6281.466075383643;5970.611910225822;5976.73104710289;5980.940190598125;5985.910211346833;5997.610155339889;5817.38917413674;6017.097435384728;6101.725422355916;6115.174451448997;6126.877462450726;5886.411358864079;5920.526357891358;5922.555808314393;5924.022536281552;5933.406659334032;5879.323877754027;5919.230500034968;5942.435468630473;5944.613600115978;5950.464458198783;4140.15126898985;4750.881227969259;4761.262004389325;4767;4779.354787103766;4183.748975061506;4286.464191976003;4295.74225546462;4375.1031038637;4397.252467080604;5534.833954981357;5538.113901775697;5572.58205634579;5580.720963951786;5589.692566908778;6118.388161183846;6123.571350005457;6142.938570614237;6160.753569857246;6163.568550201443;5394.083005889008;5459.863503412367;5469.972650136724;5470.343558772893;5486.067368547357;4754.0016596515115;4915.43472500667;5007.023630392038;5238.7904483820985;5249.973750131225;6132.386761323832;6268.655223962726;6281.277653069864;6281.937180628318;6328.430441260309;4924.335214745964;6097.5731698780655;6106.877862442638;6138.140660307471;6153.661548614869;6214.533909956862;6268.937310626835;6303.527235457267;6328.6202827830475;6330.208723909512;5036.420811606712;5884.558658100746;5973.342932277359;5975.551833612583;5980.0431930890845;5987.191729116514;6078.057901025348;6078.45293923549;6081.452669259788;6089;5946.43508866657;5954;5972.014617588049;5985.910211346921;6051.878962420815;5949.91075133882;5950.553708471968;5955.04719244936;5969.820905372846;5970.223870896755;5581.804636837691;5722.313322401338;5787.681677507778;5815.79644712433;5821.534277257724;4501.707389019703;5848.941510584839;5891.587588868738;5906;5938.703064846827;5771.8557036074435;5775.1914731938405;5780.046292361882;5782.10377391505;5786.132080188006 18:21:57 INFO - PROCESS | 2778 | |1;ext.html;519.7894852584702;524.9658173398406;553.6760994818031;621.3382747374047;655.84915469442;25093.62359564608;28896;37843.621563784385;38730.03174920628;38783.83648490543;42416;43104.78447607766;43344;43360.783196084056;43559;9334.486603236819;9554.378965367228;9585.424874507551;9587.089226523469;9592.664256751015;8243.464174828618;8459.154084591553;9184.678536251233;11202.215844890861;11217.66347009591;11144.888551114465;11310.547578096866;11324;11333.603323883666;11353.262037967506;8266.214709602638;11165.050970667504;11303.434828258554;11308.604198853041;11322.773544529064;10919.945400273027;11197.552097916074;11206.775864482664;11215.439228038565;11234.438278086063;38023.42964855529;38415.03962400935;38433.80783096095;38523.4590616375;38632.42051369232;3233.5634689316876;3285.83570821458;3319.8008119512906;3773.811309434545;4054.553999060101;27201.45597088047;27941.580876286873;28003.439931201465;29043;29082.27294317638;2732.53546897028;2803;2810.634617499712;2815.211740712604;2823.392970511329;470063;472798;473368.63315683545;474827;475782;48083.519164807905;55198;59088.40911590829;59602;59703.70148149232;56236.43763562394;56298.15552766712;56580;56713.716431418405;56744;16100.597485062734;16297.34810607586;16306.103164326076;16347.019178849307;16348.34606615722;11537.48081336342;11775.352355620353;12511.812322815165;12841.036922230871;12919.870801292054;15043.323050462755;15306.158161301235;15449.459268925533;15491.147986860606;15496.30266638004;7067.681954312068;7178.102737157856;7181.712731490787;7222.422206223595;7284.198738138801;970.7330484116924;1018.4449475036123;1025.3898930136556;1029.917606591471;1117.692634525512;27051.729482705316;29272.26819329534;29601.25996850053;29784.95752648647;30139.698603014127;1051.9894801052044;1076.101455284846;1124.4658787076073;1124.983125253122;1194.42070595761;13132.540361087507;14952.252387380588;15116.697666046617;15168.544943651708;15468.767968480483;607753.961230191;621782;627770;627804;630223;60105.699471503234;60388.698056510315;60579.394206058256;60586.69706651438;61439.084873210035;1433.749093908562;1462.4808193091512;1507.0957425544586;1512.7277090123664;1512.9243537823286;24274.635880461454;27102;28438.85780571084;29595.85202073976;29884.252893676963;48425.757871210415;48558.5144148554;48565.75717121392;48582.78543036452;48623;420148;433756;435004;437259;438570;31323.77129087544;31480.685193148693;31833.840830795696;31875.84062079675;37260.96976908191;33716.66283337135;39070.4139437903;42484;43218.783906080265;44946;33472.83263583667;35727.64272357243;36203.81898090493;36328.63671363358;37887.81056094812;30393;34951.475727864614;36036.73871414488;36054;37237.81381093077;34326.65673343235;35046.47430288599;35871.64128358683;35919;36024.459633105;30283;31872.203194920312;32842.67157328396;32903.34193316168;33009;23635;23636.409089772893;24070.759292406852;24247;24319.66241856715 18:21:57 INFO - PROCESS | 2778 | |2;jquery.html;398.609362824432;400.64743026136995;401.51416785689315;401.6224748736186;410.4704930639475;14140.363683634236;19664.803351966475;20544.486387840312;20597.38207853765;20628.793712062874;15617.453389131382;17226.741598876026;19626.705599415985;20334.59330813383;20460.89769551154;5883.794067207648;6049.032154855224;6095.725692343833;6098.237720284965;6117.143599896018;5953.732082056296;5963.582549221556;5969.253843269591;5979.611325263866;6069.453749162574;5881.294244690622;5911.172435858984;5922.141289513018;5924.289085309769;5925.970370148143;5824.825255242328;5957.582969192158;6062.120742569773;6064.939350606482;6067.7269522871575;5088.313077734496;5247.501487358695;5267.683938963674;5274.446183150752;5279.155335146396;23675.881620591957;24923.50152996948;26022.73977260222;26170.73829261721;26228.344291392677;2025.868318559289;2209.8674079555117;2257.1761307122783;2612.529744645963;2845.359794046323;11712.941435292854;14456.421743130368;14653.926730366387;14956.775648365283;15292.235388230543;1738.6957282475644;1752.9298828046863;1781.153951872863;1831.5054935167564;1849.759531260941;100732;103109;104178;104331.47834260856;106925.39611905905;10633.89366106329;10812.837807432894;11115.05451055471;11167.888321116685;11278.774424511465;12594.937025314815;13709.588712338646;14254.429822807178;14263.85736142646;14296.142231466145;7849.803754906173;7935.603219838985;8082.232187942136;8754.956225218833;8939.374243803002;8782.033976262734;8802.295816334678;8820.25027872638;8920.643174272956;8959.1936725695;6583.572067815577;6672.766453174116;6697.330266973361;6706.7652632158615;6713.530052896251;1660.3607611069824;1881.5484283771796;1905.1617288393063;1916.9041547922548;1950.3953774330002;898.2993265253114;971.4608392342177;1015.4465816130227;1042.5256508288862;1100.1858624617726;20389.490262743253;21340.466488337916;21366.35900922975;21401.357959261248;21435.78564214338;975.9316847820585;1013.4679293370892;1020.8158536098091;1111.5494279964723;1115.3475216998147;10811.567537298577;11140.777184456265;11525.366104864146;11560.6531804046;11738.413079345999;563647;581231.3138080378;601871.9251229989;601923;602948;112685.87314126917;115811;126762.36618816847;135953;140232.2988385072;1015.4262841494532;1018.7351288664852;1048.7745134795903;1065.6270305393205;1072.2226385870338;17767.111644417986;18117.184726687596;18173.909130454264;18341.358052468633;18765.624687506446;43915.682529524165;45240.96415661369;51351.743241283555;54645.45354546563;55297.72351138219;54670;71183;73356;75086.24913750793;77163.61418192873;9987.700368988943;10531.420771857314;11832.230904991147;11944.283342999448;12054.216475929037;11792.941035294769;12425.813612795997;12497.375131243583;12531.561395351118;12550.246985180918;10554.472276385997;10791.784164316512;10858.34849909008;11022.228444008842;11224.94387528057;10269.486525673836;10489.160867130764;10621.362718236931;10695.411752353695;10800;10836.512356943798;10904.836427453436;10963.945180274048;11058.060064894253;11539.36533490666;11408.258463199865;11485.598004069818;11634.6509604712;11667.941660291644;11687.941560292144;10640.361578305326;10830.620928267474;10880.619178329038;10892.292001019909;10893.945530272298 18:21:57 INFO - PROCESS | 2778 | |3;mootools.html;1432.3983926750764;1605.3257631794645;1626.6502701919085;1851.8796278241914;1970.4581240158964;7589.127250366209;8548.187922147401;8743.912560874405;8750.824983500326;8795.164459376358;8339.207775261355;8547.743567692964;8555;8564.486130832154;8573.699920502784;2144.9142034318606;2222.4999375140546;2224.688543603897;2419.322589674894;2467.062516243825;2201.636729939558;2426.890789914449;2500.5124000819847;2500.5374005808894;2503.949921001579;2411.059686722171;2442.584760590695;2448.155386391692;2454.6931633545805;2455;2118.9682154767693;2394.6048901931163;2417.9602770808547;2428.4171798768343;2436.3300092474624;2397.9640305395433;2461.59383701689;2474.344298760812;2474.789642880339;2478.3804048987754;8529.061803201643;8591.699290524864;8592.8711069334;8600.526971016592;8614.44006139599;5229;5440.455954404528;5454.263674403984;5469.453054694539;5471.50756431919;3460.3079384123275;3572.392693242155;3665.688416484602;3674.8713795017316;3679.558452985632;742.5581778841581;908.2960705453327;1022.6676330192718;1040.959040959041;1069.6149386220955;11474.311541307548;11485.196036277464;11498.942505287503;11514.02130818873;11541.134414918915;4833.661643684979;4851;4881.048195601895;4954;5013.674111182761;9926.553305101288;9940.204783617293;9940.353876997971;9941.005899410146;9965.701028969142;5441.428649991773;5481.205225242397;5491.697956612345;5492.450754924555;5503.642263252876;4837.516248375135;4873.585745211697;4927.334809800703;4976.925346119811;4999.675021123615;4015.8393664253686;4016.457778199964;4067.51189857219;4095.344744840811;4132.483439570054;1373.4574842936984;1379.3999610169608;1380.8688174623394;1385.8683425074603;1394.3585950462698;798.4011991006745;819.9467034642729;895.4806212396794;925.1812146250617;938.394735395666;5517.200005999108;5545.556355491551;5746.626469279484;5926.762929482858;6086.6348019119;419.737663960025;441.271901362754;452.41638286609907;454.22781271838005;467.8596421073664;3747.868824591126;3833.616638336144;3840.036695235119;3872.670822980022;3908.022994251437;10013.148882344936;10473.685789426178;10590.841137382946;10721.41032243234;10722.03501684852;10560.155187584975;10569.735756605993;10590.993855583707;10672.359658420519;10699.572017119384;7523;7932.643031063616;8029.678812847421;8063.919360806317;8073.83852322962;5694.515966142759;5898.882022359443;5944.524438045032;5951.821445356646;5955.106733989936;9167.862482062641;9362.765930851783;9709.563069662023;9840.212782977487;9858.55636496345;8906.332025098014;9629.085236902483;9769.120779129913;9819.9018009819;9907.950460247652;6779.084823548955;7261.745838895613;7529.134149572869;7534.510256833289;7539.547627142389;7978.84042319162;8019.558924258989;8032.357411406956;8067.273945344947;8092.635831387719;7330.560166390033;7339.522931009467;7434;7492.550446973199;7537.095548533992;7106.182788979334;7122.6438678066925;7340.265973402617;7361;7396.371308438628;6872.381485666314;6893.276205998298;6980.650967451708;6981.127359080115;6995.405390541861;4195.538490765953;4595.977020114878;4751.311059896227;4818.180909245367;4936.901261974668;4851.636127290397;4881.096997055556;5002.299678045149;5020.046191223656;5162.993216322781 18:21:57 INFO - PROCESS | 2778 | |4;prototype.html;303.71450836213955;316.11645450964556;322.34563835414116;324.99512507312386;325.0233049685694;4351.369051487536;4438.489573699021;4685.578297953192;5143.3827940647125;5156.303898973638;4350.58669426404;4437.600615944564;5033.672811267265;5300.363956325236;5311.893762124765;4089.6912987843752;4090.1206240658325;4101.856435024774;4122.340425531916;4144.109016561439;3183.7265093962483;3541.433370660695;4098.774567398792;4105.445764821756;4142.2543942090415;3584.4264917613186;4016.7748836604824;4056.2495938251373;4058.127502586943;4093.8771836844794;3549.733769967237;4011.438398624195;4035.535913369956;4060.2285565742413;4097.97951010246;2932.648082230146;3557.3596752584526;3565.5008298837924;3565.7682250653884;3574.4638304254568;4660.1145782301255;4929.531694489017;5085.516875896784;5124.975634624302;5185.714785686786;4317.438732964726;4653.7440440775745;4685.508021657711;4687.359424201502;4687.484376718558;5286.312779338661;5510.283663123768;5537.723113844291;5568.777248910039;5589.385167631557;3905.3946638271113;3924.6271604197555;3935.5277366716177;3945.559870647222;3945.763254204757;5143.3570803649545;5694.430556944314;5770.249867517238;5843.737031833578;5865.824025279248;3540.451230059345;3983.900402489903;3996.9200615987515;4005.839766409311;4006.939895901564;3820.9617903821163;4056.7363121397016;4067.349224124126;4078.469798926121;4091.529474110456;4463.750149957952;4618.630509559227;4634.490206077328;4686.133065382915;4702.858914232578;4522.819087236474;5237.921431178535;5321.866953326198;5368.8657783554945;5599.748011339418;1219.762146381447;1308.7959077648586;1484.317214081513;1486.1603194195245;1486.7472529669983;991.3556188477547;992.5905214595292;1002.2132625888643;1014.8325526288228;1016.7712264740522;5562.3047119110115;5588.3176075484;5598.1602759586385;5622;5668.858278543069;5409.134538473825;5915.254999775077;6140.631562106288;6150.846228844314;6185.051708711741;4689;4962.478939711351;5321.642981039888;5562.332520097533;5592.384837667853;2809.2976755811046;3148.795328303653;3290.5064240364136;3336.3160552086765;3338.981610608788;5528.75120619573;5728.684922329312;6020.969895150583;6272.09054687077;6308.148399966038;5645.407232240637;6208.255009398901;6260.311365749764;6386.521010924199;6463.385978332051;5560.221568980265;5736.713164341682;6429.549931504844;6503.837404064936;6540.705668245036;4813.350197723263;5051.928709229199;6239.563230573816;6278.811635650938;6282.717277722422;5504.284443022381;6182.938170618237;6185.195924529782;6192.752289908353;6210.7515699373325;6189.535784816247;6513.8697226056165;6544;6548.345165483413;6557.606543607399;4101.405296232089;4106.753594784323;4130.855420060284;4131.566185550594;4144.254034273739;4738.431388233434;5437.211604317431;5649.378568357395;6143.262808463013;6294.370562943669;4464.866054018385;5361.954418888279;5459.699716515471;5463.153211252262;5673.943260567342;5181.844544663666;5529.917051244154;5536.418676039119;5578.601201483401;5622.493975542221;4718.056388722201;4968.503149685147;5019.7490125494305;5292.814751483679;5483.4516548344845;5037.118504261813;5164.664296820694;5362.0481265795715;5597.313289621086;5611.579131565068;4538.659600529907;4615.884602884955;5462.371827239918;5465.672059676433;5492 18:21:57 INFO - PROCESS | 2778 | |5;yui.html;630.8990561510159;732.4177279063144;758.6396461680699;759.0739298056372;769.242296338107;12271.44778484968;14644.341004654789;14658.560243192707;14875.628109297273;14899.40402383906;11733.002694770954;13631.522896698618;13674.042817002814;13739.450421983109;13785.172889626605;669.9966500167517;711.8576284743046;763.9571984241526;895.6506962284714;944.7496413450441;759.1232126893447;762.6834863531627;767.1637914673005;909.0500427053712;942.627662073484;1003.6888564545014;1012.691129205592;1013.5337744637476;1015.6749840051186;1019.8572199892021;1006.3157053203855;1006.7986402719449;1006.9697909062739;1008.1128606825946;1010.4240582867768;574.8821491594213;706.9257727938544;740.311510295425;746.3655892491394;748.2666986353349;14842.183679897602;14896;14910.850891491054;14913.776293355608;14944.40222391103;12436.1345798131;12492.437840297209;12577.811332829924;12597.859799942167;12982.740345193044;9648.807023859554;9797.755056123582;9816.41101533903;10103.444310562916;10597;3386.255023894739;3395.0833275015852;3440.139965008748;3461;3493.9475907861147;78434;78604.21395786027;78667;78686;78864.60567697183;9156.359054866229;9488.42838294953;9536.995846308391;9621.177362404978;9622.51887405627;21192.36422907315;21250.681239781417;21518;21518.246861359778;21555.245566405414;7529.916234543366;7570.242975702386;7570.545767253982;7576.8484630308185;7690.2694244046725;3151.68483151683;3828.062124779447;3864.5362556492837;3917.5886531914307;4084.7957602119777;3576.088097535112;3636.509071275344;3654.798986055739;3678.2459595782802;3679.0066681996245;645.6158585641535;649.870025994803;664.5912763650321;667.8497338098986;674.2482132422411;514.4366918224538;524.9816256431006;534.2333751067231;561;564.5511818104617;7508.911207874937;8062.6464544953305;8230.053543842416;8261;8497.235248827636;500.5395036566368;550.931133608299;585.6105689716376;603.6830663901399;614.183136428549;3808.124131449782;3822.6604918556436;3891.610838916086;3923.1761330120994;4090.9590904091174;94186.5290673556;94661;96258;97691.02308976962;97914;80329;80401;80426.83292368558;80524.98687532858;81631.5918420404;70661.64669176622;72566;72579;72724.27275727176;73087.26912730804;6580.638064906476;7638.579878106758;7851.293383595504;7854.607269636381;7874.527528348317;35385.64614353824;42823.7858810704;43126.78436607797;43268.35097473478;45296.09407811891;61139;66815.33184668285;67155.3284467149;67568.66215668891;67797.3220267791;10224;10339.379637221791;11623.476943537393;11651.825222621497;11692.70768230767;11717.414129293331;11777.116716246144;11820.113491488;11901.166918315635;12896.935515322364;11114.555417783198;11124.888751112385;11284.717882053013;11295.209335346775;12401.875981240433;11154.498047587716;11162.553497859995;11187.944060279646;11190.32858028521;11193.160512961398;11043.72390690207;11101.500432480396;11265.380404077527;11550;12269.079819013788;9400.294977876823;9518.95240523793;9600.589213022304;9692.079252471285;9768.951155244178;9280.303977201871;9424;9434.29242806806;9526.380785248937;9692.903070969482 18:21:57 INFO - PROCESS | 2778 | __end_tp_report 18:21:57 INFO - PROCESS | 2778 | __start_cc_report 18:21:57 INFO - PROCESS | 2778 | _x_x_mozilla_cycle_collect,1155 18:21:57 INFO - PROCESS | 2778 | __end_cc_report 18:21:57 INFO - PROCESS | 2778 | __startTimestamp1461892917664__endTimestamp 18:21:57 INFO - PROCESS | 2778 | 18:21:57 INFO - PROCESS | 2778 | ------- Summary: start ------- 18:21:57 INFO - PROCESS | 2778 | Number of tests: 6 18:21:57 INFO - PROCESS | 2778 | 18:21:57 INFO - PROCESS | 2778 | [#0] dojo.html Cycles:165 Average:5567.25 Median:5780.05 stddev:651.30 (11.3%) stddev-sans-first:628.06 18:21:57 INFO - PROCESS | 2778 | Values: 3278.5 3333.4 3342.8 3366.1 3405.6 5476.6 5546.7 5565.6 5608.2 5619.2 4704.5 5525.9 5567.5 5606.4 5670.1 5023.5 5058.7 5088.5 5107.8 5195.9 5143.3 5152.8 5171.0 5195.3 5243.5 5037.4 5075.6 5132.7 5137.7 5141.5 5098.5 5102.1 5110.0 5158.5 5192.3 5075.3 5088.2 5115.8 5124.9 5142.3 4282.1 5207.8 5536.8 5537.6 5537.7 6042.3 6077.7 6105.3 6115.0 6120.9 6119.1 6197.8 6199.0 6207.0 6222.9 5534.6 5636.3 5713.6 5715.1 5771.9 6151.0 6247.1 6265.8 6278.9 6281.5 5970.6 5976.7 5980.9 5985.9 5997.6 5817.4 6017.1 6101.7 6115.2 6126.9 5886.4 5920.5 5922.6 5924.0 5933.4 5879.3 5919.2 5942.4 5944.6 5950.5 4140.2 4750.9 4761.3 4767.0 4779.4 4183.7 4286.5 4295.7 4375.1 4397.3 5534.8 5538.1 5572.6 5580.7 5589.7 6118.4 6123.6 6142.9 6160.8 6163.6 5394.1 5459.9 5470.0 5470.3 5486.1 4754.0 4915.4 5007.0 5238.8 5250.0 6132.4 6268.7 6281.3 6281.9 6328.4 4924.3 6097.6 6106.9 6138.1 6153.7 6214.5 6268.9 6303.5 6328.6 6330.2 5036.4 5884.6 5973.3 5975.6 5980.0 5987.2 6078.1 6078.5 6081.5 6089.0 5946.4 5954.0 5972.0 5985.9 6051.9 5949.9 5950.6 5955.0 5969.8 5970.2 5581.8 5722.3 5787.7 5815.8 5821.5 4501.7 5848.9 5891.6 5906.0 5938.7 5771.9 5775.2 5780.0 5782.1 5786.1 18:21:57 INFO - PROCESS | 2778 | 18:21:57 INFO - PROCESS | 2778 | [#1] ext.html Cycles:180 Average:64355.02 Median:33594.75 stddev:137795.56 (410.2%) stddev-sans-first:138098.76 18:21:57 INFO - PROCESS | 2778 | Values: 519.8 525.0 553.7 621.3 655.8 25093.6 28896.0 37843.6 38730.0 38783.8 42416.0 43104.8 43344.0 43360.8 43559.0 9334.5 9554.4 9585.4 9587.1 9592.7 8243.5 8459.2 9184.7 11202.2 11217.7 11144.9 11310.5 11324.0 11333.6 11353.3 8266.2 11165.1 11303.4 11308.6 11322.8 10919.9 11197.6 11206.8 11215.4 11234.4 38023.4 38415.0 38433.8 38523.5 38632.4 3233.6 3285.8 3319.8 3773.8 4054.6 27201.5 27941.6 28003.4 29043.0 29082.3 2732.5 2803.0 2810.6 2815.2 2823.4 470063.0 472798.0 473368.6 474827.0 475782.0 48083.5 55198.0 59088.4 59602.0 59703.7 56236.4 56298.2 56580.0 56713.7 56744.0 16100.6 16297.3 16306.1 16347.0 16348.3 11537.5 11775.4 12511.8 12841.0 12919.9 15043.3 15306.2 15449.5 15491.1 15496.3 7067.7 7178.1 7181.7 7222.4 7284.2 970.7 1018.4 1025.4 1029.9 1117.7 27051.7 29272.3 29601.3 29785.0 30139.7 1052.0 1076.1 1124.5 1125.0 1194.4 13132.5 14952.3 15116.7 15168.5 15468.8 607754.0 621782.0 627770.0 627804.0 630223.0 60105.7 60388.7 60579.4 60586.7 61439.1 1433.7 1462.5 1507.1 1512.7 1512.9 24274.6 27102.0 28438.9 29595.9 29884.3 48425.8 48558.5 48565.8 48582.8 48623.0 420148.0 433756.0 435004.0 437259.0 438570.0 31323.8 31480.7 31833.8 31875.8 37261.0 33716.7 39070.4 42484.0 43218.8 44946.0 33472.8 35727.6 36203.8 36328.6 37887.8 30393.0 34951.5 36036.7 36054.0 37237.8 34326.7 35046.5 35871.6 35919.0 36024.5 30283.0 31872.2 32842.7 32903.3 33009.0 23635.0 23636.4 24070.8 24247.0 24319.7 18:21:57 INFO - PROCESS | 2778 | 18:21:57 INFO - PROCESS | 2778 | [#2] jquery.html Cycles:180 Average:34304.49 Median:1893.36 stddev:98141.25 (5183.5%) stddev-sans-first:98383.54 18:21:57 INFO - PROCESS | 2778 | Values: 398.6 400.6 401.5 401.6 410.5 14140.4 19664.8 20544.5 20597.4 20628.8 15617.5 17226.7 19626.7 20334.6 20460.9 5883.8 6049.0 6095.7 6098.2 6117.1 5953.7 5963.6 5969.3 5979.6 6069.5 5881.3 5911.2 5922.1 5924.3 5926.0 5824.8 5957.6 6062.1 6064.9 6067.7 5088.3 5247.5 5267.7 5274.4 5279.2 23675.9 24923.5 26022.7 26170.7 26228.3 2025.9 2209.9 2257.2 2612.5 2845.4 11712.9 14456.4 14653.9 14956.8 15292.2 1738.7 1752.9 1781.2 1831.5 1849.8 100732.0 103109.0 104178.0 104331.5 106925.4 10633.9 10812.8 11115.1 11167.9 11278.8 12594.9 13709.6 14254.4 14263.9 14296.1 7849.8 7935.6 8082.2 8755.0 8939.4 8782.0 8802.3 8820.3 8920.6 8959.2 6583.6 6672.8 6697.3 6706.8 6713.5 1660.4 1881.5 1905.2 1916.9 1950.4 898.3 971.5 1015.4 1042.5 1100.2 20389.5 21340.5 21366.4 21401.4 21435.8 975.9 1013.5 1020.8 1111.5 1115.3 10811.6 11140.8 11525.4 11560.7 11738.4 563647.0 581231.3 601871.9 601923.0 602948.0 112685.9 115811.0 126762.4 135953.0 140232.3 1015.4 1018.7 1048.8 1065.6 1072.2 17767.1 18117.2 18173.9 18341.4 18765.6 43915.7 45241.0 51351.7 54645.5 55297.7 54670.0 71183.0 73356.0 75086.2 77163.6 9987.7 10531.4 11832.2 11944.3 12054.2 11792.9 12425.8 12497.4 12531.6 12550.2 10554.5 10791.8 10858.3 11022.2 11224.9 10269.5 10489.2 10621.4 10695.4 10800.0 10836.5 10904.8 10963.9 11058.1 11539.4 11408.3 11485.6 11634.7 11667.9 11687.9 10640.4 10830.6 10880.6 10892.3 10893.9 18:21:57 INFO - PROCESS | 2778 | 18:21:57 INFO - PROCESS | 2778 | [#3] mootools.html Cycles:180 Average:5643.24 Median:5016.86 stddev:3128.08 (62.4%) stddev-sans-first:3120.85 18:21:57 INFO - PROCESS | 2778 | Values: 1432.4 1605.3 1626.7 1851.9 1970.5 7589.1 8548.2 8743.9 8750.8 8795.2 8339.2 8547.7 8555.0 8564.5 8573.7 2144.9 2222.5 2224.7 2419.3 2467.1 2201.6 2426.9 2500.5 2500.5 2503.9 2411.1 2442.6 2448.2 2454.7 2455.0 2119.0 2394.6 2418.0 2428.4 2436.3 2398.0 2461.6 2474.3 2474.8 2478.4 8529.1 8591.7 8592.9 8600.5 8614.4 5229.0 5440.5 5454.3 5469.5 5471.5 3460.3 3572.4 3665.7 3674.9 3679.6 742.6 908.3 1022.7 1041.0 1069.6 11474.3 11485.2 11498.9 11514.0 11541.1 4833.7 4851.0 4881.0 4954.0 5013.7 9926.6 9940.2 9940.4 9941.0 9965.7 5441.4 5481.2 5491.7 5492.5 5503.6 4837.5 4873.6 4927.3 4976.9 4999.7 4015.8 4016.5 4067.5 4095.3 4132.5 1373.5 1379.4 1380.9 1385.9 1394.4 798.4 819.9 895.5 925.2 938.4 5517.2 5545.6 5746.6 5926.8 6086.6 419.7 441.3 452.4 454.2 467.9 3747.9 3833.6 3840.0 3872.7 3908.0 10013.1 10473.7 10590.8 10721.4 10722.0 10560.2 10569.7 10591.0 10672.4 10699.6 7523.0 7932.6 8029.7 8063.9 8073.8 5694.5 5898.9 5944.5 5951.8 5955.1 9167.9 9362.8 9709.6 9840.2 9858.6 8906.3 9629.1 9769.1 9819.9 9908.0 6779.1 7261.7 7529.1 7534.5 7539.5 7978.8 8019.6 8032.4 8067.3 8092.6 7330.6 7339.5 7434.0 7492.6 7537.1 7106.2 7122.6 7340.3 7361.0 7396.4 6872.4 6893.3 6980.7 6981.1 6995.4 4195.5 4596.0 4751.3 4818.2 4936.9 4851.6 4881.1 5002.3 5020.0 5163.0 18:21:57 INFO - PROCESS | 2778 | 18:21:57 INFO - PROCESS | 2778 | [#4] prototype.html Cycles:180 Average:4632.43 Median:5026.71 stddev:1436.75 (28.6%) stddev-sans-first:1403.56 18:21:57 INFO - PROCESS | 2778 | Values: 303.7 316.1 322.3 325.0 325.0 4351.4 4438.5 4685.6 5143.4 5156.3 4350.6 4437.6 5033.7 5300.4 5311.9 4089.7 4090.1 4101.9 4122.3 4144.1 3183.7 3541.4 4098.8 4105.4 4142.3 3584.4 4016.8 4056.2 4058.1 4093.9 3549.7 4011.4 4035.5 4060.2 4098.0 2932.6 3557.4 3565.5 3565.8 3574.5 4660.1 4929.5 5085.5 5125.0 5185.7 4317.4 4653.7 4685.5 4687.4 4687.5 5286.3 5510.3 5537.7 5568.8 5589.4 3905.4 3924.6 3935.5 3945.6 3945.8 5143.4 5694.4 5770.2 5843.7 5865.8 3540.5 3983.9 3996.9 4005.8 4006.9 3821.0 4056.7 4067.3 4078.5 4091.5 4463.8 4618.6 4634.5 4686.1 4702.9 4522.8 5237.9 5321.9 5368.9 5599.7 1219.8 1308.8 1484.3 1486.2 1486.7 991.4 992.6 1002.2 1014.8 1016.8 5562.3 5588.3 5598.2 5622.0 5668.9 5409.1 5915.3 6140.6 6150.8 6185.1 4689.0 4962.5 5321.6 5562.3 5592.4 2809.3 3148.8 3290.5 3336.3 3339.0 5528.8 5728.7 6021.0 6272.1 6308.1 5645.4 6208.3 6260.3 6386.5 6463.4 5560.2 5736.7 6429.5 6503.8 6540.7 4813.4 5051.9 6239.6 6278.8 6282.7 5504.3 6182.9 6185.2 6192.8 6210.8 6189.5 6513.9 6544.0 6548.3 6557.6 4101.4 4106.8 4130.9 4131.6 4144.3 4738.4 5437.2 5649.4 6143.3 6294.4 4464.9 5362.0 5459.7 5463.2 5673.9 5181.8 5529.9 5536.4 5578.6 5622.5 4718.1 4968.5 5019.7 5292.8 5483.5 5037.1 5164.7 5362.0 5597.3 5611.6 4538.7 4615.9 5462.4 5465.7 5492.0 18:21:57 INFO - PROCESS | 2778 | 18:21:57 INFO - PROCESS | 2778 | [#5] yui.html Cycles:180 Average:18138.13 Median:542.58 stddev:25873.38 (4768.6%) stddev-sans-first:25912.57 18:21:57 INFO - PROCESS | 2778 | Values: 630.9 732.4 758.6 759.1 769.2 12271.4 14644.3 14658.6 14875.6 14899.4 11733.0 13631.5 13674.0 13739.5 13785.2 670.0 711.9 764.0 895.7 944.7 759.1 762.7 767.2 909.1 942.6 1003.7 1012.7 1013.5 1015.7 1019.9 1006.3 1006.8 1007.0 1008.1 1010.4 574.9 706.9 740.3 746.4 748.3 14842.2 14896.0 14910.9 14913.8 14944.4 12436.1 12492.4 12577.8 12597.9 12982.7 9648.8 9797.8 9816.4 10103.4 10597.0 3386.3 3395.1 3440.1 3461.0 3493.9 78434.0 78604.2 78667.0 78686.0 78864.6 9156.4 9488.4 9537.0 9621.2 9622.5 21192.4 21250.7 21518.0 21518.2 21555.2 7529.9 7570.2 7570.5 7576.8 7690.3 3151.7 3828.1 3864.5 3917.6 4084.8 3576.1 3636.5 3654.8 3678.2 3679.0 645.6 649.9 664.6 667.8 674.2 514.4 525.0 534.2 561.0 564.6 7508.9 8062.6 8230.1 8261.0 8497.2 500.5 550.9 585.6 603.7 614.2 3808.1 3822.7 3891.6 3923.2 4091.0 94186.5 94661.0 96258.0 97691.0 97914.0 80329.0 80401.0 80426.8 80525.0 81631.6 70661.6 72566.0 72579.0 72724.3 73087.3 6580.6 7638.6 7851.3 7854.6 7874.5 35385.6 42823.8 43126.8 43268.4 45296.1 61139.0 66815.3 67155.3 67568.7 67797.3 10224.0 10339.4 11623.5 11651.8 11692.7 11717.4 11777.1 11820.1 11901.2 12896.9 11114.6 11124.9 11284.7 11295.2 12401.9 11154.5 11162.6 11187.9 11190.3 11193.2 11043.7 11101.5 11265.4 11550.0 12269.1 9400.3 9519.0 9600.6 9692.1 9769.0 9280.3 9424.0 9434.3 9526.4 9692.9 18:21:57 INFO - PROCESS | 2778 | -------- Summary: end -------- 18:21:57 INFO - PROCESS | 2778 | 18:21:58 INFO - TEST-INFO | 2778: exit 0 18:21:58 INFO - TEST-OK | dromaeo_css | took 1115649ms 18:21:58 INFO - TEST-START | kraken 18:21:58 INFO - operating with platform_type : mac_ 18:21:58 INFO - Initialising browser for kraken test... 18:21:58 INFO - TEST-INFO | started process 2781 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpgEhpil/profile http://localhost:61754/getInfo.html) 18:22:00 INFO - PROCESS | 2781 | __metrics Screen width/height:1600/1200 18:22:00 INFO - PROCESS | 2781 | colorDepth:24 18:22:00 INFO - PROCESS | 2781 | Browser inner width/height: 1024/689 18:22:00 INFO - PROCESS | 2781 | __metrics 18:22:00 INFO - PROCESS | 2781 | 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" 18:22:00 ERROR - PROCESS | 2781 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:22:01 INFO - TEST-INFO | 2781: exit 0 18:22:01 INFO - Browser initialized. 18:22:01 INFO - Running cycle 1/1 for kraken test... 18:22:01 INFO - TEST-INFO | started process 2783 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpgEhpil/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:22:13 INFO - PROCESS | 2783 | RSS: Main: 239812608 18:22:13 INFO - PROCESS | 2783 | 18:22:16 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61754/tests/kraken/driver.html?testName=audio-beat-detection) 18:22:17 INFO - PROCESS | 2783 | RSS: Main: 287412224 18:22:17 INFO - PROCESS | 2783 | 18:22:19 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61754/tests/kraken/driver.html?testName=audio-dft) 18:22:20 INFO - PROCESS | 2783 | RSS: Main: 296046592 18:22:20 INFO - PROCESS | 2783 | 18:22:23 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61754/tests/kraken/driver.html?testName=audio-fft) 18:22:24 INFO - PROCESS | 2783 | RSS: Main: 381288448 18:22:24 INFO - PROCESS | 2783 | 18:22:26 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61754/tests/kraken/driver.html?testName=audio-oscillator) 18:22:27 INFO - PROCESS | 2783 | RSS: Main: 280342528 18:22:27 INFO - PROCESS | 2783 | 18:22:29 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61754/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:22:29 INFO - PROCESS | 2783 | RSS: Main: 284168192 18:22:29 INFO - PROCESS | 2783 | 18:22:32 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61754/tests/kraken/driver.html?testName=imaging-darkroom) 18:22:33 INFO - PROCESS | 2783 | RSS: Main: 322068480 18:22:33 INFO - PROCESS | 2783 | 18:22:35 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61754/tests/kraken/driver.html?testName=imaging-desaturate) 18:22:36 INFO - PROCESS | 2783 | RSS: Main: 322772992 18:22:36 INFO - PROCESS | 2783 | 18:22:39 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61754/tests/kraken/driver.html?testName=json-parse-financial) 18:22:40 INFO - PROCESS | 2783 | RSS: Main: 319594496 18:22:40 INFO - PROCESS | 2783 | 18:22:41 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61754/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:22:42 INFO - PROCESS | 2783 | RSS: Main: 313491456 18:22:42 INFO - PROCESS | 2783 | 18:22:44 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61754/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:22:44 INFO - PROCESS | 2783 | RSS: Main: 320913408 18:22:44 INFO - PROCESS | 2783 | 18:22:46 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61754/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:22:47 INFO - PROCESS | 2783 | RSS: Main: 322297856 18:22:47 INFO - PROCESS | 2783 | 18:22:50 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61754/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:22:51 INFO - PROCESS | 2783 | RSS: Main: 324612096 18:22:51 INFO - PROCESS | 2783 | 18:22:54 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61754/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:22:54 INFO - PROCESS | 2783 | RSS: Main: 321019904 18:22:54 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | Cycle 1(1): loaded http://localhost:61754/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61754/tests/kraken/driver.html?testName=ai-astar) 18:22:56 INFO - PROCESS | 2783 | RSS: Main: 386215936 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | __start_tp_report 18:22:56 INFO - PROCESS | 2783 | _x_x_mozilla_page_load 18:22:56 INFO - PROCESS | 2783 | _x_x_mozilla_page_load_details 18:22:56 INFO - PROCESS | 2783 | |i|pagename|runs| 18:22:56 INFO - PROCESS | 2783 | |0;ai-astar;111;113;110;112;112;116;114;116;106;155 18:22:56 INFO - PROCESS | 2783 | |1;audio-beat-detection;160;126;144;171;122;155;177;124;172;122 18:22:56 INFO - PROCESS | 2783 | |2;audio-dft;185;187;185;185;189;187;184;174;183;187 18:22:56 INFO - PROCESS | 2783 | |3;audio-fft;78;74;91;93;56;58;59;81;86;55 18:22:56 INFO - PROCESS | 2783 | |4;audio-oscillator;92;91;138;77;80;80;121;78;76;127 18:22:56 INFO - PROCESS | 2783 | |5;imaging-gaussian-blur;87;84;87;84;88;83;88;83;87;84 18:22:56 INFO - PROCESS | 2783 | |6;imaging-darkroom;93;93;94;93;92;92;93;93;92;95 18:22:56 INFO - PROCESS | 2783 | |7;imaging-desaturate;94;133;140;85;85;86;85;86;84;85 18:22:56 INFO - PROCESS | 2783 | |8;json-parse-financial;60;62;62;59;58;58;60;76;61;58 18:22:56 INFO - PROCESS | 2783 | |9;json-stringify-tinderbox;63;65;77;74;70;69;68;68;67;69 18:22:56 INFO - PROCESS | 2783 | |10;stanford-crypto-aes;85;80;83;83;80;75;81;69;96;103 18:22:56 INFO - PROCESS | 2783 | |11;stanford-crypto-ccm;137;137;138;134;135;146;128;146;242;160 18:22:56 INFO - PROCESS | 2783 | |12;stanford-crypto-pbkdf2;169;170;172;170;179;171;168;167;171;166 18:22:56 INFO - PROCESS | 2783 | |13;stanford-crypto-sha256-iterative;68;70;70;68;68;67;68;69;65;67 18:22:56 INFO - PROCESS | 2783 | __end_tp_report 18:22:56 INFO - PROCESS | 2783 | __start_cc_report 18:22:56 INFO - PROCESS | 2783 | _x_x_mozilla_cycle_collect,2031 18:22:56 INFO - PROCESS | 2783 | __end_cc_report 18:22:56 INFO - PROCESS | 2783 | __startTimestamp1461892976727__endTimestamp 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | ------- Summary: start ------- 18:22:56 INFO - PROCESS | 2783 | Number of tests: 14 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#0] ai-astar Cycles:10 Average:116.50 Median:113.50 stddev:13.84 (12.2%) stddev-sans-first:14.54 18:22:56 INFO - PROCESS | 2783 | Values: 111.0 113.0 110.0 112.0 112.0 116.0 114.0 116.0 106.0 155.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#1] audio-beat-detection Cycles:10 Average:147.30 Median:157.50 stddev:22.51 (14.3%) stddev-sans-first:23.41 18:22:56 INFO - PROCESS | 2783 | Values: 160.0 126.0 144.0 171.0 122.0 155.0 177.0 124.0 172.0 122.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#2] audio-dft Cycles:10 Average:184.60 Median:186.00 stddev:4.12 (2.2%) stddev-sans-first:4.36 18:22:56 INFO - PROCESS | 2783 | Values: 185.0 187.0 185.0 185.0 189.0 187.0 184.0 174.0 183.0 187.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#3] audio-fft Cycles:10 Average:73.10 Median:79.50 stddev:14.97 (18.8%) stddev-sans-first:15.77 18:22:56 INFO - PROCESS | 2783 | Values: 78.0 74.0 91.0 93.0 56.0 58.0 59.0 81.0 86.0 55.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:79.00 stddev:23.54 (29.8%) stddev-sans-first:24.93 18:22:56 INFO - PROCESS | 2783 | Values: 92.0 91.0 138.0 77.0 80.0 80.0 121.0 78.0 76.0 127.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:87.00 stddev:2.07 (2.4%) stddev-sans-first:2.12 18:22:56 INFO - PROCESS | 2783 | Values: 87.0 84.0 87.0 84.0 88.0 83.0 88.0 83.0 87.0 84.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.94 (1.0%) stddev-sans-first:1.00 18:22:56 INFO - PROCESS | 2783 | Values: 93.0 93.0 94.0 93.0 92.0 92.0 93.0 93.0 92.0 95.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:85.00 stddev:21.44 (25.2%) stddev-sans-first:22.72 18:22:56 INFO - PROCESS | 2783 | Values: 94.0 133.0 140.0 85.0 85.0 86.0 85.0 86.0 84.0 85.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:60.50 stddev:5.36 (8.9%) stddev-sans-first:5.66 18:22:56 INFO - PROCESS | 2783 | Values: 60.0 62.0 62.0 59.0 58.0 58.0 60.0 76.0 61.0 58.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:69.00 stddev:4.06 (5.9%) stddev-sans-first:3.67 18:22:56 INFO - PROCESS | 2783 | Values: 63.0 65.0 77.0 74.0 70.0 69.0 68.0 68.0 67.0 69.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:82.00 stddev:9.73 (11.9%) stddev-sans-first:10.31 18:22:56 INFO - PROCESS | 2783 | Values: 85.0 80.0 83.0 83.0 80.0 75.0 81.0 69.0 96.0 103.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:142.00 stddev:33.40 (23.5%) stddev-sans-first:35.08 18:22:56 INFO - PROCESS | 2783 | Values: 137.0 137.0 138.0 134.0 135.0 146.0 128.0 146.0 242.0 160.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.30 Median:170.50 stddev:3.59 (2.1%) stddev-sans-first:3.78 18:22:56 INFO - PROCESS | 2783 | Values: 169.0 170.0 172.0 170.0 179.0 171.0 168.0 167.0 171.0 166.0 18:22:56 INFO - PROCESS | 2783 | 18:22:56 INFO - PROCESS | 2783 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.49 (2.2%) stddev-sans-first:1.58 18:22:56 INFO - PROCESS | 2783 | Values: 68.0 70.0 70.0 68.0 68.0 67.0 68.0 69.0 65.0 67.0 18:22:56 INFO - PROCESS | 2783 | -------- Summary: end -------- 18:22:56 INFO - PROCESS | 2783 | 18:22:57 INFO - TEST-INFO | 2783: exit 0 18:22:57 INFO - TEST-OK | kraken | took 59221ms 18:22:57 INFO - SUITE-END | took 1174s 18:22:57 INFO - Completed test suite (00:19:35) 18:22:57 INFO - javascript benchmark 18:22:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3278.541004259404, 3333.399988002159, 3342.766006379554, 3366.141633883359, 3405.6424075472073, 5476.561875049996, 5546.69493177875, 5565.554755619546, 5608.214849921009, 5619.1852181433715, 4704.482506924235, 5525.944740552593, 5567.47109024642, 5606.383297837235, 5670.121131224657, 5023.522765337287, 5058.747062646871, 5088.465711100337, 5107.795688172488, 5195.9480405195845, 5143.254228136918, 5152.768125434364, 5171.043356978952, 5195.324607801, 5243.475652434745, 5037.395512538482, 5075.593952483792, 5132.717700526469, 5137.691738495683, 5141.53726164647, 5098.515641014098, 5102.132637451642, 5110.0, 5158.484151584849, 5192.299039629639, 5075.264086707407, 5088.23676448532, 5115.8209462669, 5124.897502049976, 5142.280080788692, 4282.100758840647, 5207.765650545734, 5536.778528858841, 5537.640053396557, 5537.667739935617, 6042.335343112254, 6077.662914158878, 6105.267367915834, 6114.996965385497, 6120.938790612081, 6119.143319935212, 6197.845053873644, 6198.969005154945, 6207.006878899399, 6222.937770622281, 5534.557235421156, 5636.2954630671165, 5713.628614140068, 5715.142728590745, 5771.942280577183, 6151.0, 6247.125402443661, 6265.8120256392385, 6278.905816412757, 6281.466075383643, 5970.611910225822, 5976.73104710289, 5980.940190598125, 5985.910211346833, 5997.610155339889, 5817.38917413674, 6017.097435384728, 6101.725422355916, 6115.174451448997, 6126.877462450726, 5886.411358864079, 5920.526357891358, 5922.555808314393, 5924.022536281552, 5933.406659334032, 5879.323877754027, 5919.230500034968, 5942.435468630473, 5944.613600115978, 5950.464458198783, 4140.15126898985, 4750.881227969259, 4761.262004389325, 4767.0, 4779.354787103766, 4183.748975061506, 4286.464191976003, 4295.74225546462, 4375.1031038637, 4397.252467080604, 5534.833954981357, 5538.113901775697, 5572.58205634579, 5580.720963951786, 5589.692566908778, 6118.388161183846, 6123.571350005457, 6142.938570614237, 6160.753569857246, 6163.568550201443, 5394.083005889008, 5459.863503412367, 5469.972650136724, 5470.343558772893, 5486.067368547357, 4754.0016596515115, 4915.43472500667, 5007.023630392038, 5238.7904483820985, 5249.973750131225, 6132.386761323832, 6268.655223962726, 6281.277653069864, 6281.937180628318, 6328.430441260309, 4924.335214745964, 6097.5731698780655, 6106.877862442638, 6138.140660307471, 6153.661548614869, 6214.533909956862, 6268.937310626835, 6303.527235457267, 6328.6202827830475, 6330.208723909512, 5036.420811606712, 5884.558658100746, 5973.342932277359, 5975.551833612583, 5980.0431930890845, 5987.191729116514, 6078.057901025348, 6078.45293923549, 6081.452669259788, 6089.0, 5946.43508866657, 5954.0, 5972.014617588049, 5985.910211346921, 6051.878962420815, 5949.91075133882, 5950.553708471968, 5955.04719244936, 5969.820905372846, 5970.223870896755, 5581.804636837691, 5722.313322401338, 5787.681677507778, 5815.79644712433, 5821.534277257724, 4501.707389019703, 5848.941510584839, 5891.587588868738, 5906.0, 5938.703064846827, 5771.8557036074435, 5775.1914731938405, 5780.046292361882, 5782.10377391505, 5786.132080188006], "value": 5527.007421945328, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.7894852584702, 524.9658173398406, 553.6760994818031, 621.3382747374047, 655.84915469442, 25093.62359564608, 28896.0, 37843.621563784385, 38730.03174920628, 38783.83648490543, 42416.0, 43104.78447607766, 43344.0, 43360.783196084056, 43559.0, 9334.486603236819, 9554.378965367228, 9585.424874507551, 9587.089226523469, 9592.664256751015, 8243.464174828618, 8459.154084591553, 9184.678536251233, 11202.215844890861, 11217.66347009591, 11144.888551114465, 11310.547578096866, 11324.0, 11333.603323883666, 11353.262037967506, 8266.214709602638, 11165.050970667504, 11303.434828258554, 11308.604198853041, 11322.773544529064, 10919.945400273027, 11197.552097916074, 11206.775864482664, 11215.439228038565, 11234.438278086063, 38023.42964855529, 38415.03962400935, 38433.80783096095, 38523.4590616375, 38632.42051369232, 3233.5634689316876, 3285.83570821458, 3319.8008119512906, 3773.811309434545, 4054.553999060101, 27201.45597088047, 27941.580876286873, 28003.439931201465, 29043.0, 29082.27294317638, 2732.53546897028, 2803.0, 2810.634617499712, 2815.211740712604, 2823.392970511329, 470063.0, 472798.0, 473368.63315683545, 474827.0, 475782.0, 48083.519164807905, 55198.0, 59088.40911590829, 59602.0, 59703.70148149232, 56236.43763562394, 56298.15552766712, 56580.0, 56713.716431418405, 56744.0, 16100.597485062734, 16297.34810607586, 16306.103164326076, 16347.019178849307, 16348.34606615722, 11537.48081336342, 11775.352355620353, 12511.812322815165, 12841.036922230871, 12919.870801292054, 15043.323050462755, 15306.158161301235, 15449.459268925533, 15491.147986860606, 15496.30266638004, 7067.681954312068, 7178.102737157856, 7181.712731490787, 7222.422206223595, 7284.198738138801, 970.7330484116924, 1018.4449475036123, 1025.3898930136556, 1029.917606591471, 1117.692634525512, 27051.729482705316, 29272.26819329534, 29601.25996850053, 29784.95752648647, 30139.698603014127, 1051.9894801052044, 1076.101455284846, 1124.4658787076073, 1124.983125253122, 1194.42070595761, 13132.540361087507, 14952.252387380588, 15116.697666046617, 15168.544943651708, 15468.767968480483, 607753.961230191, 621782.0, 627770.0, 627804.0, 630223.0, 60105.699471503234, 60388.698056510315, 60579.394206058256, 60586.69706651438, 61439.084873210035, 1433.749093908562, 1462.4808193091512, 1507.0957425544586, 1512.7277090123664, 1512.9243537823286, 24274.635880461454, 27102.0, 28438.85780571084, 29595.85202073976, 29884.252893676963, 48425.757871210415, 48558.5144148554, 48565.75717121392, 48582.78543036452, 48623.0, 420148.0, 433756.0, 435004.0, 437259.0, 438570.0, 31323.77129087544, 31480.685193148693, 31833.840830795696, 31875.84062079675, 37260.96976908191, 33716.66283337135, 39070.4139437903, 42484.0, 43218.783906080265, 44946.0, 33472.83263583667, 35727.64272357243, 36203.81898090493, 36328.63671363358, 37887.81056094812, 30393.0, 34951.475727864614, 36036.73871414488, 36054.0, 37237.81381093077, 34326.65673343235, 35046.47430288599, 35871.64128358683, 35919.0, 36024.459633105, 30283.0, 31872.203194920312, 32842.67157328396, 32903.34193316168, 33009.0, 23635.0, 23636.409089772893, 24070.759292406852, 24247.0, 24319.66241856715], "value": 19504.705494998256, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.609362824432, 400.64743026136995, 401.51416785689315, 401.6224748736186, 410.4704930639475, 14140.363683634236, 19664.803351966475, 20544.486387840312, 20597.38207853765, 20628.793712062874, 15617.453389131382, 17226.741598876026, 19626.705599415985, 20334.59330813383, 20460.89769551154, 5883.794067207648, 6049.032154855224, 6095.725692343833, 6098.237720284965, 6117.143599896018, 5953.732082056296, 5963.582549221556, 5969.253843269591, 5979.611325263866, 6069.453749162574, 5881.294244690622, 5911.172435858984, 5922.141289513018, 5924.289085309769, 5925.970370148143, 5824.825255242328, 5957.582969192158, 6062.120742569773, 6064.939350606482, 6067.7269522871575, 5088.313077734496, 5247.501487358695, 5267.683938963674, 5274.446183150752, 5279.155335146396, 23675.881620591957, 24923.50152996948, 26022.73977260222, 26170.73829261721, 26228.344291392677, 2025.868318559289, 2209.8674079555117, 2257.1761307122783, 2612.529744645963, 2845.359794046323, 11712.941435292854, 14456.421743130368, 14653.926730366387, 14956.775648365283, 15292.235388230543, 1738.6957282475644, 1752.9298828046863, 1781.153951872863, 1831.5054935167564, 1849.759531260941, 100732.0, 103109.0, 104178.0, 104331.47834260856, 106925.39611905905, 10633.89366106329, 10812.837807432894, 11115.05451055471, 11167.888321116685, 11278.774424511465, 12594.937025314815, 13709.588712338646, 14254.429822807178, 14263.85736142646, 14296.142231466145, 7849.803754906173, 7935.603219838985, 8082.232187942136, 8754.956225218833, 8939.374243803002, 8782.033976262734, 8802.295816334678, 8820.25027872638, 8920.643174272956, 8959.1936725695, 6583.572067815577, 6672.766453174116, 6697.330266973361, 6706.7652632158615, 6713.530052896251, 1660.3607611069824, 1881.5484283771796, 1905.1617288393063, 1916.9041547922548, 1950.3953774330002, 898.2993265253114, 971.4608392342177, 1015.4465816130227, 1042.5256508288862, 1100.1858624617726, 20389.490262743253, 21340.466488337916, 21366.35900922975, 21401.357959261248, 21435.78564214338, 975.9316847820585, 1013.4679293370892, 1020.8158536098091, 1111.5494279964723, 1115.3475216998147, 10811.567537298577, 11140.777184456265, 11525.366104864146, 11560.6531804046, 11738.413079345999, 563647.0, 581231.3138080378, 601871.9251229989, 601923.0, 602948.0, 112685.87314126917, 115811.0, 126762.36618816847, 135953.0, 140232.2988385072, 1015.4262841494532, 1018.7351288664852, 1048.7745134795903, 1065.6270305393205, 1072.2226385870338, 17767.111644417986, 18117.184726687596, 18173.909130454264, 18341.358052468633, 18765.624687506446, 43915.682529524165, 45240.96415661369, 51351.743241283555, 54645.45354546563, 55297.72351138219, 54670.0, 71183.0, 73356.0, 75086.24913750793, 77163.61418192873, 9987.700368988943, 10531.420771857314, 11832.230904991147, 11944.283342999448, 12054.216475929037, 11792.941035294769, 12425.813612795997, 12497.375131243583, 12531.561395351118, 12550.246985180918, 10554.472276385997, 10791.784164316512, 10858.34849909008, 11022.228444008842, 11224.94387528057, 10269.486525673836, 10489.160867130764, 10621.362718236931, 10695.411752353695, 10800.0, 10836.512356943798, 10904.836427453436, 10963.945180274048, 11058.060064894253, 11539.36533490666, 11408.258463199865, 11485.598004069818, 11634.6509604712, 11667.941660291644, 11687.941560292144, 10640.361578305326, 10830.620928267474, 10880.619178329038, 10892.292001019909, 10893.945530272298], "value": 9863.775257773092, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.3983926750764, 1605.3257631794645, 1626.6502701919085, 1851.8796278241914, 1970.4581240158964, 7589.127250366209, 8548.187922147401, 8743.912560874405, 8750.824983500326, 8795.164459376358, 8339.207775261355, 8547.743567692964, 8555.0, 8564.486130832154, 8573.699920502784, 2144.9142034318606, 2222.4999375140546, 2224.688543603897, 2419.322589674894, 2467.062516243825, 2201.636729939558, 2426.890789914449, 2500.5124000819847, 2500.5374005808894, 2503.949921001579, 2411.059686722171, 2442.584760590695, 2448.155386391692, 2454.6931633545805, 2455.0, 2118.9682154767693, 2394.6048901931163, 2417.9602770808547, 2428.4171798768343, 2436.3300092474624, 2397.9640305395433, 2461.59383701689, 2474.344298760812, 2474.789642880339, 2478.3804048987754, 8529.061803201643, 8591.699290524864, 8592.8711069334, 8600.526971016592, 8614.44006139599, 5229.0, 5440.455954404528, 5454.263674403984, 5469.453054694539, 5471.50756431919, 3460.3079384123275, 3572.392693242155, 3665.688416484602, 3674.8713795017316, 3679.558452985632, 742.5581778841581, 908.2960705453327, 1022.6676330192718, 1040.959040959041, 1069.6149386220955, 11474.311541307548, 11485.196036277464, 11498.942505287503, 11514.02130818873, 11541.134414918915, 4833.661643684979, 4851.0, 4881.048195601895, 4954.0, 5013.674111182761, 9926.553305101288, 9940.204783617293, 9940.353876997971, 9941.005899410146, 9965.701028969142, 5441.428649991773, 5481.205225242397, 5491.697956612345, 5492.450754924555, 5503.642263252876, 4837.516248375135, 4873.585745211697, 4927.334809800703, 4976.925346119811, 4999.675021123615, 4015.8393664253686, 4016.457778199964, 4067.51189857219, 4095.344744840811, 4132.483439570054, 1373.4574842936984, 1379.3999610169608, 1380.8688174623394, 1385.8683425074603, 1394.3585950462698, 798.4011991006745, 819.9467034642729, 895.4806212396794, 925.1812146250617, 938.394735395666, 5517.200005999108, 5545.556355491551, 5746.626469279484, 5926.762929482858, 6086.6348019119, 419.737663960025, 441.271901362754, 452.41638286609907, 454.22781271838005, 467.8596421073664, 3747.868824591126, 3833.616638336144, 3840.036695235119, 3872.670822980022, 3908.022994251437, 10013.148882344936, 10473.685789426178, 10590.841137382946, 10721.41032243234, 10722.03501684852, 10560.155187584975, 10569.735756605993, 10590.993855583707, 10672.359658420519, 10699.572017119384, 7523.0, 7932.643031063616, 8029.678812847421, 8063.919360806317, 8073.83852322962, 5694.515966142759, 5898.882022359443, 5944.524438045032, 5951.821445356646, 5955.106733989936, 9167.862482062641, 9362.765930851783, 9709.563069662023, 9840.212782977487, 9858.55636496345, 8906.332025098014, 9629.085236902483, 9769.120779129913, 9819.9018009819, 9907.950460247652, 6779.084823548955, 7261.745838895613, 7529.134149572869, 7534.510256833289, 7539.547627142389, 7978.84042319162, 8019.558924258989, 8032.357411406956, 8067.273945344947, 8092.635831387719, 7330.560166390033, 7339.522931009467, 7434.0, 7492.550446973199, 7537.095548533992, 7106.182788979334, 7122.6438678066925, 7340.265973402617, 7361.0, 7396.371308438628, 6872.381485666314, 6893.276205998298, 6980.650967451708, 6981.127359080115, 6995.405390541861, 4195.538490765953, 4595.977020114878, 4751.311059896227, 4818.180909245367, 4936.901261974668, 4851.636127290397, 4881.096997055556, 5002.299678045149, 5020.046191223656, 5162.993216322781], "value": 4481.545594484932, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.71450836213955, 316.11645450964556, 322.34563835414116, 324.99512507312386, 325.0233049685694, 4351.369051487536, 4438.489573699021, 4685.578297953192, 5143.3827940647125, 5156.303898973638, 4350.58669426404, 4437.600615944564, 5033.672811267265, 5300.363956325236, 5311.893762124765, 4089.6912987843752, 4090.1206240658325, 4101.856435024774, 4122.340425531916, 4144.109016561439, 3183.7265093962483, 3541.433370660695, 4098.774567398792, 4105.445764821756, 4142.2543942090415, 3584.4264917613186, 4016.7748836604824, 4056.2495938251373, 4058.127502586943, 4093.8771836844794, 3549.733769967237, 4011.438398624195, 4035.535913369956, 4060.2285565742413, 4097.97951010246, 2932.648082230146, 3557.3596752584526, 3565.5008298837924, 3565.7682250653884, 3574.4638304254568, 4660.1145782301255, 4929.531694489017, 5085.516875896784, 5124.975634624302, 5185.714785686786, 4317.438732964726, 4653.7440440775745, 4685.508021657711, 4687.359424201502, 4687.484376718558, 5286.312779338661, 5510.283663123768, 5537.723113844291, 5568.777248910039, 5589.385167631557, 3905.3946638271113, 3924.6271604197555, 3935.5277366716177, 3945.559870647222, 3945.763254204757, 5143.3570803649545, 5694.430556944314, 5770.249867517238, 5843.737031833578, 5865.824025279248, 3540.451230059345, 3983.900402489903, 3996.9200615987515, 4005.839766409311, 4006.939895901564, 3820.9617903821163, 4056.7363121397016, 4067.349224124126, 4078.469798926121, 4091.529474110456, 4463.750149957952, 4618.630509559227, 4634.490206077328, 4686.133065382915, 4702.858914232578, 4522.819087236474, 5237.921431178535, 5321.866953326198, 5368.8657783554945, 5599.748011339418, 1219.762146381447, 1308.7959077648586, 1484.317214081513, 1486.1603194195245, 1486.7472529669983, 991.3556188477547, 992.5905214595292, 1002.2132625888643, 1014.8325526288228, 1016.7712264740522, 5562.3047119110115, 5588.3176075484, 5598.1602759586385, 5622.0, 5668.858278543069, 5409.134538473825, 5915.254999775077, 6140.631562106288, 6150.846228844314, 6185.051708711741, 4689.0, 4962.478939711351, 5321.642981039888, 5562.332520097533, 5592.384837667853, 2809.2976755811046, 3148.795328303653, 3290.5064240364136, 3336.3160552086765, 3338.981610608788, 5528.75120619573, 5728.684922329312, 6020.969895150583, 6272.09054687077, 6308.148399966038, 5645.407232240637, 6208.255009398901, 6260.311365749764, 6386.521010924199, 6463.385978332051, 5560.221568980265, 5736.713164341682, 6429.549931504844, 6503.837404064936, 6540.705668245036, 4813.350197723263, 5051.928709229199, 6239.563230573816, 6278.811635650938, 6282.717277722422, 5504.284443022381, 6182.938170618237, 6185.195924529782, 6192.752289908353, 6210.7515699373325, 6189.535784816247, 6513.8697226056165, 6544.0, 6548.345165483413, 6557.606543607399, 4101.405296232089, 4106.753594784323, 4130.855420060284, 4131.566185550594, 4144.254034273739, 4738.431388233434, 5437.211604317431, 5649.378568357395, 6143.262808463013, 6294.370562943669, 4464.866054018385, 5361.954418888279, 5459.699716515471, 5463.153211252262, 5673.943260567342, 5181.844544663666, 5529.917051244154, 5536.418676039119, 5578.601201483401, 5622.493975542221, 4718.056388722201, 4968.503149685147, 5019.7490125494305, 5292.814751483679, 5483.4516548344845, 5037.118504261813, 5164.664296820694, 5362.0481265795715, 5597.313289621086, 5611.579131565068, 4538.659600529907, 4615.884602884955, 5462.371827239918, 5465.672059676433, 5492.0], "value": 4190.171001583122, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.8990561510159, 732.4177279063144, 758.6396461680699, 759.0739298056372, 769.242296338107, 12271.44778484968, 14644.341004654789, 14658.560243192707, 14875.628109297273, 14899.40402383906, 11733.002694770954, 13631.522896698618, 13674.042817002814, 13739.450421983109, 13785.172889626605, 669.9966500167517, 711.8576284743046, 763.9571984241526, 895.6506962284714, 944.7496413450441, 759.1232126893447, 762.6834863531627, 767.1637914673005, 909.0500427053712, 942.627662073484, 1003.6888564545014, 1012.691129205592, 1013.5337744637476, 1015.6749840051186, 1019.8572199892021, 1006.3157053203855, 1006.7986402719449, 1006.9697909062739, 1008.1128606825946, 1010.4240582867768, 574.8821491594213, 706.9257727938544, 740.311510295425, 746.3655892491394, 748.2666986353349, 14842.183679897602, 14896.0, 14910.850891491054, 14913.776293355608, 14944.40222391103, 12436.1345798131, 12492.437840297209, 12577.811332829924, 12597.859799942167, 12982.740345193044, 9648.807023859554, 9797.755056123582, 9816.41101533903, 10103.444310562916, 10597.0, 3386.255023894739, 3395.0833275015852, 3440.139965008748, 3461.0, 3493.9475907861147, 78434.0, 78604.21395786027, 78667.0, 78686.0, 78864.60567697183, 9156.359054866229, 9488.42838294953, 9536.995846308391, 9621.177362404978, 9622.51887405627, 21192.36422907315, 21250.681239781417, 21518.0, 21518.246861359778, 21555.245566405414, 7529.916234543366, 7570.242975702386, 7570.545767253982, 7576.8484630308185, 7690.2694244046725, 3151.68483151683, 3828.062124779447, 3864.5362556492837, 3917.5886531914307, 4084.7957602119777, 3576.088097535112, 3636.509071275344, 3654.798986055739, 3678.2459595782802, 3679.0066681996245, 645.6158585641535, 649.870025994803, 664.5912763650321, 667.8497338098986, 674.2482132422411, 514.4366918224538, 524.9816256431006, 534.2333751067231, 561.0, 564.5511818104617, 7508.911207874937, 8062.6464544953305, 8230.053543842416, 8261.0, 8497.235248827636, 500.5395036566368, 550.931133608299, 585.6105689716376, 603.6830663901399, 614.183136428549, 3808.124131449782, 3822.6604918556436, 3891.610838916086, 3923.1761330120994, 4090.9590904091174, 94186.5290673556, 94661.0, 96258.0, 97691.02308976962, 97914.0, 80329.0, 80401.0, 80426.83292368558, 80524.98687532858, 81631.5918420404, 70661.64669176622, 72566.0, 72579.0, 72724.27275727176, 73087.26912730804, 6580.638064906476, 7638.579878106758, 7851.293383595504, 7854.607269636381, 7874.527528348317, 35385.64614353824, 42823.7858810704, 43126.78436607797, 43268.35097473478, 45296.09407811891, 61139.0, 66815.33184668285, 67155.3284467149, 67568.66215668891, 67797.3220267791, 10224.0, 10339.379637221791, 11623.476943537393, 11651.825222621497, 11692.70768230767, 11717.414129293331, 11777.116716246144, 11820.113491488, 11901.166918315635, 12896.935515322364, 11114.555417783198, 11124.888751112385, 11284.717882053013, 11295.209335346775, 12401.875981240433, 11154.498047587716, 11162.553497859995, 11187.944060279646, 11190.32858028521, 11193.160512961398, 11043.72390690207, 11101.500432480396, 11265.380404077527, 11550.0, 12269.079819013788, 9400.294977876823, 9518.95240523793, 9600.589213022304, 9692.079252471285, 9768.951155244178, 9280.303977201871, 9424.0, 9434.29242806806, 9526.380785248937, 9692.903070969482], "value": 6805.668882518036, "unit": "score"}], "name": "dromaeo_css", "value": 7170.368392452465, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 110.0, 112.0, 112.0, 116.0, 114.0, 116.0, 106.0, 155.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 126.0, 144.0, 171.0, 122.0, 155.0, 177.0, 124.0, 172.0, 122.0], "value": 147.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 185.0, 185.0, 189.0, 187.0, 184.0, 174.0, 183.0, 187.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 91.0, 93.0, 56.0, 58.0, 59.0, 81.0, 86.0, 55.0], "value": 73.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 138.0, 77.0, 80.0, 80.0, 121.0, 78.0, 76.0, 127.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 88.0, 83.0, 88.0, 83.0, 87.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 92.0, 92.0, 93.0, 93.0, 92.0, 95.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 133.0, 140.0, 85.0, 85.0, 86.0, 85.0, 86.0, 84.0, 85.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 62.0, 59.0, 58.0, 58.0, 60.0, 76.0, 61.0, 58.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 77.0, 74.0, 70.0, 69.0, 68.0, 68.0, 67.0, 69.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 80.0, 83.0, 83.0, 80.0, 75.0, 81.0, 69.0, 96.0, 103.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 138.0, 134.0, 135.0, 146.0, 128.0, 146.0, 242.0, 160.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 172.0, 170.0, 179.0, 171.0, 168.0, 167.0, 171.0, 166.0], "value": 170.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 70.0, 68.0, 68.0, 67.0, 68.0, 69.0, 65.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1494.7999999999997, "alertThreshold": 2.0}]} 18:22:57 INFO - Return code: 0 18:22:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:22:58 ERROR - # TBPL SUCCESS # 18:22:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:22:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:22:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:22:58 INFO - 2016-04-28 18:22:58.043 screenresolution[2787:33330] starting screenresolution argv=screenresolution get 18:22:58 INFO - 2016-04-28 18:22:58.045 screenresolution[2787:33330] Display 0: 1600x1200x32@60 18:22:58 INFO - 2016-04-28 18:22:58.052 screenresolution[2788:33333] starting screenresolution argv=screenresolution list 18:22:58 INFO - Available Modes on Display 0 18:22:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:22:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:22:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:22:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:22:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:22:58 INFO - Intel Iris: 18:22:58 INFO - Chipset Model: Intel Iris 18:22:58 INFO - Type: GPU 18:22:58 INFO - Bus: Built-In 18:22:58 INFO - VRAM (Dynamic, Max): 1536 MB 18:22:58 INFO - Vendor: Intel (0x8086) 18:22:58 INFO - Device ID: 0x0a2e 18:22:58 INFO - Revision ID: 0x0009 18:22:58 INFO - Displays: 18:22:58 INFO - Display: 18:22:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:22:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:22:58 INFO - Display Serial Number: 1600x1200 60 18:22:58 INFO - Main Display: Yes 18:22:58 INFO - Mirror: Off 18:22:58 INFO - Online: Yes 18:22:58 INFO - Rotation: Supported 18:22:58 INFO - Return code: 0 18:22:58 INFO - Running post-action listener: _resource_record_post_action 18:22:58 INFO - Running post-run listener: _resource_record_post_run 18:22:59 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 4096; Write bytes: 693807104; Read time: 0; Write time: 1831 18:22:59 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 385088000; Write bytes: 379041792; Read time: 28490; Write time: 689 18:22:59 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 4096; Write bytes: 294932480; Read time: 0; Write time: 1098 18:22:59 INFO - Running post-run listener: _upload_blobber_files 18:22:59 INFO - Blob upload gear active. 18:22:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:22:59 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 18:22:59 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'] 18:22:59 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 18:22:59 INFO - (blobuploader) - INFO - Open directory for files ... 18:22:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:22:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:23:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:00 INFO - (blobuploader) - INFO - Done attempting. 18:23:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:23:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:23:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:01 INFO - (blobuploader) - INFO - Done attempting. 18:23:01 INFO - (blobuploader) - INFO - Iteration through files over. 18:23:01 INFO - Return code: 0 18:23:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:23:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:23:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e5860f54ae578a89115f554555e1f8f332d1689843f94cd9f999f1979a1ca8f93bb27f7220060f5cbf42c33b6dc61716264bd265f0ad254519829c8fe5466682", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b6a4af74826a81b7dfcd2b75d5132ea58ba09b3319e1e9bd11e6216dafc66acedd3b4b00b5a985690f76b204e4db07d515136c24bf13e02c8c68bceea5408c68"} 18:23:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:23:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:23:01 INFO - Contents: 18:23:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e5860f54ae578a89115f554555e1f8f332d1689843f94cd9f999f1979a1ca8f93bb27f7220060f5cbf42c33b6dc61716264bd265f0ad254519829c8fe5466682", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b6a4af74826a81b7dfcd2b75d5132ea58ba09b3319e1e9bd11e6216dafc66acedd3b4b00b5a985690f76b204e4db07d515136c24bf13e02c8c68bceea5408c68"} 18:23:01 INFO - Running post-run listener: copy_logs_to_upload_dir 18:23:01 INFO - Copying logs to upload dir... 18:23:01 INFO - mkdir: /builds/slave/test/build/upload/logs 18:23:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.551212 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-28 18:23:01.653357) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 18:23:01.659520) ========= 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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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/e5860f54ae578a89115f554555e1f8f332d1689843f94cd9f999f1979a1ca8f93bb27f7220060f5cbf42c33b6dc61716264bd265f0ad254519829c8fe5466682", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b6a4af74826a81b7dfcd2b75d5132ea58ba09b3319e1e9bd11e6216dafc66acedd3b4b00b5a985690f76b204e4db07d515136c24bf13e02c8c68bceea5408c68"} build_url:https://queue.taskcluster.net/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011442 build_url: 'https://queue.taskcluster.net/v1/task/EVkKgY3RRKyDPH_TH-tC3g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e5860f54ae578a89115f554555e1f8f332d1689843f94cd9f999f1979a1ca8f93bb27f7220060f5cbf42c33b6dc61716264bd265f0ad254519829c8fe5466682", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b6a4af74826a81b7dfcd2b75d5132ea58ba09b3319e1e9bd11e6216dafc66acedd3b4b00b5a985690f76b204e4db07d515136c24bf13e02c8c68bceea5408c68"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 18:23:01.700045) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 18:23:01.704500) ========= 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.Dt72AdlzAt/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.UbbU4UIn1f/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.005279 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 18:23:01.759884) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 18:23:01.760203) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 18:23:01.760597) ========= ========= Total master_lag: 7.08 =========