builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0246 starttime: 1464139869.62 results: success (0) buildid: 20160524164650 builduid: 14755ce953e344db8806d97804776a25 revision: 788365ddaf61746bc66479335ae72d3e566c38ec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:09.624609) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:09.625018) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-05-24 18:31:09.625293) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-05-24 18:31:12.163744) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-05-24 18:31:12.164059) ========= 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.xpal0DPj4f/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.92gVrI0cof/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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-05-24 18:31:11-- 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% 170M=0s 2016-05-24 18:31:11 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140168 ========= master_lag: 5.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-05-24 18:31:17.489733) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:17.490071) ========= 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.xpal0DPj4f/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.92gVrI0cof/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.025191 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:17.606768) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 18:31:17.607123) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 788365ddaf61746bc66479335ae72d3e566c38ec --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 788365ddaf61746bc66479335ae72d3e566c38ec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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-05-24 18:31:17,096 truncating revision to first 12 chars 2016-05-24 18:31:17,096 Setting DEBUG logging. 2016-05-24 18:31:17,096 attempt 1/10 2016-05-24 18:31:17,096 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/788365ddaf61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 18:31:17,757 unpacking tar archive at: fx-team-788365ddaf61/testing/mozharness/ program finished with exit code 0 elapsedTime=0.913327 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 18:31:18.666458) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:18.666751) ========= script_repo_revision: 788365ddaf61746bc66479335ae72d3e566c38ec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:18.667144) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 5 secs) (at 2016-05-24 18:31:18.667413) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 5 secs) (at 2016-05-24 18:31:24.123480) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-24 18:31:24.123945) ========= /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.xpal0DPj4f/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.92gVrI0cof/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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:31:23 INFO - MultiFileLogger online at 20160524 18:31:23 in /builds/slave/test 18:31:23 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:31:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:31:23 INFO - {'append_to_log': False, 18:31:23 INFO - 'base_work_dir': '/builds/slave/test', 18:31:23 INFO - 'blob_upload_branch': 'Fx-Team', 18:31:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:31:23 INFO - 'branch': 'Fx-Team', 18:31:23 INFO - 'buildbot_json_path': 'buildprops.json', 18:31:23 INFO - 'config_files': ('talos/mac_config.py',), 18:31:23 INFO - 'default_actions': ('clobber', 18:31:23 INFO - 'read-buildbot-config', 18:31:23 INFO - 'download-and-extract', 18:31:23 INFO - 'populate-webroot', 18:31:23 INFO - 'create-virtualenv', 18:31:23 INFO - 'install', 18:31:23 INFO - 'run-tests'), 18:31:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:31:23 INFO - 'download_minidump_stackwalk': True, 18:31:23 INFO - 'download_symbols': 'ondemand', 18:31:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:31:23 INFO - 'tooltool.py': '/tools/tooltool.py', 18:31:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:31:23 INFO - '/tools/misc-python/virtualenv.py')}, 18:31:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:31:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:31:23 INFO - 'installer_path': 'installer.exe', 18:31:23 INFO - 'log_level': 'info', 18:31:23 INFO - 'log_name': 'talos', 18:31:23 INFO - 'log_to_console': True, 18:31:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:31:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:31:23 INFO - 'opt_config_files': (), 18:31:23 INFO - 'pip_index': False, 18:31:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:31:23 INFO - 'cmd': ('bash', 18:31:23 INFO - '-c', 18:31:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:31:23 INFO - 'enabled': True, 18:31:23 INFO - 'halt_on_failure': False, 18:31:23 INFO - 'name': 'check_screen_resolution'},), 18:31:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:31:23 INFO - 'cmd': ('bash', 18:31:23 INFO - '-c', 18:31:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:31:23 INFO - 'enabled': True, 18:31:23 INFO - 'halt_on_failure': False, 18:31:23 INFO - 'name': 'check_screen_resolution'},), 18:31:23 INFO - 'run_cmd_checks_enabled': True, 18:31:23 INFO - 'sps_profile': False, 18:31:23 INFO - 'sps_profile_interval': 0, 18:31:23 INFO - 'suite': 'dromaeojs', 18:31:23 INFO - 'system_bits': '32', 18:31:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:31:23 INFO - 'title': 't-yosemite-r7-0246', 18:31:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:31:23 INFO - 'use_talos_json': True, 18:31:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:31:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:31:23 INFO - 'work_dir': 'build'} 18:31:23 INFO - ##### 18:31:23 INFO - ##### Running clobber step. 18:31:23 INFO - ##### 18:31:23 INFO - Running pre-action listener: _resource_record_pre_action 18:31:23 INFO - Running main action method: clobber 18:31:23 INFO - rmtree: /builds/slave/test/build 18:31:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:31:25 INFO - Running post-action listener: _resource_record_post_action 18:31:25 INFO - ##### 18:31:25 INFO - ##### Finished clobber step (success) 18:31:25 INFO - ##### 18:31:25 INFO - ##### 18:31:25 INFO - ##### Running read-buildbot-config step. 18:31:25 INFO - ##### 18:31:25 INFO - Running pre-action listener: _resource_record_pre_action 18:31:25 INFO - Running main action method: read_buildbot_config 18:31:25 INFO - Using buildbot properties: 18:31:25 INFO - { 18:31:25 INFO - "project": "", 18:31:25 INFO - "product": "firefox", 18:31:25 INFO - "script_repo_revision": "production", 18:31:25 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:31:25 INFO - "repository": "", 18:31:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:31:25 INFO - "buildid": "20160524164650", 18:31:25 INFO - "pgo_build": "False", 18:31:25 INFO - "basedir": "/builds/slave/test", 18:31:25 INFO - "buildnumber": 722, 18:31:25 INFO - "slavename": "t-yosemite-r7-0246", 18:31:25 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:31:25 INFO - "platform": "yosemite_r7", 18:31:25 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:31:25 INFO - "branch": "fx-team", 18:31:25 INFO - "repo_path": "integration/fx-team", 18:31:25 INFO - "revision": "788365ddaf61746bc66479335ae72d3e566c38ec", 18:31:25 INFO - "stage_platform": "macosx64", 18:31:25 INFO - "builduid": "14755ce953e344db8806d97804776a25", 18:31:25 INFO - "slavebuilddir": "test" 18:31:25 INFO - } 18:31:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:31:25 INFO - Running post-action listener: _resource_record_post_action 18:31:25 INFO - ##### 18:31:25 INFO - ##### Finished read-buildbot-config step (success) 18:31:25 INFO - ##### 18:31:25 INFO - ##### 18:31:25 INFO - ##### Running download-and-extract step. 18:31:25 INFO - ##### 18:31:25 INFO - Running pre-action listener: _resource_record_pre_action 18:31:25 INFO - Running main action method: download_and_extract 18:31:25 INFO - mkdir: /builds/slave/test/build/tests 18:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:25 INFO - https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:31:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:31:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:31:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/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:31:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/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:31:27 INFO - Downloaded 1199 bytes. 18:31:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:31:27 INFO - Using the following test package requirements: 18:31:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:31:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:31:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:31:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:27 INFO - u'jsshell-mac.zip'], 18:31:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:31:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:31:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:31:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:31:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:31:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:31:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:31:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:27 INFO - https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:31:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:31:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:31:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/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:31:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/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:31:34 INFO - Downloaded 18632966 bytes. 18:31:34 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:31:34 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:31:37 INFO - Return code: 0 18:31:37 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:31:37 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:31:37 INFO - https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:31:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:31:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:31:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/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:31:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/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:31:39 INFO - Downloaded 18632966 bytes. 18:31:39 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:31:39 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:31:43 INFO - Return code: 0 18:31: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:31:43 INFO - https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:31:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:31:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:31:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/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:31:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/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:31:44 INFO - Downloaded 10840359 bytes. 18:31:44 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:31:44 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:31:44 INFO - Return code: 0 18:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:44 INFO - https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:31:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:31:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:31:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:31:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/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:31:47 INFO - Downloaded 110242124 bytes. 18:31:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:31:47 INFO - mkdir: /builds/slave/test/properties 18:31:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:31:47 INFO - Writing to file /builds/slave/test/properties/build_url 18:31:47 INFO - Contents: 18:31:47 INFO - build_url:https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:31:48 INFO - Running post-action listener: _resource_record_post_action 18:31:48 INFO - Running post-action listener: set_extra_try_arguments 18:31:48 INFO - ##### 18:31:48 INFO - ##### Finished download-and-extract step (success) 18:31:48 INFO - ##### 18:31:48 INFO - ##### 18:31:48 INFO - ##### Running populate-webroot step. 18:31:48 INFO - ##### 18:31:48 INFO - Running pre-action listener: _resource_record_pre_action 18:31:48 INFO - Running main action method: populate_webroot 18:31:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:31:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:31:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:31:49 INFO - '--tppagecycles', 18:31:49 INFO - '7'], 18:31:49 INFO - 'tests': ['tsvgm']}}, 18:31:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:31:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:31:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:31:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:31:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:31:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:31:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:49 INFO - 'talos_options': ['--disable-e10s'], 18:31:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:49 INFO - 'talos_options': ['--disable-e10s'], 18:31:49 INFO - 'tests': ['damp', 'tps']}, 18:31:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:49 INFO - 'tests': ['damp', 'tps']}, 18:31:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:31:49 INFO - 'tests': ['dromaeo_dom']}, 18:31:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:31:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:31:49 INFO - 'tests': ['a11yr', 18:31:49 INFO - 'ts_paint', 18:31:49 INFO - 'tpaint', 18:31:49 INFO - 'sessionrestore', 18:31:49 INFO - 'sessionrestore_no_auto_restore', 18:31:49 INFO - 'tabpaint']}, 18:31:49 INFO - 'other-e10s': {'tests': ['a11yr', 18:31:49 INFO - 'ts_paint', 18:31:49 INFO - 'tpaint', 18:31:49 INFO - 'sessionrestore', 18:31:49 INFO - 'sessionrestore_no_auto_restore', 18:31:49 INFO - 'tabpaint']}, 18:31:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:31:49 INFO - 'ts_paint', 18:31:49 INFO - 'tpaint', 18:31:49 INFO - 'sessionrestore', 18:31:49 INFO - 'sessionrestore_no_auto_restore', 18:31:49 INFO - 'tabpaint']}, 18:31:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:31:49 INFO - 'ts_paint', 18:31:49 INFO - 'tpaint', 18:31:49 INFO - 'sessionrestore', 18:31:49 INFO - 'sessionrestore_no_auto_restore', 18:31:49 INFO - 'tabpaint']}, 18:31:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:31:49 INFO - 'tests': ['a11yr', 18:31:49 INFO - 'ts_paint', 18:31:49 INFO - 'tpaint', 18:31:49 INFO - 'sessionrestore', 18:31:49 INFO - 'sessionrestore_no_auto_restore', 18:31:49 INFO - 'tabpaint']}, 18:31:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:31:49 INFO - 'tests': ['a11yr', 18:31:49 INFO - 'ts_paint', 18:31:49 INFO - 'tpaint', 18:31:49 INFO - 'sessionrestore', 18:31:49 INFO - 'sessionrestore_no_auto_restore', 18:31:49 INFO - 'tabpaint']}, 18:31:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:31:49 INFO - 'tests': ['tsvgx', 18:31:49 INFO - 'tsvgr_opacity', 18:31:49 INFO - 'tart', 18:31:49 INFO - 'tscrollx', 18:31:49 INFO - 'cart']}, 18:31:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:31:49 INFO - 'tsvgr_opacity', 18:31:49 INFO - 'tart', 18:31:49 INFO - 'tscrollx', 18:31:49 INFO - 'cart']}, 18:31:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:49 INFO - 'talos_options': ['--disable-e10s'], 18:31:49 INFO - 'tests': ['tp5o']}, 18:31:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:49 INFO - 'tests': ['tp5o']}, 18:31:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:49 INFO - 'talos_options': ['--disable-e10s', 18:31:49 INFO - '--xperf_path', 18:31:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:49 INFO - 'tests': ['tp5n']}, 18:31:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:49 INFO - 'talos_options': ['--xperf_path', 18:31:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:49 INFO - 'tests': ['tp5n']}}, 18:31:49 INFO - 'talos.zip': {'path': '', 18:31:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:31:49 INFO - Running post-action listener: _resource_record_post_action 18:31:49 INFO - ##### 18:31:49 INFO - ##### Finished populate-webroot step (success) 18:31:49 INFO - ##### 18:31:49 INFO - ##### 18:31:49 INFO - ##### Running create-virtualenv step. 18:31:49 INFO - ##### 18:31:49 INFO - Running pre-action listener: _resource_record_pre_action 18:31:49 INFO - Running main action method: create_virtualenv 18:31:49 INFO - Creating virtualenv /builds/slave/test/build/venv 18:31:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:31:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:31:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:31:49 INFO - Using real prefix '/tools/python27' 18:31:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:31:49 INFO - Installing distribute.............................................................................................................................................................................................done. 18:31:52 INFO - Installing pip.................done. 18:31:52 INFO - Return code: 0 18:31:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, '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.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render', 18:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:52 INFO - 'HOME': '/Users/cltbld', 18:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:52 INFO - 'LOGNAME': 'cltbld', 18:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:52 INFO - 'MOZ_NO_REMOTE': '1', 18:31:52 INFO - 'NO_EM_RESTART': '1', 18:31:52 INFO - 'PAGER': '/bin/cat', 18:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:52 INFO - 'PWD': '/builds/slave/test', 18:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:52 INFO - 'SHELL': '/bin/bash', 18:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners', 18:31:52 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 18:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:52 INFO - 'USER': 'cltbld', 18:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:52 INFO - 'XPC_FLAGS': '0x0', 18:31:52 INFO - 'XPC_SERVICE_NAME': '0', 18:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:53 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:31:53 INFO - Cleaning up... 18:31:53 INFO - Return code: 0 18:31:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, '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.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render', 18:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:53 INFO - 'HOME': '/Users/cltbld', 18:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:53 INFO - 'LOGNAME': 'cltbld', 18:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:53 INFO - 'MOZ_NO_REMOTE': '1', 18:31:53 INFO - 'NO_EM_RESTART': '1', 18:31:53 INFO - 'PAGER': '/bin/cat', 18:31:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:53 INFO - 'PWD': '/builds/slave/test', 18:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:53 INFO - 'SHELL': '/bin/bash', 18:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners', 18:31:53 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 18:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:53 INFO - 'USER': 'cltbld', 18:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:53 INFO - 'XPC_FLAGS': '0x0', 18:31:53 INFO - 'XPC_SERVICE_NAME': '0', 18:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:53 INFO - Downloading/unpacking psutil>=3.1.1 18:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:31:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:56 INFO - Installing collected packages: psutil 18:31:56 INFO - Running setup.py install for psutil 18:31:56 INFO - building 'psutil._psutil_osx' extension 18:31:56 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:31:57 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:31:57 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:31:57 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:31:57 INFO - building 'psutil._psutil_posix' extension 18:31:57 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:31:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:31:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:31:57 INFO - ^ 18:31:57 INFO - 1 warning generated. 18:31:57 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:31:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:57 INFO - Successfully installed psutil 18:31:57 INFO - Cleaning up... 18:31:57 INFO - Return code: 0 18:31:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:31: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:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, '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.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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:31:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:31:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render', 18:31:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:57 INFO - 'HOME': '/Users/cltbld', 18:31:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:57 INFO - 'LOGNAME': 'cltbld', 18:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:57 INFO - 'MOZ_NO_REMOTE': '1', 18:31:57 INFO - 'NO_EM_RESTART': '1', 18:31:57 INFO - 'PAGER': '/bin/cat', 18:31:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:57 INFO - 'PWD': '/builds/slave/test', 18:31:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:57 INFO - 'SHELL': '/bin/bash', 18:31:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners', 18:31:57 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 18:31:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:57 INFO - 'USER': 'cltbld', 18:31:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:57 INFO - 'XPC_FLAGS': '0x0', 18:31:57 INFO - 'XPC_SERVICE_NAME': '0', 18:31:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:31:58 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:31:58 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:31:58 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:31:58 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:31:58 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:31:58 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:32:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:32:00 INFO - Installing collected packages: mozsystemmonitor 18:32:00 INFO - Running setup.py install for mozsystemmonitor 18:32:00 INFO - Successfully installed mozsystemmonitor 18:32:00 INFO - Cleaning up... 18:32:00 INFO - Return code: 0 18:32:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, '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.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render', 18:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:00 INFO - 'HOME': '/Users/cltbld', 18:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:00 INFO - 'LOGNAME': 'cltbld', 18:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:00 INFO - 'MOZ_NO_REMOTE': '1', 18:32:00 INFO - 'NO_EM_RESTART': '1', 18:32:00 INFO - 'PAGER': '/bin/cat', 18:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:00 INFO - 'PWD': '/builds/slave/test', 18:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:00 INFO - 'SHELL': '/bin/bash', 18:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners', 18:32:00 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 18:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:00 INFO - 'USER': 'cltbld', 18:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:00 INFO - 'XPC_FLAGS': '0x0', 18:32:00 INFO - 'XPC_SERVICE_NAME': '0', 18:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:01 INFO - Downloading/unpacking blobuploader==1.2.4 18:32:01 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:32:01 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:32:01 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:32:01 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:32:01 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:32:01 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:32:03 INFO - Downloading blobuploader-1.2.4.tar.gz 18:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:32:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:32:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 INFO - Downloading docopt-0.6.1.tar.gz 18:32:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:32:04 INFO - Installing collected packages: blobuploader, requests, docopt 18:32:04 INFO - Running setup.py install for blobuploader 18:32:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:32:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:32:04 INFO - Running setup.py install for requests 18:32:05 INFO - Running setup.py install for docopt 18:32:05 INFO - Successfully installed blobuploader requests docopt 18:32:05 INFO - Cleaning up... 18:32:05 INFO - Return code: 0 18:32:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:05 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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, '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.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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:32:05 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:32:05 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:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render', 18:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:05 INFO - 'HOME': '/Users/cltbld', 18:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:05 INFO - 'LOGNAME': 'cltbld', 18:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:05 INFO - 'MOZ_NO_REMOTE': '1', 18:32:05 INFO - 'NO_EM_RESTART': '1', 18:32:05 INFO - 'PAGER': '/bin/cat', 18:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:05 INFO - 'PWD': '/builds/slave/test', 18:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:05 INFO - 'SHELL': '/bin/bash', 18:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners', 18:32:05 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 18:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:05 INFO - 'USER': 'cltbld', 18:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:05 INFO - 'XPC_FLAGS': '0x0', 18:32:05 INFO - 'XPC_SERVICE_NAME': '0', 18:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:32:05 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-vGBWgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-X0ngWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-YzIBHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-oRLohB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-jf4TWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-GkMhto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-c4H82D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-aYEEqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-nrZjac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-gi2IjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-aalPTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-09BZbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-biGTvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-f9sTWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-mQ1JkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-iBFQVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-356N7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:32:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:32:07 INFO - Running setup.py install for manifestparser 18:32:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:32:07 INFO - Running setup.py install for mozcrash 18:32:07 INFO - Running setup.py install for mozdebug 18:32:08 INFO - Running setup.py install for mozdevice 18:32:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:32:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:32:08 INFO - Running setup.py install for mozfile 18:32:08 INFO - Running setup.py install for mozhttpd 18:32:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:32:08 INFO - Running setup.py install for mozinfo 18:32:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:32:08 INFO - Running setup.py install for mozInstall 18:32:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:32:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:32:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:32:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:32:08 INFO - Running setup.py install for mozleak 18:32:09 INFO - Running setup.py install for mozlog 18:32:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:32:09 INFO - Running setup.py install for moznetwork 18:32:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:32:09 INFO - Running setup.py install for mozprocess 18:32:09 INFO - Running setup.py install for mozprofile 18:32:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:32:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:32:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:32:09 INFO - Running setup.py install for mozrunner 18:32:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:32:09 INFO - Running setup.py install for mozscreenshot 18:32:09 INFO - Running setup.py install for moztest 18:32:10 INFO - Running setup.py install for mozversion 18:32:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:32:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:32:10 INFO - Cleaning up... 18:32:10 INFO - Return code: 0 18:32:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:10 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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, '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.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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:32:10 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:32:10 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:32:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render', 18:32:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:10 INFO - 'HOME': '/Users/cltbld', 18:32:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:10 INFO - 'LOGNAME': 'cltbld', 18:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:10 INFO - 'MOZ_NO_REMOTE': '1', 18:32:10 INFO - 'NO_EM_RESTART': '1', 18:32:10 INFO - 'PAGER': '/bin/cat', 18:32:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:10 INFO - 'PWD': '/builds/slave/test', 18:32:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:10 INFO - 'SHELL': '/bin/bash', 18:32:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners', 18:32:10 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 18:32:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:10 INFO - 'USER': 'cltbld', 18:32:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:10 INFO - 'XPC_FLAGS': '0x0', 18:32:10 INFO - 'XPC_SERVICE_NAME': '0', 18:32:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:32:10 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-q5zcLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:32:10 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:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:32:10 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-4lCTPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:32:10 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:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:32:10 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-qAeU8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:32:10 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:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:32:10 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-l3hEN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:32:10 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:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-3S8fZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:32:11 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:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-66uf4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:32:11 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:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-CVHgUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:32:11 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:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ZTDxsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:32:11 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:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-G5e0Cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:32:11 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:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-N46Snt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Ku9GSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:32:11 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:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-itiR8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:32:11 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:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-YvhXD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:32:11 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:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-uuUp8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:32:12 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:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-LoN2QS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:32:12 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:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:32:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-3NPCFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:32:12 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:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:32:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-BYM7Ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:32:12 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:32:12 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:32:12 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:32:12 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:32:12 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:32:12 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:32:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:14 INFO - Downloading blessings-1.6.tar.gz 18:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:32:14 INFO - Installing collected packages: blessings 18:32:14 INFO - Running setup.py install for blessings 18:32:15 INFO - Successfully installed blessings 18:32:15 INFO - Cleaning up... 18:32:15 INFO - Return code: 0 18:32:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:32:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:32:15 INFO - Reading from file tmpfile_stdout 18:32:15 INFO - Current package versions: 18:32:15 INFO - blessings == 1.6 18:32:15 INFO - blobuploader == 1.2.4 18:32:15 INFO - docopt == 0.6.1 18:32:15 INFO - manifestparser == 1.1 18:32:15 INFO - mozInstall == 1.12 18:32:15 INFO - mozcrash == 0.17 18:32:15 INFO - mozdebug == 0.1 18:32:15 INFO - mozdevice == 0.48 18:32:15 INFO - mozfile == 1.2 18:32:15 INFO - mozhttpd == 0.7 18:32:15 INFO - mozinfo == 0.9 18:32:15 INFO - mozleak == 0.1 18:32:15 INFO - mozlog == 3.2 18:32:15 INFO - moznetwork == 0.27 18:32:15 INFO - mozprocess == 0.22 18:32:15 INFO - mozprofile == 0.28 18:32:15 INFO - mozrunner == 6.11 18:32:15 INFO - mozscreenshot == 0.1 18:32:15 INFO - mozsystemmonitor == 0.3 18:32:15 INFO - moztest == 0.7 18:32:15 INFO - mozversion == 1.4 18:32:15 INFO - psutil == 3.1.1 18:32:15 INFO - requests == 1.2.3 18:32:15 INFO - wsgiref == 0.1.2 18:32:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:15 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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, '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.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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:32:15 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:32:15 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:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render', 18:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:15 INFO - 'HOME': '/Users/cltbld', 18:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:15 INFO - 'LOGNAME': 'cltbld', 18:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:15 INFO - 'MOZ_NO_REMOTE': '1', 18:32:15 INFO - 'NO_EM_RESTART': '1', 18:32:15 INFO - 'PAGER': '/bin/cat', 18:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:15 INFO - 'PWD': '/builds/slave/test', 18:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:15 INFO - 'SHELL': '/bin/bash', 18:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners', 18:32:15 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 18:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:15 INFO - 'USER': 'cltbld', 18:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:15 INFO - 'XPC_FLAGS': '0x0', 18:32:15 INFO - 'XPC_SERVICE_NAME': '0', 18:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:15 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:32:15 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:32:15 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:32:15 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:32:15 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:32:15 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:32:15 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:32:15 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:32:15 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:32:15 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:32:15 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:32:15 INFO - Cleaning up... 18:32:15 INFO - Return code: 0 18:32:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:15 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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, '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.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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:32:15 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:32:15 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:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render', 18:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:15 INFO - 'HOME': '/Users/cltbld', 18:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:15 INFO - 'LOGNAME': 'cltbld', 18:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:15 INFO - 'MOZ_NO_REMOTE': '1', 18:32:15 INFO - 'NO_EM_RESTART': '1', 18:32:15 INFO - 'PAGER': '/bin/cat', 18:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:15 INFO - 'PWD': '/builds/slave/test', 18:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:15 INFO - 'SHELL': '/bin/bash', 18:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners', 18:32:15 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 18:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:15 INFO - 'USER': 'cltbld', 18:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:15 INFO - 'XPC_FLAGS': '0x0', 18:32:15 INFO - 'XPC_SERVICE_NAME': '0', 18:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:15 INFO - Downloading/unpacking jsonschema 18:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:32:18 INFO - Downloading/unpacking functools32 (from jsonschema) 18:32:18 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:32:18 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:32:18 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:32:18 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:32:18 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:32:18 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:32:18 INFO - Downloading functools32-3.2.3-2.tar.gz 18:32:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:32:18 INFO - warning: no files found matching '*.txt' 18:32:19 INFO - no previously-included directories found matching 'build' 18:32:19 INFO - no previously-included directories found matching 'dist' 18:32:19 INFO - no previously-included directories found matching '.git*' 18:32:19 INFO - Installing collected packages: jsonschema, functools32 18:32:19 INFO - Running setup.py install for functools32 18:32:19 INFO - warning: no files found matching '*.txt' 18:32:19 INFO - no previously-included directories found matching 'build' 18:32:19 INFO - no previously-included directories found matching 'dist' 18:32:19 INFO - no previously-included directories found matching '.git*' 18:32:19 INFO - Successfully installed jsonschema functools32 18:32:19 INFO - Cleaning up... 18:32:19 INFO - Return code: 0 18:32:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:19 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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, '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.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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:32:19 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:32:19 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:32:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render', 18:32:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:19 INFO - 'HOME': '/Users/cltbld', 18:32:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:19 INFO - 'LOGNAME': 'cltbld', 18:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:19 INFO - 'MOZ_NO_REMOTE': '1', 18:32:19 INFO - 'NO_EM_RESTART': '1', 18:32:19 INFO - 'PAGER': '/bin/cat', 18:32:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:19 INFO - 'PWD': '/builds/slave/test', 18:32:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:19 INFO - 'SHELL': '/bin/bash', 18:32:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners', 18:32:19 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 18:32:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:19 INFO - 'USER': 'cltbld', 18:32:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:19 INFO - 'XPC_FLAGS': '0x0', 18:32:19 INFO - 'XPC_SERVICE_NAME': '0', 18:32:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:19 INFO - Downloading/unpacking flake8 18:32:19 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:32:19 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:32:19 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:32:19 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:32:19 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:32:19 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:32:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:32:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:32:22 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:32:22 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:32:22 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:32:22 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:32:22 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:32:22 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:32:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:32:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:32:22 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:32:22 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:32:22 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:32:22 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:32:22 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:32:22 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:32:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:32:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:32:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:32:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:32:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:32:23 INFO - no previously-included directories found matching 'docs/_build' 18:32:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:32:23 INFO - Running setup.py install for pep8 18:32:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:32:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:32:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:32:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:32:23 INFO - no previously-included directories found matching 'docs/_build' 18:32:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:32:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:32:23 INFO - Cleaning up... 18:32:23 INFO - Return code: 0 18:32:23 INFO - Running post-action listener: _resource_record_post_action 18:32:23 INFO - Running post-action listener: _start_resource_monitoring 18:32:23 INFO - Starting resource monitoring. 18:32:23 INFO - ##### 18:32:23 INFO - ##### Finished create-virtualenv step (success) 18:32:23 INFO - ##### 18:32:23 INFO - ##### 18:32:23 INFO - ##### Running install step. 18:32:23 INFO - ##### 18:32:23 INFO - Running pre-action listener: _resource_record_pre_action 18:32:23 INFO - Running main action method: install 18:32:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:32:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:32:24 INFO - Reading from file tmpfile_stdout 18:32:24 INFO - Detecting whether we're running mozinstall >=1.0... 18:32:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:32:24 INFO - Reading from file tmpfile_stdout 18:32:24 INFO - Output received: 18:32:24 INFO - Usage: mozinstall [options] installer 18:32:24 INFO - Options: 18:32:24 INFO - -h, --help show this help message and exit 18:32:24 INFO - -d DEST, --destination=DEST 18:32:24 INFO - Directory to install application into. [default: 18:32:24 INFO - "/builds/slave/test"] 18:32:24 INFO - --app=APP Application being installed. [default: firefox] 18:32:24 INFO - mkdir: /builds/slave/test/build/application 18:32:24 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:32:24 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:32:58 INFO - Reading from file tmpfile_stdout 18:32:58 INFO - Output received: 18:32:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:32:58 INFO - Running post-action listener: _resource_record_post_action 18:32:58 INFO - ##### 18:32:58 INFO - ##### Finished install step (success) 18:32:58 INFO - ##### 18:32:58 INFO - ##### 18:32:58 INFO - ##### Running run-tests step. 18:32:58 INFO - ##### 18:32:58 INFO - Running pre-action listener: _resource_record_pre_action 18:32:58 INFO - Running main action method: run_tests 18:32:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:32:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:32:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:32:58 INFO - 2016-05-24 18:32:58.289 screenresolution[1645:7600] starting screenresolution argv=screenresolution get 18:32:58 INFO - 2016-05-24 18:32:58.292 screenresolution[1645:7600] Display 0: 1600x1200x32@60 18:32:58 INFO - 2016-05-24 18:32:58.298 screenresolution[1646:7602] starting screenresolution argv=screenresolution list 18:32:58 INFO - Available Modes on Display 0 18:32:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:32:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:32:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:32:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:32:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:32:58 INFO - Intel Iris: 18:32:58 INFO - Chipset Model: Intel Iris 18:32:58 INFO - Type: GPU 18:32:58 INFO - Bus: Built-In 18:32:58 INFO - VRAM (Dynamic, Max): 1536 MB 18:32:58 INFO - Vendor: Intel (0x8086) 18:32:58 INFO - Device ID: 0x0a2e 18:32:58 INFO - Revision ID: 0x0009 18:32:58 INFO - Displays: 18:32:58 INFO - Display: 18:32:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:32:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:32:58 INFO - Display Serial Number: 1600x1200 60 18:32:58 INFO - Main Display: Yes 18:32:58 INFO - Mirror: Off 18:32:58 INFO - Online: Yes 18:32:58 INFO - Rotation: Supported 18:32:58 INFO - Return code: 0 18:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:32:58 INFO - Python 2.7.3 18:32:58 INFO - Return code: 0 18:32:58 INFO - grabbing minidump binary from tooltool 18:32:58 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:32:58 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:32:58 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:32:58 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:32:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:32:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:32:58 INFO - Return code: 0 18:32:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:32:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:32:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:32:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:32:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:32:59 INFO - Return code: 0 18:32:59 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/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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:32:59 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/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0246 --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:32:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render', 18:32:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:59 INFO - 'HOME': '/Users/cltbld', 18:32:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:59 INFO - 'LOGNAME': 'cltbld', 18:32:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:32:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:59 INFO - 'MOZ_NO_REMOTE': '1', 18:32:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:32:59 INFO - 'NO_EM_RESTART': '1', 18:32:59 INFO - 'PAGER': '/bin/cat', 18:32:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:59 INFO - 'PWD': '/builds/slave/test', 18:32:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:32:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:59 INFO - 'SHELL': '/bin/bash', 18:32:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners', 18:32:59 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 18:32:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:59 INFO - 'USER': 'cltbld', 18:32:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:59 INFO - 'XPC_FLAGS': '0x0', 18:32:59 INFO - 'XPC_SERVICE_NAME': '0', 18:32:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:59 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/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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:32:59 INFO - mozversion application_buildid: 20160524164650 18:32:59 INFO - mozversion application_changeset: 788365ddaf61746bc66479335ae72d3e566c38ec 18:32:59 INFO - mozversion application_display_name: Nightly 18:32:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:32:59 INFO - mozversion application_name: Firefox 18:32:59 INFO - mozversion application_remotingname: firefox 18:32:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:32:59 INFO - mozversion application_vendor: Mozilla 18:32:59 INFO - mozversion application_version: 49.0a1 18:32:59 INFO - mozversion platform_buildid: 20160524164650 18:32:59 INFO - mozversion platform_changeset: 788365ddaf61746bc66479335ae72d3e566c38ec 18:32:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:32:59 INFO - mozversion platform_version: 49.0a1 18:32:59 INFO - using testdate: 1464139979 18:32:59 INFO - actual date: 1464139979 18:32:59 INFO - starting webserver on 'localhost:49270' 18:32:59 INFO - SUITE-START | Running 2 tests 18:32:59 INFO - TEST-START | dromaeo_css 18:32:59 INFO - operating with platform_type : mac_ 18:32:59 INFO - Initialising browser for dromaeo_css test... 18:33:00 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp4KsxGJ/profile http://localhost:49270/getInfo.html) 18:33:02 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 18:33:02 INFO - PROCESS | 1664 | colorDepth:24 18:33:02 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 18:33:02 INFO - PROCESS | 1664 | __metrics 18:33:02 INFO - PROCESS | 1664 | 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:33:02 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:33:03 INFO - TEST-INFO | 1664: exit 0 18:33:03 INFO - Browser initialized. 18:33:03 INFO - Running cycle 1/1 for dromaeo_css test... 18:33:03 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp4KsxGJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:33:15 INFO - PROCESS | 1666 | RSS: Main: 239005696 18:33:15 INFO - PROCESS | 1666 | 18:34:06 INFO - PROCESS | 1666 | 1464140046071 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:36:17 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 18:36:18 INFO - PROCESS | 1666 | RSS: Main: 323837952 18:36:18 INFO - PROCESS | 1666 | 18:39:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 18:39:22 INFO - PROCESS | 1666 | RSS: Main: 375353344 18:39:22 INFO - PROCESS | 1666 | 18:42:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 18:42:25 INFO - PROCESS | 1666 | RSS: Main: 370434048 18:42:25 INFO - PROCESS | 1666 | 18:45:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 18:45:28 INFO - PROCESS | 1666 | RSS: Main: 369668096 18:45:28 INFO - PROCESS | 1666 | 18:48:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 18:48:31 INFO - PROCESS | 1666 | RSS: Main: 338694144 18:48:31 INFO - PROCESS | 1666 | 18:51:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 18:51:34 INFO - PROCESS | 1666 | RSS: Main: 379039744 18:51:34 INFO - PROCESS | 1666 | 18:51:34 INFO - PROCESS | 1666 | __start_tp_report 18:51:34 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 18:51:34 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 18:51:34 INFO - PROCESS | 1666 | |i|pagename|runs| 18:51:34 INFO - PROCESS | 1666 | |0;dojo.html;3425.81158036308;3458.135466133468;3470.646447885325;3491.3540994915957;3522.5244591980077;5621;5643.858903527409;5665.660060396369;5792.884142317156;5798.420157984199;5670.121131224657;5749.396313387097;5857.150713146601;5900;5950.285965684113;5020.924686129711;5052.9242061369105;5116.769745361449;5148.665336753118;5190.403103643072;5203.1414816555225;5208.479152084799;5218.947810521884;5249.396319423258;5252.737363131847;5053.620978426633;5080.847574572768;5096.464871188546;5159.458256883029;5170.095233334151;5138.332016837825;5146.691198528063;5148.9702059588235;5159.871003224912;5163.586913046947;5046.19260918255;5102.540771330561;5104.055749686283;5107.770150343244;5113.156329205676;5529.889402211933;5539.50144486994;5601.523870471015;5689.687067211302;5696.8006119785605;6196.411340922605;6241.469475094577;6273.404026617464;6275.09011193379;6297.527685423612;6150.523874270188;6205;6282.308946015934;6331.968340158316;6333.524985626051;5826.679532625704;5884.205632239678;5908.675022873697;5920.289565252111;5930.584859059867;6250.406211409909;6266.874662506724;6276.780312689125;6281.214848143982;6286.339934306923;5021.949780502148;5984.2818861736;6134.539909506805;6174.938250617436;6194.81415557525;6242.781502647385;6294.276158241861;6297.874042519124;6302.621842689453;6307.211598550181;6246.969250073777;6247.656378899113;6251.78118765841;6271.27880293763;6292.685365731695;5501.669899806025;5977.611455255482;6108.04631976549;6147.846303842351;6258.3741625837965;4925.13810083234;4948.802047918043;4949.381327334083;4955.8513244602;5001.799928002839;4428.4685837699035;4433.113377324548;4454.7549884756;4462.620677242406;4505.36924830524;5530.861728456821;5576.079946808731;5607.943920560742;5678.659280443187;5701.686407247641;6232.283287421914;6259.906101408483;6293.087502312231;6313.747450101944;6323.683815809191;5650.26546548946;5665.065264231438;5676.063449530864;5676.290463692038;5721.942780572224;5233.528982391603;5334.333208348957;5344;5407.675539467645;5444.6188766785945;6314.778982735582;6315.842103947346;6331.9366806331345;6338.873222535616;6347.206599175103;6251.093591429309;6256.0303153011355;6296.748130074746;6301.558890877594;6303.653299068412;6180.907286390618;6229.345918678474;6263.248410190807;6271.153394291712;6306.684665766785;6201.96899015502;6255.90521658717;6265.624062556261;6268.592541484789;6271;5524.281843360337;6158.661273629993;6275.717592708431;6299.748010079546;6319.115323854756;5910.290765108214;5986.16193732869;6063.242094738158;6094.51243900478;6106;6085.117657939485;6090.086487026982;6097.085437184458;6105.877882442415;6109.236345456818;5888.4700376966275;5901.262342207224;5915.349311575809;5936.851578710567;6017.759289628366;5926.407359264039;5935.792247271324;5941.465268125889;5963.254593175853;6005.129256257906;5804.738786754522;5833.649981001154;5845.1232315153065;5848.239728835394;5856.824295271149 18:51:34 INFO - PROCESS | 1666 | |1;ext.html;505.82900585145387;512.1038183179434;520.336570872138;524.0802391802389;548.478945002248;28700.712992870063;41604.37593436101;42684;42704;42768.57231427685;44151.558484415145;44452.110957780824;44604.55395446053;44678.553214467844;44693.55306446943;8453.57732113395;8653.913460865406;9484.478353690543;9672.613095476172;9688.273379496532;11370.260933039368;11634.360110193937;11670.35813030283;11800.291982481078;11923.224990375598;11651.300921944669;11655.883441165606;11671.708207294801;11677.357745324003;11711.7072073198;8539.957300213522;11466.94266528662;11644.30134191947;11742.765144697058;11749.001334886545;11658;11752.941235293854;11752.941235293854;11758.353290569015;11771.411429428494;40714.18571628551;40866.795666021775;41239;41287;41300.58699413027;3355.74831887607;3449;3522.7710198836994;3654.7807131571926;3908.6873050156205;28998.13005609835;29262.122136335944;30314.84842575795;30330.69669303301;30378.240543986358;2628;2635.5783074707956;2765.9308517287027;2769.1138835572624;2775.791815613837;524549;525460;525787;527312.3634381841;527694;62403.6879815598;62612.68693656503;62955.68522157452;63092.05361919575;63174.68412657907;62784.37215627785;63755.36244637587;65075;65130;65133.34866651273;16177.676446471005;16291;16507.422240221535;16519.174041297887;16549;11935.985441237419;12194.085443591588;12866.356682165855;13167.012474064484;13176;16259.756103658454;16309.227431336463;16742.58143546421;16758.91067080636;16902.985820850787;5757.366689664133;5961.344252132262;6726.226483954408;6883.690233939485;6976.476764242702;734.5225603357753;882.6513527156737;908.6592527802075;994.2195376629311;1011.079917275276;26849;28923.855380722962;29609.703902961126;29671.258218544277;29675.554866677016;1025.553884060436;1089.090609341209;1097.1113398147527;1115.7712668902855;1117.7876203521469;12305.507779688713;12313.753724925451;12950.740985180244;14907.403703851724;15234;676433;681135;681802;682561;682584;59895.700521497114;60016.69991650101;60088;60168.39831601628;60203.698981504815;1136.9772604547863;1432.4485073246874;1475.7417451945867;1487.6355292953297;1488.24843454055;25445.982160713364;26246;29322;30278.09165725032;30344.54483182798;48619;51139.488605113474;51163.488365115874;51279.743601281756;51336.74331628318;538960;558174;558288;558666;560407;33773.15567110842;39138.41292380673;39247.607523924395;39670;39899.60100398959;46297.6110716679;46475;46793.76603116962;48000;48440;37810.865674029825;37983.430248545745;38134.809325953196;38632.6136738629;38672.613273866904;31649.8417507911;37588.436173456874;37626;38346.0413489665;38548.229035418575;33127.83436082804;36849;37304.813475932446;37757;38518.614813851505;32157.678423215468;33051.504227436126;33393;33553.32893342071;33643.99068027963;19736.40790776279;23898.163564275168;24451.877740611184;24635.63046554339;24865.378365541008 18:51:34 INFO - PROCESS | 1666 | |2;jquery.html;362.5051804287148;387.79253099591716;389.62401282762147;400.19560683027106;407.17953324052024;19633.705494417576;21548.461288467795;21978.89010554947;21996.89001554992;22383.992720327584;20612.89693551534;20653;21450;21860.8906955465;21901.452463688416;5128.48715128486;5384.40771515133;5391.838244852641;5401.837944861661;5403.162509810985;3050.3136794221346;4028.858989935353;5225.712585807779;5649.943500565003;5959.70201489926;5982.79060232892;5993.4006599340155;6003.30961939376;6010.8497287567725;6014.729337179838;5868.970655146718;5872;5950.55370847186;5952.70236488176;6047.274327080734;3738.9439158412647;5393.541548968343;5710.486056254957;5710.71446427677;5811.941880581182;10048.44733539655;11973.40132993347;12481.126321157522;12514.74970500594;13740.65648358789;1902.4958386027713;2034.9084291206932;2253.9887300563555;2341.9297421077395;2444.4377793107683;8968.013518512958;9647.469389183592;9716.562754675986;9730.854037189449;10312.329698569569;1458.9197594132318;1667.608112093657;1691.8139004709471;1711.3069206971197;1733.7226043832927;110241;117142;119332;128066;129296.3535182318;11229.27009744364;11261;11309.49107290174;11532.481038353126;11641.243319184226;12107.39463026845;14555.490557830424;14656.706865862836;14688.632784180267;14698.338574763948;8619.870701939475;8621.267192288726;8661.263792577702;8716.43343182691;8814.779630509289;8415.116412776693;8598.613062412207;8670.523121228392;8758.56207189638;8768.517731524827;3929.410588411704;4574.519675434098;4584.151931892544;4610.400647915817;4640.559146881041;1604.7994000749907;1774.3434929076325;1793.22483869242;1823.3076187503427;1913.2926908424702;877.7761670774039;956.416585882611;969.2827307792325;988.4761076629397;1000.5847573257017;19976.700349494768;20608.454365922615;20652.96735163236;20672.48318792042;20862.687059694115;907.8865141857268;921.2959962509921;951.5147274889717;997.804829375377;1002.3484734922358;10092.949535252423;10148.543315550818;10696.94651526753;10834.266517357186;10899.782004360028;592743;595192;600171;608137;613124;104639;115751.2637310441;127307;134508.6549134496;146820;838.7693384319239;981.6061193105807;986.063239922077;1021.5096753558186;1023.3961962442195;18040;18256.178471968527;18316.72524912154;18330.725039124158;18374.448766537025;52296;52852;53255;53389.73305133605;53590.46409535855;74244.62877685577;74796.62601687174;75308.24691753232;75882;76967.61516192384;5355.3305836770405;7404.851902962018;7598.506097103671;7823.100343460575;7846.882296765667;10457.84313235287;12025.27848329068;12187.729381276096;12193.451294691937;12408.193467424588;8546.871796922927;10338.534765935701;10466.947665261625;10529.210309226992;10670.253082284165;9034.683786067457;10181.592736290466;10341.4312212826;10396.48017599132;10638.41488718128;10569.260151789302;10666;10743.677689669323;10911.399873007062;10961;7699.422543309162;7946.32456241226;7968.960155199419;7973.401994850526;8190.180981901762;9110.773748786529;9446.858297125686;9862.013798620368;10678.092362149304;10694.465276736288 18:51:34 INFO - PROCESS | 1666 | |3;mootools.html;1389.5414513210642;1546.1959780913921;1576.4009676322996;1815.4825874625726;1980.1188471130345;7787.64955576999;8682.956585217082;8824.249938755198;8884.511351875644;9018.774530636738;8624.310055195585;8708.738737837875;8708.782280442992;8850.645974161042;8853.026167121627;2069.286096296775;2270.8069814065825;2318.791308782205;2349.400902769791;2352.5177338645535;2383.630537266718;2398.3764221302413;2403.471236328005;2406.254061241012;2412.8431651942656;1993.372087792348;2326.7673232676766;2365.7042869641296;2366.5148644527917;2371.430856594422;1954.6188493243824;2207.6357401028813;2267.365137761413;2335.1943579465055;2338.298510446859;2348.4833336665906;2365.042157926043;2366.928992130239;2368.5973384524673;2370.4192472844093;8793.516356600385;8854.114588541095;8859.06979767128;8859.689910853154;8889.333300002461;5220.138677118271;5277.6305658603915;5371.946280537184;5375.354957405098;5400.513953744143;3338.6327503974544;3373.443381841993;3571.1786289153642;3571.8392672329805;3579.3736096183065;877.2148926710558;996.5814357969598;997.9900200998043;1016.588281745894;1045.5869931377063;11825.586104486301;11876.643700688906;11884.821727674092;11896.583619573274;11907.583234586747;4643.187442197602;4764.523547645277;4820.180569303227;4832.855014349575;4843.733594652328;9959.352642078242;9971.650992215238;10015.899841001643;10024;10041.548130334153;5328.92006619901;5409.459054094559;5412.377576578665;5421.864453388697;5426.077566813651;4366.825326986885;4531.025829446685;4741.2176990796825;4890.388701412324;4934.358533390637;3883.747556408881;3948.1314110895555;3990.0423898264207;4029.979850100731;4052.615001574846;1167.7197472606683;1266.316189257809;1293.7994610835146;1313.3170751209318;1327.0644195841908;822.2229992656881;858.8797568340437;905.3391024551983;920.4201353147608;943.7726964364095;5605.271314729059;5779.855503612359;5887.234659494324;5986.281646202484;5996.687354073949;438.36218302369974;438.48478038304864;444.56876829475357;450.9368688383629;461.10545541649094;3832.789196594214;3883.689304855605;3930;3983.780892050907;3997.6801855851463;10506.212034097472;10838.67483975482;10868.402237876993;10923.836142457869;10960.013598776148;10161.237907156992;10764.730881728019;10838.67483975482;10852.077573406192;10886.29239099456;8091.109977902426;8215.548144852091;8315.95842020786;8331.500109993298;8334.874976875351;5843.649381037151;5869.297903607906;5933.525317974465;6072.937235983597;6187.1647327612;9551.235901127755;10063.748406289902;10137.884832668547;10230.539625716712;10246.026627470379;10052.64815731446;10111.848322275024;10242.897571024492;10290;10344.844827327746;6393.487474620461;6887.793366199022;7129.607871567114;7418.295261950106;7611.543307401574;7893.605319734105;8366.33069354438;8474.152584741476;8479.279261262862;8510.361722870686;7390.852182956418;7453.776386708408;7473.028506294147;7585.658645361083;7660.463767536219;7279;7343.853122937405;7362.815929601803;7410.333070023508;7508;6842.55523390978;6996.440284777103;7006.124234470692;7012.509124361246;7024.473164512784;4780.713157210578;4834.250691142878;4913.06651736169;4930.901381972412;5104.2343648453025;4886.364772579542;4943.134951383566;4954.083494553519;4981.70109793399;5176.948230517647 18:51:34 INFO - PROCESS | 1666 | |4;prototype.html;304.30162776428097;307.8799268285368;313.08266778339464;315.55034076441063;318.41411802283795;4922;5118.564921981631;5432.311006052;5514.91727624086;5552.972235138819;4469.888252793682;4546.635749410305;5389.137737961927;5389.676619402839;5412.83761487156;3398.779079359846;3549.988253347796;3854.383298672213;3989.780562069085;4063.4108054332105;3115.115355210099;3465.220325426784;3651.561812582494;3928.998105483098;4035.6367926886574;4012.5184977802705;4025.859094931678;4038.2327357801923;4041.737287076345;4066.8983275418054;3862.806859657006;3975.4434379186937;4024.9195016099807;4048.392741088831;4075.8777236682945;2982.492976194052;3510.33303672304;3513.1744040150543;3519.63043880394;3537.2218112015316;5091;5107;5117.411497677777;5126.820561280375;5167.612429067797;3864.8260828262514;4076.857309994136;4147.921540399488;4387.166438376698;4604.204360299486;4782.880427989293;5300.840974770763;5372.54333381663;5610.326760788691;5616.691081990489;3483.477478378238;3798.841353387216;3927;3952.940705889414;3956.881293561169;5551.750171242304;5862.443067908584;5894.789453954767;5912.34964153947;5951.553633477506;3334.6498617645284;3596.3526565218513;3751.531058617673;3859.807009649506;3904.3557812960544;3934.403293826061;3944.783036932939;3967.504061992251;3974.5627980922077;3983.3029219886985;3943.369060950234;3980.7014473914574;4323.832565207376;4661.720296782204;4666.300054991711;4964.641795147276;5287.550558202596;5448.828501872037;5509.779608815683;5580.381689310055;1296.397175313465;1348.2315080404271;1353.8781509664177;1356.796480527909;1365.7336819320137;730.9195988441268;734.7391675954959;808.7048227396958;820.9425340226246;825.5005721538503;4375.5405682403525;4674.994876101655;4780.617550596014;4849.599448273128;5301.284326615934;4655.348251244828;4729.763511824395;5785.739641716133;5793.333766616809;5861.443162899518;4945.950540494621;5264.92102618461;5317.202419637086;5339.01228272763;5368.436314187033;2533.5946248600135;3008.518637018067;3039.9240018999703;3051.725344718986;3060.6786287439945;5187.406296851575;5778.16216648592;5984.611000284967;6017.338092809788;6047.365026672224;4683.297505374153;5123.641345105882;5375.3280839895015;5914.112883067574;6033.849153771103;5099.388073431212;5217.973910130425;6065.2418447694035;6080.9391906081255;6083.878322433615;5671.262735844314;5851.707414629166;5871.796281762314;5890.587658863838;5899.793507227227;5718.342390625131;5758.387651457599;5767.769289228551;5768.480836724715;5788.594798364074;4839.395075615548;5346.65246758975;5551.8889622206525;6053.909191362045;6059.6364218147055;3507.5440192774777;3681.8645185791643;3684.4289135183017;3850;3888.902777430587;5687.573431992534;5797.739101740516;5815.302163740209;5822.155787410887;5830.329512106162;5024.84925452237;5034.539983404771;5638.126090455986;5660.320761508646;5680.40355762639;4974.353334066548;5306.975024236148;5413.269208656781;5663.915041274467;5681;5505.504504594445;5517.503424691797;5531.668099914018;5534.557235421237;5540.722963851711;4794.352762377174;5383.838484845304;5451.555337835397;5524.889502210013;5621.016322143691;4932.7286999215385;5543.667379957215;5557.9709058563985;5566.524870909293;5579.856129493363 18:51:34 INFO - PROCESS | 1666 | |5;yui.html;618.227215980025;630.9905351419727;652.7225928980185;668.1547841979893;747.19676347926;14920.850791492081;15033.398664053439;15231.238438078079;15238.542843714678;15260.542183734491;13514.391852366642;14268.143911365325;14349.210793406384;14367.35346909389;14369.281535923214;719.6509692798988;890.1143362354467;900.954952252388;909.9499527525984;910.7495438754329;732.0227496292456;888.1518150166607;914.7301546043933;917.4082716647758;920.102899672817;931.5156118818211;954.4511905654242;954.7279025477741;955.6416343871049;958.1233171647934;754.811297175706;922.9676961306322;947.0576776107782;949.924006079512;950.7100334398006;709.0923617769264;709.1313141401793;709.6238993333542;711.498393632493;712.291270186168;14862.256887155707;14995.925020374938;15002.24988750558;15003.549893503212;15028.849711502964;12231.449584768618;12280.631581052583;12347.320897350643;12357.135000549964;12573.11988160829;10711.571537138505;10954.068904143078;10965.177611679077;11310.151738619636;11311.547538098466;3965.1078507335906;3983.810493382646;4003.459532963042;4043.9460012516465;4164.292070348048;85615;88084;88376;88406;89228.55385723095;9407.388519746195;9418.105279998526;9447.905520944703;9458.243340532741;9875.456849873326;21920.89039554792;22094.668579971312;22116;22147.231596050493;22152;7110.253423390573;7223.494355395177;7315.853682926312;7370.078740157481;7495.8500829983095;3818.427235914635;3847.615238476186;3997.2804895119157;4004.839806407769;4008.5390180129075;3410.0963244740165;3422.7261819054415;3426.811525366079;3508.6315936826777;3513.2622149348413;563.4168635462277;595.8410890817405;614.4377894226733;634.194572892424;647.8445173158408;467.41806450968085;495.2892599120202;545.9153831156177;551.2640624765902;564.3989151553583;6460.515461340418;7639.93040974264;7718.53688778675;7780.3386712130105;7998.880016799752;535.7589084911727;537.492069993852;554.0995881692251;577.6707276852154;578.6961845031392;3585;3659.688926441229;3750.6061863504483;3759.586445490994;3775.7923314217983;65747.67126164338;93170;95824;96329;99342.5032874831;78084.60957695176;83348;84877.57561212154;85562.57218713866;85814.14185858061;71974;72311;72348.63825680838;72561;73331;6909.754329842922;6936.994135850173;7222.6388680566815;7315.122185337581;7451.4038876888635;45398.77300613476;46161.076778464914;46186.538134618226;46801.063978720915;47155;58539.70730146322;59639.105413419704;59710.40289597048;64483.677581611795;65742.67128664517;9741.220702343653;9982.101610854768;10273.9486302568;10283.588656453958;10343.63797267092;10359.896401035892;10414.531346089596;10442.425666588108;10461.320014199357;10710.518026688662;9668.70993870185;9851.556679949275;9900.257480688833;9994.850077248993;10286.948565257126;9977.401355918668;10003.899961000297;10027.649032283834;10107.69676909694;10433.478326083818;9707.6116955321;9731.562079706571;9794.412335259907;9848.803023939625;10191.796164076824;8385.748427547183;8493.872591911002;8561.82876342482;8598.570071496526;8920.776980575538;8492.872606911025;8609.182127697859;8668.78328041804;8799.296056315607;8811.427257228259 18:51:34 INFO - PROCESS | 1666 | __end_tp_report 18:51:34 INFO - PROCESS | 1666 | __start_cc_report 18:51:34 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1307 18:51:34 INFO - PROCESS | 1666 | __end_cc_report 18:51:34 INFO - PROCESS | 1666 | __startTimestamp1464141094861__endTimestamp 18:51:34 INFO - PROCESS | 1666 | 18:51:34 INFO - PROCESS | 1666 | ------- Summary: start ------- 18:51:34 INFO - PROCESS | 1666 | Number of tests: 6 18:51:34 INFO - PROCESS | 1666 | 18:51:34 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5723.40 Median:5910.29 stddev:635.48 (10.8%) stddev-sans-first:611.33 18:51:34 INFO - PROCESS | 1666 | Values: 3425.8 3458.1 3470.6 3491.4 3522.5 5621.0 5643.9 5665.7 5792.9 5798.4 5670.1 5749.4 5857.2 5900.0 5950.3 5020.9 5052.9 5116.8 5148.7 5190.4 5203.1 5208.5 5218.9 5249.4 5252.7 5053.6 5080.8 5096.5 5159.5 5170.1 5138.3 5146.7 5149.0 5159.9 5163.6 5046.2 5102.5 5104.1 5107.8 5113.2 5529.9 5539.5 5601.5 5689.7 5696.8 6196.4 6241.5 6273.4 6275.1 6297.5 6150.5 6205.0 6282.3 6332.0 6333.5 5826.7 5884.2 5908.7 5920.3 5930.6 6250.4 6266.9 6276.8 6281.2 6286.3 5021.9 5984.3 6134.5 6174.9 6194.8 6242.8 6294.3 6297.9 6302.6 6307.2 6247.0 6247.7 6251.8 6271.3 6292.7 5501.7 5977.6 6108.0 6147.8 6258.4 4925.1 4948.8 4949.4 4955.9 5001.8 4428.5 4433.1 4454.8 4462.6 4505.4 5530.9 5576.1 5607.9 5678.7 5701.7 6232.3 6259.9 6293.1 6313.7 6323.7 5650.3 5665.1 5676.1 5676.3 5721.9 5233.5 5334.3 5344.0 5407.7 5444.6 6314.8 6315.8 6331.9 6338.9 6347.2 6251.1 6256.0 6296.7 6301.6 6303.7 6180.9 6229.3 6263.2 6271.2 6306.7 6202.0 6255.9 6265.6 6268.6 6271.0 5524.3 6158.7 6275.7 6299.7 6319.1 5910.3 5986.2 6063.2 6094.5 6106.0 6085.1 6090.1 6097.1 6105.9 6109.2 5888.5 5901.3 5915.3 5936.9 6017.8 5926.4 5935.8 5941.5 5963.3 6005.1 5804.7 5833.6 5845.1 5848.2 5856.8 18:51:34 INFO - PROCESS | 1666 | 18:51:34 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:72153.42 Median:37607.22 stddev:158046.26 (420.3%) stddev-sans-first:158398.07 18:51:34 INFO - PROCESS | 1666 | Values: 505.8 512.1 520.3 524.1 548.5 28700.7 41604.4 42684.0 42704.0 42768.6 44151.6 44452.1 44604.6 44678.6 44693.6 8453.6 8653.9 9484.5 9672.6 9688.3 11370.3 11634.4 11670.4 11800.3 11923.2 11651.3 11655.9 11671.7 11677.4 11711.7 8540.0 11466.9 11644.3 11742.8 11749.0 11658.0 11752.9 11752.9 11758.4 11771.4 40714.2 40866.8 41239.0 41287.0 41300.6 3355.7 3449.0 3522.8 3654.8 3908.7 28998.1 29262.1 30314.8 30330.7 30378.2 2628.0 2635.6 2765.9 2769.1 2775.8 524549.0 525460.0 525787.0 527312.4 527694.0 62403.7 62612.7 62955.7 63092.1 63174.7 62784.4 63755.4 65075.0 65130.0 65133.3 16177.7 16291.0 16507.4 16519.2 16549.0 11936.0 12194.1 12866.4 13167.0 13176.0 16259.8 16309.2 16742.6 16758.9 16903.0 5757.4 5961.3 6726.2 6883.7 6976.5 734.5 882.7 908.7 994.2 1011.1 26849.0 28923.9 29609.7 29671.3 29675.6 1025.6 1089.1 1097.1 1115.8 1117.8 12305.5 12313.8 12950.7 14907.4 15234.0 676433.0 681135.0 681802.0 682561.0 682584.0 59895.7 60016.7 60088.0 60168.4 60203.7 1137.0 1432.4 1475.7 1487.6 1488.2 25446.0 26246.0 29322.0 30278.1 30344.5 48619.0 51139.5 51163.5 51279.7 51336.7 538960.0 558174.0 558288.0 558666.0 560407.0 33773.2 39138.4 39247.6 39670.0 39899.6 46297.6 46475.0 46793.8 48000.0 48440.0 37810.9 37983.4 38134.8 38632.6 38672.6 31649.8 37588.4 37626.0 38346.0 38548.2 33127.8 36849.0 37304.8 37757.0 38518.6 32157.7 33051.5 33393.0 33553.3 33644.0 19736.4 23898.2 24451.9 24635.6 24865.4 18:51:34 INFO - PROCESS | 1666 | 18:51:34 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34515.06 Median:1706.41 stddev:100556.32 (5892.9%) stddev-sans-first:100805.70 18:51:34 INFO - PROCESS | 1666 | Values: 362.5 387.8 389.6 400.2 407.2 19633.7 21548.5 21978.9 21996.9 22384.0 20612.9 20653.0 21450.0 21860.9 21901.5 5128.5 5384.4 5391.8 5401.8 5403.2 3050.3 4028.9 5225.7 5649.9 5959.7 5982.8 5993.4 6003.3 6010.8 6014.7 5869.0 5872.0 5950.6 5952.7 6047.3 3738.9 5393.5 5710.5 5710.7 5811.9 10048.4 11973.4 12481.1 12514.7 13740.7 1902.5 2034.9 2254.0 2341.9 2444.4 8968.0 9647.5 9716.6 9730.9 10312.3 1458.9 1667.6 1691.8 1711.3 1733.7 110241.0 117142.0 119332.0 128066.0 129296.4 11229.3 11261.0 11309.5 11532.5 11641.2 12107.4 14555.5 14656.7 14688.6 14698.3 8619.9 8621.3 8661.3 8716.4 8814.8 8415.1 8598.6 8670.5 8758.6 8768.5 3929.4 4574.5 4584.2 4610.4 4640.6 1604.8 1774.3 1793.2 1823.3 1913.3 877.8 956.4 969.3 988.5 1000.6 19976.7 20608.5 20653.0 20672.5 20862.7 907.9 921.3 951.5 997.8 1002.3 10092.9 10148.5 10696.9 10834.3 10899.8 592743.0 595192.0 600171.0 608137.0 613124.0 104639.0 115751.3 127307.0 134508.7 146820.0 838.8 981.6 986.1 1021.5 1023.4 18040.0 18256.2 18316.7 18330.7 18374.4 52296.0 52852.0 53255.0 53389.7 53590.5 74244.6 74796.6 75308.2 75882.0 76967.6 5355.3 7404.9 7598.5 7823.1 7846.9 10457.8 12025.3 12187.7 12193.5 12408.2 8546.9 10338.5 10466.9 10529.2 10670.3 9034.7 10181.6 10341.4 10396.5 10638.4 10569.3 10666.0 10743.7 10911.4 10961.0 7699.4 7946.3 7969.0 7973.4 8190.2 9110.8 9446.9 9862.0 10678.1 10694.5 18:51:34 INFO - PROCESS | 1666 | 18:51:34 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5710.73 Median:4826.52 stddev:3256.98 (67.5%) stddev-sans-first:3249.93 18:51:34 INFO - PROCESS | 1666 | Values: 1389.5 1546.2 1576.4 1815.5 1980.1 7787.6 8683.0 8824.2 8884.5 9018.8 8624.3 8708.7 8708.8 8850.6 8853.0 2069.3 2270.8 2318.8 2349.4 2352.5 2383.6 2398.4 2403.5 2406.3 2412.8 1993.4 2326.8 2365.7 2366.5 2371.4 1954.6 2207.6 2267.4 2335.2 2338.3 2348.5 2365.0 2366.9 2368.6 2370.4 8793.5 8854.1 8859.1 8859.7 8889.3 5220.1 5277.6 5371.9 5375.4 5400.5 3338.6 3373.4 3571.2 3571.8 3579.4 877.2 996.6 998.0 1016.6 1045.6 11825.6 11876.6 11884.8 11896.6 11907.6 4643.2 4764.5 4820.2 4832.9 4843.7 9959.4 9971.7 10015.9 10024.0 10041.5 5328.9 5409.5 5412.4 5421.9 5426.1 4366.8 4531.0 4741.2 4890.4 4934.4 3883.7 3948.1 3990.0 4030.0 4052.6 1167.7 1266.3 1293.8 1313.3 1327.1 822.2 858.9 905.3 920.4 943.8 5605.3 5779.9 5887.2 5986.3 5996.7 438.4 438.5 444.6 450.9 461.1 3832.8 3883.7 3930.0 3983.8 3997.7 10506.2 10838.7 10868.4 10923.8 10960.0 10161.2 10764.7 10838.7 10852.1 10886.3 8091.1 8215.5 8316.0 8331.5 8334.9 5843.6 5869.3 5933.5 6072.9 6187.2 9551.2 10063.7 10137.9 10230.5 10246.0 10052.6 10111.8 10242.9 10290.0 10344.8 6393.5 6887.8 7129.6 7418.3 7611.5 7893.6 8366.3 8474.2 8479.3 8510.4 7390.9 7453.8 7473.0 7585.7 7660.5 7279.0 7343.9 7362.8 7410.3 7508.0 6842.6 6996.4 7006.1 7012.5 7024.5 4780.7 4834.3 4913.1 4930.9 5104.2 4886.4 4943.1 4954.1 4981.7 5176.9 18:51:34 INFO - PROCESS | 1666 | 18:51:34 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4511.23 Median:5103.19 stddev:1415.15 (27.7%) stddev-sans-first:1383.44 18:51:34 INFO - PROCESS | 1666 | Values: 304.3 307.9 313.1 315.6 318.4 4922.0 5118.6 5432.3 5514.9 5553.0 4469.9 4546.6 5389.1 5389.7 5412.8 3398.8 3550.0 3854.4 3989.8 4063.4 3115.1 3465.2 3651.6 3929.0 4035.6 4012.5 4025.9 4038.2 4041.7 4066.9 3862.8 3975.4 4024.9 4048.4 4075.9 2982.5 3510.3 3513.2 3519.6 3537.2 5091.0 5107.0 5117.4 5126.8 5167.6 3864.8 4076.9 4147.9 4387.2 4604.2 4782.9 5300.8 5372.5 5610.3 5616.7 3483.5 3798.8 3927.0 3952.9 3956.9 5551.8 5862.4 5894.8 5912.3 5951.6 3334.6 3596.4 3751.5 3859.8 3904.4 3934.4 3944.8 3967.5 3974.6 3983.3 3943.4 3980.7 4323.8 4661.7 4666.3 4964.6 5287.6 5448.8 5509.8 5580.4 1296.4 1348.2 1353.9 1356.8 1365.7 730.9 734.7 808.7 820.9 825.5 4375.5 4675.0 4780.6 4849.6 5301.3 4655.3 4729.8 5785.7 5793.3 5861.4 4946.0 5264.9 5317.2 5339.0 5368.4 2533.6 3008.5 3039.9 3051.7 3060.7 5187.4 5778.2 5984.6 6017.3 6047.4 4683.3 5123.6 5375.3 5914.1 6033.8 5099.4 5218.0 6065.2 6080.9 6083.9 5671.3 5851.7 5871.8 5890.6 5899.8 5718.3 5758.4 5767.8 5768.5 5788.6 4839.4 5346.7 5551.9 6053.9 6059.6 3507.5 3681.9 3684.4 3850.0 3888.9 5687.6 5797.7 5815.3 5822.2 5830.3 5024.8 5034.5 5638.1 5660.3 5680.4 4974.4 5307.0 5413.3 5663.9 5681.0 5505.5 5517.5 5531.7 5534.6 5540.7 4794.4 5383.8 5451.6 5524.9 5621.0 4932.7 5543.7 5558.0 5566.5 5579.9 18:51:34 INFO - PROCESS | 1666 | 18:51:34 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:18135.98 Median:650.28 stddev:26293.65 (4043.4%) stddev-sans-first:26334.51 18:51:34 INFO - PROCESS | 1666 | Values: 618.2 631.0 652.7 668.2 747.2 14920.9 15033.4 15231.2 15238.5 15260.5 13514.4 14268.1 14349.2 14367.4 14369.3 719.7 890.1 901.0 909.9 910.7 732.0 888.2 914.7 917.4 920.1 931.5 954.5 954.7 955.6 958.1 754.8 923.0 947.1 949.9 950.7 709.1 709.1 709.6 711.5 712.3 14862.3 14995.9 15002.2 15003.5 15028.8 12231.4 12280.6 12347.3 12357.1 12573.1 10711.6 10954.1 10965.2 11310.2 11311.5 3965.1 3983.8 4003.5 4043.9 4164.3 85615.0 88084.0 88376.0 88406.0 89228.6 9407.4 9418.1 9447.9 9458.2 9875.5 21920.9 22094.7 22116.0 22147.2 22152.0 7110.3 7223.5 7315.9 7370.1 7495.9 3818.4 3847.6 3997.3 4004.8 4008.5 3410.1 3422.7 3426.8 3508.6 3513.3 563.4 595.8 614.4 634.2 647.8 467.4 495.3 545.9 551.3 564.4 6460.5 7639.9 7718.5 7780.3 7998.9 535.8 537.5 554.1 577.7 578.7 3585.0 3659.7 3750.6 3759.6 3775.8 65747.7 93170.0 95824.0 96329.0 99342.5 78084.6 83348.0 84877.6 85562.6 85814.1 71974.0 72311.0 72348.6 72561.0 73331.0 6909.8 6937.0 7222.6 7315.1 7451.4 45398.8 46161.1 46186.5 46801.1 47155.0 58539.7 59639.1 59710.4 64483.7 65742.7 9741.2 9982.1 10273.9 10283.6 10343.6 10359.9 10414.5 10442.4 10461.3 10710.5 9668.7 9851.6 9900.3 9994.9 10286.9 9977.4 10003.9 10027.6 10107.7 10433.5 9707.6 9731.6 9794.4 9848.8 10191.8 8385.7 8493.9 8561.8 8598.6 8920.8 8492.9 8609.2 8668.8 8799.3 8811.4 18:51:34 INFO - PROCESS | 1666 | -------- Summary: end -------- 18:51:34 INFO - PROCESS | 1666 | 18:51:35 INFO - TEST-INFO | 1666: exit 0 18:51:35 INFO - TEST-OK | dromaeo_css | took 1115588ms 18:51:35 INFO - TEST-START | kraken 18:51:35 INFO - operating with platform_type : mac_ 18:51:35 INFO - Initialising browser for kraken test... 18:51:35 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpSZXEAA/profile http://localhost:49270/getInfo.html) 18:51:37 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 18:51:37 INFO - PROCESS | 1672 | colorDepth:24 18:51:37 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 18:51:37 INFO - PROCESS | 1672 | __metrics 18:51:38 INFO - PROCESS | 1672 | 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:51:38 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:51:38 INFO - TEST-INFO | 1672: exit 0 18:51:38 INFO - Browser initialized. 18:51:38 INFO - Running cycle 1/1 for kraken test... 18:51:38 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpSZXEAA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:51:50 INFO - PROCESS | 1674 | RSS: Main: 238948352 18:51:50 INFO - PROCESS | 1674 | 18:51:53 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 18:51:54 INFO - PROCESS | 1674 | RSS: Main: 286765056 18:51:54 INFO - PROCESS | 1674 | 18:51:56 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 18:51:57 INFO - PROCESS | 1674 | RSS: Main: 295157760 18:51:57 INFO - PROCESS | 1674 | 18:52:01 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 18:52:01 INFO - PROCESS | 1674 | RSS: Main: 382746624 18:52:01 INFO - PROCESS | 1674 | 18:52:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 18:52:04 INFO - PROCESS | 1674 | RSS: Main: 283062272 18:52:04 INFO - PROCESS | 1674 | 18:52:06 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:52:06 INFO - PROCESS | 1674 | RSS: Main: 287219712 18:52:06 INFO - PROCESS | 1674 | 18:52:09 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 18:52:10 INFO - PROCESS | 1674 | RSS: Main: 325533696 18:52:10 INFO - PROCESS | 1674 | 18:52:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 18:52:13 INFO - PROCESS | 1674 | RSS: Main: 326369280 18:52:13 INFO - PROCESS | 1674 | 18:52:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 18:52:17 INFO - PROCESS | 1674 | RSS: Main: 326008832 18:52:17 INFO - PROCESS | 1674 | 18:52:18 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:52:19 INFO - PROCESS | 1674 | RSS: Main: 312573952 18:52:19 INFO - PROCESS | 1674 | 18:52:21 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:52:22 INFO - PROCESS | 1674 | RSS: Main: 319840256 18:52:22 INFO - PROCESS | 1674 | 18:52:24 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:52:25 INFO - PROCESS | 1674 | RSS: Main: 321003520 18:52:25 INFO - PROCESS | 1674 | 18:52:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:52:28 INFO - PROCESS | 1674 | RSS: Main: 325861376 18:52:28 INFO - PROCESS | 1674 | 18:52:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:52:32 INFO - PROCESS | 1674 | RSS: Main: 323510272 18:52:32 INFO - PROCESS | 1674 | 18:52:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 18:52:34 INFO - PROCESS | 1674 | RSS: Main: 370688000 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | __start_tp_report 18:52:34 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 18:52:34 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 18:52:34 INFO - PROCESS | 1674 | |i|pagename|runs| 18:52:34 INFO - PROCESS | 1674 | |0;ai-astar;113;105;113;112;112;111;112;113;111;112 18:52:34 INFO - PROCESS | 1674 | |1;audio-beat-detection;153;117;118;168;116;118;139;115;149;114 18:52:34 INFO - PROCESS | 1674 | |2;audio-dft;183;185;185;183;188;186;183;182;182;185 18:52:34 INFO - PROCESS | 1674 | |3;audio-fft;78;57;60;87;59;57;64;68;83;57 18:52:34 INFO - PROCESS | 1674 | |4;audio-oscillator;89;69;104;70;76;69;96;70;69;102 18:52:34 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;93;129;90;88;91;87;91;87;89;87 18:52:34 INFO - PROCESS | 1674 | |6;imaging-darkroom;93;95;95;95;94;95;95;96;95;95 18:52:34 INFO - PROCESS | 1674 | |7;imaging-desaturate;84;84;85;84;85;84;84;84;84;90 18:52:34 INFO - PROCESS | 1674 | |8;json-parse-financial;57;56;58;55;56;56;55;76;58;54 18:52:34 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;66;68;74;76;71;72;69;71;69;69 18:52:34 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;86;86;89;102;88;85;85;86;84;87 18:52:34 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;140;141;138;140;138;139;146;146;179;157 18:52:34 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;161;167;165;164;167;163;166;163;163;216 18:52:34 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;65;66;66;67;66;65;67;67;67;65 18:52:34 INFO - PROCESS | 1674 | __end_tp_report 18:52:34 INFO - PROCESS | 1674 | __start_cc_report 18:52:34 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,2059 18:52:34 INFO - PROCESS | 1674 | __end_cc_report 18:52:34 INFO - PROCESS | 1674 | __startTimestamp1464141154067__endTimestamp 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | ------- Summary: start ------- 18:52:34 INFO - PROCESS | 1674 | Number of tests: 14 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:2.37 (2.1%) stddev-sans-first:2.44 18:52:34 INFO - PROCESS | 1674 | Values: 113.0 105.0 113.0 112.0 112.0 111.0 112.0 113.0 111.0 112.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:128.50 stddev:19.84 (15.4%) stddev-sans-first:19.34 18:52:34 INFO - PROCESS | 1674 | Values: 153.0 117.0 118.0 168.0 116.0 118.0 139.0 115.0 149.0 114.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:1.93 (1.0%) stddev-sans-first:2.00 18:52:34 INFO - PROCESS | 1674 | Values: 183.0 185.0 185.0 183.0 188.0 186.0 183.0 182.0 182.0 185.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:67.00 Median:66.00 stddev:11.55 (17.5%) stddev-sans-first:11.54 18:52:34 INFO - PROCESS | 1674 | Values: 78.0 57.0 60.0 87.0 59.0 57.0 64.0 68.0 83.0 57.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:70.00 stddev:14.74 (21.1%) stddev-sans-first:15.38 18:52:34 INFO - PROCESS | 1674 | Values: 89.0 69.0 104.0 70.0 76.0 69.0 96.0 70.0 69.0 102.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:89.50 stddev:12.74 (14.2%) stddev-sans-first:13.52 18:52:34 INFO - PROCESS | 1674 | Values: 93.0 129.0 90.0 88.0 91.0 87.0 91.0 87.0 89.0 87.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.79 (0.8%) stddev-sans-first:0.50 18:52:34 INFO - PROCESS | 1674 | Values: 93.0 95.0 95.0 95.0 94.0 95.0 95.0 96.0 95.0 95.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:84.00 stddev:1.87 (2.2%) stddev-sans-first:1.96 18:52:34 INFO - PROCESS | 1674 | Values: 84.0 84.0 85.0 84.0 85.0 84.0 84.0 84.0 84.0 90.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:56.50 stddev:6.42 (11.4%) stddev-sans-first:6.80 18:52:34 INFO - PROCESS | 1674 | Values: 57.0 56.0 58.0 55.0 56.0 56.0 55.0 76.0 58.0 54.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.00 stddev:2.95 (4.2%) stddev-sans-first:2.65 18:52:34 INFO - PROCESS | 1674 | Values: 66.0 68.0 74.0 76.0 71.0 72.0 69.0 71.0 69.0 69.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:86.00 stddev:5.20 (6.0%) stddev-sans-first:5.48 18:52:34 INFO - PROCESS | 1674 | Values: 86.0 86.0 89.0 102.0 88.0 85.0 85.0 86.0 84.0 87.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:143.50 stddev:12.83 (8.9%) stddev-sans-first:13.40 18:52:34 INFO - PROCESS | 1674 | Values: 140.0 141.0 138.0 140.0 138.0 139.0 146.0 146.0 179.0 157.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:165.50 stddev:16.45 (9.9%) stddev-sans-first:17.16 18:52:34 INFO - PROCESS | 1674 | Values: 161.0 167.0 165.0 164.0 167.0 163.0 166.0 163.0 163.0 216.0 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:0.88 (1.3%) stddev-sans-first:0.83 18:52:34 INFO - PROCESS | 1674 | Values: 65.0 66.0 66.0 67.0 66.0 65.0 67.0 67.0 67.0 65.0 18:52:34 INFO - PROCESS | 1674 | -------- Summary: end -------- 18:52:34 INFO - PROCESS | 1674 | 18:52:34 INFO - TEST-INFO | 1674: exit 0 18:52:34 INFO - TEST-OK | kraken | took 59338ms 18:52:34 INFO - SUITE-END | took 1174s 18:52:35 INFO - Completed test suite (00:19:35) 18:52:35 INFO - javascript benchmark 18:52:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3425.81158036308, 3458.135466133468, 3470.646447885325, 3491.3540994915957, 3522.5244591980077, 5621.0, 5643.858903527409, 5665.660060396369, 5792.884142317156, 5798.420157984199, 5670.121131224657, 5749.396313387097, 5857.150713146601, 5900.0, 5950.285965684113, 5020.924686129711, 5052.9242061369105, 5116.769745361449, 5148.665336753118, 5190.403103643072, 5203.1414816555225, 5208.479152084799, 5218.947810521884, 5249.396319423258, 5252.737363131847, 5053.620978426633, 5080.847574572768, 5096.464871188546, 5159.458256883029, 5170.095233334151, 5138.332016837825, 5146.691198528063, 5148.9702059588235, 5159.871003224912, 5163.586913046947, 5046.19260918255, 5102.540771330561, 5104.055749686283, 5107.770150343244, 5113.156329205676, 5529.889402211933, 5539.50144486994, 5601.523870471015, 5689.687067211302, 5696.8006119785605, 6196.411340922605, 6241.469475094577, 6273.404026617464, 6275.09011193379, 6297.527685423612, 6150.523874270188, 6205.0, 6282.308946015934, 6331.968340158316, 6333.524985626051, 5826.679532625704, 5884.205632239678, 5908.675022873697, 5920.289565252111, 5930.584859059867, 6250.406211409909, 6266.874662506724, 6276.780312689125, 6281.214848143982, 6286.339934306923, 5021.949780502148, 5984.2818861736, 6134.539909506805, 6174.938250617436, 6194.81415557525, 6242.781502647385, 6294.276158241861, 6297.874042519124, 6302.621842689453, 6307.211598550181, 6246.969250073777, 6247.656378899113, 6251.78118765841, 6271.27880293763, 6292.685365731695, 5501.669899806025, 5977.611455255482, 6108.04631976549, 6147.846303842351, 6258.3741625837965, 4925.13810083234, 4948.802047918043, 4949.381327334083, 4955.8513244602, 5001.799928002839, 4428.4685837699035, 4433.113377324548, 4454.7549884756, 4462.620677242406, 4505.36924830524, 5530.861728456821, 5576.079946808731, 5607.943920560742, 5678.659280443187, 5701.686407247641, 6232.283287421914, 6259.906101408483, 6293.087502312231, 6313.747450101944, 6323.683815809191, 5650.26546548946, 5665.065264231438, 5676.063449530864, 5676.290463692038, 5721.942780572224, 5233.528982391603, 5334.333208348957, 5344.0, 5407.675539467645, 5444.6188766785945, 6314.778982735582, 6315.842103947346, 6331.9366806331345, 6338.873222535616, 6347.206599175103, 6251.093591429309, 6256.0303153011355, 6296.748130074746, 6301.558890877594, 6303.653299068412, 6180.907286390618, 6229.345918678474, 6263.248410190807, 6271.153394291712, 6306.684665766785, 6201.96899015502, 6255.90521658717, 6265.624062556261, 6268.592541484789, 6271.0, 5524.281843360337, 6158.661273629993, 6275.717592708431, 6299.748010079546, 6319.115323854756, 5910.290765108214, 5986.16193732869, 6063.242094738158, 6094.51243900478, 6106.0, 6085.117657939485, 6090.086487026982, 6097.085437184458, 6105.877882442415, 6109.236345456818, 5888.4700376966275, 5901.262342207224, 5915.349311575809, 5936.851578710567, 6017.759289628366, 5926.407359264039, 5935.792247271324, 5941.465268125889, 5963.254593175853, 6005.129256257906, 5804.738786754522, 5833.649981001154, 5845.1232315153065, 5848.239728835394, 5856.824295271149], "value": 5683.84267179452, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.82900585145387, 512.1038183179434, 520.336570872138, 524.0802391802389, 548.478945002248, 28700.712992870063, 41604.37593436101, 42684.0, 42704.0, 42768.57231427685, 44151.558484415145, 44452.110957780824, 44604.55395446053, 44678.553214467844, 44693.55306446943, 8453.57732113395, 8653.913460865406, 9484.478353690543, 9672.613095476172, 9688.273379496532, 11370.260933039368, 11634.360110193937, 11670.35813030283, 11800.291982481078, 11923.224990375598, 11651.300921944669, 11655.883441165606, 11671.708207294801, 11677.357745324003, 11711.7072073198, 8539.957300213522, 11466.94266528662, 11644.30134191947, 11742.765144697058, 11749.001334886545, 11658.0, 11752.941235293854, 11752.941235293854, 11758.353290569015, 11771.411429428494, 40714.18571628551, 40866.795666021775, 41239.0, 41287.0, 41300.58699413027, 3355.74831887607, 3449.0, 3522.7710198836994, 3654.7807131571926, 3908.6873050156205, 28998.13005609835, 29262.122136335944, 30314.84842575795, 30330.69669303301, 30378.240543986358, 2628.0, 2635.5783074707956, 2765.9308517287027, 2769.1138835572624, 2775.791815613837, 524549.0, 525460.0, 525787.0, 527312.3634381841, 527694.0, 62403.6879815598, 62612.68693656503, 62955.68522157452, 63092.05361919575, 63174.68412657907, 62784.37215627785, 63755.36244637587, 65075.0, 65130.0, 65133.34866651273, 16177.676446471005, 16291.0, 16507.422240221535, 16519.174041297887, 16549.0, 11935.985441237419, 12194.085443591588, 12866.356682165855, 13167.012474064484, 13176.0, 16259.756103658454, 16309.227431336463, 16742.58143546421, 16758.91067080636, 16902.985820850787, 5757.366689664133, 5961.344252132262, 6726.226483954408, 6883.690233939485, 6976.476764242702, 734.5225603357753, 882.6513527156737, 908.6592527802075, 994.2195376629311, 1011.079917275276, 26849.0, 28923.855380722962, 29609.703902961126, 29671.258218544277, 29675.554866677016, 1025.553884060436, 1089.090609341209, 1097.1113398147527, 1115.7712668902855, 1117.7876203521469, 12305.507779688713, 12313.753724925451, 12950.740985180244, 14907.403703851724, 15234.0, 676433.0, 681135.0, 681802.0, 682561.0, 682584.0, 59895.700521497114, 60016.69991650101, 60088.0, 60168.39831601628, 60203.698981504815, 1136.9772604547863, 1432.4485073246874, 1475.7417451945867, 1487.6355292953297, 1488.24843454055, 25445.982160713364, 26246.0, 29322.0, 30278.09165725032, 30344.54483182798, 48619.0, 51139.488605113474, 51163.488365115874, 51279.743601281756, 51336.74331628318, 538960.0, 558174.0, 558288.0, 558666.0, 560407.0, 33773.15567110842, 39138.41292380673, 39247.607523924395, 39670.0, 39899.60100398959, 46297.6110716679, 46475.0, 46793.76603116962, 48000.0, 48440.0, 37810.865674029825, 37983.430248545745, 38134.809325953196, 38632.6136738629, 38672.613273866904, 31649.8417507911, 37588.436173456874, 37626.0, 38346.0413489665, 38548.229035418575, 33127.83436082804, 36849.0, 37304.813475932446, 37757.0, 38518.614813851505, 32157.678423215468, 33051.504227436126, 33393.0, 33553.32893342071, 33643.99068027963, 19736.40790776279, 23898.163564275168, 24451.877740611184, 24635.63046554339, 24865.378365541008], "value": 20261.48388637678, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.5051804287148, 387.79253099591716, 389.62401282762147, 400.19560683027106, 407.17953324052024, 19633.705494417576, 21548.461288467795, 21978.89010554947, 21996.89001554992, 22383.992720327584, 20612.89693551534, 20653.0, 21450.0, 21860.8906955465, 21901.452463688416, 5128.48715128486, 5384.40771515133, 5391.838244852641, 5401.837944861661, 5403.162509810985, 3050.3136794221346, 4028.858989935353, 5225.712585807779, 5649.943500565003, 5959.70201489926, 5982.79060232892, 5993.4006599340155, 6003.30961939376, 6010.8497287567725, 6014.729337179838, 5868.970655146718, 5872.0, 5950.55370847186, 5952.70236488176, 6047.274327080734, 3738.9439158412647, 5393.541548968343, 5710.486056254957, 5710.71446427677, 5811.941880581182, 10048.44733539655, 11973.40132993347, 12481.126321157522, 12514.74970500594, 13740.65648358789, 1902.4958386027713, 2034.9084291206932, 2253.9887300563555, 2341.9297421077395, 2444.4377793107683, 8968.013518512958, 9647.469389183592, 9716.562754675986, 9730.854037189449, 10312.329698569569, 1458.9197594132318, 1667.608112093657, 1691.8139004709471, 1711.3069206971197, 1733.7226043832927, 110241.0, 117142.0, 119332.0, 128066.0, 129296.3535182318, 11229.27009744364, 11261.0, 11309.49107290174, 11532.481038353126, 11641.243319184226, 12107.39463026845, 14555.490557830424, 14656.706865862836, 14688.632784180267, 14698.338574763948, 8619.870701939475, 8621.267192288726, 8661.263792577702, 8716.43343182691, 8814.779630509289, 8415.116412776693, 8598.613062412207, 8670.523121228392, 8758.56207189638, 8768.517731524827, 3929.410588411704, 4574.519675434098, 4584.151931892544, 4610.400647915817, 4640.559146881041, 1604.7994000749907, 1774.3434929076325, 1793.22483869242, 1823.3076187503427, 1913.2926908424702, 877.7761670774039, 956.416585882611, 969.2827307792325, 988.4761076629397, 1000.5847573257017, 19976.700349494768, 20608.454365922615, 20652.96735163236, 20672.48318792042, 20862.687059694115, 907.8865141857268, 921.2959962509921, 951.5147274889717, 997.804829375377, 1002.3484734922358, 10092.949535252423, 10148.543315550818, 10696.94651526753, 10834.266517357186, 10899.782004360028, 592743.0, 595192.0, 600171.0, 608137.0, 613124.0, 104639.0, 115751.2637310441, 127307.0, 134508.6549134496, 146820.0, 838.7693384319239, 981.6061193105807, 986.063239922077, 1021.5096753558186, 1023.3961962442195, 18040.0, 18256.178471968527, 18316.72524912154, 18330.725039124158, 18374.448766537025, 52296.0, 52852.0, 53255.0, 53389.73305133605, 53590.46409535855, 74244.62877685577, 74796.62601687174, 75308.24691753232, 75882.0, 76967.61516192384, 5355.3305836770405, 7404.851902962018, 7598.506097103671, 7823.100343460575, 7846.882296765667, 10457.84313235287, 12025.27848329068, 12187.729381276096, 12193.451294691937, 12408.193467424588, 8546.871796922927, 10338.534765935701, 10466.947665261625, 10529.210309226992, 10670.253082284165, 9034.683786067457, 10181.592736290466, 10341.4312212826, 10396.48017599132, 10638.41488718128, 10569.260151789302, 10666.0, 10743.677689669323, 10911.399873007062, 10961.0, 7699.422543309162, 7946.32456241226, 7968.960155199419, 7973.401994850526, 8190.180981901762, 9110.773748786529, 9446.858297125686, 9862.013798620368, 10678.092362149304, 10694.465276736288], "value": 9103.516082775619, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.5414513210642, 1546.1959780913921, 1576.4009676322996, 1815.4825874625726, 1980.1188471130345, 7787.64955576999, 8682.956585217082, 8824.249938755198, 8884.511351875644, 9018.774530636738, 8624.310055195585, 8708.738737837875, 8708.782280442992, 8850.645974161042, 8853.026167121627, 2069.286096296775, 2270.8069814065825, 2318.791308782205, 2349.400902769791, 2352.5177338645535, 2383.630537266718, 2398.3764221302413, 2403.471236328005, 2406.254061241012, 2412.8431651942656, 1993.372087792348, 2326.7673232676766, 2365.7042869641296, 2366.5148644527917, 2371.430856594422, 1954.6188493243824, 2207.6357401028813, 2267.365137761413, 2335.1943579465055, 2338.298510446859, 2348.4833336665906, 2365.042157926043, 2366.928992130239, 2368.5973384524673, 2370.4192472844093, 8793.516356600385, 8854.114588541095, 8859.06979767128, 8859.689910853154, 8889.333300002461, 5220.138677118271, 5277.6305658603915, 5371.946280537184, 5375.354957405098, 5400.513953744143, 3338.6327503974544, 3373.443381841993, 3571.1786289153642, 3571.8392672329805, 3579.3736096183065, 877.2148926710558, 996.5814357969598, 997.9900200998043, 1016.588281745894, 1045.5869931377063, 11825.586104486301, 11876.643700688906, 11884.821727674092, 11896.583619573274, 11907.583234586747, 4643.187442197602, 4764.523547645277, 4820.180569303227, 4832.855014349575, 4843.733594652328, 9959.352642078242, 9971.650992215238, 10015.899841001643, 10024.0, 10041.548130334153, 5328.92006619901, 5409.459054094559, 5412.377576578665, 5421.864453388697, 5426.077566813651, 4366.825326986885, 4531.025829446685, 4741.2176990796825, 4890.388701412324, 4934.358533390637, 3883.747556408881, 3948.1314110895555, 3990.0423898264207, 4029.979850100731, 4052.615001574846, 1167.7197472606683, 1266.316189257809, 1293.7994610835146, 1313.3170751209318, 1327.0644195841908, 822.2229992656881, 858.8797568340437, 905.3391024551983, 920.4201353147608, 943.7726964364095, 5605.271314729059, 5779.855503612359, 5887.234659494324, 5986.281646202484, 5996.687354073949, 438.36218302369974, 438.48478038304864, 444.56876829475357, 450.9368688383629, 461.10545541649094, 3832.789196594214, 3883.689304855605, 3930.0, 3983.780892050907, 3997.6801855851463, 10506.212034097472, 10838.67483975482, 10868.402237876993, 10923.836142457869, 10960.013598776148, 10161.237907156992, 10764.730881728019, 10838.67483975482, 10852.077573406192, 10886.29239099456, 8091.109977902426, 8215.548144852091, 8315.95842020786, 8331.500109993298, 8334.874976875351, 5843.649381037151, 5869.297903607906, 5933.525317974465, 6072.937235983597, 6187.1647327612, 9551.235901127755, 10063.748406289902, 10137.884832668547, 10230.539625716712, 10246.026627470379, 10052.64815731446, 10111.848322275024, 10242.897571024492, 10290.0, 10344.844827327746, 6393.487474620461, 6887.793366199022, 7129.607871567114, 7418.295261950106, 7611.543307401574, 7893.605319734105, 8366.33069354438, 8474.152584741476, 8479.279261262862, 8510.361722870686, 7390.852182956418, 7453.776386708408, 7473.028506294147, 7585.658645361083, 7660.463767536219, 7279.0, 7343.853122937405, 7362.815929601803, 7410.333070023508, 7508.0, 6842.55523390978, 6996.440284777103, 7006.124234470692, 7012.509124361246, 7024.473164512784, 4780.713157210578, 4834.250691142878, 4913.06651736169, 4930.901381972412, 5104.2343648453025, 4886.364772579542, 4943.134951383566, 4954.083494553519, 4981.70109793399, 5176.948230517647], "value": 4486.17423952756, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.30162776428097, 307.8799268285368, 313.08266778339464, 315.55034076441063, 318.41411802283795, 4922.0, 5118.564921981631, 5432.311006052, 5514.91727624086, 5552.972235138819, 4469.888252793682, 4546.635749410305, 5389.137737961927, 5389.676619402839, 5412.83761487156, 3398.779079359846, 3549.988253347796, 3854.383298672213, 3989.780562069085, 4063.4108054332105, 3115.115355210099, 3465.220325426784, 3651.561812582494, 3928.998105483098, 4035.6367926886574, 4012.5184977802705, 4025.859094931678, 4038.2327357801923, 4041.737287076345, 4066.8983275418054, 3862.806859657006, 3975.4434379186937, 4024.9195016099807, 4048.392741088831, 4075.8777236682945, 2982.492976194052, 3510.33303672304, 3513.1744040150543, 3519.63043880394, 3537.2218112015316, 5091.0, 5107.0, 5117.411497677777, 5126.820561280375, 5167.612429067797, 3864.8260828262514, 4076.857309994136, 4147.921540399488, 4387.166438376698, 4604.204360299486, 4782.880427989293, 5300.840974770763, 5372.54333381663, 5610.326760788691, 5616.691081990489, 3483.477478378238, 3798.841353387216, 3927.0, 3952.940705889414, 3956.881293561169, 5551.750171242304, 5862.443067908584, 5894.789453954767, 5912.34964153947, 5951.553633477506, 3334.6498617645284, 3596.3526565218513, 3751.531058617673, 3859.807009649506, 3904.3557812960544, 3934.403293826061, 3944.783036932939, 3967.504061992251, 3974.5627980922077, 3983.3029219886985, 3943.369060950234, 3980.7014473914574, 4323.832565207376, 4661.720296782204, 4666.300054991711, 4964.641795147276, 5287.550558202596, 5448.828501872037, 5509.779608815683, 5580.381689310055, 1296.397175313465, 1348.2315080404271, 1353.8781509664177, 1356.796480527909, 1365.7336819320137, 730.9195988441268, 734.7391675954959, 808.7048227396958, 820.9425340226246, 825.5005721538503, 4375.5405682403525, 4674.994876101655, 4780.617550596014, 4849.599448273128, 5301.284326615934, 4655.348251244828, 4729.763511824395, 5785.739641716133, 5793.333766616809, 5861.443162899518, 4945.950540494621, 5264.92102618461, 5317.202419637086, 5339.01228272763, 5368.436314187033, 2533.5946248600135, 3008.518637018067, 3039.9240018999703, 3051.725344718986, 3060.6786287439945, 5187.406296851575, 5778.16216648592, 5984.611000284967, 6017.338092809788, 6047.365026672224, 4683.297505374153, 5123.641345105882, 5375.3280839895015, 5914.112883067574, 6033.849153771103, 5099.388073431212, 5217.973910130425, 6065.2418447694035, 6080.9391906081255, 6083.878322433615, 5671.262735844314, 5851.707414629166, 5871.796281762314, 5890.587658863838, 5899.793507227227, 5718.342390625131, 5758.387651457599, 5767.769289228551, 5768.480836724715, 5788.594798364074, 4839.395075615548, 5346.65246758975, 5551.8889622206525, 6053.909191362045, 6059.6364218147055, 3507.5440192774777, 3681.8645185791643, 3684.4289135183017, 3850.0, 3888.902777430587, 5687.573431992534, 5797.739101740516, 5815.302163740209, 5822.155787410887, 5830.329512106162, 5024.84925452237, 5034.539983404771, 5638.126090455986, 5660.320761508646, 5680.40355762639, 4974.353334066548, 5306.975024236148, 5413.269208656781, 5663.915041274467, 5681.0, 5505.504504594445, 5517.503424691797, 5531.668099914018, 5534.557235421237, 5540.722963851711, 4794.352762377174, 5383.838484845304, 5451.555337835397, 5524.889502210013, 5621.016322143691, 4932.7286999215385, 5543.667379957215, 5557.9709058563985, 5566.524870909293, 5579.856129493363], "value": 4056.917803709889, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.227215980025, 630.9905351419727, 652.7225928980185, 668.1547841979893, 747.19676347926, 14920.850791492081, 15033.398664053439, 15231.238438078079, 15238.542843714678, 15260.542183734491, 13514.391852366642, 14268.143911365325, 14349.210793406384, 14367.35346909389, 14369.281535923214, 719.6509692798988, 890.1143362354467, 900.954952252388, 909.9499527525984, 910.7495438754329, 732.0227496292456, 888.1518150166607, 914.7301546043933, 917.4082716647758, 920.102899672817, 931.5156118818211, 954.4511905654242, 954.7279025477741, 955.6416343871049, 958.1233171647934, 754.811297175706, 922.9676961306322, 947.0576776107782, 949.924006079512, 950.7100334398006, 709.0923617769264, 709.1313141401793, 709.6238993333542, 711.498393632493, 712.291270186168, 14862.256887155707, 14995.925020374938, 15002.24988750558, 15003.549893503212, 15028.849711502964, 12231.449584768618, 12280.631581052583, 12347.320897350643, 12357.135000549964, 12573.11988160829, 10711.571537138505, 10954.068904143078, 10965.177611679077, 11310.151738619636, 11311.547538098466, 3965.1078507335906, 3983.810493382646, 4003.459532963042, 4043.9460012516465, 4164.292070348048, 85615.0, 88084.0, 88376.0, 88406.0, 89228.55385723095, 9407.388519746195, 9418.105279998526, 9447.905520944703, 9458.243340532741, 9875.456849873326, 21920.89039554792, 22094.668579971312, 22116.0, 22147.231596050493, 22152.0, 7110.253423390573, 7223.494355395177, 7315.853682926312, 7370.078740157481, 7495.8500829983095, 3818.427235914635, 3847.615238476186, 3997.2804895119157, 4004.839806407769, 4008.5390180129075, 3410.0963244740165, 3422.7261819054415, 3426.811525366079, 3508.6315936826777, 3513.2622149348413, 563.4168635462277, 595.8410890817405, 614.4377894226733, 634.194572892424, 647.8445173158408, 467.41806450968085, 495.2892599120202, 545.9153831156177, 551.2640624765902, 564.3989151553583, 6460.515461340418, 7639.93040974264, 7718.53688778675, 7780.3386712130105, 7998.880016799752, 535.7589084911727, 537.492069993852, 554.0995881692251, 577.6707276852154, 578.6961845031392, 3585.0, 3659.688926441229, 3750.6061863504483, 3759.586445490994, 3775.7923314217983, 65747.67126164338, 93170.0, 95824.0, 96329.0, 99342.5032874831, 78084.60957695176, 83348.0, 84877.57561212154, 85562.57218713866, 85814.14185858061, 71974.0, 72311.0, 72348.63825680838, 72561.0, 73331.0, 6909.754329842922, 6936.994135850173, 7222.6388680566815, 7315.122185337581, 7451.4038876888635, 45398.77300613476, 46161.076778464914, 46186.538134618226, 46801.063978720915, 47155.0, 58539.70730146322, 59639.105413419704, 59710.40289597048, 64483.677581611795, 65742.67128664517, 9741.220702343653, 9982.101610854768, 10273.9486302568, 10283.588656453958, 10343.63797267092, 10359.896401035892, 10414.531346089596, 10442.425666588108, 10461.320014199357, 10710.518026688662, 9668.70993870185, 9851.556679949275, 9900.257480688833, 9994.850077248993, 10286.948565257126, 9977.401355918668, 10003.899961000297, 10027.649032283834, 10107.69676909694, 10433.478326083818, 9707.6116955321, 9731.562079706571, 9794.412335259907, 9848.803023939625, 10191.796164076824, 8385.748427547183, 8493.872591911002, 8561.82876342482, 8598.570071496526, 8920.776980575538, 8492.872606911025, 8609.182127697859, 8668.78328041804, 8799.296056315607, 8811.427257228259], "value": 6658.978801094576, "unit": "score"}], "name": "dromaeo_css", "value": 7090.468384723972, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 113.0, 112.0, 112.0, 111.0, 112.0, 113.0, 111.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 117.0, 118.0, 168.0, 116.0, 118.0, 139.0, 115.0, 149.0, 114.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 183.0, 188.0, 186.0, 183.0, 182.0, 182.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 60.0, 87.0, 59.0, 57.0, 64.0, 68.0, 83.0, 57.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 104.0, 70.0, 76.0, 69.0, 96.0, 70.0, 69.0, 102.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 129.0, 90.0, 88.0, 91.0, 87.0, 91.0, 87.0, 89.0, 87.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 84.0, 85.0, 84.0, 84.0, 84.0, 84.0, 90.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 58.0, 55.0, 56.0, 56.0, 55.0, 76.0, 58.0, 54.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 74.0, 76.0, 71.0, 72.0, 69.0, 71.0, 69.0, 69.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 89.0, 102.0, 88.0, 85.0, 85.0, 86.0, 84.0, 87.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 138.0, 140.0, 138.0, 139.0, 146.0, 146.0, 179.0, 157.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 167.0, 165.0, 164.0, 167.0, 163.0, 166.0, 163.0, 163.0, 216.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 66.0, 65.0, 67.0, 67.0, 67.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1445.8999999999999, "alertThreshold": 2.0}]} 18:52:35 INFO - Return code: 0 18:52:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:52:35 ERROR - # TBPL SUCCESS # 18:52:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:52:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:52:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:52:35 INFO - 2016-05-24 18:52:35.385 screenresolution[1678:9881] starting screenresolution argv=screenresolution get 18:52:35 INFO - 2016-05-24 18:52:35.388 screenresolution[1678:9881] Display 0: 1600x1200x32@60 18:52:35 INFO - 2016-05-24 18:52:35.394 screenresolution[1679:9884] starting screenresolution argv=screenresolution list 18:52:35 INFO - Available Modes on Display 0 18:52:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:52:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:52:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:52:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:52:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:52:35 INFO - Intel Iris: 18:52:35 INFO - Chipset Model: Intel Iris 18:52:35 INFO - Type: GPU 18:52:35 INFO - Bus: Built-In 18:52:35 INFO - VRAM (Dynamic, Max): 1536 MB 18:52:35 INFO - Vendor: Intel (0x8086) 18:52:35 INFO - Device ID: 0x0a2e 18:52:35 INFO - Revision ID: 0x0009 18:52:35 INFO - Displays: 18:52:35 INFO - Display: 18:52:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:52:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:52:35 INFO - Display Serial Number: 1600x1200 60 18:52:35 INFO - Main Display: Yes 18:52:35 INFO - Mirror: Off 18:52:35 INFO - Online: Yes 18:52:35 INFO - Rotation: Supported 18:52:35 INFO - Return code: 0 18:52:35 INFO - Running post-action listener: _resource_record_post_action 18:52:35 INFO - ##### 18:52:35 INFO - ##### Finished run-tests step (success) 18:52:35 INFO - ##### 18:52:35 INFO - Running post-run listener: _resource_record_post_run 18:52:35 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31538688; Write bytes: 694974976; Read time: 200; Write time: 1910 18:52:35 INFO - TinderboxPrint: CPU usage
53.8% 18:52:35 INFO - TinderboxPrint: I/O read bytes / time
31,538,688 / 200 18:52:35 INFO - TinderboxPrint: I/O write bytes / time
694,974,976 / 1,910 18:52:35 INFO - TinderboxPrint: CPU idle
2,240.9 (46.2%) 18:52:35 INFO - TinderboxPrint: CPU system
973.1 (20.1%) 18:52:35 INFO - TinderboxPrint: CPU user
1,631.7 (33.7%) 18:52:35 INFO - TinderboxPrint: Swap in / out
424,189,952 / 0 18:52:35 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 390492672; Write bytes: 385075200; Read time: 29372; Write time: 781 18:52:35 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29756928; Write bytes: 294564352; Read time: 184; Write time: 1085 18:52:36 INFO - Running post-run listener: _upload_blobber_files 18:52:36 INFO - Blob upload gear active. 18:52:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:52:36 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:52:36 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:52:36 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:52:36 INFO - (blobuploader) - INFO - Open directory for files ... 18:52:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:52:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:52:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:37 INFO - (blobuploader) - INFO - Done attempting. 18:52:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:52:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:52:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:38 INFO - (blobuploader) - INFO - Done attempting. 18:52:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:52:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:52:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:41 INFO - (blobuploader) - INFO - Done attempting. 18:52:41 INFO - (blobuploader) - INFO - Iteration through files over. 18:52:41 INFO - Return code: 0 18:52:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:52:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:52:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/879ead7aee2c951e02f666d0003229b99a53d27fa73d53d1ea23a4579261bd181cc405534af03370494c243026bc6c89c0e30c06624dc99d123877bcf0527499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/492c077dad2eb4cbee75d47740936218b34b4b44f9147a711b128e6c07a8495981ea9e40642f0f9351788ce9a6677c1966981b07a5acf9b5ce2137ee54289607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77c9fb0ca83c71b53cd5cadff19fc0516fd053424e5a94d647d8313401c576eca03525bc8883ea0b9fefc59f6cceeccab21f8cc74207a624fe1e3b135c4c5a7f"} 18:52:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:52:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:52:41 INFO - Contents: 18:52:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/879ead7aee2c951e02f666d0003229b99a53d27fa73d53d1ea23a4579261bd181cc405534af03370494c243026bc6c89c0e30c06624dc99d123877bcf0527499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/492c077dad2eb4cbee75d47740936218b34b4b44f9147a711b128e6c07a8495981ea9e40642f0f9351788ce9a6677c1966981b07a5acf9b5ce2137ee54289607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77c9fb0ca83c71b53cd5cadff19fc0516fd053424e5a94d647d8313401c576eca03525bc8883ea0b9fefc59f6cceeccab21f8cc74207a624fe1e3b135c4c5a7f"} 18:52:41 INFO - Running post-run listener: copy_logs_to_upload_dir 18:52:41 INFO - Copying logs to upload dir... 18:52:41 INFO - mkdir: /builds/slave/test/build/upload/logs 18:52:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.859653 ========= master_lag: 0.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-24 18:52:42.489213) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 18:52:42.493164) ========= 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.xpal0DPj4f/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.92gVrI0cof/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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/879ead7aee2c951e02f666d0003229b99a53d27fa73d53d1ea23a4579261bd181cc405534af03370494c243026bc6c89c0e30c06624dc99d123877bcf0527499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/492c077dad2eb4cbee75d47740936218b34b4b44f9147a711b128e6c07a8495981ea9e40642f0f9351788ce9a6677c1966981b07a5acf9b5ce2137ee54289607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77c9fb0ca83c71b53cd5cadff19fc0516fd053424e5a94d647d8313401c576eca03525bc8883ea0b9fefc59f6cceeccab21f8cc74207a624fe1e3b135c4c5a7f"} build_url:https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011113 build_url: 'https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/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/879ead7aee2c951e02f666d0003229b99a53d27fa73d53d1ea23a4579261bd181cc405534af03370494c243026bc6c89c0e30c06624dc99d123877bcf0527499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/492c077dad2eb4cbee75d47740936218b34b4b44f9147a711b128e6c07a8495981ea9e40642f0f9351788ce9a6677c1966981b07a5acf9b5ce2137ee54289607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77c9fb0ca83c71b53cd5cadff19fc0516fd053424e5a94d647d8313401c576eca03525bc8883ea0b9fefc59f6cceeccab21f8cc74207a624fe1e3b135c4c5a7f"}' ========= master_lag: 0.59 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 18:52:43.093965) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:52:43.094280) ========= 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.xpal0DPj4f/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.92gVrI0cof/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.005212 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:52:43.184159) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 18:52:43.184462) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 18:52:43.184893) ========= ========= Total master_lag: 6.60 =========