builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0268 starttime: 1464359774.29 results: success (0) buildid: 20160527053644 builduid: 70899685370c4d899c113fabe58a40e2 revision: df3281895c93b96241347e8c6a699256bcb3970c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:14.293573) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:14.294053) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:14.294390) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:14.412043) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:14.412575) ========= 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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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-27 07:36:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.8M=0.001s 2016-05-27 07:36:14 (19.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.156117 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:14.610012) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:14.610524) ========= 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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.025745 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:14.665281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 07:36:14.665691) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev df3281895c93b96241347e8c6a699256bcb3970c --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 df3281895c93b96241347e8c6a699256bcb3970c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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-27 07:36:14,780 truncating revision to first 12 chars 2016-05-27 07:36:14,780 Setting DEBUG logging. 2016-05-27 07:36:14,780 attempt 1/10 2016-05-27 07:36:14,780 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/df3281895c93?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 07:36:15,794 unpacking tar archive at: fx-team-df3281895c93/testing/mozharness/ program finished with exit code 0 elapsedTime=1.270161 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 07:36:15.957901) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:15.958248) ========= script_repo_revision: df3281895c93b96241347e8c6a699256bcb3970c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:15.958691) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:15.959006) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 07:36:15.977534) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-27 07:36:15.978159) ========= /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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:36:16 INFO - MultiFileLogger online at 20160527 07:36:16 in /builds/slave/test 07:36:16 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 07:36:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:36:16 INFO - {'append_to_log': False, 07:36:16 INFO - 'base_work_dir': '/builds/slave/test', 07:36:16 INFO - 'blob_upload_branch': 'Fx-Team', 07:36:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:36:16 INFO - 'branch': 'Fx-Team', 07:36:16 INFO - 'buildbot_json_path': 'buildprops.json', 07:36:16 INFO - 'config_files': ('talos/mac_config.py',), 07:36:16 INFO - 'default_actions': ('clobber', 07:36:16 INFO - 'read-buildbot-config', 07:36:16 INFO - 'download-and-extract', 07:36:16 INFO - 'populate-webroot', 07:36:16 INFO - 'create-virtualenv', 07:36:16 INFO - 'install', 07:36:16 INFO - 'run-tests'), 07:36:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:36:16 INFO - 'download_minidump_stackwalk': True, 07:36:16 INFO - 'download_symbols': 'ondemand', 07:36:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:36:16 INFO - 'tooltool.py': '/tools/tooltool.py', 07:36:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:36:16 INFO - '/tools/misc-python/virtualenv.py')}, 07:36:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:36:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:36:16 INFO - 'installer_path': 'installer.exe', 07:36:16 INFO - 'log_level': 'info', 07:36:16 INFO - 'log_name': 'talos', 07:36:16 INFO - 'log_to_console': True, 07:36:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:36:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:36:16 INFO - 'opt_config_files': (), 07:36:16 INFO - 'pip_index': False, 07:36:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:36:16 INFO - 'cmd': ('bash', 07:36:16 INFO - '-c', 07:36:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:36:16 INFO - 'enabled': True, 07:36:16 INFO - 'halt_on_failure': False, 07:36:16 INFO - 'name': 'check_screen_resolution'},), 07:36:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:36:16 INFO - 'cmd': ('bash', 07:36:16 INFO - '-c', 07:36:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:36:16 INFO - 'enabled': True, 07:36:16 INFO - 'halt_on_failure': False, 07:36:16 INFO - 'name': 'check_screen_resolution'},), 07:36:16 INFO - 'run_cmd_checks_enabled': True, 07:36:16 INFO - 'sps_profile': False, 07:36:16 INFO - 'sps_profile_interval': 0, 07:36:16 INFO - 'suite': 'dromaeojs', 07:36:16 INFO - 'system_bits': '32', 07:36:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:36:16 INFO - 'title': 't-yosemite-r7-0268', 07:36:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:36:16 INFO - 'use_talos_json': True, 07:36:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:36:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:36:16 INFO - 'work_dir': 'build'} 07:36:16 INFO - ##### 07:36:16 INFO - ##### Running clobber step. 07:36:16 INFO - ##### 07:36:16 INFO - Running pre-action listener: _resource_record_pre_action 07:36:16 INFO - Running main action method: clobber 07:36:16 INFO - rmtree: /builds/slave/test/build 07:36:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:36:18 INFO - Running post-action listener: _resource_record_post_action 07:36:18 INFO - ##### 07:36:18 INFO - ##### Finished clobber step (success) 07:36:18 INFO - ##### 07:36:18 INFO - ##### 07:36:18 INFO - ##### Running read-buildbot-config step. 07:36:18 INFO - ##### 07:36:18 INFO - Running pre-action listener: _resource_record_pre_action 07:36:18 INFO - Running main action method: read_buildbot_config 07:36:18 INFO - Using buildbot properties: 07:36:18 INFO - { 07:36:18 INFO - "project": "", 07:36:18 INFO - "product": "firefox", 07:36:18 INFO - "script_repo_revision": "production", 07:36:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:36:18 INFO - "repository": "", 07:36:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:36:18 INFO - "buildid": "20160527053644", 07:36:18 INFO - "pgo_build": "False", 07:36:18 INFO - "basedir": "/builds/slave/test", 07:36:18 INFO - "buildnumber": 40, 07:36:18 INFO - "slavename": "t-yosemite-r7-0268", 07:36:18 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:36:18 INFO - "platform": "yosemite_r7", 07:36:18 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:36:18 INFO - "branch": "fx-team", 07:36:18 INFO - "repo_path": "integration/fx-team", 07:36:18 INFO - "revision": "df3281895c93b96241347e8c6a699256bcb3970c", 07:36:18 INFO - "stage_platform": "macosx64", 07:36:18 INFO - "builduid": "70899685370c4d899c113fabe58a40e2", 07:36:18 INFO - "slavebuilddir": "test" 07:36:18 INFO - } 07:36:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:36:18 INFO - Running post-action listener: _resource_record_post_action 07:36:18 INFO - ##### 07:36:18 INFO - ##### Finished read-buildbot-config step (success) 07:36:18 INFO - ##### 07:36:18 INFO - ##### 07:36:18 INFO - ##### Running download-and-extract step. 07:36:18 INFO - ##### 07:36:18 INFO - Running pre-action listener: _resource_record_pre_action 07:36:18 INFO - Running main action method: download_and_extract 07:36:18 INFO - mkdir: /builds/slave/test/build/tests 07:36:18 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')]} 07:36:18 INFO - https://queue.taskcluster.net/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:36:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:36:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:36:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/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 07:36:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/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 07:36:29 INFO - Downloaded 1199 bytes. 07:36:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:36:29 INFO - Using the following test package requirements: 07:36:29 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:36:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:36:29 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:36:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:36:29 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:36:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:36:29 INFO - u'jsshell-mac.zip'], 07:36:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:36:29 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:36:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:36:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:36:29 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:36:29 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:36:29 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:36:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:36:29 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:36:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:36:29 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:36:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:36:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:36:29 INFO - https://queue.taskcluster.net/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:36:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:36:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:36:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/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 07:36:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/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 07:36:36 INFO - Downloaded 18684821 bytes. 07:36:36 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'] 07:36:36 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 07:36:39 INFO - Return code: 0 07:36:39 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 07:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:36:39 INFO - https://queue.taskcluster.net/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:36:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:36:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:36:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/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 07:36:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/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 07:36:46 INFO - Downloaded 18684821 bytes. 07:36:46 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'] 07:36:46 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 07:36:50 INFO - Return code: 0 07:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:36:50 INFO - https://queue.taskcluster.net/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:36:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:36:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:36:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/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 07:36:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/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 07:36:51 INFO - Downloaded 10834034 bytes. 07:36:51 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'] 07:36:51 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 07:36:51 INFO - Return code: 0 07:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:36:51 INFO - https://queue.taskcluster.net/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:36:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:36:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:36:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:36:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BMi-wQBRQge783nRqNABZw/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 07:36:57 INFO - Downloaded 110476488 bytes. 07:36:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:36:57 INFO - mkdir: /builds/slave/test/properties 07:36:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:36:57 INFO - Writing to file /builds/slave/test/properties/build_url 07:36:57 INFO - Contents: 07:36:57 INFO - build_url:https://queue.taskcluster.net/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:36:58 INFO - Running post-action listener: _resource_record_post_action 07:36:58 INFO - Running post-action listener: set_extra_try_arguments 07:36:58 INFO - ##### 07:36:58 INFO - ##### Finished download-and-extract step (success) 07:36:58 INFO - ##### 07:36:58 INFO - ##### 07:36:58 INFO - ##### Running populate-webroot step. 07:36:58 INFO - ##### 07:36:58 INFO - Running pre-action listener: _resource_record_pre_action 07:36:58 INFO - Running main action method: populate_webroot 07:36:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:36:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:36:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:36:58 INFO - '--tppagecycles', 07:36:58 INFO - '7'], 07:36:58 INFO - 'tests': ['tsvgm']}}, 07:36:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:36:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:36:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:36:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:36:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:36:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:36:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:58 INFO - 'talos_options': ['--disable-e10s'], 07:36:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:36:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:36:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:58 INFO - 'talos_options': ['--disable-e10s'], 07:36:58 INFO - 'tests': ['damp', 'tps']}, 07:36:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:58 INFO - 'tests': ['damp', 'tps']}, 07:36:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:36:58 INFO - 'tests': ['dromaeo_dom']}, 07:36:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:36:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:36:58 INFO - 'tests': ['a11yr', 07:36:58 INFO - 'ts_paint', 07:36:58 INFO - 'tpaint', 07:36:58 INFO - 'sessionrestore', 07:36:58 INFO - 'sessionrestore_no_auto_restore', 07:36:58 INFO - 'tabpaint']}, 07:36:58 INFO - 'other-e10s': {'tests': ['a11yr', 07:36:58 INFO - 'ts_paint', 07:36:58 INFO - 'tpaint', 07:36:58 INFO - 'sessionrestore', 07:36:58 INFO - 'sessionrestore_no_auto_restore', 07:36:58 INFO - 'tabpaint']}, 07:36:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:36:58 INFO - 'ts_paint', 07:36:58 INFO - 'tpaint', 07:36:58 INFO - 'sessionrestore', 07:36:58 INFO - 'sessionrestore_no_auto_restore', 07:36:58 INFO - 'tabpaint']}, 07:36:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:36:58 INFO - 'ts_paint', 07:36:58 INFO - 'tpaint', 07:36:58 INFO - 'sessionrestore', 07:36:58 INFO - 'sessionrestore_no_auto_restore', 07:36:58 INFO - 'tabpaint']}, 07:36:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:36:58 INFO - 'tests': ['a11yr', 07:36:58 INFO - 'ts_paint', 07:36:58 INFO - 'tpaint', 07:36:58 INFO - 'sessionrestore', 07:36:58 INFO - 'sessionrestore_no_auto_restore', 07:36:58 INFO - 'tabpaint']}, 07:36:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:36:58 INFO - 'tests': ['a11yr', 07:36:58 INFO - 'ts_paint', 07:36:58 INFO - 'tpaint', 07:36:58 INFO - 'sessionrestore', 07:36:58 INFO - 'sessionrestore_no_auto_restore', 07:36:58 INFO - 'tabpaint']}, 07:36:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:36:58 INFO - 'tests': ['tsvgx', 07:36:58 INFO - 'tsvgr_opacity', 07:36:58 INFO - 'tart', 07:36:58 INFO - 'tscrollx', 07:36:58 INFO - 'cart']}, 07:36:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:36:58 INFO - 'tsvgr_opacity', 07:36:58 INFO - 'tart', 07:36:58 INFO - 'tscrollx', 07:36:58 INFO - 'cart']}, 07:36:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:58 INFO - 'talos_options': ['--disable-e10s'], 07:36:58 INFO - 'tests': ['tp5o']}, 07:36:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:58 INFO - 'tests': ['tp5o']}, 07:36:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:58 INFO - 'talos_options': ['--disable-e10s', 07:36:58 INFO - '--xperf_path', 07:36:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:36:58 INFO - 'tests': ['tp5n']}, 07:36:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:58 INFO - 'talos_options': ['--xperf_path', 07:36:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:36:58 INFO - 'tests': ['tp5n']}}, 07:36:58 INFO - 'talos.zip': {'path': '', 07:36:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:36:58 INFO - Running post-action listener: _resource_record_post_action 07:36:58 INFO - ##### 07:36:58 INFO - ##### Finished populate-webroot step (success) 07:36:58 INFO - ##### 07:36:58 INFO - ##### 07:36:58 INFO - ##### Running create-virtualenv step. 07:36:58 INFO - ##### 07:36:58 INFO - Running pre-action listener: _resource_record_pre_action 07:36:58 INFO - Running main action method: create_virtualenv 07:36:58 INFO - Creating virtualenv /builds/slave/test/build/venv 07:36:58 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 07:36:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:36:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:36:58 INFO - Using real prefix '/tools/python27' 07:36:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:36:59 INFO - Installing distribute.............................................................................................................................................................................................done. 07:37:02 INFO - Installing pip.................done. 07:37:02 INFO - Return code: 0 07:37:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:37:02 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')]} 07:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:02 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')]} 07:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:02 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 0x106a02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f966ad65580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068a6d30>, '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 0x1066cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10689db20>, '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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:37:02 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 07:37:02 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 07:37:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vTX7wYqDP0/Render', 07:37:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:37:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:37:02 INFO - 'HOME': '/Users/cltbld', 07:37:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:37:02 INFO - 'LOGNAME': 'cltbld', 07:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:02 INFO - 'MOZ_NO_REMOTE': '1', 07:37:02 INFO - 'NO_EM_RESTART': '1', 07:37:02 INFO - 'PAGER': '/bin/cat', 07:37:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:02 INFO - 'PWD': '/builds/slave/test', 07:37:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:37:02 INFO - 'SHELL': '/bin/bash', 07:37:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tZZ5HeH7Av/Listeners', 07:37:02 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:37:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:37:02 INFO - 'USER': 'cltbld', 07:37:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:37:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:02 INFO - 'XPC_FLAGS': '0x0', 07:37:02 INFO - 'XPC_SERVICE_NAME': '0', 07:37:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:02 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 07:37:02 INFO - Cleaning up... 07:37:02 INFO - Return code: 0 07:37:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:37:02 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')]} 07:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:02 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')]} 07:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:02 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 0x106a02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f966ad65580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068a6d30>, '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 0x1066cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10689db20>, '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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:37:02 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 07:37:02 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 07:37:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vTX7wYqDP0/Render', 07:37:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:37:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:37:02 INFO - 'HOME': '/Users/cltbld', 07:37:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:37:02 INFO - 'LOGNAME': 'cltbld', 07:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:02 INFO - 'MOZ_NO_REMOTE': '1', 07:37:02 INFO - 'NO_EM_RESTART': '1', 07:37:02 INFO - 'PAGER': '/bin/cat', 07:37:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:02 INFO - 'PWD': '/builds/slave/test', 07:37:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:37:02 INFO - 'SHELL': '/bin/bash', 07:37:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tZZ5HeH7Av/Listeners', 07:37:02 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:37:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:37:02 INFO - 'USER': 'cltbld', 07:37:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:37:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:02 INFO - 'XPC_FLAGS': '0x0', 07:37:02 INFO - 'XPC_SERVICE_NAME': '0', 07:37:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:02 INFO - Downloading/unpacking psutil>=3.1.1 07:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:37:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:37:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:37:05 INFO - Installing collected packages: psutil 07:37:05 INFO - Running setup.py install for psutil 07:37:05 INFO - building 'psutil._psutil_osx' extension 07:37:05 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 07:37:06 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 07:37:06 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 07:37:06 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 07:37:06 INFO - building 'psutil._psutil_posix' extension 07:37:06 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 07:37:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:37:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:37:06 INFO - ^ 07:37:06 INFO - 1 warning generated. 07:37:06 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 07:37:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:37:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:37:06 INFO - Successfully installed psutil 07:37:06 INFO - Cleaning up... 07:37:06 INFO - Return code: 0 07:37:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:37:06 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')]} 07:37:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:06 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')]} 07:37:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:06 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 0x106a02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f966ad65580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068a6d30>, '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 0x1066cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10689db20>, '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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:37:06 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 07:37:06 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 07:37:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vTX7wYqDP0/Render', 07:37:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:37:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:37:06 INFO - 'HOME': '/Users/cltbld', 07:37:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:37:06 INFO - 'LOGNAME': 'cltbld', 07:37:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:06 INFO - 'MOZ_NO_REMOTE': '1', 07:37:06 INFO - 'NO_EM_RESTART': '1', 07:37:06 INFO - 'PAGER': '/bin/cat', 07:37:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:37:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:06 INFO - 'PWD': '/builds/slave/test', 07:37:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:37:06 INFO - 'SHELL': '/bin/bash', 07:37:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tZZ5HeH7Av/Listeners', 07:37:06 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:37:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:37:06 INFO - 'USER': 'cltbld', 07:37:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:37:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:37:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:06 INFO - 'XPC_FLAGS': '0x0', 07:37:06 INFO - 'XPC_SERVICE_NAME': '0', 07:37:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:37:07 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 07:37:07 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 07:37:07 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 07:37:07 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 07:37:07 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 07:37:07 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 07:37:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:37:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:37:09 INFO - Installing collected packages: mozsystemmonitor 07:37:09 INFO - Running setup.py install for mozsystemmonitor 07:37:09 INFO - Successfully installed mozsystemmonitor 07:37:09 INFO - Cleaning up... 07:37:09 INFO - Return code: 0 07:37:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:37:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106a02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f966ad65580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068a6d30>, '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 0x1066cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10689db20>, '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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:37:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:37:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vTX7wYqDP0/Render', 07:37:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:37:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:37:09 INFO - 'HOME': '/Users/cltbld', 07:37:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:37:09 INFO - 'LOGNAME': 'cltbld', 07:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:09 INFO - 'MOZ_NO_REMOTE': '1', 07:37:09 INFO - 'NO_EM_RESTART': '1', 07:37:09 INFO - 'PAGER': '/bin/cat', 07:37:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:09 INFO - 'PWD': '/builds/slave/test', 07:37:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:37:09 INFO - 'SHELL': '/bin/bash', 07:37:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tZZ5HeH7Av/Listeners', 07:37:09 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:37:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:37:09 INFO - 'USER': 'cltbld', 07:37:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:37:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:09 INFO - 'XPC_FLAGS': '0x0', 07:37:09 INFO - 'XPC_SERVICE_NAME': '0', 07:37:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:37:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:10 INFO - Downloading/unpacking blobuploader==1.2.4 07:37:10 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 07:37:10 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 07:37:10 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 07:37:10 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 07:37:10 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 07:37:10 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 07:37:12 INFO - Downloading blobuploader-1.2.4.tar.gz 07:37:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:37:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:37:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:37:13 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 07:37:13 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 07:37:13 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 07:37:13 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 07:37:13 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 07:37:13 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 07:37:13 INFO - Downloading docopt-0.6.1.tar.gz 07:37:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:37:13 INFO - Installing collected packages: blobuploader, requests, docopt 07:37:13 INFO - Running setup.py install for blobuploader 07:37:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:37:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:37:13 INFO - Running setup.py install for requests 07:37:14 INFO - Running setup.py install for docopt 07:37:14 INFO - Successfully installed blobuploader requests docopt 07:37:14 INFO - Cleaning up... 07:37:14 INFO - Return code: 0 07:37:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37:14 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')]} 07:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:14 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')]} 07:37:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:14 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 0x106a02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f966ad65580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068a6d30>, '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 0x1066cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10689db20>, '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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:37:14 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 07:37:14 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 07:37:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vTX7wYqDP0/Render', 07:37:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:37:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:37:14 INFO - 'HOME': '/Users/cltbld', 07:37:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:37:14 INFO - 'LOGNAME': 'cltbld', 07:37:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:14 INFO - 'MOZ_NO_REMOTE': '1', 07:37:14 INFO - 'NO_EM_RESTART': '1', 07:37:14 INFO - 'PAGER': '/bin/cat', 07:37:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:37:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:14 INFO - 'PWD': '/builds/slave/test', 07:37:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:37:14 INFO - 'SHELL': '/bin/bash', 07:37:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tZZ5HeH7Av/Listeners', 07:37:14 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:37:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:37:14 INFO - 'USER': 'cltbld', 07:37:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:37:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:37:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:14 INFO - 'XPC_FLAGS': '0x0', 07:37:14 INFO - 'XPC_SERVICE_NAME': '0', 07:37:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:37:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:37:14 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-XAM0iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:37:15 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-0Uu4d_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:37:15 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-xSJupS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:37:15 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-8Cz1ev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:37:15 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-vxgnwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:37:15 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-FTkAiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:37:15 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-cC_jpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:37:15 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-JOlrJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:37:15 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-H7vkV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:37:15 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-meHm3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:37:15 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-7uuQrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:37:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-QxfrRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:37:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-jwCAl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:37:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-fz6KFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-sz3zeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:37:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-L1pYsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:37:16 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-DtDcMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:37:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:37:16 INFO - Running setup.py install for manifestparser 07:37:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:37:16 INFO - Running setup.py install for mozcrash 07:37:16 INFO - Running setup.py install for mozdebug 07:37:17 INFO - Running setup.py install for mozdevice 07:37:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:37:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:37:17 INFO - Running setup.py install for mozfile 07:37:17 INFO - Running setup.py install for mozhttpd 07:37:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:37:17 INFO - Running setup.py install for mozinfo 07:37:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:37:17 INFO - Running setup.py install for mozInstall 07:37:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:37:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:37:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:37:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:37:17 INFO - Running setup.py install for mozleak 07:37:18 INFO - Running setup.py install for mozlog 07:37:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:37:18 INFO - Running setup.py install for moznetwork 07:37:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:37:18 INFO - Running setup.py install for mozprocess 07:37:18 INFO - Running setup.py install for mozprofile 07:37:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:37:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:37:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:37:18 INFO - Running setup.py install for mozrunner 07:37:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:37:18 INFO - Running setup.py install for mozscreenshot 07:37:18 INFO - Running setup.py install for moztest 07:37:19 INFO - Running setup.py install for mozversion 07:37:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:37:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:37:19 INFO - Cleaning up... 07:37:19 INFO - Return code: 0 07:37:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37: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')]} 07:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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')]} 07:37:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:19 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 0x106a02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f966ad65580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068a6d30>, '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 0x1066cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10689db20>, '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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:37:19 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 07:37:19 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 07:37:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vTX7wYqDP0/Render', 07:37:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:37:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:37:19 INFO - 'HOME': '/Users/cltbld', 07:37:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:37:19 INFO - 'LOGNAME': 'cltbld', 07:37:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:19 INFO - 'MOZ_NO_REMOTE': '1', 07:37:19 INFO - 'NO_EM_RESTART': '1', 07:37:19 INFO - 'PAGER': '/bin/cat', 07:37:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:37:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:19 INFO - 'PWD': '/builds/slave/test', 07:37:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:37:19 INFO - 'SHELL': '/bin/bash', 07:37:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tZZ5HeH7Av/Listeners', 07:37:19 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:37:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:37:19 INFO - 'USER': 'cltbld', 07:37:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:37:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:37:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:19 INFO - 'XPC_FLAGS': '0x0', 07:37:19 INFO - 'XPC_SERVICE_NAME': '0', 07:37:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:37:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:37:19 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-PG0SjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:37:19 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)) 07:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:37:19 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-3Sqpfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:37:19 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)) 07:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:37:19 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-JvIG2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:37:19 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)) 07:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:37:19 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-VTXju2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:37:20 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)) 07:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:37:20 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-6s_jx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:37:20 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)) 07:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:37:20 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-MJ4Slr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:37:20 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)) 07:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:37:20 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-89PdaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:37:20 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)) 07:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:37:20 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-bAHRtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:37:20 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)) 07:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:37:20 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-4djNUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:37:20 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)) 07:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:37:20 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Gk2nE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:37:20 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)) 07:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:37:20 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-QCzpBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:37:20 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)) 07:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:37:20 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-8faE8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:37:20 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)) 07:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:37:20 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-EwCqXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:37:21 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)) 07:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:37:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Qsn4sW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:37:21 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)) 07:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Oy2isC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:21 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)) 07:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:37:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-JtJy5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:37:21 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)) 07:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:37:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-RzyRAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:37:21 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)) 07:37:21 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)) 07:37:21 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)) 07:37:21 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)) 07:37:21 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)) 07:37:21 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)) 07:37:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:23 INFO - Downloading blessings-1.6.tar.gz 07:37:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:37:23 INFO - Installing collected packages: blessings 07:37:23 INFO - Running setup.py install for blessings 07:37:24 INFO - Successfully installed blessings 07:37:24 INFO - Cleaning up... 07:37:24 INFO - Return code: 0 07:37:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:37:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:37:24 INFO - Reading from file tmpfile_stdout 07:37:24 INFO - Current package versions: 07:37:24 INFO - blessings == 1.6 07:37:24 INFO - blobuploader == 1.2.4 07:37:24 INFO - docopt == 0.6.1 07:37:24 INFO - manifestparser == 1.1 07:37:24 INFO - mozInstall == 1.12 07:37:24 INFO - mozcrash == 0.17 07:37:24 INFO - mozdebug == 0.1 07:37:24 INFO - mozdevice == 0.48 07:37:24 INFO - mozfile == 1.2 07:37:24 INFO - mozhttpd == 0.7 07:37:24 INFO - mozinfo == 0.9 07:37:24 INFO - mozleak == 0.1 07:37:24 INFO - mozlog == 3.2 07:37:24 INFO - moznetwork == 0.27 07:37:24 INFO - mozprocess == 0.22 07:37:24 INFO - mozprofile == 0.28 07:37:24 INFO - mozrunner == 6.11 07:37:24 INFO - mozscreenshot == 0.1 07:37:24 INFO - mozsystemmonitor == 0.3 07:37:24 INFO - moztest == 0.7 07:37:24 INFO - mozversion == 1.4 07:37:24 INFO - psutil == 3.1.1 07:37:24 INFO - requests == 1.2.3 07:37:24 INFO - wsgiref == 0.1.2 07:37:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:37:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:24 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 0x106a02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f966ad65580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068a6d30>, '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 0x1066cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10689db20>, '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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:37:24 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 07:37:24 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 07:37:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vTX7wYqDP0/Render', 07:37:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:37:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:37:24 INFO - 'HOME': '/Users/cltbld', 07:37:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:37:24 INFO - 'LOGNAME': 'cltbld', 07:37:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:24 INFO - 'MOZ_NO_REMOTE': '1', 07:37:24 INFO - 'NO_EM_RESTART': '1', 07:37:24 INFO - 'PAGER': '/bin/cat', 07:37:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:37:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:24 INFO - 'PWD': '/builds/slave/test', 07:37:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:37:24 INFO - 'SHELL': '/bin/bash', 07:37:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tZZ5HeH7Av/Listeners', 07:37:24 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:37:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:37:24 INFO - 'USER': 'cltbld', 07:37:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:37:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:24 INFO - 'XPC_FLAGS': '0x0', 07:37:24 INFO - 'XPC_SERVICE_NAME': '0', 07:37:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:24 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)) 07:37:24 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)) 07:37:24 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)) 07:37:24 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)) 07:37:24 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)) 07:37:24 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)) 07:37:24 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)) 07:37:24 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)) 07:37:24 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)) 07:37:24 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)) 07:37:24 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)) 07:37:24 INFO - Cleaning up... 07:37:24 INFO - Return code: 0 07:37:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:37:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f966ad65580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068a6d30>, '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 0x1066cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10689db20>, '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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:37:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:37:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vTX7wYqDP0/Render', 07:37:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:37:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:37:24 INFO - 'HOME': '/Users/cltbld', 07:37:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:37:24 INFO - 'LOGNAME': 'cltbld', 07:37:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:24 INFO - 'MOZ_NO_REMOTE': '1', 07:37:24 INFO - 'NO_EM_RESTART': '1', 07:37:24 INFO - 'PAGER': '/bin/cat', 07:37:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:37:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:24 INFO - 'PWD': '/builds/slave/test', 07:37:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:37:24 INFO - 'SHELL': '/bin/bash', 07:37:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tZZ5HeH7Av/Listeners', 07:37:24 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:37:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:37:24 INFO - 'USER': 'cltbld', 07:37:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:37:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:24 INFO - 'XPC_FLAGS': '0x0', 07:37:24 INFO - 'XPC_SERVICE_NAME': '0', 07:37:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:37:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:25 INFO - Downloading/unpacking jsonschema 07:37:25 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 07:37:25 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 07:37:25 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 07:37:25 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 07:37:25 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 07:37:25 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 07:37:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:37:27 INFO - Downloading/unpacking functools32 (from jsonschema) 07:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:27 INFO - Downloading functools32-3.2.3-2.tar.gz 07:37:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:37:28 INFO - warning: no files found matching '*.txt' 07:37:28 INFO - no previously-included directories found matching 'build' 07:37:28 INFO - no previously-included directories found matching 'dist' 07:37:28 INFO - no previously-included directories found matching '.git*' 07:37:28 INFO - Installing collected packages: jsonschema, functools32 07:37:28 INFO - Running setup.py install for functools32 07:37:28 INFO - warning: no files found matching '*.txt' 07:37:28 INFO - no previously-included directories found matching 'build' 07:37:28 INFO - no previously-included directories found matching 'dist' 07:37:28 INFO - no previously-included directories found matching '.git*' 07:37:28 INFO - Successfully installed jsonschema functools32 07:37:28 INFO - Cleaning up... 07:37:28 INFO - Return code: 0 07:37:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:37:28 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')]} 07:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:28 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')]} 07:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:28 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 0x106a02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a08570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f966ad65580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068a6d30>, '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 0x1066cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10689db20>, '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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 07:37:28 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 07:37:28 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 07:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vTX7wYqDP0/Render', 07:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:37:28 INFO - 'HOME': '/Users/cltbld', 07:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:37:28 INFO - 'LOGNAME': 'cltbld', 07:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:28 INFO - 'MOZ_NO_REMOTE': '1', 07:37:28 INFO - 'NO_EM_RESTART': '1', 07:37:28 INFO - 'PAGER': '/bin/cat', 07:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:28 INFO - 'PWD': '/builds/slave/test', 07:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:37:28 INFO - 'SHELL': '/bin/bash', 07:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tZZ5HeH7Av/Listeners', 07:37:28 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:37:28 INFO - 'USER': 'cltbld', 07:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:28 INFO - 'XPC_FLAGS': '0x0', 07:37:28 INFO - 'XPC_SERVICE_NAME': '0', 07:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:28 INFO - Downloading/unpacking flake8 07:37:28 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 07:37:28 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 07:37:28 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 07:37:28 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 07:37:28 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 07:37:28 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 07:37:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:37:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:37:31 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 07:37:31 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 07:37:31 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 07:37:31 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 07:37:31 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 07:37:31 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 07:37:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:37:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:37:31 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 07:37:31 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 07:37:31 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 07:37:31 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 07:37:31 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 07:37:31 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 07:37:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:37:31 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 07:37:31 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 07:37:31 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 07:37:31 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 07:37:31 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 07:37:31 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 07:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:37:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:37:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:37:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:37:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:37:32 INFO - no previously-included directories found matching 'docs/_build' 07:37:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:37:32 INFO - Running setup.py install for pep8 07:37:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:37:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:37:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:37:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:37:32 INFO - no previously-included directories found matching 'docs/_build' 07:37:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:37:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:37:32 INFO - Cleaning up... 07:37:32 INFO - Return code: 0 07:37:32 INFO - Running post-action listener: _resource_record_post_action 07:37:32 INFO - Running post-action listener: _start_resource_monitoring 07:37:32 INFO - Starting resource monitoring. 07:37:32 INFO - ##### 07:37:32 INFO - ##### Finished create-virtualenv step (success) 07:37:32 INFO - ##### 07:37:32 INFO - ##### 07:37:32 INFO - ##### Running install step. 07:37:32 INFO - ##### 07:37:32 INFO - Running pre-action listener: _resource_record_pre_action 07:37:32 INFO - Running main action method: install 07:37:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:37:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:37:33 INFO - Reading from file tmpfile_stdout 07:37:33 INFO - Detecting whether we're running mozinstall >=1.0... 07:37:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:37:33 INFO - Reading from file tmpfile_stdout 07:37:33 INFO - Output received: 07:37:33 INFO - Usage: mozinstall [options] installer 07:37:33 INFO - Options: 07:37:33 INFO - -h, --help show this help message and exit 07:37:33 INFO - -d DEST, --destination=DEST 07:37:33 INFO - Directory to install application into. [default: 07:37:33 INFO - "/builds/slave/test"] 07:37:33 INFO - --app=APP Application being installed. [default: firefox] 07:37:33 INFO - mkdir: /builds/slave/test/build/application 07:37:33 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'] 07:37:33 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 07:38:06 INFO - Reading from file tmpfile_stdout 07:38:06 INFO - Output received: 07:38:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:38:06 INFO - Running post-action listener: _resource_record_post_action 07:38:06 INFO - ##### 07:38:06 INFO - ##### Finished install step (success) 07:38:06 INFO - ##### 07:38:06 INFO - ##### 07:38:06 INFO - ##### Running run-tests step. 07:38:06 INFO - ##### 07:38:06 INFO - Running pre-action listener: _resource_record_pre_action 07:38:06 INFO - Running main action method: run_tests 07:38:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:38:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:38:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:38:06 INFO - 2016-05-27 07:38:06.535 screenresolution[1646:8543] starting screenresolution argv=screenresolution get 07:38:06 INFO - 2016-05-27 07:38:06.537 screenresolution[1646:8543] Display 0: 1600x1200x32@60 07:38:06 INFO - 2016-05-27 07:38:06.544 screenresolution[1647:8545] starting screenresolution argv=screenresolution list 07:38:06 INFO - Available Modes on Display 0 07:38:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:38:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:38:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:38:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:38:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:38:06 INFO - Intel Iris: 07:38:06 INFO - Chipset Model: Intel Iris 07:38:06 INFO - Type: GPU 07:38:06 INFO - Bus: Built-In 07:38:06 INFO - VRAM (Dynamic, Max): 1536 MB 07:38:06 INFO - Vendor: Intel (0x8086) 07:38:06 INFO - Device ID: 0x0a2e 07:38:06 INFO - Revision ID: 0x0009 07:38:06 INFO - Displays: 07:38:06 INFO - Display: 07:38:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:38:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:38:06 INFO - Display Serial Number: 1600x1200 60 07:38:06 INFO - Main Display: Yes 07:38:06 INFO - Mirror: Off 07:38:06 INFO - Online: Yes 07:38:06 INFO - Rotation: Supported 07:38:06 INFO - Return code: 0 07:38:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:38:06 INFO - Python 2.7.3 07:38:06 INFO - Return code: 0 07:38:06 INFO - grabbing minidump binary from tooltool 07:38:06 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')]} 07:38:06 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 0x1068a6d30>, '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 0x1066cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10689db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:38:06 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 07:38:06 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 07:38:06 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 07:38:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:38:06 INFO - Return code: 0 07:38:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:38:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:38:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:38:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:38:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:38:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:38:08 INFO - Return code: 0 07:38:08 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/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--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 07:38:08 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/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0268 --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 07:38:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vTX7wYqDP0/Render', 07:38:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:08 INFO - 'HOME': '/Users/cltbld', 07:38:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:08 INFO - 'LOGNAME': 'cltbld', 07:38:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:38:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:08 INFO - 'MOZ_NO_REMOTE': '1', 07:38:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:38:08 INFO - 'NO_EM_RESTART': '1', 07:38:08 INFO - 'PAGER': '/bin/cat', 07:38:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:08 INFO - 'PWD': '/builds/slave/test', 07:38:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:38:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:08 INFO - 'SHELL': '/bin/bash', 07:38:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tZZ5HeH7Av/Listeners', 07:38:08 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 07:38:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:08 INFO - 'USER': 'cltbld', 07:38:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:08 INFO - 'XPC_FLAGS': '0x0', 07:38:08 INFO - 'XPC_SERVICE_NAME': '0', 07:38:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:08 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/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--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 07:38:08 INFO - mozversion application_buildid: 20160527053644 07:38:08 INFO - mozversion application_changeset: df3281895c93b96241347e8c6a699256bcb3970c 07:38:08 INFO - mozversion application_display_name: Nightly 07:38:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:38:08 INFO - mozversion application_name: Firefox 07:38:08 INFO - mozversion application_remotingname: firefox 07:38:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:38:08 INFO - mozversion application_vendor: Mozilla 07:38:08 INFO - mozversion application_version: 49.0a1 07:38:08 INFO - mozversion platform_buildid: 20160527053644 07:38:08 INFO - mozversion platform_changeset: df3281895c93b96241347e8c6a699256bcb3970c 07:38:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:38:08 INFO - mozversion platform_version: 49.0a1 07:38:08 INFO - using testdate: 1464359888 07:38:08 INFO - actual date: 1464359888 07:38:08 INFO - starting webserver on 'localhost:49270' 07:38:08 INFO - SUITE-START | Running 2 tests 07:38:08 INFO - TEST-START | dromaeo_css 07:38:08 INFO - operating with platform_type : mac_ 07:38:08 INFO - Initialising browser for dromaeo_css test... 07:38:08 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpWyqezM/profile http://localhost:49270/getInfo.html) 07:38:10 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 07:38:10 INFO - PROCESS | 1665 | colorDepth:24 07:38:10 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 07:38:10 INFO - PROCESS | 1665 | __metrics 07:38:10 INFO - PROCESS | 1665 | 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" 07:38:10 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:38:11 INFO - TEST-INFO | 1665: exit 0 07:38:11 INFO - Browser initialized. 07:38:11 INFO - Running cycle 1/1 for dromaeo_css test... 07:38:11 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpWyqezM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:23 INFO - PROCESS | 1668 | RSS: Main: 241352704 07:38:23 INFO - PROCESS | 1668 | 07:39:14 INFO - PROCESS | 1668 | 1464359954510 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] 07:41:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 07:41:26 INFO - PROCESS | 1668 | RSS: Main: 373846016 07:41:26 INFO - PROCESS | 1668 | 07:44:29 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 07:44:29 INFO - PROCESS | 1668 | RSS: Main: 371814400 07:44:29 INFO - PROCESS | 1668 | 07:47:32 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 07:47:32 INFO - PROCESS | 1668 | RSS: Main: 368492544 07:47:32 INFO - PROCESS | 1668 | 07:50:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 07:50:35 INFO - PROCESS | 1668 | RSS: Main: 372146176 07:50:35 INFO - PROCESS | 1668 | 07:53:39 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 07:53:40 INFO - PROCESS | 1668 | RSS: Main: 337477632 07:53:40 INFO - PROCESS | 1668 | 07:56:43 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 07:56:43 INFO - PROCESS | 1668 | RSS: Main: 392110080 07:56:43 INFO - PROCESS | 1668 | 07:56:43 INFO - PROCESS | 1668 | __start_tp_report 07:56:43 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 07:56:43 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 07:56:43 INFO - PROCESS | 1668 | |i|pagename|runs| 07:56:43 INFO - PROCESS | 1668 | |0;dojo.html;2825.8587070646468;3429.108431807731;3440.4667276572122;3455.0671318743957;3493.2664140530487;5460.836174914759;5548.223248745179;5625.071863142587;5650.604457687963;5657.179708942206;5566.916496252559;5584.636998595089;5684.175794509793;5705.229793977812;5764.913526297109;5077.873053173664;5088.567471764904;5149;5182.067227898977;5244.527992480676;5239.947600523984;5279.973600131994;5302.973485132569;5304.310439642841;5331.600129990266;5093.567046801027;5102.566281866046;5128.410232823236;5145.279660847485;5165.948340516585;5107.284980102789;5113.718745468997;5117.232415137759;5136.203888397305;5154.92267615986;5146.665466744687;5162.767675454614;5183.5853131749745;5183.5853131749745;5233.084210263189;5418.322709661293;5432.728363581845;5495.917561236584;5526.640768350085;5559.221708960748;6105.925223381411;6127.264728232641;6151.415615516519;6173.69131543421;6176.567640265183;6206.379362063757;6206.875862482771;6238.313785483593;6240.687965601702;6257.092721555349;5597.832065038056;5631.549476041907;5664.122061080498;5666.320041595035;5797.391273916221;6224.06639004153;6237.844053898689;6238.344973777779;6244.313125556185;6275.748970041239;6052.608078743428;6053.02751013817;6053.788117415869;6066.817995460143;6093.207882975274;6006.789762358297;6019.69901504923;6086.513078953673;6115.788007244199;6144.279601711528;6039.124326972565;6093;6097.085437184369;6104.053871649901;6107.328193898667;6100.75596976116;6120.632762034204;6124.479419249325;6130.111133885562;6131.172291740647;4852.611791056707;4885.853424397203;4894.706317620883;4896.559309662076;4904.8038078477175;4312.37470566766;4357.7385356878685;4396.736195828261;4413.205622987893;4474.26174681174;5648.15277708347;5662.462066103713;5662.7734890603915;5677.006523858308;5696.743646535916;6044.063170208625;6065.059915713072;6122.326544080148;6136.631802091889;6221.502279817604;5609.775608975595;5619.185218143346;5653.095504719226;5666.858328541819;5717.828465146053;5289.867753306198;5321.175217841241;5323.1749078892835;5325.946740532545;5335.039692855245;6134.8039827316215;6152.830962117273;6201.131841542097;6212.1303017577575;6218.347073557302;6194.81415557534;6201.96899015502;6203.875922481616;6213.347598502092;6215.254169499689;6211.2857021443115;6214.658193799384;6220.222472190976;6225.533085018551;6226.50187984951;5891.558133139946;5967.850803729942;6052.788152414644;6060.6969651516365;6116.847078823065;6177.567570270038;6187.474064704551;6219.0982307566055;6239.0953311768635;6245.469135123565;6009.038553831366;6033.849153771015;6040.486558642565;6047.460066771879;6093.695315234132;5314.5216930476445;5530.5022547970875;5815.621984570989;5950.7917222896995;5967.343592204941;5894.616849904743;5900.085486749561;5902.6458412495385;5931.466168044896;5957.314908785372;5858.414158584278;6075.406813027949;6078.326688853789;6096.682351421296;6096.961938643388;5800.883982320414;5804.245448091721;5860.2967643883;5868.882622347614;5893.086571581412 07:56:43 INFO - PROCESS | 1668 | |1;ext.html;494.83175720255105;498.68831980012493;503.87151276424504;504.01213621302236;511.1132185657883;29953.251168720766;33551.66448335516;34807;43296.3505547417;43452;44735.10529789402;45065;45179.548204517945;45208.095838083216;45292.0941581169;9599.184069354096;9803.117719405253;9803.31376803624;9810.656627018056;9821;8731.214190722834;8948;8955.820883582324;9050.728478145666;9059.909400906006;11786.528538858436;12041.458134383975;12089.455974481125;12259.754804903896;12287.324197169151;11832.40837958103;11979.820302695467;11986.160968732192;11997.40012999351;12029.09781766371;8673.349498787553;11471.082313414907;11806.704832379259;11827.05383569321;11945.343006134659;41731.7913410431;42142.789286053376;42165.78917105425;42199.789001055105;42225;3584.2652256287397;3591.443326284404;3633.418653015531;4128.710990230685;4438.60052595268;27623.86188069067;29200;33115.00654980355;33157.33685326304;34825.825870870736;3241.173500757315;3288.5560449339273;3389.983005098485;3440.9311813763584;3442.8278586070596;433776;512480;515268;515298;518975;62705.68647156826;62866.37133628697;63374.683126584074;63381.68309158517;63448.68275658592;66599.33400665931;66656.33343666598;66694.66652666706;66855.99716004264;67114.32885671081;16311.1028893412;16553.420630277884;16594.50216493507;16602.83397166037;16620.66758664844;12773.042021848398;12808.80786788199;12818.743625127445;12823.871761282267;12854;14877.958542901893;15245.847541524665;16874.831251687327;17035.744463833053;17257;6254.74981000764;6306.085617585516;6364.913017186661;6368.77709280173;6375.426211640882;972.3679608254691;1101.5703875488405;1119.8376235445814;1142.2175809570306;1145.4559084434993;27753.861230693718;30052;30103.69896301009;30603;31156;1080.7631144721452;1088.4924379734125;1102.913630074376;1111.3276467737064;1152.844366010578;15860.969037012557;15981.36074557028;16015.279312430968;16107.114108723898;16374.426895058616;650417;656742;659886;660800;667345;61371;61484.07773883306;61521;61607.38392616106;61673;1409.3516982188103;1442.314900422291;1457.0529156048653;1460.955416876938;1475.3360987555643;29711.702882971756;29820.956266530568;29988.550171747003;30702.539461907643;31129.688703113585;52348.738256308476;52411.47588524219;52689.736551317;52763.20855187099;52830.735846320524;595016;597334;597493;600084;604858;41639.433957572655;42171.04802663784;42829;43139.92150196145;43154.921126972076;35472.75845345401;42104.3684344729;48742.75628621834;52613;53300;40590.57932972332;40630;40657.82461662558;40769.59230407776;41242.38136427896;37792;39283.80358098191;39984.20031599294;40928.1814363717;40931.38602921018;30995.915142969887;39153.41269881011;39853;40153.79923100483;40189.99525011781;32478.837605812765;32691.182720432178;35687.46468803022;35720.64279357173;36042.8197859009;23197.536049279257;25013.874930625232;25536.872315638302;25711.228663140137;25817.870910645328 07:56:43 INFO - PROCESS | 1668 | |2;jquery.html;384.2718049296583;385.4911516797828;387.73827666325235;399.40089865202197;407.08406086305814;18898;20450.795492045076;20613.27853525127;20656;20660.793392066054;11901.40492975352;11922.28466292023;12072.818907716392;12208.02335813135;12232;4189.937150942738;4225.978870105645;4237.0466645004935;5282.128448805952;5495.203195536645;3843.1737176507045;4078.1028173801865;4092.1611069730634;5108.8211912583065;6859.691313890862;6478.838029049264;6630.3038180991025;6650.900236496456;6838.589684618914;6854.21176564694;5905.261842269716;6010.068439391901;6024.909626355587;6218.81343559694;6614.437772789294;3797.582265950743;5443.863903402407;5800.042992906166;5913.970430147821;5924.140999555084;13093.86906130936;13252.271125088117;13306;13414.664633384244;13416.798748018788;2256.165218869029;2332.6734257204007;2362.9645555316683;2430.1373012580457;2702.297402675299;9831.459269740162;10165.847512287322;14458.421663133462;14947.925260373628;14975.70534482412;1655.7433597792362;1789.3111152206495;1799.2589526597378;1841.4751795738284;1856.9814301856943;99614.5019274899;122806;123597.14604280943;125657;125977;9473.431594104239;11560.884391156149;11696.824547631792;11755.941220293844;11907.404629768476;14724;14813.777793333109;14824;14826.851731482762;14848.480303189337;8515.19105684959;8577.742667719978;8606.741797746077;8691;8764.03595604483;8515.659373625109;8524.786880328042;8549.615267312985;8638;8681.175288347597;4501.077279157765;4569.075791493447;4578.2903649934315;4586.8165273388695;4653.488116307203;1600.5518454832666;1912.5887934093957;1929.4983304340972;1932.1788239997945;1933.94198174055;746.8535797550842;836.652789092532;865.9911203448039;897.9371443998857;1041.5156952017348;20443.4889127773;21486.24798132058;21538.892305538684;21686.674699879513;21747.67378489324;910.9407888487227;928.962841486333;991.2317953586029;1002.5939494504737;1010.9696709098739;11146;11366.829497557543;11378.597140186504;11547.191696581158;11580.420978951019;565076;591651;594662;602972;605347.946520538;92404;127713;128562;130183;132493.33753331364;940.8165407745424;961.3318743473219;971.1890571372842;1038.5326603028668;1051.931624444424;14885.404583816526;19318.4204473866;19971.88597428032;20010.89994550018;20245;49184.77038074077;54118.729406354294;54305.72847135739;54370.7281463606;54741.72629136988;75021.24978750142;77620.61189694016;77735.22264777507;81752.18247817684;84315.31369372456;7059.752908648173;7489.625518723933;7500;7546.169921308542;7745.070591529053;10323.741906452398;13074.346282685638;13140.540081097115;13140.802887956497;13426.932865335611;9972.850407244043;10639.840402394126;10963.451827408438;11400.087992960378;11449.885501144881;5262.342207224097;5386.461353864583;5922.555808314308;11076;11443.542258309575;11464.713382165513;11478.368689722145;11751.236169649288;11791;11797.410129493663;7369.594672293076;7421.740239091821;7510.774676759706;7588.430867684835;7706.884396733942;9796.971318011507;10780.065276173984;10907.072898803373;11849.466773995018;11861.169718119994 07:56:43 INFO - PROCESS | 1668 | |3;mootools.html;1232.2729589542168;1523.8818042920343;1571.0416645846035;1789.7762779652544;1972.0534143611076;7763;8560.914390856089;8789.472631642106;8801.51591662459;8945.373823832335;8562.143785621434;8796.824063518727;8798.472091674488;8804;8808.251298639607;1846.5937493751353;1923.249932526316;1967.842572594189;1970.2611520679745;2187.4750059985568;2028.0468179955471;2048.6005228980357;2165.9241926532573;2241.1707668162803;2241.2827895073583;2204.195468653938;2209.745879223886;2212.966805497919;2213.247495851418;2214.0922221889027;2155.4719093822036;2223.944401389962;2224.810891074261;2226.877521736304;2246.7416247131464;1962.4112766170092;2138.4974530985205;2211.889405529717;2213.0926320208637;2213.7232845894264;8801.427907186013;8850.690225842065;8857.689980850704;8857.867131993025;8860.689875854314;4944.901101977976;5106.4638212987475;5107.693538387709;5110.514501122388;5116.667416617908;3460.4290292101773;3594.263176048904;3666.156783939682;3667.7065834733157;3668.578113516953;1067.781104873499;1092.7104317355906;1103.696483467045;1103.7351035751362;1117.2681893359863;11657.533698652129;11679.065674746;11687.181897267195;11693;11698.94150529242;4591.540845915449;4733.431988161443;4802.639802014863;4809.134355815987;4826.710397376169;9821.950890245504;9895.01049895019;9898.901010989797;9912.107910288109;9914.504274786375;5336.759845806948;5336.973315133399;5343.866403339947;5343.973280133574;5346;4125.958740412558;4398.824047038147;4751.168545504592;4899.6570240083565;4909.2881532177635;3685.4471829225295;3958.3468727659615;4022.9597704022585;4097.078157414618;4103.281925663056;1274.8151518029833;1297.4356155072385;1297.5217334890315;1312.9080964332404;1315.1780137414116;834.5201509132273;938.1603464898877;949.8670186173941;952.2905435450633;976.5068640336584;5559.805406810742;5701.344345400332;5827.94172058274;5878.912401205791;5961.850953726192;916.935814492979;920.1994264989369;969.2682025071026;976.5410257179115;981.2738573455734;3756.380197267475;4083.816228269735;4103.753774773463;4139.77231252278;4159.604837540429;10499.632512862167;10637.680869573926;10698.946505267424;10719.464026798629;10733.570657173781;10476.266661333786;10571;10640.25518213733;10644.467776611138;10653.201009924287;7732.110807257125;7943.165967573437;8046.919530804734;8057.476264042935;8204.507729536246;4537.8184872604725;5071.949280507295;5851.180834683063;5863.355030946507;6055.455009049207;9596.424214547149;9743.61025558998;9892.258080644124;9917.752056198653;9926.751831204278;8468.280196183394;9776.560054797408;10123.848142277724;10151.593936242467;10151.89848101539;6898;7128.429725622042;7527.397808175442;7685.4620176587105;7686.269804368576;6644.667766611747;7598.582077985543;7804.931256910282;8410.747677569683;8430.747077587437;7400.555966641803;7454.329110380092;7518.323350898446;7550.5469671819865;7562.546247225184;7287.161976372785;7297.671604777904;7312.1591017033725;7428.220036896048;7488.850222995401;6325.683715814283;6625.668716564249;6898.8620227596175;6988.231294557696;7003.824904377431;4944.554990050912;4970.956099219205;4971.37857767779;4986;5089.541941225308;4964.230544265644;5028.9245661315845;5052.444231134646;5088.618353623567;5124.769385377593 07:56:43 INFO - PROCESS | 1668 | |4;prototype.html;302.23232988209946;307.8553080052375;309.29172195671913;310.9113902537777;316.7102101577058;5341.03861304965;5358.866028349294;5360.490753378433;5368;5369.838904832852;4232.5344212136715;4865.878353041176;5207.6354655174155;5324.467553244674;5331.893362132755;3247.9188020299443;3813.8283777229954;3918.9804050979706;3960.4653371794725;4004.6996475264327;3572.517710109128;3723.385641369184;3892.9026774330587;3998.4402183694306;4028.415879697442;3619.678817231718;3652.9634703653023;3701.4262789267705;3829.885103446887;4063.8984025399304;3556.8043757593323;3988.78061706606;4022.5172979242384;4040.373742069984;4049.8131009254967;2944.2492164497917;3165.968340316613;3279.754018448602;3425.9143521411916;3570.946435803465;4467.932981005288;4535.795889184961;4645.674802763808;4676.812927482896;5124.155304090525;4110.46563946688;4600.7929643166135;4660.86017419478;4695.201815691341;4706.22347312693;4832.806687732486;5080.669756465818;5186.988537235241;5192.92210616841;5605.439456054402;3160.8103513789247;3644.9453258201147;3766.832281992591;3795.7153213508823;4036.1725846201457;4944.579710724593;5272.604554658416;5736.4550367715;5901.396910409844;5943.257092863392;3300.5874265716784;3683.889483315505;3890.455336252927;3899.317619416591;3903.4535165076913;3976.741511801724;4054.1486287879875;4074.6774338627292;4078.0416602098485;4093.467849179647;3964.623360780721;4632.822573390501;4652.604528615106;4700.647451441156;4719.362886010414;5612.326520817528;5613.726440791486;5636.266410790763;5641.433147917687;5651.802186923438;1217.8173274008793;1229.6987238126717;1311.4229738915037;1398.706271682958;1403.5508637236192;710.6249407396704;829.1459796409707;848.2281124176969;868.5570359116896;868.8878235858111;4410.911781764411;4437.977810110929;4441.555844415532;4442.73343599385;4477.417935668343;4208.810603522848;4456.398386217884;4822.855314340575;4897.559219670246;4944.453606392711;3795.6204379561823;3911.8044097795564;4125.484314460692;4274.221755858716;4424.358468022118;2244.5062086340977;2257.825930516155;2286.3611734654296;2359.4809141988735;2571.4857028594356;4425.889352766207;4915.5330243626795;4933.802647894044;5033.723145227048;5065.417476990119;4483.422032331422;4580.541945805459;4957.6281778866005;4961.5286547777905;5018.974905125451;5039.799727173271;5367.973160134253;5459.86058016642;5902.288336382401;6087.721578468571;5557.388027627009;5760.695721711349;5848.473637372657;5894.351621321737;5910;4745.501722319244;4893.828715994923;5187.325647665779;5410.513053825174;5658.801941932013;4813.95186048135;5241.475852414728;5898.734556944862;5973;6049.3950604939155;3657.9209133305208;3708.647678470541;3727.906802329855;3881.84472621092;3922.78424686645;5103.540681338549;5289.047971365191;5393.948180104999;5710.828675139753;5841.591088623755;4854.538818812207;4899.118158731462;5654.886902261849;5671.1927595840325;5684.289463817023;5445.482679145475;5500.724963751876;5528.9447105530035;5592.804251851166;5596.524295434933;5045.351190624951;5079.771410286472;5243.397009343822;5517.944820551904;5556.833295000994;5213.608979326642;5428.891422171613;5628.437156284339;5633.436656334334;5638.661680299195;4933.112039832865;5266.289050978069;5475.014497390508;5538.6122971391615;5547.583931205257 07:56:43 INFO - PROCESS | 1668 | |5;yui.html;765.3418060467997;773.7562667759659;786.7345068294302;825.9504429734214;870.8084221471282;14822.555323340304;15197.620059498504;15286.464973725922;15332.926695131346;15336.309866056026;14014.859851401483;14082.225477598753;14089.718205635882;14093.224872632001;14109.576712698636;730.2952650692081;758.776161032494;885.8361203177433;923.3213588012806;924.2652091587204;915.1306259053925;924.2328867040374;925.1211349218236;925.9212966897821;929.3262384771054;985.9408435493857;986.9654562090328;987.279286121132;989.1443901025597;993.940363578184;768.1205020251808;957.9137877591015;985.950702464881;986.5659109991652;987.7629368951472;732.5677850068486;734.4785202506228;735.7902997645699;735.9926400736031;740.5630677900065;14445.566633001026;14474.769644580223;14475.710485790225;14481.565553033426;14499.927500362535;13428.127171733806;13603.795943060863;13633;13648.112872663245;13692;12307.384630768425;12383.93808030963;12410.875891241063;12465.127441079128;12552.497900083985;4454.28731402974;4503.189425903336;4547.090581883604;4580.061087477059;4723.220668589679;66793.66603167001;85468.29063418697;85984.57007714984;86515.56742216248;89719;10852.294600850919;10875.564977400816;10895.618653347095;10922;11053;20647;20648.48378790542;20658.967051647356;20662;20675.58648827045;6517.804465866032;6615.470762339001;6814.863702725917;6935.410490108397;6941.930580694229;4211.368294755811;4316.43886294786;4320.935185972213;4326.6538676905775;4463.776811159429;3311.784733992283;3401.200717831308;3408.7784294020807;3409.7272218222483;3410.4543273076188;633.8637193003526;643.8551325951623;646.4763541531374;660.570629091085;661.0117873778731;624.5659266809614;632.0962212931245;640.3756337570832;648.2415573778691;650.4536189600758;8378.49729016261;8426.988761348677;8431.367647426467;8640.956795216109;8701.504354711056;641.6310621392718;642.0465608571268;644.78399736088;646.2568046746279;647.2459584584001;4035.4753881995152;4124.13393187428;4133.834646614162;4180.26845302077;4193.30810416284;82233;86721;87040;87059.56470217608;87312.56343718241;63552.68223658945;71869.8163359121;75311;75956;79827.60086199532;60020;61323.69338153281;61868.69065654643;68113.65943170253;68742.96885546621;8443.493390396596;8638.82722345562;8743.38796284254;9226.308026898143;9286.860697089414;38602.42096368613;39487.407688884115;39581.40627890526;40861;41597.58402415937;28983.84064637391;29108.27229319284;59330.703346482995;59785.80428391495;61105;12651.093439363816;12673.8098928518;12808.423620936894;13173.47306107745;13356.599302020955;14064.859351406763;14209.715805684034;14364.994450388373;14422.495212667507;14706.411743530567;13158.736825263633;13198.406071726604;13211.492140746519;13325.200487970753;13367.598972030855;13200.603981880558;13274.469021239429;13288.003399744865;13322.800157997832;13441.327933603085;12955.5465558705;12967.157134786208;12982.559621700068;13110.213387196798;13394.062415630813;10422.530986105492;10456;10638.574457021632;10800.189985750943;10853.081977936785;11048.944755276494;11114.555417783198;11355.205135640426;11373.658790236306;11373.829392558953 07:56:43 INFO - PROCESS | 1668 | __end_tp_report 07:56:43 INFO - PROCESS | 1668 | __start_cc_report 07:56:43 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1306 07:56:43 INFO - PROCESS | 1668 | __end_cc_report 07:56:43 INFO - PROCESS | 1668 | __startTimestamp1464361003319__endTimestamp 07:56:43 INFO - PROCESS | 1668 | 07:56:43 INFO - PROCESS | 1668 | ------- Summary: start ------- 07:56:43 INFO - PROCESS | 1668 | Number of tests: 6 07:56:43 INFO - PROCESS | 1668 | 07:56:43 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5667.38 Median:5868.88 stddev:623.15 (10.6%) stddev-sans-first:583.84 07:56:43 INFO - PROCESS | 1668 | Values: 2825.9 3429.1 3440.5 3455.1 3493.3 5460.8 5548.2 5625.1 5650.6 5657.2 5566.9 5584.6 5684.2 5705.2 5764.9 5077.9 5088.6 5149.0 5182.1 5244.5 5239.9 5280.0 5303.0 5304.3 5331.6 5093.6 5102.6 5128.4 5145.3 5165.9 5107.3 5113.7 5117.2 5136.2 5154.9 5146.7 5162.8 5183.6 5183.6 5233.1 5418.3 5432.7 5495.9 5526.6 5559.2 6105.9 6127.3 6151.4 6173.7 6176.6 6206.4 6206.9 6238.3 6240.7 6257.1 5597.8 5631.5 5664.1 5666.3 5797.4 6224.1 6237.8 6238.3 6244.3 6275.7 6052.6 6053.0 6053.8 6066.8 6093.2 6006.8 6019.7 6086.5 6115.8 6144.3 6039.1 6093.0 6097.1 6104.1 6107.3 6100.8 6120.6 6124.5 6130.1 6131.2 4852.6 4885.9 4894.7 4896.6 4904.8 4312.4 4357.7 4396.7 4413.2 4474.3 5648.2 5662.5 5662.8 5677.0 5696.7 6044.1 6065.1 6122.3 6136.6 6221.5 5609.8 5619.2 5653.1 5666.9 5717.8 5289.9 5321.2 5323.2 5325.9 5335.0 6134.8 6152.8 6201.1 6212.1 6218.3 6194.8 6202.0 6203.9 6213.3 6215.3 6211.3 6214.7 6220.2 6225.5 6226.5 5891.6 5967.9 6052.8 6060.7 6116.8 6177.6 6187.5 6219.1 6239.1 6245.5 6009.0 6033.8 6040.5 6047.5 6093.7 5314.5 5530.5 5815.6 5950.8 5967.3 5894.6 5900.1 5902.6 5931.5 5957.3 5858.4 6075.4 6078.3 6096.7 6097.0 5800.9 5804.2 5860.3 5868.9 5893.1 07:56:43 INFO - PROCESS | 1668 | 07:56:43 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:72716.41 Median:40069.00 stddev:157511.93 (393.1%) stddev-sans-first:157860.45 07:56:43 INFO - PROCESS | 1668 | Values: 494.8 498.7 503.9 504.0 511.1 29953.3 33551.7 34807.0 43296.4 43452.0 44735.1 45065.0 45179.5 45208.1 45292.1 9599.2 9803.1 9803.3 9810.7 9821.0 8731.2 8948.0 8955.8 9050.7 9059.9 11786.5 12041.5 12089.5 12259.8 12287.3 11832.4 11979.8 11986.2 11997.4 12029.1 8673.3 11471.1 11806.7 11827.1 11945.3 41731.8 42142.8 42165.8 42199.8 42225.0 3584.3 3591.4 3633.4 4128.7 4438.6 27623.9 29200.0 33115.0 33157.3 34825.8 3241.2 3288.6 3390.0 3440.9 3442.8 433776.0 512480.0 515268.0 515298.0 518975.0 62705.7 62866.4 63374.7 63381.7 63448.7 66599.3 66656.3 66694.7 66856.0 67114.3 16311.1 16553.4 16594.5 16602.8 16620.7 12773.0 12808.8 12818.7 12823.9 12854.0 14878.0 15245.8 16874.8 17035.7 17257.0 6254.7 6306.1 6364.9 6368.8 6375.4 972.4 1101.6 1119.8 1142.2 1145.5 27753.9 30052.0 30103.7 30603.0 31156.0 1080.8 1088.5 1102.9 1111.3 1152.8 15861.0 15981.4 16015.3 16107.1 16374.4 650417.0 656742.0 659886.0 660800.0 667345.0 61371.0 61484.1 61521.0 61607.4 61673.0 1409.4 1442.3 1457.1 1461.0 1475.3 29711.7 29821.0 29988.6 30702.5 31129.7 52348.7 52411.5 52689.7 52763.2 52830.7 595016.0 597334.0 597493.0 600084.0 604858.0 41639.4 42171.0 42829.0 43139.9 43154.9 35472.8 42104.4 48742.8 52613.0 53300.0 40590.6 40630.0 40657.8 40769.6 41242.4 37792.0 39283.8 39984.2 40928.2 40931.4 30995.9 39153.4 39853.0 40153.8 40190.0 32478.8 32691.2 35687.5 35720.6 36042.8 23197.5 25013.9 25536.9 25711.2 25817.9 07:56:43 INFO - PROCESS | 1668 | 07:56:43 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34178.63 Median:3249.94 stddev:98949.95 (3044.7%) stddev-sans-first:99194.99 07:56:43 INFO - PROCESS | 1668 | Values: 384.3 385.5 387.7 399.4 407.1 18898.0 20450.8 20613.3 20656.0 20660.8 11901.4 11922.3 12072.8 12208.0 12232.0 4189.9 4226.0 4237.0 5282.1 5495.2 3843.2 4078.1 4092.2 5108.8 6859.7 6478.8 6630.3 6650.9 6838.6 6854.2 5905.3 6010.1 6024.9 6218.8 6614.4 3797.6 5443.9 5800.0 5914.0 5924.1 13093.9 13252.3 13306.0 13414.7 13416.8 2256.2 2332.7 2363.0 2430.1 2702.3 9831.5 10165.8 14458.4 14947.9 14975.7 1655.7 1789.3 1799.3 1841.5 1857.0 99614.5 122806.0 123597.1 125657.0 125977.0 9473.4 11560.9 11696.8 11755.9 11907.4 14724.0 14813.8 14824.0 14826.9 14848.5 8515.2 8577.7 8606.7 8691.0 8764.0 8515.7 8524.8 8549.6 8638.0 8681.2 4501.1 4569.1 4578.3 4586.8 4653.5 1600.6 1912.6 1929.5 1932.2 1933.9 746.9 836.7 866.0 897.9 1041.5 20443.5 21486.2 21538.9 21686.7 21747.7 910.9 929.0 991.2 1002.6 1011.0 11146.0 11366.8 11378.6 11547.2 11580.4 565076.0 591651.0 594662.0 602972.0 605347.9 92404.0 127713.0 128562.0 130183.0 132493.3 940.8 961.3 971.2 1038.5 1051.9 14885.4 19318.4 19971.9 20010.9 20245.0 49184.8 54118.7 54305.7 54370.7 54741.7 75021.2 77620.6 77735.2 81752.2 84315.3 7059.8 7489.6 7500.0 7546.2 7745.1 10323.7 13074.3 13140.5 13140.8 13426.9 9972.9 10639.8 10963.5 11400.1 11449.9 5262.3 5386.5 5922.6 11076.0 11443.5 11464.7 11478.4 11751.2 11791.0 11797.4 7369.6 7421.7 7510.8 7588.4 7706.9 9797.0 10780.1 10907.1 11849.5 11861.2 07:56:43 INFO - PROCESS | 1668 | 07:56:43 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5629.46 Median:5040.68 stddev:3186.11 (63.2%) stddev-sans-first:3177.90 07:56:43 INFO - PROCESS | 1668 | Values: 1232.3 1523.9 1571.0 1789.8 1972.1 7763.0 8560.9 8789.5 8801.5 8945.4 8562.1 8796.8 8798.5 8804.0 8808.3 1846.6 1923.2 1967.8 1970.3 2187.5 2028.0 2048.6 2165.9 2241.2 2241.3 2204.2 2209.7 2213.0 2213.2 2214.1 2155.5 2223.9 2224.8 2226.9 2246.7 1962.4 2138.5 2211.9 2213.1 2213.7 8801.4 8850.7 8857.7 8857.9 8860.7 4944.9 5106.5 5107.7 5110.5 5116.7 3460.4 3594.3 3666.2 3667.7 3668.6 1067.8 1092.7 1103.7 1103.7 1117.3 11657.5 11679.1 11687.2 11693.0 11698.9 4591.5 4733.4 4802.6 4809.1 4826.7 9822.0 9895.0 9898.9 9912.1 9914.5 5336.8 5337.0 5343.9 5344.0 5346.0 4126.0 4398.8 4751.2 4899.7 4909.3 3685.4 3958.3 4023.0 4097.1 4103.3 1274.8 1297.4 1297.5 1312.9 1315.2 834.5 938.2 949.9 952.3 976.5 5559.8 5701.3 5827.9 5878.9 5961.9 916.9 920.2 969.3 976.5 981.3 3756.4 4083.8 4103.8 4139.8 4159.6 10499.6 10637.7 10698.9 10719.5 10733.6 10476.3 10571.0 10640.3 10644.5 10653.2 7732.1 7943.2 8046.9 8057.5 8204.5 4537.8 5071.9 5851.2 5863.4 6055.5 9596.4 9743.6 9892.3 9917.8 9926.8 8468.3 9776.6 10123.8 10151.6 10151.9 6898.0 7128.4 7527.4 7685.5 7686.3 6644.7 7598.6 7804.9 8410.7 8430.7 7400.6 7454.3 7518.3 7550.5 7562.5 7287.2 7297.7 7312.2 7428.2 7488.9 6325.7 6625.7 6898.9 6988.2 7003.8 4944.6 4971.0 4971.4 4986.0 5089.5 4964.2 5028.9 5052.4 5088.6 5124.8 07:56:43 INFO - PROCESS | 1668 | 07:56:43 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4352.54 Median:4779.73 stddev:1360.79 (28.5%) stddev-sans-first:1330.22 07:56:43 INFO - PROCESS | 1668 | Values: 302.2 307.9 309.3 310.9 316.7 5341.0 5358.9 5360.5 5368.0 5369.8 4232.5 4865.9 5207.6 5324.5 5331.9 3247.9 3813.8 3919.0 3960.5 4004.7 3572.5 3723.4 3892.9 3998.4 4028.4 3619.7 3653.0 3701.4 3829.9 4063.9 3556.8 3988.8 4022.5 4040.4 4049.8 2944.2 3166.0 3279.8 3425.9 3570.9 4467.9 4535.8 4645.7 4676.8 5124.2 4110.5 4600.8 4660.9 4695.2 4706.2 4832.8 5080.7 5187.0 5192.9 5605.4 3160.8 3644.9 3766.8 3795.7 4036.2 4944.6 5272.6 5736.5 5901.4 5943.3 3300.6 3683.9 3890.5 3899.3 3903.5 3976.7 4054.1 4074.7 4078.0 4093.5 3964.6 4632.8 4652.6 4700.6 4719.4 5612.3 5613.7 5636.3 5641.4 5651.8 1217.8 1229.7 1311.4 1398.7 1403.6 710.6 829.1 848.2 868.6 868.9 4410.9 4438.0 4441.6 4442.7 4477.4 4208.8 4456.4 4822.9 4897.6 4944.5 3795.6 3911.8 4125.5 4274.2 4424.4 2244.5 2257.8 2286.4 2359.5 2571.5 4425.9 4915.5 4933.8 5033.7 5065.4 4483.4 4580.5 4957.6 4961.5 5019.0 5039.8 5368.0 5459.9 5902.3 6087.7 5557.4 5760.7 5848.5 5894.4 5910.0 4745.5 4893.8 5187.3 5410.5 5658.8 4814.0 5241.5 5898.7 5973.0 6049.4 3657.9 3708.6 3727.9 3881.8 3922.8 5103.5 5289.0 5393.9 5710.8 5841.6 4854.5 4899.1 5654.9 5671.2 5684.3 5445.5 5500.7 5528.9 5592.8 5596.5 5045.4 5079.8 5243.4 5517.9 5556.8 5213.6 5428.9 5628.4 5633.4 5638.7 4933.1 5266.3 5475.0 5538.6 5547.6 07:56:43 INFO - PROCESS | 1668 | 07:56:43 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:17551.88 Median:4390.47 stddev:23525.71 (535.8%) stddev-sans-first:23557.93 07:56:43 INFO - PROCESS | 1668 | Values: 765.3 773.8 786.7 826.0 870.8 14822.6 15197.6 15286.5 15332.9 15336.3 14014.9 14082.2 14089.7 14093.2 14109.6 730.3 758.8 885.8 923.3 924.3 915.1 924.2 925.1 925.9 929.3 985.9 987.0 987.3 989.1 993.9 768.1 957.9 986.0 986.6 987.8 732.6 734.5 735.8 736.0 740.6 14445.6 14474.8 14475.7 14481.6 14499.9 13428.1 13603.8 13633.0 13648.1 13692.0 12307.4 12383.9 12410.9 12465.1 12552.5 4454.3 4503.2 4547.1 4580.1 4723.2 66793.7 85468.3 85984.6 86515.6 89719.0 10852.3 10875.6 10895.6 10922.0 11053.0 20647.0 20648.5 20659.0 20662.0 20675.6 6517.8 6615.5 6814.9 6935.4 6941.9 4211.4 4316.4 4320.9 4326.7 4463.8 3311.8 3401.2 3408.8 3409.7 3410.5 633.9 643.9 646.5 660.6 661.0 624.6 632.1 640.4 648.2 650.5 8378.5 8427.0 8431.4 8641.0 8701.5 641.6 642.0 644.8 646.3 647.2 4035.5 4124.1 4133.8 4180.3 4193.3 82233.0 86721.0 87040.0 87059.6 87312.6 63552.7 71869.8 75311.0 75956.0 79827.6 60020.0 61323.7 61868.7 68113.7 68743.0 8443.5 8638.8 8743.4 9226.3 9286.9 38602.4 39487.4 39581.4 40861.0 41597.6 28983.8 29108.3 59330.7 59785.8 61105.0 12651.1 12673.8 12808.4 13173.5 13356.6 14064.9 14209.7 14365.0 14422.5 14706.4 13158.7 13198.4 13211.5 13325.2 13367.6 13200.6 13274.5 13288.0 13322.8 13441.3 12955.5 12967.2 12982.6 13110.2 13394.1 10422.5 10456.0 10638.6 10800.2 10853.1 11048.9 11114.6 11355.2 11373.7 11373.8 07:56:43 INFO - PROCESS | 1668 | -------- Summary: end -------- 07:56:43 INFO - PROCESS | 1668 | 07:56:43 INFO - TEST-INFO | 1668: exit 0 07:56:43 INFO - TEST-OK | dromaeo_css | took 1115621ms 07:56:43 INFO - TEST-START | kraken 07:56:43 INFO - operating with platform_type : mac_ 07:56:43 INFO - Initialising browser for kraken test... 07:56:43 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpw4Fn89/profile http://localhost:49270/getInfo.html) 07:56:46 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 07:56:46 INFO - PROCESS | 1676 | colorDepth:24 07:56:46 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 07:56:46 INFO - PROCESS | 1676 | __metrics 07:56:46 INFO - PROCESS | 1676 | 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" 07:56:46 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:56:46 INFO - TEST-INFO | 1676: exit 0 07:56:46 INFO - Browser initialized. 07:56:46 INFO - Running cycle 1/1 for kraken test... 07:56:46 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpw4Fn89/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:56:58 INFO - PROCESS | 1677 | RSS: Main: 241438720 07:56:58 INFO - PROCESS | 1677 | 07:57:01 INFO - PROCESS | 1677 | 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) 07:57:02 INFO - PROCESS | 1677 | RSS: Main: 291426304 07:57:02 INFO - PROCESS | 1677 | 07:57:05 INFO - PROCESS | 1677 | 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) 07:57:05 INFO - PROCESS | 1677 | RSS: Main: 300867584 07:57:05 INFO - PROCESS | 1677 | 07:57:09 INFO - PROCESS | 1677 | 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) 07:57:09 INFO - PROCESS | 1677 | RSS: Main: 385212416 07:57:09 INFO - PROCESS | 1677 | 07:57:11 INFO - PROCESS | 1677 | 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) 07:57:12 INFO - PROCESS | 1677 | RSS: Main: 289546240 07:57:12 INFO - PROCESS | 1677 | 07:57:14 INFO - PROCESS | 1677 | 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) 07:57:15 INFO - PROCESS | 1677 | RSS: Main: 292958208 07:57:15 INFO - PROCESS | 1677 | 07:57:17 INFO - PROCESS | 1677 | 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) 07:57:18 INFO - PROCESS | 1677 | RSS: Main: 331898880 07:57:18 INFO - PROCESS | 1677 | 07:57:21 INFO - PROCESS | 1677 | 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) 07:57:22 INFO - PROCESS | 1677 | RSS: Main: 333119488 07:57:22 INFO - PROCESS | 1677 | 07:57:24 INFO - PROCESS | 1677 | 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) 07:57:25 INFO - PROCESS | 1677 | RSS: Main: 330010624 07:57:25 INFO - PROCESS | 1677 | 07:57:26 INFO - PROCESS | 1677 | 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) 07:57:27 INFO - PROCESS | 1677 | RSS: Main: 310968320 07:57:27 INFO - PROCESS | 1677 | 07:57:29 INFO - PROCESS | 1677 | 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) 07:57:30 INFO - PROCESS | 1677 | RSS: Main: 319225856 07:57:30 INFO - PROCESS | 1677 | 07:57:32 INFO - PROCESS | 1677 | 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) 07:57:32 INFO - PROCESS | 1677 | RSS: Main: 320962560 07:57:32 INFO - PROCESS | 1677 | 07:57:35 INFO - PROCESS | 1677 | 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) 07:57:36 INFO - PROCESS | 1677 | RSS: Main: 323743744 07:57:36 INFO - PROCESS | 1677 | 07:57:39 INFO - PROCESS | 1677 | 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) 07:57:40 INFO - PROCESS | 1677 | RSS: Main: 324141056 07:57:40 INFO - PROCESS | 1677 | 07:57:41 INFO - PROCESS | 1677 | 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) 07:57:42 INFO - PROCESS | 1677 | RSS: Main: 367763456 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | __start_tp_report 07:57:42 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 07:57:42 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 07:57:42 INFO - PROCESS | 1677 | |i|pagename|runs| 07:57:42 INFO - PROCESS | 1677 | |0;ai-astar;119;153;164;114;112;112;112;112;111;112 07:57:42 INFO - PROCESS | 1677 | |1;audio-beat-detection;151;118;116;164;117;115;140;107;148;115 07:57:42 INFO - PROCESS | 1677 | |2;audio-dft;181;185;182;197;264;182;178;182;181;182 07:57:42 INFO - PROCESS | 1677 | |3;audio-fft;76;57;62;85;58;55;62;59;123;57 07:57:42 INFO - PROCESS | 1677 | |4;audio-oscillator;83;68;101;73;81;68;93;76;67;100 07:57:42 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;87;84;104;84;86;84;87;85;91;85 07:57:42 INFO - PROCESS | 1677 | |6;imaging-darkroom;92;92;105;148;120;93;93;93;93;93 07:57:42 INFO - PROCESS | 1677 | |7;imaging-desaturate;85;83;83;84;85;82;83;84;83;85 07:57:42 INFO - PROCESS | 1677 | |8;json-parse-financial;56;56;56;55;55;53;55;52;52;53 07:57:42 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;67;77;80;75;71;70;69;69;70;71 07:57:42 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;86;86;86;83;84;86;84;88;122;87 07:57:42 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;143;132;140;146;142;131;133;137;150;137 07:57:42 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;162;161;164;222;156;193;222;202;164;218 07:57:42 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;67;66;66;65;66;65;65;66;65;66 07:57:42 INFO - PROCESS | 1677 | __end_tp_report 07:57:42 INFO - PROCESS | 1677 | __start_cc_report 07:57:42 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1922 07:57:42 INFO - PROCESS | 1677 | __end_cc_report 07:57:42 INFO - PROCESS | 1677 | __startTimestamp1464361062015__endTimestamp 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | ------- Summary: start ------- 07:57:42 INFO - PROCESS | 1677 | Number of tests: 14 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:122.10 Median:113.00 stddev:19.49 (17.2%) stddev-sans-first:20.64 07:57:42 INFO - PROCESS | 1677 | Values: 119.0 153.0 164.0 114.0 112.0 112.0 112.0 112.0 111.0 112.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:129.00 stddev:19.72 (15.3%) stddev-sans-first:19.26 07:57:42 INFO - PROCESS | 1677 | Values: 151.0 118.0 116.0 164.0 117.0 115.0 140.0 107.0 148.0 115.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:191.40 Median:182.00 stddev:26.02 (14.3%) stddev-sans-first:27.32 07:57:42 INFO - PROCESS | 1677 | Values: 181.0 185.0 182.0 197.0 264.0 182.0 178.0 182.0 181.0 182.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:69.40 Median:60.50 stddev:21.14 (34.9%) stddev-sans-first:22.29 07:57:42 INFO - PROCESS | 1677 | Values: 76.0 57.0 62.0 85.0 58.0 55.0 62.0 59.0 123.0 57.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:74.50 stddev:13.05 (17.5%) stddev-sans-first:13.82 07:57:42 INFO - PROCESS | 1677 | Values: 83.0 68.0 101.0 73.0 81.0 68.0 93.0 76.0 67.0 100.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:85.50 stddev:6.11 (7.1%) stddev-sans-first:6.48 07:57:42 INFO - PROCESS | 1677 | Values: 87.0 84.0 104.0 84.0 86.0 84.0 87.0 85.0 91.0 85.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:102.20 Median:93.00 stddev:18.42 (19.8%) stddev-sans-first:19.16 07:57:42 INFO - PROCESS | 1677 | Values: 92.0 92.0 105.0 148.0 120.0 93.0 93.0 93.0 93.0 93.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:83.70 Median:84.00 stddev:1.06 (1.3%) stddev-sans-first:1.01 07:57:42 INFO - PROCESS | 1677 | Values: 85.0 83.0 83.0 84.0 85.0 82.0 83.0 84.0 83.0 85.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:55.00 stddev:1.64 (3.0%) stddev-sans-first:1.62 07:57:42 INFO - PROCESS | 1677 | Values: 56.0 56.0 56.0 55.0 55.0 53.0 55.0 52.0 52.0 53.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:71.00 stddev:4.09 (5.8%) stddev-sans-first:3.94 07:57:42 INFO - PROCESS | 1677 | Values: 67.0 77.0 80.0 75.0 71.0 70.0 69.0 69.0 70.0 71.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:86.00 stddev:11.62 (13.5%) stddev-sans-first:12.27 07:57:42 INFO - PROCESS | 1677 | Values: 86.0 86.0 86.0 83.0 84.0 86.0 84.0 88.0 122.0 87.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:141.00 stddev:6.26 (4.4%) stddev-sans-first:6.48 07:57:42 INFO - PROCESS | 1677 | Values: 143.0 132.0 140.0 146.0 142.0 131.0 133.0 137.0 150.0 137.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.40 Median:197.50 stddev:27.87 (14.1%) stddev-sans-first:28.12 07:57:42 INFO - PROCESS | 1677 | Values: 162.0 161.0 164.0 222.0 156.0 193.0 222.0 202.0 164.0 218.0 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.53 07:57:42 INFO - PROCESS | 1677 | Values: 67.0 66.0 66.0 65.0 66.0 65.0 65.0 66.0 65.0 66.0 07:57:42 INFO - PROCESS | 1677 | -------- Summary: end -------- 07:57:42 INFO - PROCESS | 1677 | 07:57:42 INFO - TEST-INFO | 1677: exit 0 07:57:42 INFO - TEST-OK | kraken | took 58942ms 07:57:42 INFO - SUITE-END | took 1174s 07:57:43 INFO - Completed test suite (00:19:34) 07:57:43 INFO - javascript benchmark 07:57:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2825.8587070646468, 3429.108431807731, 3440.4667276572122, 3455.0671318743957, 3493.2664140530487, 5460.836174914759, 5548.223248745179, 5625.071863142587, 5650.604457687963, 5657.179708942206, 5566.916496252559, 5584.636998595089, 5684.175794509793, 5705.229793977812, 5764.913526297109, 5077.873053173664, 5088.567471764904, 5149.0, 5182.067227898977, 5244.527992480676, 5239.947600523984, 5279.973600131994, 5302.973485132569, 5304.310439642841, 5331.600129990266, 5093.567046801027, 5102.566281866046, 5128.410232823236, 5145.279660847485, 5165.948340516585, 5107.284980102789, 5113.718745468997, 5117.232415137759, 5136.203888397305, 5154.92267615986, 5146.665466744687, 5162.767675454614, 5183.5853131749745, 5183.5853131749745, 5233.084210263189, 5418.322709661293, 5432.728363581845, 5495.917561236584, 5526.640768350085, 5559.221708960748, 6105.925223381411, 6127.264728232641, 6151.415615516519, 6173.69131543421, 6176.567640265183, 6206.379362063757, 6206.875862482771, 6238.313785483593, 6240.687965601702, 6257.092721555349, 5597.832065038056, 5631.549476041907, 5664.122061080498, 5666.320041595035, 5797.391273916221, 6224.06639004153, 6237.844053898689, 6238.344973777779, 6244.313125556185, 6275.748970041239, 6052.608078743428, 6053.02751013817, 6053.788117415869, 6066.817995460143, 6093.207882975274, 6006.789762358297, 6019.69901504923, 6086.513078953673, 6115.788007244199, 6144.279601711528, 6039.124326972565, 6093.0, 6097.085437184369, 6104.053871649901, 6107.328193898667, 6100.75596976116, 6120.632762034204, 6124.479419249325, 6130.111133885562, 6131.172291740647, 4852.611791056707, 4885.853424397203, 4894.706317620883, 4896.559309662076, 4904.8038078477175, 4312.37470566766, 4357.7385356878685, 4396.736195828261, 4413.205622987893, 4474.26174681174, 5648.15277708347, 5662.462066103713, 5662.7734890603915, 5677.006523858308, 5696.743646535916, 6044.063170208625, 6065.059915713072, 6122.326544080148, 6136.631802091889, 6221.502279817604, 5609.775608975595, 5619.185218143346, 5653.095504719226, 5666.858328541819, 5717.828465146053, 5289.867753306198, 5321.175217841241, 5323.1749078892835, 5325.946740532545, 5335.039692855245, 6134.8039827316215, 6152.830962117273, 6201.131841542097, 6212.1303017577575, 6218.347073557302, 6194.81415557534, 6201.96899015502, 6203.875922481616, 6213.347598502092, 6215.254169499689, 6211.2857021443115, 6214.658193799384, 6220.222472190976, 6225.533085018551, 6226.50187984951, 5891.558133139946, 5967.850803729942, 6052.788152414644, 6060.6969651516365, 6116.847078823065, 6177.567570270038, 6187.474064704551, 6219.0982307566055, 6239.0953311768635, 6245.469135123565, 6009.038553831366, 6033.849153771015, 6040.486558642565, 6047.460066771879, 6093.695315234132, 5314.5216930476445, 5530.5022547970875, 5815.621984570989, 5950.7917222896995, 5967.343592204941, 5894.616849904743, 5900.085486749561, 5902.6458412495385, 5931.466168044896, 5957.314908785372, 5858.414158584278, 6075.406813027949, 6078.326688853789, 6096.682351421296, 6096.961938643388, 5800.883982320414, 5804.245448091721, 5860.2967643883, 5868.882622347614, 5893.086571581412], "value": 5627.388827296644, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.83175720255105, 498.68831980012493, 503.87151276424504, 504.01213621302236, 511.1132185657883, 29953.251168720766, 33551.66448335516, 34807.0, 43296.3505547417, 43452.0, 44735.10529789402, 45065.0, 45179.548204517945, 45208.095838083216, 45292.0941581169, 9599.184069354096, 9803.117719405253, 9803.31376803624, 9810.656627018056, 9821.0, 8731.214190722834, 8948.0, 8955.820883582324, 9050.728478145666, 9059.909400906006, 11786.528538858436, 12041.458134383975, 12089.455974481125, 12259.754804903896, 12287.324197169151, 11832.40837958103, 11979.820302695467, 11986.160968732192, 11997.40012999351, 12029.09781766371, 8673.349498787553, 11471.082313414907, 11806.704832379259, 11827.05383569321, 11945.343006134659, 41731.7913410431, 42142.789286053376, 42165.78917105425, 42199.789001055105, 42225.0, 3584.2652256287397, 3591.443326284404, 3633.418653015531, 4128.710990230685, 4438.60052595268, 27623.86188069067, 29200.0, 33115.00654980355, 33157.33685326304, 34825.825870870736, 3241.173500757315, 3288.5560449339273, 3389.983005098485, 3440.9311813763584, 3442.8278586070596, 433776.0, 512480.0, 515268.0, 515298.0, 518975.0, 62705.68647156826, 62866.37133628697, 63374.683126584074, 63381.68309158517, 63448.68275658592, 66599.33400665931, 66656.33343666598, 66694.66652666706, 66855.99716004264, 67114.32885671081, 16311.1028893412, 16553.420630277884, 16594.50216493507, 16602.83397166037, 16620.66758664844, 12773.042021848398, 12808.80786788199, 12818.743625127445, 12823.871761282267, 12854.0, 14877.958542901893, 15245.847541524665, 16874.831251687327, 17035.744463833053, 17257.0, 6254.74981000764, 6306.085617585516, 6364.913017186661, 6368.77709280173, 6375.426211640882, 972.3679608254691, 1101.5703875488405, 1119.8376235445814, 1142.2175809570306, 1145.4559084434993, 27753.861230693718, 30052.0, 30103.69896301009, 30603.0, 31156.0, 1080.7631144721452, 1088.4924379734125, 1102.913630074376, 1111.3276467737064, 1152.844366010578, 15860.969037012557, 15981.36074557028, 16015.279312430968, 16107.114108723898, 16374.426895058616, 650417.0, 656742.0, 659886.0, 660800.0, 667345.0, 61371.0, 61484.07773883306, 61521.0, 61607.38392616106, 61673.0, 1409.3516982188103, 1442.314900422291, 1457.0529156048653, 1460.955416876938, 1475.3360987555643, 29711.702882971756, 29820.956266530568, 29988.550171747003, 30702.539461907643, 31129.688703113585, 52348.738256308476, 52411.47588524219, 52689.736551317, 52763.20855187099, 52830.735846320524, 595016.0, 597334.0, 597493.0, 600084.0, 604858.0, 41639.433957572655, 42171.04802663784, 42829.0, 43139.92150196145, 43154.921126972076, 35472.75845345401, 42104.3684344729, 48742.75628621834, 52613.0, 53300.0, 40590.57932972332, 40630.0, 40657.82461662558, 40769.59230407776, 41242.38136427896, 37792.0, 39283.80358098191, 39984.20031599294, 40928.1814363717, 40931.38602921018, 30995.915142969887, 39153.41269881011, 39853.0, 40153.79923100483, 40189.99525011781, 32478.837605812765, 32691.182720432178, 35687.46468803022, 35720.64279357173, 36042.8197859009, 23197.536049279257, 25013.874930625232, 25536.872315638302, 25711.228663140137, 25817.870910645328], "value": 20885.21180694858, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.2718049296583, 385.4911516797828, 387.73827666325235, 399.40089865202197, 407.08406086305814, 18898.0, 20450.795492045076, 20613.27853525127, 20656.0, 20660.793392066054, 11901.40492975352, 11922.28466292023, 12072.818907716392, 12208.02335813135, 12232.0, 4189.937150942738, 4225.978870105645, 4237.0466645004935, 5282.128448805952, 5495.203195536645, 3843.1737176507045, 4078.1028173801865, 4092.1611069730634, 5108.8211912583065, 6859.691313890862, 6478.838029049264, 6630.3038180991025, 6650.900236496456, 6838.589684618914, 6854.21176564694, 5905.261842269716, 6010.068439391901, 6024.909626355587, 6218.81343559694, 6614.437772789294, 3797.582265950743, 5443.863903402407, 5800.042992906166, 5913.970430147821, 5924.140999555084, 13093.86906130936, 13252.271125088117, 13306.0, 13414.664633384244, 13416.798748018788, 2256.165218869029, 2332.6734257204007, 2362.9645555316683, 2430.1373012580457, 2702.297402675299, 9831.459269740162, 10165.847512287322, 14458.421663133462, 14947.925260373628, 14975.70534482412, 1655.7433597792362, 1789.3111152206495, 1799.2589526597378, 1841.4751795738284, 1856.9814301856943, 99614.5019274899, 122806.0, 123597.14604280943, 125657.0, 125977.0, 9473.431594104239, 11560.884391156149, 11696.824547631792, 11755.941220293844, 11907.404629768476, 14724.0, 14813.777793333109, 14824.0, 14826.851731482762, 14848.480303189337, 8515.19105684959, 8577.742667719978, 8606.741797746077, 8691.0, 8764.03595604483, 8515.659373625109, 8524.786880328042, 8549.615267312985, 8638.0, 8681.175288347597, 4501.077279157765, 4569.075791493447, 4578.2903649934315, 4586.8165273388695, 4653.488116307203, 1600.5518454832666, 1912.5887934093957, 1929.4983304340972, 1932.1788239997945, 1933.94198174055, 746.8535797550842, 836.652789092532, 865.9911203448039, 897.9371443998857, 1041.5156952017348, 20443.4889127773, 21486.24798132058, 21538.892305538684, 21686.674699879513, 21747.67378489324, 910.9407888487227, 928.962841486333, 991.2317953586029, 1002.5939494504737, 1010.9696709098739, 11146.0, 11366.829497557543, 11378.597140186504, 11547.191696581158, 11580.420978951019, 565076.0, 591651.0, 594662.0, 602972.0, 605347.946520538, 92404.0, 127713.0, 128562.0, 130183.0, 132493.33753331364, 940.8165407745424, 961.3318743473219, 971.1890571372842, 1038.5326603028668, 1051.931624444424, 14885.404583816526, 19318.4204473866, 19971.88597428032, 20010.89994550018, 20245.0, 49184.77038074077, 54118.729406354294, 54305.72847135739, 54370.7281463606, 54741.72629136988, 75021.24978750142, 77620.61189694016, 77735.22264777507, 81752.18247817684, 84315.31369372456, 7059.752908648173, 7489.625518723933, 7500.0, 7546.169921308542, 7745.070591529053, 10323.741906452398, 13074.346282685638, 13140.540081097115, 13140.802887956497, 13426.932865335611, 9972.850407244043, 10639.840402394126, 10963.451827408438, 11400.087992960378, 11449.885501144881, 5262.342207224097, 5386.461353864583, 5922.555808314308, 11076.0, 11443.542258309575, 11464.713382165513, 11478.368689722145, 11751.236169649288, 11791.0, 11797.410129493663, 7369.594672293076, 7421.740239091821, 7510.774676759706, 7588.430867684835, 7706.884396733942, 9796.971318011507, 10780.065276173984, 10907.072898803373, 11849.466773995018, 11861.169718119994], "value": 9153.49000514554, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1232.2729589542168, 1523.8818042920343, 1571.0416645846035, 1789.7762779652544, 1972.0534143611076, 7763.0, 8560.914390856089, 8789.472631642106, 8801.51591662459, 8945.373823832335, 8562.143785621434, 8796.824063518727, 8798.472091674488, 8804.0, 8808.251298639607, 1846.5937493751353, 1923.249932526316, 1967.842572594189, 1970.2611520679745, 2187.4750059985568, 2028.0468179955471, 2048.6005228980357, 2165.9241926532573, 2241.1707668162803, 2241.2827895073583, 2204.195468653938, 2209.745879223886, 2212.966805497919, 2213.247495851418, 2214.0922221889027, 2155.4719093822036, 2223.944401389962, 2224.810891074261, 2226.877521736304, 2246.7416247131464, 1962.4112766170092, 2138.4974530985205, 2211.889405529717, 2213.0926320208637, 2213.7232845894264, 8801.427907186013, 8850.690225842065, 8857.689980850704, 8857.867131993025, 8860.689875854314, 4944.901101977976, 5106.4638212987475, 5107.693538387709, 5110.514501122388, 5116.667416617908, 3460.4290292101773, 3594.263176048904, 3666.156783939682, 3667.7065834733157, 3668.578113516953, 1067.781104873499, 1092.7104317355906, 1103.696483467045, 1103.7351035751362, 1117.2681893359863, 11657.533698652129, 11679.065674746, 11687.181897267195, 11693.0, 11698.94150529242, 4591.540845915449, 4733.431988161443, 4802.639802014863, 4809.134355815987, 4826.710397376169, 9821.950890245504, 9895.01049895019, 9898.901010989797, 9912.107910288109, 9914.504274786375, 5336.759845806948, 5336.973315133399, 5343.866403339947, 5343.973280133574, 5346.0, 4125.958740412558, 4398.824047038147, 4751.168545504592, 4899.6570240083565, 4909.2881532177635, 3685.4471829225295, 3958.3468727659615, 4022.9597704022585, 4097.078157414618, 4103.281925663056, 1274.8151518029833, 1297.4356155072385, 1297.5217334890315, 1312.9080964332404, 1315.1780137414116, 834.5201509132273, 938.1603464898877, 949.8670186173941, 952.2905435450633, 976.5068640336584, 5559.805406810742, 5701.344345400332, 5827.94172058274, 5878.912401205791, 5961.850953726192, 916.935814492979, 920.1994264989369, 969.2682025071026, 976.5410257179115, 981.2738573455734, 3756.380197267475, 4083.816228269735, 4103.753774773463, 4139.77231252278, 4159.604837540429, 10499.632512862167, 10637.680869573926, 10698.946505267424, 10719.464026798629, 10733.570657173781, 10476.266661333786, 10571.0, 10640.25518213733, 10644.467776611138, 10653.201009924287, 7732.110807257125, 7943.165967573437, 8046.919530804734, 8057.476264042935, 8204.507729536246, 4537.8184872604725, 5071.949280507295, 5851.180834683063, 5863.355030946507, 6055.455009049207, 9596.424214547149, 9743.61025558998, 9892.258080644124, 9917.752056198653, 9926.751831204278, 8468.280196183394, 9776.560054797408, 10123.848142277724, 10151.593936242467, 10151.89848101539, 6898.0, 7128.429725622042, 7527.397808175442, 7685.4620176587105, 7686.269804368576, 6644.667766611747, 7598.582077985543, 7804.931256910282, 8410.747677569683, 8430.747077587437, 7400.555966641803, 7454.329110380092, 7518.323350898446, 7550.5469671819865, 7562.546247225184, 7287.161976372785, 7297.671604777904, 7312.1591017033725, 7428.220036896048, 7488.850222995401, 6325.683715814283, 6625.668716564249, 6898.8620227596175, 6988.231294557696, 7003.824904377431, 4944.554990050912, 4970.956099219205, 4971.37857767779, 4986.0, 5089.541941225308, 4964.230544265644, 5028.9245661315845, 5052.444231134646, 5088.618353623567, 5124.769385377593], "value": 4511.476671180173, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.23232988209946, 307.8553080052375, 309.29172195671913, 310.9113902537777, 316.7102101577058, 5341.03861304965, 5358.866028349294, 5360.490753378433, 5368.0, 5369.838904832852, 4232.5344212136715, 4865.878353041176, 5207.6354655174155, 5324.467553244674, 5331.893362132755, 3247.9188020299443, 3813.8283777229954, 3918.9804050979706, 3960.4653371794725, 4004.6996475264327, 3572.517710109128, 3723.385641369184, 3892.9026774330587, 3998.4402183694306, 4028.415879697442, 3619.678817231718, 3652.9634703653023, 3701.4262789267705, 3829.885103446887, 4063.8984025399304, 3556.8043757593323, 3988.78061706606, 4022.5172979242384, 4040.373742069984, 4049.8131009254967, 2944.2492164497917, 3165.968340316613, 3279.754018448602, 3425.9143521411916, 3570.946435803465, 4467.932981005288, 4535.795889184961, 4645.674802763808, 4676.812927482896, 5124.155304090525, 4110.46563946688, 4600.7929643166135, 4660.86017419478, 4695.201815691341, 4706.22347312693, 4832.806687732486, 5080.669756465818, 5186.988537235241, 5192.92210616841, 5605.439456054402, 3160.8103513789247, 3644.9453258201147, 3766.832281992591, 3795.7153213508823, 4036.1725846201457, 4944.579710724593, 5272.604554658416, 5736.4550367715, 5901.396910409844, 5943.257092863392, 3300.5874265716784, 3683.889483315505, 3890.455336252927, 3899.317619416591, 3903.4535165076913, 3976.741511801724, 4054.1486287879875, 4074.6774338627292, 4078.0416602098485, 4093.467849179647, 3964.623360780721, 4632.822573390501, 4652.604528615106, 4700.647451441156, 4719.362886010414, 5612.326520817528, 5613.726440791486, 5636.266410790763, 5641.433147917687, 5651.802186923438, 1217.8173274008793, 1229.6987238126717, 1311.4229738915037, 1398.706271682958, 1403.5508637236192, 710.6249407396704, 829.1459796409707, 848.2281124176969, 868.5570359116896, 868.8878235858111, 4410.911781764411, 4437.977810110929, 4441.555844415532, 4442.73343599385, 4477.417935668343, 4208.810603522848, 4456.398386217884, 4822.855314340575, 4897.559219670246, 4944.453606392711, 3795.6204379561823, 3911.8044097795564, 4125.484314460692, 4274.221755858716, 4424.358468022118, 2244.5062086340977, 2257.825930516155, 2286.3611734654296, 2359.4809141988735, 2571.4857028594356, 4425.889352766207, 4915.5330243626795, 4933.802647894044, 5033.723145227048, 5065.417476990119, 4483.422032331422, 4580.541945805459, 4957.6281778866005, 4961.5286547777905, 5018.974905125451, 5039.799727173271, 5367.973160134253, 5459.86058016642, 5902.288336382401, 6087.721578468571, 5557.388027627009, 5760.695721711349, 5848.473637372657, 5894.351621321737, 5910.0, 4745.501722319244, 4893.828715994923, 5187.325647665779, 5410.513053825174, 5658.801941932013, 4813.95186048135, 5241.475852414728, 5898.734556944862, 5973.0, 6049.3950604939155, 3657.9209133305208, 3708.647678470541, 3727.906802329855, 3881.84472621092, 3922.78424686645, 5103.540681338549, 5289.047971365191, 5393.948180104999, 5710.828675139753, 5841.591088623755, 4854.538818812207, 4899.118158731462, 5654.886902261849, 5671.1927595840325, 5684.289463817023, 5445.482679145475, 5500.724963751876, 5528.9447105530035, 5592.804251851166, 5596.524295434933, 5045.351190624951, 5079.771410286472, 5243.397009343822, 5517.944820551904, 5556.833295000994, 5213.608979326642, 5428.891422171613, 5628.437156284339, 5633.436656334334, 5638.661680299195, 4933.112039832865, 5266.289050978069, 5475.014497390508, 5538.6122971391615, 5547.583931205257], "value": 3922.0337522029663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.3418060467997, 773.7562667759659, 786.7345068294302, 825.9504429734214, 870.8084221471282, 14822.555323340304, 15197.620059498504, 15286.464973725922, 15332.926695131346, 15336.309866056026, 14014.859851401483, 14082.225477598753, 14089.718205635882, 14093.224872632001, 14109.576712698636, 730.2952650692081, 758.776161032494, 885.8361203177433, 923.3213588012806, 924.2652091587204, 915.1306259053925, 924.2328867040374, 925.1211349218236, 925.9212966897821, 929.3262384771054, 985.9408435493857, 986.9654562090328, 987.279286121132, 989.1443901025597, 993.940363578184, 768.1205020251808, 957.9137877591015, 985.950702464881, 986.5659109991652, 987.7629368951472, 732.5677850068486, 734.4785202506228, 735.7902997645699, 735.9926400736031, 740.5630677900065, 14445.566633001026, 14474.769644580223, 14475.710485790225, 14481.565553033426, 14499.927500362535, 13428.127171733806, 13603.795943060863, 13633.0, 13648.112872663245, 13692.0, 12307.384630768425, 12383.93808030963, 12410.875891241063, 12465.127441079128, 12552.497900083985, 4454.28731402974, 4503.189425903336, 4547.090581883604, 4580.061087477059, 4723.220668589679, 66793.66603167001, 85468.29063418697, 85984.57007714984, 86515.56742216248, 89719.0, 10852.294600850919, 10875.564977400816, 10895.618653347095, 10922.0, 11053.0, 20647.0, 20648.48378790542, 20658.967051647356, 20662.0, 20675.58648827045, 6517.804465866032, 6615.470762339001, 6814.863702725917, 6935.410490108397, 6941.930580694229, 4211.368294755811, 4316.43886294786, 4320.935185972213, 4326.6538676905775, 4463.776811159429, 3311.784733992283, 3401.200717831308, 3408.7784294020807, 3409.7272218222483, 3410.4543273076188, 633.8637193003526, 643.8551325951623, 646.4763541531374, 660.570629091085, 661.0117873778731, 624.5659266809614, 632.0962212931245, 640.3756337570832, 648.2415573778691, 650.4536189600758, 8378.49729016261, 8426.988761348677, 8431.367647426467, 8640.956795216109, 8701.504354711056, 641.6310621392718, 642.0465608571268, 644.78399736088, 646.2568046746279, 647.2459584584001, 4035.4753881995152, 4124.13393187428, 4133.834646614162, 4180.26845302077, 4193.30810416284, 82233.0, 86721.0, 87040.0, 87059.56470217608, 87312.56343718241, 63552.68223658945, 71869.8163359121, 75311.0, 75956.0, 79827.60086199532, 60020.0, 61323.69338153281, 61868.69065654643, 68113.65943170253, 68742.96885546621, 8443.493390396596, 8638.82722345562, 8743.38796284254, 9226.308026898143, 9286.860697089414, 38602.42096368613, 39487.407688884115, 39581.40627890526, 40861.0, 41597.58402415937, 28983.84064637391, 29108.27229319284, 59330.703346482995, 59785.80428391495, 61105.0, 12651.093439363816, 12673.8098928518, 12808.423620936894, 13173.47306107745, 13356.599302020955, 14064.859351406763, 14209.715805684034, 14364.994450388373, 14422.495212667507, 14706.411743530567, 13158.736825263633, 13198.406071726604, 13211.492140746519, 13325.200487970753, 13367.598972030855, 13200.603981880558, 13274.469021239429, 13288.003399744865, 13322.800157997832, 13441.327933603085, 12955.5465558705, 12967.157134786208, 12982.559621700068, 13110.213387196798, 13394.062415630813, 10422.530986105492, 10456.0, 10638.574457021632, 10800.189985750943, 10853.081977936785, 11048.944755276494, 11114.555417783198, 11355.205135640426, 11373.658790236306, 11373.829392558953], "value": 7170.3745605006525, "unit": "score"}], "name": "dromaeo_css", "value": 7175.613497923312, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 153.0, 164.0, 114.0, 112.0, 112.0, 112.0, 112.0, 111.0, 112.0], "value": 122.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 116.0, 164.0, 117.0, 115.0, 140.0, 107.0, 148.0, 115.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 182.0, 197.0, 264.0, 182.0, 178.0, 182.0, 181.0, 182.0], "value": 191.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 57.0, 62.0, 85.0, 58.0, 55.0, 62.0, 59.0, 123.0, 57.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 68.0, 101.0, 73.0, 81.0, 68.0, 93.0, 76.0, 67.0, 100.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 104.0, 84.0, 86.0, 84.0, 87.0, 85.0, 91.0, 85.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 105.0, 148.0, 120.0, 93.0, 93.0, 93.0, 93.0, 93.0], "value": 102.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 83.0, 84.0, 85.0, 82.0, 83.0, 84.0, 83.0, 85.0], "value": 83.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 55.0, 55.0, 53.0, 55.0, 52.0, 52.0, 53.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 77.0, 80.0, 75.0, 71.0, 70.0, 69.0, 69.0, 70.0, 71.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 83.0, 84.0, 86.0, 84.0, 88.0, 122.0, 87.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 132.0, 140.0, 146.0, 142.0, 131.0, 133.0, 137.0, 150.0, 137.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 164.0, 222.0, 156.0, 193.0, 222.0, 202.0, 164.0, 218.0], "value": 186.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 66.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1473.2, "alertThreshold": 2.0}]} 07:57:43 INFO - Return code: 0 07:57:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:57:43 ERROR - # TBPL SUCCESS # 07:57:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:57:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:57:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:57:43 INFO - 2016-05-27 07:57:43.325 screenresolution[1681:10802] starting screenresolution argv=screenresolution get 07:57:43 INFO - 2016-05-27 07:57:43.327 screenresolution[1681:10802] Display 0: 1600x1200x32@60 07:57:43 INFO - 2016-05-27 07:57:43.333 screenresolution[1682:10805] starting screenresolution argv=screenresolution list 07:57:43 INFO - Available Modes on Display 0 07:57:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:57:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:57:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:57:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:57:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:57:43 INFO - Intel Iris: 07:57:43 INFO - Chipset Model: Intel Iris 07:57:43 INFO - Type: GPU 07:57:43 INFO - Bus: Built-In 07:57:43 INFO - VRAM (Dynamic, Max): 1536 MB 07:57:43 INFO - Vendor: Intel (0x8086) 07:57:43 INFO - Device ID: 0x0a2e 07:57:43 INFO - Revision ID: 0x0009 07:57:43 INFO - Displays: 07:57:43 INFO - Display: 07:57:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:57:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:57:43 INFO - Display Serial Number: 1600x1200 60 07:57:43 INFO - Main Display: Yes 07:57:43 INFO - Mirror: Off 07:57:43 INFO - Online: Yes 07:57:43 INFO - Rotation: Supported 07:57:43 INFO - Return code: 0 07:57:43 INFO - Running post-action listener: _resource_record_post_action 07:57:43 INFO - ##### 07:57:43 INFO - ##### Finished run-tests step (success) 07:57:43 INFO - ##### 07:57:43 INFO - Running post-run listener: _resource_record_post_run 07:57:43 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 31669760; Write bytes: 700228096; Read time: 198; Write time: 1943 07:57:43 INFO - TinderboxPrint: CPU usage
53.9% 07:57:43 INFO - TinderboxPrint: I/O read bytes / time
31,669,760 / 198 07:57:43 INFO - TinderboxPrint: I/O write bytes / time
700,228,096 / 1,943 07:57:43 INFO - TinderboxPrint: CPU idle
2,232.5 (46.1%) 07:57:43 INFO - TinderboxPrint: CPU system
963.4 (19.9%) 07:57:43 INFO - TinderboxPrint: CPU user
1,645.8 (34.0%) 07:57:43 INFO - TinderboxPrint: Swap in / out
426,041,344 / 0 07:57:43 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 385475072; Write bytes: 380708864; Read time: 28306; Write time: 768 07:57:43 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29912576; Write bytes: 297925120; Read time: 184; Write time: 1122 07:57:44 INFO - Running post-run listener: _upload_blobber_files 07:57:44 INFO - Blob upload gear active. 07:57:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:57:44 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 07:57:44 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'] 07:57:44 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 07:57:44 INFO - (blobuploader) - INFO - Open directory for files ... 07:57:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:57:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:57:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:57:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:57:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:57:45 INFO - (blobuploader) - INFO - Done attempting. 07:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:57:46 INFO - (blobuploader) - INFO - Done attempting. 07:57:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:57:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:57:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:57:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:57:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:57:48 INFO - (blobuploader) - INFO - Done attempting. 07:57:48 INFO - (blobuploader) - INFO - Iteration through files over. 07:57:48 INFO - Return code: 0 07:57:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:57:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:57:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ee0d926967f3f9aa22a698298fcd1c58efeb1647068efbba1e28fa245d6465d89d618d2d3850c1c85eea3e1b550edc8c4cbd2aeda99ba59effdf555aa31da8aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04ae41d5048237c69b8e1e89e875312c1bebfc84649b1113bace4bc05b79550dba132fe088e321c27c9a6662fb3cc331b678d28dde49e566cdfcf133b97b8d67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f08372c73fdd61730cc948278779d715dcc137d598fe6038bc823ca9e79b707d011ca99bd1a690d47724cfe24c7da789af561df3108c30f72765c4fcdfee2684"} 07:57:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:57:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:57:48 INFO - Contents: 07:57:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ee0d926967f3f9aa22a698298fcd1c58efeb1647068efbba1e28fa245d6465d89d618d2d3850c1c85eea3e1b550edc8c4cbd2aeda99ba59effdf555aa31da8aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04ae41d5048237c69b8e1e89e875312c1bebfc84649b1113bace4bc05b79550dba132fe088e321c27c9a6662fb3cc331b678d28dde49e566cdfcf133b97b8d67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f08372c73fdd61730cc948278779d715dcc137d598fe6038bc823ca9e79b707d011ca99bd1a690d47724cfe24c7da789af561df3108c30f72765c4fcdfee2684"} 07:57:48 INFO - Running post-run listener: copy_logs_to_upload_dir 07:57:48 INFO - Copying logs to upload dir... 07:57:48 INFO - mkdir: /builds/slave/test/build/upload/logs 07:57:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1292.621504 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-27 07:57:48.639566) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 07:57:48.643463) ========= 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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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/ee0d926967f3f9aa22a698298fcd1c58efeb1647068efbba1e28fa245d6465d89d618d2d3850c1c85eea3e1b550edc8c4cbd2aeda99ba59effdf555aa31da8aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04ae41d5048237c69b8e1e89e875312c1bebfc84649b1113bace4bc05b79550dba132fe088e321c27c9a6662fb3cc331b678d28dde49e566cdfcf133b97b8d67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f08372c73fdd61730cc948278779d715dcc137d598fe6038bc823ca9e79b707d011ca99bd1a690d47724cfe24c7da789af561df3108c30f72765c4fcdfee2684"} build_url:https://queue.taskcluster.net/v1/task/BMi-wQBRQge783nRqNABZw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011344 build_url: 'https://queue.taskcluster.net/v1/task/BMi-wQBRQge783nRqNABZw/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/ee0d926967f3f9aa22a698298fcd1c58efeb1647068efbba1e28fa245d6465d89d618d2d3850c1c85eea3e1b550edc8c4cbd2aeda99ba59effdf555aa31da8aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04ae41d5048237c69b8e1e89e875312c1bebfc84649b1113bace4bc05b79550dba132fe088e321c27c9a6662fb3cc331b678d28dde49e566cdfcf133b97b8d67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f08372c73fdd61730cc948278779d715dcc137d598fe6038bc823ca9e79b707d011ca99bd1a690d47724cfe24c7da789af561df3108c30f72765c4fcdfee2684"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 07:57:48.674625) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 07:57:48.674977) ========= 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.vTX7wYqDP0/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.tZZ5HeH7Av/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.005245 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 07:57:48.723993) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 07:57:48.724314) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 07:57:48.724648) ========= ========= Total master_lag: 0.20 =========