builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0249 starttime: 1463440569.91 results: success (0) buildid: 20160516141946 builduid: 183604bcd2a14df7a6aeee8db2979c17 revision: e9533997fcef31fa5d2db464c6ceec0ca6ea560f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:09.905612) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:09.906045) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:09.906528) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:09.938740) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:09.939321) ========= 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.AijxbNf6mm/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.vl7LS2lEsT/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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-16 16:16:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 170M=0s 2016-05-16 16:16:10 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123872 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:10.083504) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:10.083788) ========= 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.AijxbNf6mm/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.vl7LS2lEsT/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.025607 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:10.134733) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 16:16:10.135079) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e9533997fcef31fa5d2db464c6ceec0ca6ea560f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev e9533997fcef31fa5d2db464c6ceec0ca6ea560f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AijxbNf6mm/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.vl7LS2lEsT/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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-16 16:16:10,221 truncating revision to first 12 chars 2016-05-16 16:16:10,221 Setting DEBUG logging. 2016-05-16 16:16:10,221 attempt 1/10 2016-05-16 16:16:10,221 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e9533997fcef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 16:16:11,142 unpacking tar archive at: mozilla-central-e9533997fcef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.174780 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 16:16:11.329915) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:11.330213) ========= script_repo_revision: e9533997fcef31fa5d2db464c6ceec0ca6ea560f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:11.333616) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:11.333912) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 16:16:11.349060) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-05-16 16:16:11.349399) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AijxbNf6mm/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.vl7LS2lEsT/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 16:16:11 INFO - MultiFileLogger online at 20160516 16:16:11 in /builds/slave/test 16:16:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:16:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:16:11 INFO - {'append_to_log': False, 16:16:11 INFO - 'base_work_dir': '/builds/slave/test', 16:16:11 INFO - 'blob_upload_branch': 'Firefox', 16:16:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:16:11 INFO - 'branch': 'Firefox', 16:16:11 INFO - 'buildbot_json_path': 'buildprops.json', 16:16:11 INFO - 'config_files': ('talos/mac_config.py',), 16:16:11 INFO - 'default_actions': ('clobber', 16:16:11 INFO - 'read-buildbot-config', 16:16:11 INFO - 'download-and-extract', 16:16:11 INFO - 'populate-webroot', 16:16:11 INFO - 'create-virtualenv', 16:16:11 INFO - 'install', 16:16:11 INFO - 'run-tests'), 16:16:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:16:11 INFO - 'download_minidump_stackwalk': True, 16:16:11 INFO - 'download_symbols': 'ondemand', 16:16:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:16:11 INFO - 'tooltool.py': '/tools/tooltool.py', 16:16:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:16:11 INFO - '/tools/misc-python/virtualenv.py')}, 16:16:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:16:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:16:11 INFO - 'installer_path': 'installer.exe', 16:16:11 INFO - 'log_level': 'info', 16:16:11 INFO - 'log_name': 'talos', 16:16:11 INFO - 'log_to_console': True, 16:16:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:16:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:16:11 INFO - 'opt_config_files': (), 16:16:11 INFO - 'pip_index': False, 16:16:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:16:11 INFO - 'cmd': ('bash', 16:16:11 INFO - '-c', 16:16:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:16:11 INFO - 'enabled': True, 16:16:11 INFO - 'halt_on_failure': False, 16:16:11 INFO - 'name': 'check_screen_resolution'},), 16:16:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:16:11 INFO - 'cmd': ('bash', 16:16:11 INFO - '-c', 16:16:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:16:11 INFO - 'enabled': True, 16:16:11 INFO - 'halt_on_failure': False, 16:16:11 INFO - 'name': 'check_screen_resolution'},), 16:16:11 INFO - 'run_cmd_checks_enabled': True, 16:16:11 INFO - 'sps_profile': False, 16:16:11 INFO - 'sps_profile_interval': 0, 16:16:11 INFO - 'suite': 'dromaeojs', 16:16:11 INFO - 'system_bits': '32', 16:16:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:16:11 INFO - 'title': 't-yosemite-r7-0249', 16:16:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:16:11 INFO - 'use_talos_json': True, 16:16:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:16:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:16:11 INFO - 'work_dir': 'build'} 16:16:11 INFO - ##### 16:16:11 INFO - ##### Running clobber step. 16:16:11 INFO - ##### 16:16:11 INFO - Running pre-action listener: _resource_record_pre_action 16:16:11 INFO - Running main action method: clobber 16:16:11 INFO - rmtree: /builds/slave/test/build 16:16:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:16:13 INFO - Running post-action listener: _resource_record_post_action 16:16:13 INFO - ##### 16:16:13 INFO - ##### Finished clobber step (success) 16:16:13 INFO - ##### 16:16:13 INFO - ##### 16:16:13 INFO - ##### Running read-buildbot-config step. 16:16:13 INFO - ##### 16:16:13 INFO - Running pre-action listener: _resource_record_pre_action 16:16:13 INFO - Running main action method: read_buildbot_config 16:16:13 INFO - Using buildbot properties: 16:16:13 INFO - { 16:16:13 INFO - "project": "", 16:16:13 INFO - "product": "firefox", 16:16:13 INFO - "script_repo_revision": "production", 16:16:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:16:13 INFO - "repository": "", 16:16:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:16:13 INFO - "buildid": "20160516141946", 16:16:13 INFO - "pgo_build": "False", 16:16:13 INFO - "basedir": "/builds/slave/test", 16:16:13 INFO - "buildnumber": 251, 16:16:13 INFO - "slavename": "t-yosemite-r7-0249", 16:16:13 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:16:13 INFO - "platform": "yosemite_r7", 16:16:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:16:13 INFO - "branch": "mozilla-central", 16:16:13 INFO - "repo_path": "mozilla-central", 16:16:13 INFO - "revision": "e9533997fcef31fa5d2db464c6ceec0ca6ea560f", 16:16:13 INFO - "stage_platform": "macosx64", 16:16:13 INFO - "builduid": "183604bcd2a14df7a6aeee8db2979c17", 16:16:13 INFO - "slavebuilddir": "test" 16:16:13 INFO - } 16:16:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:16:13 INFO - Running post-action listener: _resource_record_post_action 16:16:13 INFO - ##### 16:16:13 INFO - ##### Finished read-buildbot-config step (success) 16:16:13 INFO - ##### 16:16:13 INFO - ##### 16:16:13 INFO - ##### Running download-and-extract step. 16:16:13 INFO - ##### 16:16:13 INFO - Running pre-action listener: _resource_record_pre_action 16:16:13 INFO - Running main action method: download_and_extract 16:16:13 INFO - mkdir: /builds/slave/test/build/tests 16:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:13 INFO - https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:16:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:16:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:16:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/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 16:16:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/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 16:16:22 INFO - Downloaded 1199 bytes. 16:16:22 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:16:22 INFO - Using the following test package requirements: 16:16:22 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:16:22 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:22 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:16:22 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:22 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:16:22 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:22 INFO - u'jsshell-mac.zip'], 16:16:22 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:22 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:16:22 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:16:22 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:22 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:16:22 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:22 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:16:22 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:22 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:16:22 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:22 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:16:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:22 INFO - https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:16:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:16:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:16:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/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 16:16:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/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 16:16:27 INFO - Downloaded 18267679 bytes. 16:16:27 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'] 16:16:27 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 16:16:30 INFO - Return code: 0 16:16:30 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 16:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:30 INFO - https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:16:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:16:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:16:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/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 16:16:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/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 16:16:43 INFO - Downloaded 18267679 bytes. 16:16:43 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'] 16:16:43 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 16:16:46 INFO - Return code: 0 16:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:46 INFO - https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:16:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:16:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:16:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/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 16:16:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/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 16:16:48 INFO - Downloaded 10840194 bytes. 16:16:48 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'] 16:16:48 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 16:16:48 INFO - Return code: 0 16:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:48 INFO - https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:16:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:16:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:16:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:16:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/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 16:16:51 INFO - Downloaded 110068148 bytes. 16:16:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:16:51 INFO - mkdir: /builds/slave/test/properties 16:16:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:16:51 INFO - Writing to file /builds/slave/test/properties/build_url 16:16:51 INFO - Contents: 16:16:51 INFO - build_url:https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:16:52 INFO - Running post-action listener: _resource_record_post_action 16:16:52 INFO - Running post-action listener: set_extra_try_arguments 16:16:52 INFO - ##### 16:16:52 INFO - ##### Finished download-and-extract step (success) 16:16:52 INFO - ##### 16:16:52 INFO - ##### 16:16:52 INFO - ##### Running populate-webroot step. 16:16:52 INFO - ##### 16:16:52 INFO - Running pre-action listener: _resource_record_pre_action 16:16:52 INFO - Running main action method: populate_webroot 16:16:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:16:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:16:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:16:52 INFO - '--tppagecycles', 16:16:52 INFO - '7'], 16:16:52 INFO - 'tests': ['tsvgm']}}, 16:16:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:16:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:16:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:16:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:16:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:16:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:16:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:52 INFO - 'talos_options': ['--disable-e10s'], 16:16:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:52 INFO - 'talos_options': ['--disable-e10s'], 16:16:52 INFO - 'tests': ['damp', 'tps']}, 16:16:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:52 INFO - 'tests': ['damp', 'tps']}, 16:16:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:16:52 INFO - 'tests': ['dromaeo_dom']}, 16:16:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:16:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:16:52 INFO - 'tests': ['a11yr', 16:16:52 INFO - 'ts_paint', 16:16:52 INFO - 'tpaint', 16:16:52 INFO - 'sessionrestore', 16:16:52 INFO - 'sessionrestore_no_auto_restore', 16:16:52 INFO - 'tabpaint']}, 16:16:52 INFO - 'other-e10s': {'tests': ['a11yr', 16:16:52 INFO - 'ts_paint', 16:16:52 INFO - 'tpaint', 16:16:52 INFO - 'sessionrestore', 16:16:52 INFO - 'sessionrestore_no_auto_restore', 16:16:52 INFO - 'tabpaint']}, 16:16:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:16:52 INFO - 'ts_paint', 16:16:52 INFO - 'tpaint', 16:16:52 INFO - 'sessionrestore', 16:16:52 INFO - 'sessionrestore_no_auto_restore', 16:16:52 INFO - 'tabpaint']}, 16:16:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:16:52 INFO - 'ts_paint', 16:16:52 INFO - 'tpaint', 16:16:52 INFO - 'sessionrestore', 16:16:52 INFO - 'sessionrestore_no_auto_restore', 16:16:52 INFO - 'tabpaint']}, 16:16:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:16:52 INFO - 'tests': ['a11yr', 16:16:52 INFO - 'ts_paint', 16:16:52 INFO - 'tpaint', 16:16:52 INFO - 'sessionrestore', 16:16:52 INFO - 'sessionrestore_no_auto_restore', 16:16:52 INFO - 'tabpaint']}, 16:16:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:16:52 INFO - 'tests': ['a11yr', 16:16:52 INFO - 'ts_paint', 16:16:52 INFO - 'tpaint', 16:16:52 INFO - 'sessionrestore', 16:16:52 INFO - 'sessionrestore_no_auto_restore', 16:16:52 INFO - 'tabpaint']}, 16:16:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:16:52 INFO - 'tests': ['tsvgx', 16:16:52 INFO - 'tsvgr_opacity', 16:16:52 INFO - 'tart', 16:16:52 INFO - 'tscrollx', 16:16:52 INFO - 'cart']}, 16:16:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:16:52 INFO - 'tsvgr_opacity', 16:16:52 INFO - 'tart', 16:16:52 INFO - 'tscrollx', 16:16:52 INFO - 'cart']}, 16:16:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:52 INFO - 'talos_options': ['--disable-e10s'], 16:16:52 INFO - 'tests': ['tp5o']}, 16:16:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:52 INFO - 'tests': ['tp5o']}, 16:16:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:52 INFO - 'talos_options': ['--disable-e10s', 16:16:52 INFO - '--xperf_path', 16:16:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:52 INFO - 'tests': ['tp5n']}, 16:16:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:52 INFO - 'talos_options': ['--xperf_path', 16:16:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:52 INFO - 'tests': ['tp5n']}}, 16:16:52 INFO - 'talos.zip': {'path': '', 16:16:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:16:52 INFO - Running post-action listener: _resource_record_post_action 16:16:52 INFO - ##### 16:16:52 INFO - ##### Finished populate-webroot step (success) 16:16:52 INFO - ##### 16:16:52 INFO - ##### 16:16:52 INFO - ##### Running create-virtualenv step. 16:16:52 INFO - ##### 16:16:52 INFO - Running pre-action listener: _resource_record_pre_action 16:16:52 INFO - Running main action method: create_virtualenv 16:16:52 INFO - Creating virtualenv /builds/slave/test/build/venv 16:16:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:16:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:16:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:16:52 INFO - Using real prefix '/tools/python27' 16:16:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:16:53 INFO - Installing distribute.............................................................................................................................................................................................done. 16:16:56 INFO - Installing pip.................done. 16:16:56 INFO - Return code: 0 16:16:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:56 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 0x106e46a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e4c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cead30>, '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 0x106b0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce1b20>, '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.AijxbNf6mm/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.vl7LS2lEsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 16:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AijxbNf6mm/Render', 16:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:56 INFO - 'HOME': '/Users/cltbld', 16:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:56 INFO - 'LOGNAME': 'cltbld', 16:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:56 INFO - 'MOZ_NO_REMOTE': '1', 16:16:56 INFO - 'NO_EM_RESTART': '1', 16:16:56 INFO - 'PAGER': '/bin/cat', 16:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:56 INFO - 'PWD': '/builds/slave/test', 16:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:56 INFO - 'SHELL': '/bin/bash', 16:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vl7LS2lEsT/Listeners', 16:16:56 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 16:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:56 INFO - 'USER': 'cltbld', 16:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:56 INFO - 'XPC_FLAGS': '0x0', 16:16:56 INFO - 'XPC_SERVICE_NAME': '0', 16:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:16:56 INFO - Cleaning up... 16:16:56 INFO - Return code: 0 16:16:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:56 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 0x106e46a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e4c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cead30>, '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 0x106b0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce1b20>, '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.AijxbNf6mm/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.vl7LS2lEsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 16:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AijxbNf6mm/Render', 16:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:56 INFO - 'HOME': '/Users/cltbld', 16:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:56 INFO - 'LOGNAME': 'cltbld', 16:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:56 INFO - 'MOZ_NO_REMOTE': '1', 16:16:56 INFO - 'NO_EM_RESTART': '1', 16:16:56 INFO - 'PAGER': '/bin/cat', 16:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:56 INFO - 'PWD': '/builds/slave/test', 16:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:56 INFO - 'SHELL': '/bin/bash', 16:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vl7LS2lEsT/Listeners', 16:16:56 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 16:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:56 INFO - 'USER': 'cltbld', 16:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:56 INFO - 'XPC_FLAGS': '0x0', 16:16:56 INFO - 'XPC_SERVICE_NAME': '0', 16:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:56 INFO - Downloading/unpacking psutil>=3.1.1 16:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:17:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:00 INFO - Installing collected packages: psutil 16:17:00 INFO - Running setup.py install for psutil 16:17:00 INFO - building 'psutil._psutil_osx' extension 16:17:00 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 16:17:00 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 16:17:01 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 16:17:01 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 16:17:01 INFO - building 'psutil._psutil_posix' extension 16:17:01 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 16:17:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:17:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:17:01 INFO - ^ 16:17:01 INFO - 1 warning generated. 16:17:01 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 16:17:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:01 INFO - Successfully installed psutil 16:17:01 INFO - Cleaning up... 16:17:01 INFO - Return code: 0 16:17:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e46a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e4c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cead30>, '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 0x106b0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce1b20>, '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.AijxbNf6mm/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.vl7LS2lEsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 16:17:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:17:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:17:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AijxbNf6mm/Render', 16:17:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:01 INFO - 'HOME': '/Users/cltbld', 16:17:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:01 INFO - 'LOGNAME': 'cltbld', 16:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:01 INFO - 'MOZ_NO_REMOTE': '1', 16:17:01 INFO - 'NO_EM_RESTART': '1', 16:17:01 INFO - 'PAGER': '/bin/cat', 16:17:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:01 INFO - 'PWD': '/builds/slave/test', 16:17:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:01 INFO - 'SHELL': '/bin/bash', 16:17:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vl7LS2lEsT/Listeners', 16:17:01 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 16:17:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:01 INFO - 'USER': 'cltbld', 16:17:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:01 INFO - 'XPC_FLAGS': '0x0', 16:17:01 INFO - 'XPC_SERVICE_NAME': '0', 16:17:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:17:04 INFO - Installing collected packages: mozsystemmonitor 16:17:04 INFO - Running setup.py install for mozsystemmonitor 16:17:04 INFO - Successfully installed mozsystemmonitor 16:17:04 INFO - Cleaning up... 16:17:04 INFO - Return code: 0 16:17:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:04 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 0x106e46a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e4c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cead30>, '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 0x106b0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce1b20>, '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.AijxbNf6mm/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.vl7LS2lEsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 16:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AijxbNf6mm/Render', 16:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:04 INFO - 'HOME': '/Users/cltbld', 16:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:04 INFO - 'LOGNAME': 'cltbld', 16:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:04 INFO - 'MOZ_NO_REMOTE': '1', 16:17:04 INFO - 'NO_EM_RESTART': '1', 16:17:04 INFO - 'PAGER': '/bin/cat', 16:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:04 INFO - 'PWD': '/builds/slave/test', 16:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:04 INFO - 'SHELL': '/bin/bash', 16:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vl7LS2lEsT/Listeners', 16:17:04 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 16:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:04 INFO - 'USER': 'cltbld', 16:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:04 INFO - 'XPC_FLAGS': '0x0', 16:17:04 INFO - 'XPC_SERVICE_NAME': '0', 16:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:04 INFO - Downloading/unpacking blobuploader==1.2.4 16:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:07 INFO - Downloading blobuploader-1.2.4.tar.gz 16:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:17:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:17: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 16:17: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 16:17: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 16:17: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 16:17: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 16:17: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 16:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:17:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:08 INFO - Downloading docopt-0.6.1.tar.gz 16:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:17:08 INFO - Installing collected packages: blobuploader, requests, docopt 16:17:08 INFO - Running setup.py install for blobuploader 16:17:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:17:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:17:08 INFO - Running setup.py install for requests 16:17:09 INFO - Running setup.py install for docopt 16:17:09 INFO - Successfully installed blobuploader requests docopt 16:17:09 INFO - Cleaning up... 16:17:09 INFO - Return code: 0 16:17:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17: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')]} 16:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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')]} 16:17:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:09 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 0x106e46a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e4c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cead30>, '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 0x106b0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce1b20>, '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.AijxbNf6mm/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.vl7LS2lEsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 16:17:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:17:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:17:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AijxbNf6mm/Render', 16:17:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:09 INFO - 'HOME': '/Users/cltbld', 16:17:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:09 INFO - 'LOGNAME': 'cltbld', 16:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:09 INFO - 'MOZ_NO_REMOTE': '1', 16:17:09 INFO - 'NO_EM_RESTART': '1', 16:17:09 INFO - 'PAGER': '/bin/cat', 16:17:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:09 INFO - 'PWD': '/builds/slave/test', 16:17:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:09 INFO - 'SHELL': '/bin/bash', 16:17:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vl7LS2lEsT/Listeners', 16:17:09 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 16:17:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:09 INFO - 'USER': 'cltbld', 16:17:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:09 INFO - 'XPC_FLAGS': '0x0', 16:17:09 INFO - 'XPC_SERVICE_NAME': '0', 16:17:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-ObV9bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-WzAc9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-A0xxEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-A8ldIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-vSHWeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-9LmsBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-gRNK3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-LZH_6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-hKDFEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-ECFIx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-DauC1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Gx506q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-fGEk4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:11 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-4mScG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:11 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-dfWlaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:11 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-9lBHiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:11 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-cbIiW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:17:11 INFO - Running setup.py install for manifestparser 16:17:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:17:11 INFO - Running setup.py install for mozcrash 16:17:11 INFO - Running setup.py install for mozdebug 16:17:11 INFO - Running setup.py install for mozdevice 16:17:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:17:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:17:12 INFO - Running setup.py install for mozfile 16:17:12 INFO - Running setup.py install for mozhttpd 16:17:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:17:12 INFO - Running setup.py install for mozinfo 16:17:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:17:12 INFO - Running setup.py install for mozInstall 16:17:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:17:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:17:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:17:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:17:12 INFO - Running setup.py install for mozleak 16:17:12 INFO - Running setup.py install for mozlog 16:17:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:17:12 INFO - Running setup.py install for moznetwork 16:17:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:17:13 INFO - Running setup.py install for mozprocess 16:17:13 INFO - Running setup.py install for mozprofile 16:17:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:17:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:17:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:17:13 INFO - Running setup.py install for mozrunner 16:17:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:17:13 INFO - Running setup.py install for mozscreenshot 16:17:13 INFO - Running setup.py install for moztest 16:17:13 INFO - Running setup.py install for mozversion 16:17:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:17:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:17:14 INFO - Cleaning up... 16:17:14 INFO - Return code: 0 16:17:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17: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')]} 16:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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')]} 16:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:14 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 0x106e46a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e4c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cead30>, '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 0x106b0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce1b20>, '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.AijxbNf6mm/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.vl7LS2lEsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 16:17:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:17:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AijxbNf6mm/Render', 16:17:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:14 INFO - 'HOME': '/Users/cltbld', 16:17:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:14 INFO - 'LOGNAME': 'cltbld', 16:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:14 INFO - 'MOZ_NO_REMOTE': '1', 16:17:14 INFO - 'NO_EM_RESTART': '1', 16:17:14 INFO - 'PAGER': '/bin/cat', 16:17:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:14 INFO - 'PWD': '/builds/slave/test', 16:17:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:14 INFO - 'SHELL': '/bin/bash', 16:17:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vl7LS2lEsT/Listeners', 16:17:14 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 16:17:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:14 INFO - 'USER': 'cltbld', 16:17:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:14 INFO - 'XPC_FLAGS': '0x0', 16:17:14 INFO - 'XPC_SERVICE_NAME': '0', 16:17:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-K7rt9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-RjKCOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-9H06Ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:15 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-CS9F33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:15 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-TwDunw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:15 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-dTHu9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:15 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-rH7YCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:15 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-MJO9XL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:15 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-AlQ4gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:15 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-MCYd2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:15 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)) 16:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:15 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-FgDXTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:15 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-S79ttS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:16 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)) 16:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-velA9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-prwvHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:16 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)) 16:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-y_pOqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-vhGTxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Gxia7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:17:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:19 INFO - Downloading blessings-1.6.tar.gz 16:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:17:19 INFO - Installing collected packages: blessings 16:17:19 INFO - Running setup.py install for blessings 16:17:19 INFO - Successfully installed blessings 16:17:19 INFO - Cleaning up... 16:17:19 INFO - Return code: 0 16:17:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:17:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:19 INFO - Reading from file tmpfile_stdout 16:17:19 INFO - Current package versions: 16:17:19 INFO - blessings == 1.6 16:17:19 INFO - blobuploader == 1.2.4 16:17:19 INFO - docopt == 0.6.1 16:17:19 INFO - manifestparser == 1.1 16:17:19 INFO - mozInstall == 1.12 16:17:19 INFO - mozcrash == 0.17 16:17:19 INFO - mozdebug == 0.1 16:17:19 INFO - mozdevice == 0.48 16:17:19 INFO - mozfile == 1.2 16:17:19 INFO - mozhttpd == 0.7 16:17:19 INFO - mozinfo == 0.9 16:17:19 INFO - mozleak == 0.1 16:17:19 INFO - mozlog == 3.2 16:17:19 INFO - moznetwork == 0.27 16:17:19 INFO - mozprocess == 0.22 16:17:19 INFO - mozprofile == 0.28 16:17:19 INFO - mozrunner == 6.11 16:17:19 INFO - mozscreenshot == 0.1 16:17:19 INFO - mozsystemmonitor == 0.3 16:17:19 INFO - moztest == 0.7 16:17:19 INFO - mozversion == 1.4 16:17:19 INFO - psutil == 3.1.1 16:17:19 INFO - requests == 1.2.3 16:17:19 INFO - wsgiref == 0.1.2 16:17:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17: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')]} 16:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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')]} 16:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:19 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 0x106e46a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e4c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cead30>, '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 0x106b0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce1b20>, '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.AijxbNf6mm/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.vl7LS2lEsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 16:17:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:17:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AijxbNf6mm/Render', 16:17:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:19 INFO - 'HOME': '/Users/cltbld', 16:17:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:19 INFO - 'LOGNAME': 'cltbld', 16:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:19 INFO - 'MOZ_NO_REMOTE': '1', 16:17:19 INFO - 'NO_EM_RESTART': '1', 16:17:19 INFO - 'PAGER': '/bin/cat', 16:17:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:19 INFO - 'PWD': '/builds/slave/test', 16:17:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:19 INFO - 'SHELL': '/bin/bash', 16:17:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vl7LS2lEsT/Listeners', 16:17:19 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 16:17:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:19 INFO - 'USER': 'cltbld', 16:17:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:19 INFO - 'XPC_FLAGS': '0x0', 16:17:19 INFO - 'XPC_SERVICE_NAME': '0', 16:17:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:17:20 INFO - Cleaning up... 16:17:20 INFO - Return code: 0 16:17:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:20 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 0x106e46a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e4c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cead30>, '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 0x106b0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce1b20>, '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.AijxbNf6mm/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.vl7LS2lEsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 16:17:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AijxbNf6mm/Render', 16:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:20 INFO - 'HOME': '/Users/cltbld', 16:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:20 INFO - 'LOGNAME': 'cltbld', 16:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:20 INFO - 'MOZ_NO_REMOTE': '1', 16:17:20 INFO - 'NO_EM_RESTART': '1', 16:17:20 INFO - 'PAGER': '/bin/cat', 16:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:20 INFO - 'PWD': '/builds/slave/test', 16:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:20 INFO - 'SHELL': '/bin/bash', 16:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vl7LS2lEsT/Listeners', 16:17:20 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 16:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:20 INFO - 'USER': 'cltbld', 16:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:20 INFO - 'XPC_FLAGS': '0x0', 16:17:20 INFO - 'XPC_SERVICE_NAME': '0', 16:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:20 INFO - Downloading/unpacking jsonschema 16:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:17:22 INFO - Downloading/unpacking functools32 (from jsonschema) 16:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:23 INFO - Downloading functools32-3.2.3-2.tar.gz 16:17:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:17:23 INFO - warning: no files found matching '*.txt' 16:17:23 INFO - no previously-included directories found matching 'build' 16:17:23 INFO - no previously-included directories found matching 'dist' 16:17:23 INFO - no previously-included directories found matching '.git*' 16:17:23 INFO - Installing collected packages: jsonschema, functools32 16:17:23 INFO - Running setup.py install for functools32 16:17:23 INFO - warning: no files found matching '*.txt' 16:17:23 INFO - no previously-included directories found matching 'build' 16:17:23 INFO - no previously-included directories found matching 'dist' 16:17:23 INFO - no previously-included directories found matching '.git*' 16:17:23 INFO - Successfully installed jsonschema functools32 16:17:23 INFO - Cleaning up... 16:17:23 INFO - Return code: 0 16:17:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:23 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 0x106e46a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e4c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd66890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cead30>, '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 0x106b0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce1b20>, '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.AijxbNf6mm/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.vl7LS2lEsT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 16:17:23 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 16:17:23 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 16:17:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AijxbNf6mm/Render', 16:17:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:23 INFO - 'HOME': '/Users/cltbld', 16:17:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:23 INFO - 'LOGNAME': 'cltbld', 16:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:23 INFO - 'MOZ_NO_REMOTE': '1', 16:17:23 INFO - 'NO_EM_RESTART': '1', 16:17:23 INFO - 'PAGER': '/bin/cat', 16:17:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:23 INFO - 'PWD': '/builds/slave/test', 16:17:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:23 INFO - 'SHELL': '/bin/bash', 16:17:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vl7LS2lEsT/Listeners', 16:17:23 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 16:17:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:23 INFO - 'USER': 'cltbld', 16:17:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:23 INFO - 'XPC_FLAGS': '0x0', 16:17:23 INFO - 'XPC_SERVICE_NAME': '0', 16:17:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:23 INFO - Downloading/unpacking flake8 16:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:17:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:17:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:17: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 16:17: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 16:17: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 16:17: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 16:17: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 16:17: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 16:17:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:17:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:17:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:17:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:17:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:17:27 INFO - no previously-included directories found matching 'docs/_build' 16:17:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:17:27 INFO - Running setup.py install for pep8 16:17:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:17:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:17:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:17:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:17:27 INFO - no previously-included directories found matching 'docs/_build' 16:17:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:17:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:17:27 INFO - Cleaning up... 16:17:27 INFO - Return code: 0 16:17:27 INFO - Running post-action listener: _resource_record_post_action 16:17:27 INFO - Running post-action listener: _start_resource_monitoring 16:17:28 INFO - Starting resource monitoring. 16:17:28 INFO - ##### 16:17:28 INFO - ##### Finished create-virtualenv step (success) 16:17:28 INFO - ##### 16:17:28 INFO - ##### 16:17:28 INFO - ##### Running install step. 16:17:28 INFO - ##### 16:17:28 INFO - Running pre-action listener: _resource_record_pre_action 16:17:28 INFO - Running main action method: install 16:17:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:28 INFO - Reading from file tmpfile_stdout 16:17:28 INFO - Detecting whether we're running mozinstall >=1.0... 16:17:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:17:28 INFO - Reading from file tmpfile_stdout 16:17:28 INFO - Output received: 16:17:28 INFO - Usage: mozinstall [options] installer 16:17:28 INFO - Options: 16:17:28 INFO - -h, --help show this help message and exit 16:17:28 INFO - -d DEST, --destination=DEST 16:17:28 INFO - Directory to install application into. [default: 16:17:28 INFO - "/builds/slave/test"] 16:17:28 INFO - --app=APP Application being installed. [default: firefox] 16:17:28 INFO - mkdir: /builds/slave/test/build/application 16:17:28 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'] 16:17:28 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 16:18:01 INFO - Reading from file tmpfile_stdout 16:18:01 INFO - Output received: 16:18:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:18:01 INFO - Running post-action listener: _resource_record_post_action 16:18:01 INFO - ##### 16:18:01 INFO - ##### Finished install step (success) 16:18:01 INFO - ##### 16:18:01 INFO - ##### 16:18:01 INFO - ##### Running run-tests step. 16:18:01 INFO - ##### 16:18:01 INFO - Running pre-action listener: _resource_record_pre_action 16:18:01 INFO - Running main action method: run_tests 16:18:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:18:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:18:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:18:01 INFO - 2016-05-16 16:18:01.710 screenresolution[1649:8194] starting screenresolution argv=screenresolution get 16:18:01 INFO - 2016-05-16 16:18:01.713 screenresolution[1649:8194] Display 0: 1600x1200x32@60 16:18:01 INFO - 2016-05-16 16:18:01.719 screenresolution[1650:8196] starting screenresolution argv=screenresolution list 16:18:01 INFO - Available Modes on Display 0 16:18:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:18:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:18:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:18:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:18:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:18:01 INFO - Intel Iris: 16:18:01 INFO - Chipset Model: Intel Iris 16:18:01 INFO - Type: GPU 16:18:01 INFO - Bus: Built-In 16:18:01 INFO - VRAM (Dynamic, Max): 1536 MB 16:18:01 INFO - Vendor: Intel (0x8086) 16:18:01 INFO - Device ID: 0x0a2e 16:18:01 INFO - Revision ID: 0x0009 16:18:01 INFO - Displays: 16:18:01 INFO - Display: 16:18:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:18:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:18:01 INFO - Display Serial Number: 1600x1200 60 16:18:01 INFO - Main Display: Yes 16:18:01 INFO - Mirror: Off 16:18:01 INFO - Online: Yes 16:18:01 INFO - Rotation: Supported 16:18:01 INFO - Return code: 0 16:18:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:18:01 INFO - Python 2.7.3 16:18:01 INFO - Return code: 0 16:18:01 INFO - grabbing minidump binary from tooltool 16:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:01 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 0x106cead30>, '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 0x106b0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:18:01 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 16:18:01 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 16:18:01 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 16:18:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:18:02 INFO - Return code: 0 16:18:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:18:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:18:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:18:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:18:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:18:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:18:03 INFO - Return code: 0 16:18:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0249 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:18:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AijxbNf6mm/Render', 16:18:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:03 INFO - 'HOME': '/Users/cltbld', 16:18:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:03 INFO - 'LOGNAME': 'cltbld', 16:18:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:03 INFO - 'MOZ_NO_REMOTE': '1', 16:18:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:03 INFO - 'NO_EM_RESTART': '1', 16:18:03 INFO - 'PAGER': '/bin/cat', 16:18:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:03 INFO - 'PWD': '/builds/slave/test', 16:18:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:03 INFO - 'SHELL': '/bin/bash', 16:18:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vl7LS2lEsT/Listeners', 16:18:03 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 16:18:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:03 INFO - 'USER': 'cltbld', 16:18:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:03 INFO - 'XPC_FLAGS': '0x0', 16:18:03 INFO - 'XPC_SERVICE_NAME': '0', 16:18:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:18:03 INFO - mozversion application_buildid: 20160516141946 16:18:03 INFO - mozversion application_changeset: e9533997fcef31fa5d2db464c6ceec0ca6ea560f 16:18:03 INFO - mozversion application_display_name: Nightly 16:18:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:18:03 INFO - mozversion application_name: Firefox 16:18:03 INFO - mozversion application_remotingname: firefox 16:18:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:18:03 INFO - mozversion application_vendor: Mozilla 16:18:03 INFO - mozversion application_version: 49.0a1 16:18:03 INFO - mozversion platform_buildid: 20160516141946 16:18:03 INFO - mozversion platform_changeset: e9533997fcef31fa5d2db464c6ceec0ca6ea560f 16:18:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:18:03 INFO - mozversion platform_version: 49.0a1 16:18:03 INFO - using testdate: 1463440683 16:18:03 INFO - actual date: 1463440683 16:18:03 INFO - starting webserver on 'localhost:49274' 16:18:03 INFO - SUITE-START | Running 2 tests 16:18:03 INFO - TEST-START | dromaeo_css 16:18:03 INFO - operating with platform_type : mac_ 16:18:03 INFO - Initialising browser for dromaeo_css test... 16:18:03 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpbYYnxW/profile http://localhost:49274/getInfo.html) 16:18:05 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 16:18:05 INFO - PROCESS | 1668 | colorDepth:24 16:18:05 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 16:18:05 INFO - PROCESS | 1668 | __metrics 16:18:05 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:18:05 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:18:06 INFO - TEST-INFO | 1668: exit 0 16:18:06 INFO - Browser initialized. 16:18:06 INFO - Running cycle 1/1 for dromaeo_css test... 16:18:06 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpbYYnxW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:18:18 INFO - PROCESS | 1671 | RSS: Main: 239161344 16:18:18 INFO - PROCESS | 1671 | 16:19:10 INFO - PROCESS | 1671 | 1463440750134 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:21:20 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 16:21:21 INFO - PROCESS | 1671 | RSS: Main: 357208064 16:21:21 INFO - PROCESS | 1671 | 16:24:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 16:24:25 INFO - PROCESS | 1671 | RSS: Main: 373559296 16:24:25 INFO - PROCESS | 1671 | 16:27:27 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 16:27:28 INFO - PROCESS | 1671 | RSS: Main: 371040256 16:27:28 INFO - PROCESS | 1671 | 16:30:30 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 16:30:31 INFO - PROCESS | 1671 | RSS: Main: 367747072 16:30:31 INFO - PROCESS | 1671 | 16:33:34 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 16:33:35 INFO - PROCESS | 1671 | RSS: Main: 334946304 16:33:35 INFO - PROCESS | 1671 | 16:36:38 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 16:36:38 INFO - PROCESS | 1671 | RSS: Main: 376799232 16:36:38 INFO - PROCESS | 1671 | 16:36:38 INFO - PROCESS | 1671 | __start_tp_report 16:36:38 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 16:36:38 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 16:36:38 INFO - PROCESS | 1671 | |i|pagename|runs| 16:36:38 INFO - PROCESS | 1671 | |0;dojo.html;3416.6241713411528;3433.26184870253;3458.481227815829;3464.584249890013;3506.649335066492;4579.954200457994;5623.746931388087;5653.180288858113;5721.914171287434;5732.627379220347;5649.689267090308;5652.745626446809;5672.092465205578;5766.423357664232;5774.711264436783;5047.873803154914;5072.822451214208;5132.435432102466;5142.7428628568605;5250.238715386266;5221.190715439094;5246.422893481714;5270.736463176845;5272;5305.257263983045;5074.797008119671;5132.692038477703;5146.176611742141;5173.5085166909275;5174.990876779029;5125.256837758541;5129.281900533928;5163.586913046947;5202.765875535572;5229.163333866563;5070.3915530136255;5108.846734597968;5128.384593848725;5132.101882170605;5162.457941916082;5696.401877802812;5704.058830293038;5708.20085188074;5722.7424765885635;5756.251687280668;6059.191114378535;6095.166312950018;6147.692615369214;6156.353582873779;6240.906386404207;6232.40792124752;6269.310375858652;6280.685965701742;6282.2461304643275;6305.274893387274;5652.8869422611315;5755.194272801769;5827.912581311284;5829.183914252008;5834.912476312858;6269.996800511896;6297.779577714758;6304.810855674337;6332.050192471074;6368.29948705642;6113.240357180302;6137.312239134274;6147.477464415485;6156.630602163885;6156.907646385308;6134.405972711579;6188.907166392508;6253.437190652863;6272.435480806749;6275.843103922439;6117.979653188387;6166.845828854314;6185;6252.343503932113;6253.499720022387;6198.752049918043;6265.185525881698;6282.4974002079725;6289.622622642656;6291.622502649856;4802.447718512416;4851.151048566487;4904.901901961941;4914.631402644816;4950.282209079735;4352.782360882006;4363.978180109143;4374.081442897027;4414.940414300545;4428.31361139024;5697.601167918206;5716.542676585863;5718.342390625131;5722.313322401338;5733.111367738007;6105.611380716617;6212.223472065992;6283.560150789401;6307.085472606446;6319.873602527924;5734.713264336767;5735.1684005818925;5742.28221472316;5743.511801496919;5777.988851950891;5341.893162136736;5375.274337964324;5383.407825139231;5384.111621582395;5386.461353864661;6277.8744425111245;6281.780137695158;6284.340144284784;6344.746210151542;6347.68261586928;6301.275353334425;6311.495080393649;6328.936710632835;6330.715117819801;6331.715072821827;6303.1175635412;6305.054241863757;6305.5901366411035;6334;6334.651594162366;6090.725917333819;6093.685708195194;6132.172156758959;6137.539684523589;6216.658083805253;6152.723127459186;6307.180066591313;6312.368763123651;6313.368663133833;6319.96840015797;6042.969785151046;6081.148639190428;6090.573659843769;6099.207103076572;6118.60229085108;5987.251593550806;6027.427394397525;6064.545159112996;6116.480099191441;6129.172561704291;5830.76676932918;5945.672987985702;6003.5797494174985;6046.183765191819;6047.334793172659;6094.14681944537;6123.357047510125;6127.571070025234;6128.158709735206;6131.264248290234;5796.536277097906;5803.3616302207565;5870.765169393177;5962.97018514922;5982.7008649568215 16:36:38 INFO - PROCESS | 1671 | |1;ext.html;525.4876495416969;532.5473347654491;537.9166229234469;538.7952578020352;552.7319250163667;31758.841205793968;41311.79344103283;42265.154696906044;42320.9419764506;44499.55500444995;43803;44216.77891610545;44320.89197770057;44420.33369499452;44428.555714442846;8618.09510001447;9612.471314077722;9809.558569864337;9818.165455936218;9820.803583928353;8669.869951950694;11510;11703.765924681502;11706.590269340575;11834.349110798903;11747.295162290247;11753.177277590572;11825.113116516253;11840.171188016842;11840.289582625028;8696.652133914637;8838.513881736502;8848.1151884811;11704.648860534198;11792.646220613395;8719.825603487958;11370.147238957028;11626.941865290704;11641.592544260995;11723.531058757724;40080.39879401811;40455;40468.797656011535;40490.19019619621;40580.18839623191;3237.8704851806133;3367.2087059540986;3771.2268984858038;3869.400242962345;4084.9183016339803;28751.56872646912;28887.133385998455;29195.270118247;29387;29389.412211755644;2727.4954133485367;2732.767714744249;2786.8327900325844;2794.357297821492;2824.4351129774127;489836;494596;495773;495788;497540;34752.826235868655;59341.40658593445;59712.40287597155;59892;59965.70017149974;35432.114197144765;36716.44925326122;36826.26347473089;36855.63144368576;36884.89345319645;11010.779784404427;15022.699546009018;15025.474108406372;15036.248187590576;15050.548483545512;11542;11631.709207269885;11634.127440442;11886.524539018343;12610.936945315214;7701.152873184057;8000.919990800134;8183.427160098736;8193.13972032928;8199.7950051248;2728.9493544985326;2956.88172473103;3093.829839358857;3384.3569721752788;3402.1154499830222;897.3449381951212;948.6821914658667;994.1152374386802;1052.079430498314;1068.609957365556;28873.42253154925;29134.562981555293;29302.560461593093;29478;29559.556606650916;1120.4845770945456;1134.5575225661833;1135.5514571744116;1154.1978325064;1224.3327386574338;11968.083782559514;13401.597952061455;14700.338484768212;14734.894882883613;15102.697946041018;676820;689113;689122;690241;692680;58906.70546647325;59282.70358648179;59433.81132377328;59449;59458;1253.8056601226824;1351.5202103253202;1428.9214093224557;1440.4454285100312;1471.3452513631332;29145.56281655819;29816;29937.85031074831;30586.07418647926;30761.38477230398;37630;41254.79372603238;49715.502844972536;49763.27248911348;49814.25278620896;568170;582406;586922;587348;589890;23063.769362306834;25396.873015634803;25934.092306769173;26185.476290473704;26596.734032659424;43901;46248.07503849837;46291.76854115708;46602.766986164854;47210.52789472061;36127.63872361243;37803.43294850634;37899;38941.22117557576;39008.41487377748;37490.81254593709;37540.62459375481;37884.62115378811;38149.80925095357;38238.617613824616;37015.81492092522;37106.25787484181;37553.436698449;37619.43570846385;38194.236115278094;32032.410129660933;33716.15709607279;33749.156271093416;33754.156146096546;33795;20455.693164602246;24415.145469908468;24751.752482474945;24882;25219 16:36:38 INFO - PROCESS | 1671 | |2;jquery.html;394.9486566746323;400.06584624900853;400.20359484625584;406.89624145842805;425.01184745466776;13813;16997.660046799057;20046;20678.06948687309;21237.469063273427;20540.589188216225;20744.89627551864;20774.68837967428;20864.47838804031;21032.579348413023;5346.27825243593;5885.7351419186025;6101.78643747469;6136.895358835408;6146.293176284717;5213.556847667953;5735.541156707453;6066.454019138277;6115.357887421824;6781.864362712743;5830;6606.108175396308;6735.7875582395145;6774.424173945221;6780.084688567054;6463.385978332051;6620.834479138012;6663.30035346289;6663.50023748216;6681.997700344963;5642;5716.056850619642;5781.248437703114;5792.073268277056;5798.7390567424145;25929.481410371875;26673.866630666915;26862.46275074507;27116.59325110125;27177.728222717717;1979.1390745025815;2047.0992763184151;2067.640238543317;2149.4518897681146;2297.8161747060194;10039.89960100397;10080.445575493344;10347.5343609537;10356.844647330296;11757.941785239283;1708.3422882190323;1723.7330562036893;1746.8602511799027;1843.5483306589972;1865.2352535460532;107308.463457683;124856.37571812083;125412;131958.99999999904;132220;11595.826062609067;11663.125265604944;11684.12369072323;11699.941500292614;11832.639246486591;13273.734525309439;13806.93096534531;13929.442822287197;14919.776203356958;14955.327010284564;6963.408110310579;7857.1749966253865;8600.39797214189;8675.522846243517;8736.519491427905;8511.233988940901;8532.744017679479;8533.871991920127;8633.093525179765;8659.960804703476;4530.29780384041;4602.608778253819;4603.792829322689;4653.185692503798;4654.650901182425;1732.454276902797;1836.9357072502398;1919.9808001920082;1960.4628401373138;1973.3882496426154;853.9914600854037;925.5372313843078;927.1794461901131;965.971020869375;1023.6826583759058;19069;19327.226910923688;20397.694034589495;20470;20688.17247310091;948.6347756113946;962.7400601837464;1001.5493028137222;1006.8993100689872;1024.6362541297733;10734.78530429387;10805.189610779224;10905.400202988912;10936.726581835517;10983.450827458755;612869;620722;629245;632660;642301;139873.30063349617;140282;143133.28433357767;145549;148182;1045.3936716704293;1066.4827558634056;1082.880883102858;1090;1096.2326371539955;18536.385404845925;18750;18752.249910003993;18857.379941914318;18946.810531894505;52898;54961.90076198534;55861.441385585626;56382.7180864093;56879.43120568907;82956;83440;83778;84410;86155.569222156;8010.599470026592;8075.596220189084;8099.987501562305;8303.83392332138;8430.15698430152;9067.68263110788;11823;12621.495140194656;12882.806757898827;13079.280639564915;6893;10837.344850511676;10951.178661600572;11244.212905096565;11310.151738619472;10302.175825933757;10366.637167699397;10379.948100259753;10626.468676566295;10722.624708135178;7444.478886477895;10858.457077145953;11089.667309980714;11492.310461372344;11576;6863.725450981905;7901.407394445555;8097.2307630774985;8161.183881611792;8485;9677.951610241904;11020.393878336768;11051.226414150933;11053.778924421305;11260.88739112631 16:36:38 INFO - PROCESS | 1671 | |3;mootools.html;1460.8685218330347;1613.7902072730544;1648.8845780795345;1885.5663197464582;1994.7706013808415;8079.515229086252;8675.652973881046;8887.822243555125;8908.8663670045;9003.549822508881;8162.306203972655;8774.692885749;8797.42816716913;8806.11938806119;8818.426802257849;2384.9046038158494;2420.9152679656218;2423.7212720537186;2425.0178677635586;2437.341917682225;2378.262738551046;2409.6144616861307;2429.2955043037496;2429.829911906167;2431.2706188143575;2380.4881950380664;2402.9038838446527;2414.3481260059775;2421.4551725861716;2424;2361.126383238213;2387.988060059697;2395.161693407302;2396.9880150599224;2402.1832576923757;2287.1080278691325;2367.8224133189906;2372.5136347048815;2373.988130059339;2375.9406014849596;7863.174366691473;8064.798380040487;8628.568571571397;8706.30349572039;8722.99685536165;4924.285978533128;5096.261042148904;5096.362954630671;5221.895562088775;5396;2876.5972763813083;3393.694567488913;3534.6111927687934;3540.5574303212097;3544.8227588620725;1030.464158637512;1032.5353590884133;1034.9068583827416;1045.487711021594;1049.7428130108096;11735.354555499443;11859.644210673694;11870.643880683594;11875.0499960003;11884.989775869062;4609.723416595015;4713.622910167247;4737.928931066037;4742.312364707098;4901.387326584177;9912.256580756472;10050.195984321237;10054.798904021878;10059.29584929048;10084.747881303027;5443.42844001382;5448.91826622601;5450.3732070811575;5456.508914197741;5459.972700136474;4766.189747742892;4991.625628077909;4991.875203119951;5018.673786203885;5025.246213067996;3972.443857859902;4006.8998275042773;4064.9796751016056;4073.4093556434746;4086.693497987663;1318.3408295852073;1332.5069724201912;1337.25782190885;1351.49319005373;1355.1936597724339;811.3833486550265;934.6915517879083;939.3612343606277;946.9715908522755;979.1970584121095;4995.9500404996215;5718.51392631631;5729;5891.734871930774;5949.929012777741;465.62982428969053;477.98327058552786;478.6410192355733;977.4477420257532;1029.4749677664445;3904.9804750976064;3997.060690737674;4009.3785463253244;4047.1096358801015;4051.47330846988;10491.422971736474;10634.30876992993;10892.455377231106;10907.236493445384;10954.726131846608;10720.624778132727;10835.349879007284;10866.511007004545;10869.40218287986;10877.456127193767;7973.521588704696;8268.627911743866;8357.749267521984;8360.12218717026;8367.95816020916;5742.109972954198;5746.13807928814;5822.155787410887;5855.970720146372;5866.120081987736;9958.800823983334;10079.445630490394;10080.69757907264;10084.394936303552;10091.69724908254;9803.950980245054;10118.241131915283;10384.065434110966;10431.843522347323;10443.634472793416;6990.510664253454;7015.824604384931;7123.893141602776;7138.143422789299;7165.856682866418;8193.180681931759;8249.340052795882;8287.419880608299;8322.20939010793;8341.040780310099;7477.850442991218;7523.3229009389415;7528.774136775905;7614.2766437188375;7637.236276372319;7366.484346095722;7415.036045314075;7488.925110748823;7498.32515073646;7530.510516816608;6940.27127151662;7027.402670773042;7104.786856394317;7115.715371385087;7120.216776154515;4211.7472951623;4334.739915605074;4882.60939124862;4914.582260507754;4962.751862406937;4730.9763451182525;4920.901581968269;4958.553730164303;5093.439721630692;5243.082460569309 16:36:38 INFO - PROCESS | 1671 | |4;prototype.html;309.4275590158209;312.96400913894905;321.9839008049597;328.2958054972086;331.58055060348664;4502.236048998638;5044.308111792772;5420.159875219342;5442.156465747806;5452.263944367504;4062.364184055276;4811.037792441508;5098.566621837139;5192.221166824968;5201.713905735183;3502.684758371746;3868.4197370394386;3955.821988010532;3991.92218101114;4009.318415869302;3730.4590834329006;4081.857135000276;4082.2243773683053;4102.774347410891;4110.321796903508;3939.9212015759667;4060.736052156615;4074.1647962167685;4076.1236334188143;4078.5717499662405;3489.145159435929;4035.567373582395;4046.332355161395;4053.250148722481;4061.0253539150685;3031.787774855761;3385.3229354129026;3437.9656203437894;3524.1718196223865;3558.967899309197;4463.598276155161;4665.2535594304745;4785.068704533751;5095.363079615048;5122.487751224848;3819;4079.9592004080173;4505.459344878603;4694.342792009122;4710.387649605529;4456.55434456555;5464.114880366267;5591.496765291103;5606.299212598426;5615.522680572156;3886.689064874803;3892.708046896494;3895.3767397216593;3910.8631197907935;3956.140613911457;5505.366882808448;5828;5901.203337549418;5941.851453713649;5961;3197.376511580266;3846.7114966377635;3854.209886973164;3868.709846761504;3876.2635099331037;3370.292238629915;3924.0778417071965;3953.9802300988313;3955.248502784462;3997.420374045747;3907.1245802014623;4416.53626369233;4416.933745993812;4641.419822522184;4649.488556258809;4600.677952543357;4742.786574604151;5413.431589683105;5550.805721799798;5675.772969081273;1092.9180311476512;1199.2504684572143;1274.5857596281246;1319.4854006937303;1351.6958684295955;715.4634024481638;719.1909102259957;737.5948817502666;749.9152356449081;767.7888580640257;4590.655700822452;5099.184130539096;5178.741062946838;5253.816116435906;5284;5048.667879199784;5785.739641716133;5788.681622510718;5800.564957628195;5811.970940145272;4479.283314669638;5018.2974383586325;5254.816081437131;5257.5005374489365;5294.788208471618;2516.987415062913;2723.9025950018377;2818.971810281912;3001.5497675348875;3034.9665723964995;5040.420351659533;5611.494965453129;5970.283565972024;6014.346054834868;6034.396560344018;5625.46558076982;5673.546116310686;5943.732532036069;5974.118152781946;6031.668258245751;5279.788808447619;5574.665520068809;5780.1907732917425;5855.970720146457;6067.726952287246;4429.68992170545;5852.239208902815;5862.94137058641;5865.4134586541;5881.794137205177;5720.513756330759;5748.798792042258;5758.481736643722;5758.827235182951;5772.3650398456975;5139.3297178416415;5401.594880384066;5543.667379957215;5735.9139612905;6054.66699331541;3865.7822785822377;3872.5933776953016;3882.2429626223743;3883.427246932772;3894.123822139996;4443.822247110079;4763.1426343258545;5169.92245116316;5790.884182316414;5907.232059832194;5363.758630861543;5728.388647139093;5738.856528586819;5753.884922301614;5754.5684073695475;5137.6660517066275;5201;5469.589780766379;5617.662940223436;5632.887342253214;4575.24508456101;5119.48805119485;5282.867928301823;5432.483914028161;5435.87437223606;4765.428148622187;5168.012867582801;5274.314339135888;5677.68772717488;5715.399883012224;4564.748938808398;4867.63492738039;5541.778328866801;5548.278723765885;5569.832905012856 16:36:38 INFO - PROCESS | 1671 | |5;yui.html;662.552776875609;750.0699133074988;752.9872321727279;755.6486233901235;759.0625577411892;15146.16696081715;15453.922730386346;15487.070775753462;15685.607859803482;15760.76358854618;14283.642908927282;14440.277986100678;14487.275636218174;14488.2755862207;14497.347619357128;750.1523278695095;759.6012093650822;922.2391526990226;943.1040511514054;944.655200851691;751.9511231769944;923.5474617437475;926.9675561355353;933.5099072986668;938.5260443476035;975.024975024975;984.9409035457895;987.6049580167954;992.1814503034989;992.4739887859446;773.9303462688357;953.7663272498282;973.6884197056945;981.4258658684678;981.8036392721449;728.9271072892722;729.6789412658467;730.9013283206806;731.8536292741474;732.1104857598044;14923.776143357749;14976.550703478913;14976.850231497654;14989.475368362055;14991.100533967996;12195.573154939624;12359.62921112368;12384.56654017105;12469.937650311782;12473.789340833016;9357.578908949046;9653.22774178071;10079.949600251952;10665.733356666067;10901.1279097672;4046.9392959105635;4067.2272268268935;4076.8980775480554;4139.79301034947;4162.604552567501;70232.64883675548;71227;71347;71361.28638713599;71387;9236.353455258202;9248.213901818286;9347.158755712018;9516.809663806685;9717.12545870861;20682;20696.379108626767;20703.689444658343;20711.482212944797;20737.896310518652;7319.963400182965;7332.30343117403;7383.2985866342615;7435.814104647319;7559.130699969464;4018.276710192135;4094.877153685394;4097.8975525612095;4101.733387329814;4107.383892416162;3396.9490457643155;3472.461768425898;3478.843452044664;3487.250241198104;3496.125968507873;650.7397041183469;675.3651567526509;689.5896941319907;694.7507172978985;703.2229386527915;566.9659820410789;569.857535616096;581.2095550051926;604.2959951656271;622.9065640154013;8057.395695322874;8449.366297527587;8517.063123056583;8530.14698530142;8536.103709110579;417.84957415330456;487.53927538476506;587.1427715535281;597.9611325263844;616.6885722710002;3340.732741380684;3850.4801851750217;3941.3102707026155;3998.6208206117312;4021.0148513614354;78417.21582784099;81185;81369.59315203504;96121;97813;67536;74040.40858569925;77007;77455;80591;72462.63768681229;73162.99999999894;73307.26692733004;73878.63060684662;74606;8235.83528329442;8287.83424331498;8375.45559538628;8515.574221288787;8557.44376615518;42443.633454982;43917;45720;46059;47114.52885471102;58580.70709646425;59263.51841203832;59849.80300394055;61572.84495042652;62297.37702622916;10249.12882405004;10822.296550724177;10961.725956851142;11177;11727.88272117302;11923.284602923852;11995.580154694544;12301.954333881362;12354.444050017592;12890.613281601567;11418.31490110596;11482.138839586898;11577.189596728149;11842.171048026556;12126.75746485083;11357.716057098638;11524.366159860952;11544;11587.942060289644;11887.702807429883;10654.200934929757;10896.509657065293;10902.345859248471;10907.672769816918;12325.75348493043;9354.129134359831;9546.045395460398;9778.559964801743;9787.559559819967;9816.361936474106;6952.895706564304;9018.984196402156;9405.811883762422;9435.52822358893;9518.095780900803 16:36:38 INFO - PROCESS | 1671 | __end_tp_report 16:36:38 INFO - PROCESS | 1671 | __start_cc_report 16:36:38 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1343 16:36:38 INFO - PROCESS | 1671 | __end_cc_report 16:36:38 INFO - PROCESS | 1671 | __startTimestamp1463441798509__endTimestamp 16:36:38 INFO - PROCESS | 1671 | 16:36:38 INFO - PROCESS | 1671 | ------- Summary: start ------- 16:36:38 INFO - PROCESS | 1671 | Number of tests: 6 16:36:38 INFO - PROCESS | 1671 | 16:36:38 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5737.71 Median:5987.25 stddev:646.82 (10.8%) stddev-sans-first:622.65 16:36:38 INFO - PROCESS | 1671 | Values: 3416.6 3433.3 3458.5 3464.6 3506.6 4580.0 5623.7 5653.2 5721.9 5732.6 5649.7 5652.7 5672.1 5766.4 5774.7 5047.9 5072.8 5132.4 5142.7 5250.2 5221.2 5246.4 5270.7 5272.0 5305.3 5074.8 5132.7 5146.2 5173.5 5175.0 5125.3 5129.3 5163.6 5202.8 5229.2 5070.4 5108.8 5128.4 5132.1 5162.5 5696.4 5704.1 5708.2 5722.7 5756.3 6059.2 6095.2 6147.7 6156.4 6240.9 6232.4 6269.3 6280.7 6282.2 6305.3 5652.9 5755.2 5827.9 5829.2 5834.9 6270.0 6297.8 6304.8 6332.1 6368.3 6113.2 6137.3 6147.5 6156.6 6156.9 6134.4 6188.9 6253.4 6272.4 6275.8 6118.0 6166.8 6185.0 6252.3 6253.5 6198.8 6265.2 6282.5 6289.6 6291.6 4802.4 4851.2 4904.9 4914.6 4950.3 4352.8 4364.0 4374.1 4414.9 4428.3 5697.6 5716.5 5718.3 5722.3 5733.1 6105.6 6212.2 6283.6 6307.1 6319.9 5734.7 5735.2 5742.3 5743.5 5778.0 5341.9 5375.3 5383.4 5384.1 5386.5 6277.9 6281.8 6284.3 6344.7 6347.7 6301.3 6311.5 6328.9 6330.7 6331.7 6303.1 6305.1 6305.6 6334.0 6334.7 6090.7 6093.7 6132.2 6137.5 6216.7 6152.7 6307.2 6312.4 6313.4 6320.0 6043.0 6081.1 6090.6 6099.2 6118.6 5987.3 6027.4 6064.5 6116.5 6129.2 5830.8 5945.7 6003.6 6046.2 6047.3 6094.1 6123.4 6127.6 6128.2 6131.3 5796.5 5803.4 5870.8 5963.0 5982.7 16:36:38 INFO - PROCESS | 1671 | 16:36:38 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:70342.05 Median:36870.26 stddev:159187.68 (431.8%) stddev-sans-first:159547.93 16:36:38 INFO - PROCESS | 1671 | Values: 525.5 532.5 537.9 538.8 552.7 31758.8 41311.8 42265.2 42320.9 44499.6 43803.0 44216.8 44320.9 44420.3 44428.6 8618.1 9612.5 9809.6 9818.2 9820.8 8669.9 11510.0 11703.8 11706.6 11834.3 11747.3 11753.2 11825.1 11840.2 11840.3 8696.7 8838.5 8848.1 11704.6 11792.6 8719.8 11370.1 11626.9 11641.6 11723.5 40080.4 40455.0 40468.8 40490.2 40580.2 3237.9 3367.2 3771.2 3869.4 4084.9 28751.6 28887.1 29195.3 29387.0 29389.4 2727.5 2732.8 2786.8 2794.4 2824.4 489836.0 494596.0 495773.0 495788.0 497540.0 34752.8 59341.4 59712.4 59892.0 59965.7 35432.1 36716.4 36826.3 36855.6 36884.9 11010.8 15022.7 15025.5 15036.2 15050.5 11542.0 11631.7 11634.1 11886.5 12610.9 7701.2 8000.9 8183.4 8193.1 8199.8 2728.9 2956.9 3093.8 3384.4 3402.1 897.3 948.7 994.1 1052.1 1068.6 28873.4 29134.6 29302.6 29478.0 29559.6 1120.5 1134.6 1135.6 1154.2 1224.3 11968.1 13401.6 14700.3 14734.9 15102.7 676820.0 689113.0 689122.0 690241.0 692680.0 58906.7 59282.7 59433.8 59449.0 59458.0 1253.8 1351.5 1428.9 1440.4 1471.3 29145.6 29816.0 29937.9 30586.1 30761.4 37630.0 41254.8 49715.5 49763.3 49814.3 568170.0 582406.0 586922.0 587348.0 589890.0 23063.8 25396.9 25934.1 26185.5 26596.7 43901.0 46248.1 46291.8 46602.8 47210.5 36127.6 37803.4 37899.0 38941.2 39008.4 37490.8 37540.6 37884.6 38149.8 38238.6 37015.8 37106.3 37553.4 37619.4 38194.2 32032.4 33716.2 33749.2 33754.2 33795.0 20455.7 24415.1 24751.8 24882.0 25219.0 16:36:38 INFO - PROCESS | 1671 | 16:36:38 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:36620.44 Median:20948.53 stddev:105151.62 (502.0%) stddev-sans-first:105411.41 16:36:38 INFO - PROCESS | 1671 | Values: 394.9 400.1 400.2 406.9 425.0 13813.0 16997.7 20046.0 20678.1 21237.5 20540.6 20744.9 20774.7 20864.5 21032.6 5346.3 5885.7 6101.8 6136.9 6146.3 5213.6 5735.5 6066.5 6115.4 6781.9 5830.0 6606.1 6735.8 6774.4 6780.1 6463.4 6620.8 6663.3 6663.5 6682.0 5642.0 5716.1 5781.2 5792.1 5798.7 25929.5 26673.9 26862.5 27116.6 27177.7 1979.1 2047.1 2067.6 2149.5 2297.8 10039.9 10080.4 10347.5 10356.8 11757.9 1708.3 1723.7 1746.9 1843.5 1865.2 107308.5 124856.4 125412.0 131959.0 132220.0 11595.8 11663.1 11684.1 11699.9 11832.6 13273.7 13806.9 13929.4 14919.8 14955.3 6963.4 7857.2 8600.4 8675.5 8736.5 8511.2 8532.7 8533.9 8633.1 8660.0 4530.3 4602.6 4603.8 4653.2 4654.7 1732.5 1836.9 1920.0 1960.5 1973.4 854.0 925.5 927.2 966.0 1023.7 19069.0 19327.2 20397.7 20470.0 20688.2 948.6 962.7 1001.5 1006.9 1024.6 10734.8 10805.2 10905.4 10936.7 10983.5 612869.0 620722.0 629245.0 632660.0 642301.0 139873.3 140282.0 143133.3 145549.0 148182.0 1045.4 1066.5 1082.9 1090.0 1096.2 18536.4 18750.0 18752.2 18857.4 18946.8 52898.0 54961.9 55861.4 56382.7 56879.4 82956.0 83440.0 83778.0 84410.0 86155.6 8010.6 8075.6 8100.0 8303.8 8430.2 9067.7 11823.0 12621.5 12882.8 13079.3 6893.0 10837.3 10951.2 11244.2 11310.2 10302.2 10366.6 10379.9 10626.5 10722.6 7444.5 10858.5 11089.7 11492.3 11576.0 6863.7 7901.4 8097.2 8161.2 8485.0 9678.0 11020.4 11051.2 11053.8 11260.9 16:36:38 INFO - PROCESS | 1671 | 16:36:38 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5716.63 Median:4754.25 stddev:3221.05 (67.8%) stddev-sans-first:3214.20 16:36:38 INFO - PROCESS | 1671 | Values: 1460.9 1613.8 1648.9 1885.6 1994.8 8079.5 8675.7 8887.8 8908.9 9003.5 8162.3 8774.7 8797.4 8806.1 8818.4 2384.9 2420.9 2423.7 2425.0 2437.3 2378.3 2409.6 2429.3 2429.8 2431.3 2380.5 2402.9 2414.3 2421.5 2424.0 2361.1 2388.0 2395.2 2397.0 2402.2 2287.1 2367.8 2372.5 2374.0 2375.9 7863.2 8064.8 8628.6 8706.3 8723.0 4924.3 5096.3 5096.4 5221.9 5396.0 2876.6 3393.7 3534.6 3540.6 3544.8 1030.5 1032.5 1034.9 1045.5 1049.7 11735.4 11859.6 11870.6 11875.0 11885.0 4609.7 4713.6 4737.9 4742.3 4901.4 9912.3 10050.2 10054.8 10059.3 10084.7 5443.4 5448.9 5450.4 5456.5 5460.0 4766.2 4991.6 4991.9 5018.7 5025.2 3972.4 4006.9 4065.0 4073.4 4086.7 1318.3 1332.5 1337.3 1351.5 1355.2 811.4 934.7 939.4 947.0 979.2 4996.0 5718.5 5729.0 5891.7 5949.9 465.6 478.0 478.6 977.4 1029.5 3905.0 3997.1 4009.4 4047.1 4051.5 10491.4 10634.3 10892.5 10907.2 10954.7 10720.6 10835.3 10866.5 10869.4 10877.5 7973.5 8268.6 8357.7 8360.1 8368.0 5742.1 5746.1 5822.2 5856.0 5866.1 9958.8 10079.4 10080.7 10084.4 10091.7 9804.0 10118.2 10384.1 10431.8 10443.6 6990.5 7015.8 7123.9 7138.1 7165.9 8193.2 8249.3 8287.4 8322.2 8341.0 7477.9 7523.3 7528.8 7614.3 7637.2 7366.5 7415.0 7488.9 7498.3 7530.5 6940.3 7027.4 7104.8 7115.7 7120.2 4211.7 4334.7 4882.6 4914.6 4962.8 4731.0 4920.9 4958.6 5093.4 5243.1 16:36:38 INFO - PROCESS | 1671 | 16:36:38 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4492.88 Median:5046.49 stddev:1407.34 (27.9%) stddev-sans-first:1375.82 16:36:38 INFO - PROCESS | 1671 | Values: 309.4 313.0 322.0 328.3 331.6 4502.2 5044.3 5420.2 5442.2 5452.3 4062.4 4811.0 5098.6 5192.2 5201.7 3502.7 3868.4 3955.8 3991.9 4009.3 3730.5 4081.9 4082.2 4102.8 4110.3 3939.9 4060.7 4074.2 4076.1 4078.6 3489.1 4035.6 4046.3 4053.3 4061.0 3031.8 3385.3 3438.0 3524.2 3559.0 4463.6 4665.3 4785.1 5095.4 5122.5 3819.0 4080.0 4505.5 4694.3 4710.4 4456.6 5464.1 5591.5 5606.3 5615.5 3886.7 3892.7 3895.4 3910.9 3956.1 5505.4 5828.0 5901.2 5941.9 5961.0 3197.4 3846.7 3854.2 3868.7 3876.3 3370.3 3924.1 3954.0 3955.2 3997.4 3907.1 4416.5 4416.9 4641.4 4649.5 4600.7 4742.8 5413.4 5550.8 5675.8 1092.9 1199.3 1274.6 1319.5 1351.7 715.5 719.2 737.6 749.9 767.8 4590.7 5099.2 5178.7 5253.8 5284.0 5048.7 5785.7 5788.7 5800.6 5812.0 4479.3 5018.3 5254.8 5257.5 5294.8 2517.0 2723.9 2819.0 3001.5 3035.0 5040.4 5611.5 5970.3 6014.3 6034.4 5625.5 5673.5 5943.7 5974.1 6031.7 5279.8 5574.7 5780.2 5856.0 6067.7 4429.7 5852.2 5862.9 5865.4 5881.8 5720.5 5748.8 5758.5 5758.8 5772.4 5139.3 5401.6 5543.7 5735.9 6054.7 3865.8 3872.6 3882.2 3883.4 3894.1 4443.8 4763.1 5169.9 5790.9 5907.2 5363.8 5728.4 5738.9 5753.9 5754.6 5137.7 5201.0 5469.6 5617.7 5632.9 4575.2 5119.5 5282.9 5432.5 5435.9 4765.4 5168.0 5274.3 5677.7 5715.4 4564.7 4867.6 5541.8 5548.3 5569.8 16:36:38 INFO - PROCESS | 1671 | 16:36:38 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17574.37 Median:619.80 stddev:24154.12 (3897.1%) stddev-sans-first:24188.49 16:36:38 INFO - PROCESS | 1671 | Values: 662.6 750.1 753.0 755.6 759.1 15146.2 15453.9 15487.1 15685.6 15760.8 14283.6 14440.3 14487.3 14488.3 14497.3 750.2 759.6 922.2 943.1 944.7 752.0 923.5 927.0 933.5 938.5 975.0 984.9 987.6 992.2 992.5 773.9 953.8 973.7 981.4 981.8 728.9 729.7 730.9 731.9 732.1 14923.8 14976.6 14976.9 14989.5 14991.1 12195.6 12359.6 12384.6 12469.9 12473.8 9357.6 9653.2 10079.9 10665.7 10901.1 4046.9 4067.2 4076.9 4139.8 4162.6 70232.6 71227.0 71347.0 71361.3 71387.0 9236.4 9248.2 9347.2 9516.8 9717.1 20682.0 20696.4 20703.7 20711.5 20737.9 7320.0 7332.3 7383.3 7435.8 7559.1 4018.3 4094.9 4097.9 4101.7 4107.4 3396.9 3472.5 3478.8 3487.3 3496.1 650.7 675.4 689.6 694.8 703.2 567.0 569.9 581.2 604.3 622.9 8057.4 8449.4 8517.1 8530.1 8536.1 417.8 487.5 587.1 598.0 616.7 3340.7 3850.5 3941.3 3998.6 4021.0 78417.2 81185.0 81369.6 96121.0 97813.0 67536.0 74040.4 77007.0 77455.0 80591.0 72462.6 73163.0 73307.3 73878.6 74606.0 8235.8 8287.8 8375.5 8515.6 8557.4 42443.6 43917.0 45720.0 46059.0 47114.5 58580.7 59263.5 59849.8 61572.8 62297.4 10249.1 10822.3 10961.7 11177.0 11727.9 11923.3 11995.6 12302.0 12354.4 12890.6 11418.3 11482.1 11577.2 11842.2 12126.8 11357.7 11524.4 11544.0 11587.9 11887.7 10654.2 10896.5 10902.3 10907.7 12325.8 9354.1 9546.0 9778.6 9787.6 9816.4 6952.9 9019.0 9405.8 9435.5 9518.1 16:36:38 INFO - PROCESS | 1671 | -------- Summary: end -------- 16:36:38 INFO - PROCESS | 1671 | 16:36:39 INFO - TEST-INFO | 1671: exit 0 16:36:39 INFO - TEST-OK | dromaeo_css | took 1115716ms 16:36:39 INFO - TEST-START | kraken 16:36:39 INFO - operating with platform_type : mac_ 16:36:39 INFO - Initialising browser for kraken test... 16:36:39 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpnaty0N/profile http://localhost:49274/getInfo.html) 16:36:41 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 16:36:41 INFO - PROCESS | 1677 | colorDepth:24 16:36:41 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 16:36:41 INFO - PROCESS | 1677 | __metrics 16:36:41 INFO - PROCESS | 1677 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:36:41 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:36:42 INFO - TEST-INFO | 1677: exit 0 16:36:42 INFO - Browser initialized. 16:36:42 INFO - Running cycle 1/1 for kraken test... 16:36:42 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpnaty0N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:36:54 INFO - PROCESS | 1679 | RSS: Main: 239230976 16:36:54 INFO - PROCESS | 1679 | 16:36:56 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 16:36:57 INFO - PROCESS | 1679 | RSS: Main: 287862784 16:36:57 INFO - PROCESS | 1679 | 16:37:00 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 16:37:00 INFO - PROCESS | 1679 | RSS: Main: 296271872 16:37:00 INFO - PROCESS | 1679 | 16:37:04 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 16:37:05 INFO - PROCESS | 1679 | RSS: Main: 385216512 16:37:05 INFO - PROCESS | 1679 | 16:37:07 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 16:37:07 INFO - PROCESS | 1679 | RSS: Main: 285822976 16:37:07 INFO - PROCESS | 1679 | 16:37:10 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:37:10 INFO - PROCESS | 1679 | RSS: Main: 290222080 16:37:10 INFO - PROCESS | 1679 | 16:37:13 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 16:37:14 INFO - PROCESS | 1679 | RSS: Main: 327954432 16:37:14 INFO - PROCESS | 1679 | 16:37:17 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 16:37:18 INFO - PROCESS | 1679 | RSS: Main: 330469376 16:37:18 INFO - PROCESS | 1679 | 16:37:21 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 16:37:21 INFO - PROCESS | 1679 | RSS: Main: 330158080 16:37:21 INFO - PROCESS | 1679 | 16:37:23 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:37:24 INFO - PROCESS | 1679 | RSS: Main: 316882944 16:37:24 INFO - PROCESS | 1679 | 16:37:26 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:37:26 INFO - PROCESS | 1679 | RSS: Main: 323768320 16:37:26 INFO - PROCESS | 1679 | 16:37:28 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:37:29 INFO - PROCESS | 1679 | RSS: Main: 325808128 16:37:29 INFO - PROCESS | 1679 | 16:37:32 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:37:33 INFO - PROCESS | 1679 | RSS: Main: 330190848 16:37:33 INFO - PROCESS | 1679 | 16:37:36 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:37:36 INFO - PROCESS | 1679 | RSS: Main: 327925760 16:37:36 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 16:37:38 INFO - PROCESS | 1679 | RSS: Main: 374468608 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | __start_tp_report 16:37:38 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 16:37:38 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 16:37:38 INFO - PROCESS | 1679 | |i|pagename|runs| 16:37:38 INFO - PROCESS | 1679 | |0;ai-astar;112;103;112;110;112;110;111;111;111;109 16:37:38 INFO - PROCESS | 1679 | |1;audio-beat-detection;153;118;141;201;141;140;138;119;169;118 16:37:38 INFO - PROCESS | 1679 | |2;audio-dft;184;184;183;181;184;179;188;183;179;185 16:37:38 INFO - PROCESS | 1679 | |3;audio-fft;77;73;58;155;79;83;57;59;112;56 16:37:38 INFO - PROCESS | 1679 | |4;audio-oscillator;94;96;134;80;78;77;104;80;85;107 16:37:38 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;86;84;86;84;87;84;87;83;86;83 16:37:38 INFO - PROCESS | 1679 | |6;imaging-darkroom;92;93;92;93;93;93;93;93;93;92 16:37:38 INFO - PROCESS | 1679 | |7;imaging-desaturate;83;85;85;85;85;82;92;136;132;85 16:37:38 INFO - PROCESS | 1679 | |8;json-parse-financial;56;57;57;55;76;84;87;108;77;76 16:37:38 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;64;69;74;74;69;68;67;69;68;67 16:37:38 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;85;85;86;83;79;83;80;79;84;86 16:37:38 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;146;137;127;147;144;142;133;136;214;156 16:37:38 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;162;162;161;163;163;165;162;161;163;162 16:37:38 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;67;64;66;65;66;63;64;62;63;63 16:37:38 INFO - PROCESS | 1679 | __end_tp_report 16:37:38 INFO - PROCESS | 1679 | __start_cc_report 16:37:38 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2283 16:37:38 INFO - PROCESS | 1679 | __end_cc_report 16:37:38 INFO - PROCESS | 1679 | __startTimestamp1463441858816__endTimestamp 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | ------- Summary: start ------- 16:37:38 INFO - PROCESS | 1679 | Number of tests: 14 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:2.69 (2.4%) stddev-sans-first:2.76 16:37:38 INFO - PROCESS | 1679 | Values: 112.0 103.0 112.0 110.0 112.0 110.0 111.0 111.0 111.0 109.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:141.00 stddev:25.82 (18.3%) stddev-sans-first:27.17 16:37:38 INFO - PROCESS | 1679 | Values: 153.0 118.0 141.0 201.0 141.0 140.0 138.0 119.0 169.0 118.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:2.75 (1.5%) stddev-sans-first:2.89 16:37:38 INFO - PROCESS | 1679 | Values: 184.0 184.0 183.0 181.0 184.0 179.0 188.0 183.0 179.0 185.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:80.90 Median:66.00 stddev:31.16 (47.2%) stddev-sans-first:33.02 16:37:38 INFO - PROCESS | 1679 | Values: 77.0 73.0 58.0 155.0 79.0 83.0 57.0 59.0 112.0 56.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:80.00 stddev:17.91 (22.4%) stddev-sans-first:19.00 16:37:38 INFO - PROCESS | 1679 | Values: 94.0 96.0 134.0 80.0 78.0 77.0 104.0 80.0 85.0 107.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:86.00 stddev:1.56 (1.8%) stddev-sans-first:1.62 16:37:38 INFO - PROCESS | 1679 | Values: 86.0 84.0 86.0 84.0 87.0 84.0 87.0 83.0 86.0 83.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 16:37:38 INFO - PROCESS | 1679 | Values: 92.0 93.0 92.0 93.0 93.0 93.0 93.0 93.0 93.0 92.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:85.00 stddev:20.74 (24.4%) stddev-sans-first:21.54 16:37:38 INFO - PROCESS | 1679 | Values: 83.0 85.0 85.0 85.0 85.0 82.0 92.0 136.0 132.0 85.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:73.30 Median:76.00 stddev:17.32 (22.8%) stddev-sans-first:17.20 16:37:38 INFO - PROCESS | 1679 | Values: 56.0 57.0 57.0 55.0 76.0 84.0 87.0 108.0 77.0 76.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:3.07 (4.5%) stddev-sans-first:2.70 16:37:38 INFO - PROCESS | 1679 | Values: 64.0 69.0 74.0 74.0 69.0 68.0 67.0 69.0 68.0 67.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:84.50 stddev:2.75 (3.3%) stddev-sans-first:2.82 16:37:38 INFO - PROCESS | 1679 | Values: 85.0 85.0 86.0 83.0 79.0 83.0 80.0 79.0 84.0 86.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:145.00 stddev:24.51 (16.9%) stddev-sans-first:25.99 16:37:38 INFO - PROCESS | 1679 | Values: 146.0 137.0 127.0 147.0 144.0 142.0 133.0 136.0 214.0 156.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.40 Median:162.50 stddev:1.17 (0.7%) stddev-sans-first:1.24 16:37:38 INFO - PROCESS | 1679 | Values: 162.0 162.0 161.0 163.0 163.0 165.0 162.0 161.0 163.0 162.0 16:37:38 INFO - PROCESS | 1679 | 16:37:38 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.50 stddev:1.64 (2.5%) stddev-sans-first:1.41 16:37:38 INFO - PROCESS | 1679 | Values: 67.0 64.0 66.0 65.0 66.0 63.0 64.0 62.0 63.0 63.0 16:37:38 INFO - PROCESS | 1679 | -------- Summary: end -------- 16:37:38 INFO - PROCESS | 1679 | 16:37:39 INFO - TEST-INFO | 1679: exit 0 16:37:39 INFO - TEST-OK | kraken | took 60722ms 16:37:39 INFO - SUITE-END | took 1176s 16:37:39 INFO - Completed test suite (00:19:36) 16:37:39 INFO - javascript benchmark 16:37:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3416.6241713411528, 3433.26184870253, 3458.481227815829, 3464.584249890013, 3506.649335066492, 4579.954200457994, 5623.746931388087, 5653.180288858113, 5721.914171287434, 5732.627379220347, 5649.689267090308, 5652.745626446809, 5672.092465205578, 5766.423357664232, 5774.711264436783, 5047.873803154914, 5072.822451214208, 5132.435432102466, 5142.7428628568605, 5250.238715386266, 5221.190715439094, 5246.422893481714, 5270.736463176845, 5272.0, 5305.257263983045, 5074.797008119671, 5132.692038477703, 5146.176611742141, 5173.5085166909275, 5174.990876779029, 5125.256837758541, 5129.281900533928, 5163.586913046947, 5202.765875535572, 5229.163333866563, 5070.3915530136255, 5108.846734597968, 5128.384593848725, 5132.101882170605, 5162.457941916082, 5696.401877802812, 5704.058830293038, 5708.20085188074, 5722.7424765885635, 5756.251687280668, 6059.191114378535, 6095.166312950018, 6147.692615369214, 6156.353582873779, 6240.906386404207, 6232.40792124752, 6269.310375858652, 6280.685965701742, 6282.2461304643275, 6305.274893387274, 5652.8869422611315, 5755.194272801769, 5827.912581311284, 5829.183914252008, 5834.912476312858, 6269.996800511896, 6297.779577714758, 6304.810855674337, 6332.050192471074, 6368.29948705642, 6113.240357180302, 6137.312239134274, 6147.477464415485, 6156.630602163885, 6156.907646385308, 6134.405972711579, 6188.907166392508, 6253.437190652863, 6272.435480806749, 6275.843103922439, 6117.979653188387, 6166.845828854314, 6185.0, 6252.343503932113, 6253.499720022387, 6198.752049918043, 6265.185525881698, 6282.4974002079725, 6289.622622642656, 6291.622502649856, 4802.447718512416, 4851.151048566487, 4904.901901961941, 4914.631402644816, 4950.282209079735, 4352.782360882006, 4363.978180109143, 4374.081442897027, 4414.940414300545, 4428.31361139024, 5697.601167918206, 5716.542676585863, 5718.342390625131, 5722.313322401338, 5733.111367738007, 6105.611380716617, 6212.223472065992, 6283.560150789401, 6307.085472606446, 6319.873602527924, 5734.713264336767, 5735.1684005818925, 5742.28221472316, 5743.511801496919, 5777.988851950891, 5341.893162136736, 5375.274337964324, 5383.407825139231, 5384.111621582395, 5386.461353864661, 6277.8744425111245, 6281.780137695158, 6284.340144284784, 6344.746210151542, 6347.68261586928, 6301.275353334425, 6311.495080393649, 6328.936710632835, 6330.715117819801, 6331.715072821827, 6303.1175635412, 6305.054241863757, 6305.5901366411035, 6334.0, 6334.651594162366, 6090.725917333819, 6093.685708195194, 6132.172156758959, 6137.539684523589, 6216.658083805253, 6152.723127459186, 6307.180066591313, 6312.368763123651, 6313.368663133833, 6319.96840015797, 6042.969785151046, 6081.148639190428, 6090.573659843769, 6099.207103076572, 6118.60229085108, 5987.251593550806, 6027.427394397525, 6064.545159112996, 6116.480099191441, 6129.172561704291, 5830.76676932918, 5945.672987985702, 6003.5797494174985, 6046.183765191819, 6047.334793172659, 6094.14681944537, 6123.357047510125, 6127.571070025234, 6128.158709735206, 6131.264248290234, 5796.536277097906, 5803.3616302207565, 5870.765169393177, 5962.97018514922, 5982.7008649568215], "value": 5696.146407830403, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.4876495416969, 532.5473347654491, 537.9166229234469, 538.7952578020352, 552.7319250163667, 31758.841205793968, 41311.79344103283, 42265.154696906044, 42320.9419764506, 44499.55500444995, 43803.0, 44216.77891610545, 44320.89197770057, 44420.33369499452, 44428.555714442846, 8618.09510001447, 9612.471314077722, 9809.558569864337, 9818.165455936218, 9820.803583928353, 8669.869951950694, 11510.0, 11703.765924681502, 11706.590269340575, 11834.349110798903, 11747.295162290247, 11753.177277590572, 11825.113116516253, 11840.171188016842, 11840.289582625028, 8696.652133914637, 8838.513881736502, 8848.1151884811, 11704.648860534198, 11792.646220613395, 8719.825603487958, 11370.147238957028, 11626.941865290704, 11641.592544260995, 11723.531058757724, 40080.39879401811, 40455.0, 40468.797656011535, 40490.19019619621, 40580.18839623191, 3237.8704851806133, 3367.2087059540986, 3771.2268984858038, 3869.400242962345, 4084.9183016339803, 28751.56872646912, 28887.133385998455, 29195.270118247, 29387.0, 29389.412211755644, 2727.4954133485367, 2732.767714744249, 2786.8327900325844, 2794.357297821492, 2824.4351129774127, 489836.0, 494596.0, 495773.0, 495788.0, 497540.0, 34752.826235868655, 59341.40658593445, 59712.40287597155, 59892.0, 59965.70017149974, 35432.114197144765, 36716.44925326122, 36826.26347473089, 36855.63144368576, 36884.89345319645, 11010.779784404427, 15022.699546009018, 15025.474108406372, 15036.248187590576, 15050.548483545512, 11542.0, 11631.709207269885, 11634.127440442, 11886.524539018343, 12610.936945315214, 7701.152873184057, 8000.919990800134, 8183.427160098736, 8193.13972032928, 8199.7950051248, 2728.9493544985326, 2956.88172473103, 3093.829839358857, 3384.3569721752788, 3402.1154499830222, 897.3449381951212, 948.6821914658667, 994.1152374386802, 1052.079430498314, 1068.609957365556, 28873.42253154925, 29134.562981555293, 29302.560461593093, 29478.0, 29559.556606650916, 1120.4845770945456, 1134.5575225661833, 1135.5514571744116, 1154.1978325064, 1224.3327386574338, 11968.083782559514, 13401.597952061455, 14700.338484768212, 14734.894882883613, 15102.697946041018, 676820.0, 689113.0, 689122.0, 690241.0, 692680.0, 58906.70546647325, 59282.70358648179, 59433.81132377328, 59449.0, 59458.0, 1253.8056601226824, 1351.5202103253202, 1428.9214093224557, 1440.4454285100312, 1471.3452513631332, 29145.56281655819, 29816.0, 29937.85031074831, 30586.07418647926, 30761.38477230398, 37630.0, 41254.79372603238, 49715.502844972536, 49763.27248911348, 49814.25278620896, 568170.0, 582406.0, 586922.0, 587348.0, 589890.0, 23063.769362306834, 25396.873015634803, 25934.092306769173, 26185.476290473704, 26596.734032659424, 43901.0, 46248.07503849837, 46291.76854115708, 46602.766986164854, 47210.52789472061, 36127.63872361243, 37803.43294850634, 37899.0, 38941.22117557576, 39008.41487377748, 37490.81254593709, 37540.62459375481, 37884.62115378811, 38149.80925095357, 38238.617613824616, 37015.81492092522, 37106.25787484181, 37553.436698449, 37619.43570846385, 38194.236115278094, 32032.410129660933, 33716.15709607279, 33749.156271093416, 33754.156146096546, 33795.0, 20455.693164602246, 24415.145469908468, 24751.752482474945, 24882.0, 25219.0], "value": 18778.56110971296, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.9486566746323, 400.06584624900853, 400.20359484625584, 406.89624145842805, 425.01184745466776, 13813.0, 16997.660046799057, 20046.0, 20678.06948687309, 21237.469063273427, 20540.589188216225, 20744.89627551864, 20774.68837967428, 20864.47838804031, 21032.579348413023, 5346.27825243593, 5885.7351419186025, 6101.78643747469, 6136.895358835408, 6146.293176284717, 5213.556847667953, 5735.541156707453, 6066.454019138277, 6115.357887421824, 6781.864362712743, 5830.0, 6606.108175396308, 6735.7875582395145, 6774.424173945221, 6780.084688567054, 6463.385978332051, 6620.834479138012, 6663.30035346289, 6663.50023748216, 6681.997700344963, 5642.0, 5716.056850619642, 5781.248437703114, 5792.073268277056, 5798.7390567424145, 25929.481410371875, 26673.866630666915, 26862.46275074507, 27116.59325110125, 27177.728222717717, 1979.1390745025815, 2047.0992763184151, 2067.640238543317, 2149.4518897681146, 2297.8161747060194, 10039.89960100397, 10080.445575493344, 10347.5343609537, 10356.844647330296, 11757.941785239283, 1708.3422882190323, 1723.7330562036893, 1746.8602511799027, 1843.5483306589972, 1865.2352535460532, 107308.463457683, 124856.37571812083, 125412.0, 131958.99999999904, 132220.0, 11595.826062609067, 11663.125265604944, 11684.12369072323, 11699.941500292614, 11832.639246486591, 13273.734525309439, 13806.93096534531, 13929.442822287197, 14919.776203356958, 14955.327010284564, 6963.408110310579, 7857.1749966253865, 8600.39797214189, 8675.522846243517, 8736.519491427905, 8511.233988940901, 8532.744017679479, 8533.871991920127, 8633.093525179765, 8659.960804703476, 4530.29780384041, 4602.608778253819, 4603.792829322689, 4653.185692503798, 4654.650901182425, 1732.454276902797, 1836.9357072502398, 1919.9808001920082, 1960.4628401373138, 1973.3882496426154, 853.9914600854037, 925.5372313843078, 927.1794461901131, 965.971020869375, 1023.6826583759058, 19069.0, 19327.226910923688, 20397.694034589495, 20470.0, 20688.17247310091, 948.6347756113946, 962.7400601837464, 1001.5493028137222, 1006.8993100689872, 1024.6362541297733, 10734.78530429387, 10805.189610779224, 10905.400202988912, 10936.726581835517, 10983.450827458755, 612869.0, 620722.0, 629245.0, 632660.0, 642301.0, 139873.30063349617, 140282.0, 143133.28433357767, 145549.0, 148182.0, 1045.3936716704293, 1066.4827558634056, 1082.880883102858, 1090.0, 1096.2326371539955, 18536.385404845925, 18750.0, 18752.249910003993, 18857.379941914318, 18946.810531894505, 52898.0, 54961.90076198534, 55861.441385585626, 56382.7180864093, 56879.43120568907, 82956.0, 83440.0, 83778.0, 84410.0, 86155.569222156, 8010.599470026592, 8075.596220189084, 8099.987501562305, 8303.83392332138, 8430.15698430152, 9067.68263110788, 11823.0, 12621.495140194656, 12882.806757898827, 13079.280639564915, 6893.0, 10837.344850511676, 10951.178661600572, 11244.212905096565, 11310.151738619472, 10302.175825933757, 10366.637167699397, 10379.948100259753, 10626.468676566295, 10722.624708135178, 7444.478886477895, 10858.457077145953, 11089.667309980714, 11492.310461372344, 11576.0, 6863.725450981905, 7901.407394445555, 8097.2307630774985, 8161.183881611792, 8485.0, 9677.951610241904, 11020.393878336768, 11051.226414150933, 11053.778924421305, 11260.88739112631], "value": 9665.817796718124, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1460.8685218330347, 1613.7902072730544, 1648.8845780795345, 1885.5663197464582, 1994.7706013808415, 8079.515229086252, 8675.652973881046, 8887.822243555125, 8908.8663670045, 9003.549822508881, 8162.306203972655, 8774.692885749, 8797.42816716913, 8806.11938806119, 8818.426802257849, 2384.9046038158494, 2420.9152679656218, 2423.7212720537186, 2425.0178677635586, 2437.341917682225, 2378.262738551046, 2409.6144616861307, 2429.2955043037496, 2429.829911906167, 2431.2706188143575, 2380.4881950380664, 2402.9038838446527, 2414.3481260059775, 2421.4551725861716, 2424.0, 2361.126383238213, 2387.988060059697, 2395.161693407302, 2396.9880150599224, 2402.1832576923757, 2287.1080278691325, 2367.8224133189906, 2372.5136347048815, 2373.988130059339, 2375.9406014849596, 7863.174366691473, 8064.798380040487, 8628.568571571397, 8706.30349572039, 8722.99685536165, 4924.285978533128, 5096.261042148904, 5096.362954630671, 5221.895562088775, 5396.0, 2876.5972763813083, 3393.694567488913, 3534.6111927687934, 3540.5574303212097, 3544.8227588620725, 1030.464158637512, 1032.5353590884133, 1034.9068583827416, 1045.487711021594, 1049.7428130108096, 11735.354555499443, 11859.644210673694, 11870.643880683594, 11875.0499960003, 11884.989775869062, 4609.723416595015, 4713.622910167247, 4737.928931066037, 4742.312364707098, 4901.387326584177, 9912.256580756472, 10050.195984321237, 10054.798904021878, 10059.29584929048, 10084.747881303027, 5443.42844001382, 5448.91826622601, 5450.3732070811575, 5456.508914197741, 5459.972700136474, 4766.189747742892, 4991.625628077909, 4991.875203119951, 5018.673786203885, 5025.246213067996, 3972.443857859902, 4006.8998275042773, 4064.9796751016056, 4073.4093556434746, 4086.693497987663, 1318.3408295852073, 1332.5069724201912, 1337.25782190885, 1351.49319005373, 1355.1936597724339, 811.3833486550265, 934.6915517879083, 939.3612343606277, 946.9715908522755, 979.1970584121095, 4995.9500404996215, 5718.51392631631, 5729.0, 5891.734871930774, 5949.929012777741, 465.62982428969053, 477.98327058552786, 478.6410192355733, 977.4477420257532, 1029.4749677664445, 3904.9804750976064, 3997.060690737674, 4009.3785463253244, 4047.1096358801015, 4051.47330846988, 10491.422971736474, 10634.30876992993, 10892.455377231106, 10907.236493445384, 10954.726131846608, 10720.624778132727, 10835.349879007284, 10866.511007004545, 10869.40218287986, 10877.456127193767, 7973.521588704696, 8268.627911743866, 8357.749267521984, 8360.12218717026, 8367.95816020916, 5742.109972954198, 5746.13807928814, 5822.155787410887, 5855.970720146372, 5866.120081987736, 9958.800823983334, 10079.445630490394, 10080.69757907264, 10084.394936303552, 10091.69724908254, 9803.950980245054, 10118.241131915283, 10384.065434110966, 10431.843522347323, 10443.634472793416, 6990.510664253454, 7015.824604384931, 7123.893141602776, 7138.143422789299, 7165.856682866418, 8193.180681931759, 8249.340052795882, 8287.419880608299, 8322.20939010793, 8341.040780310099, 7477.850442991218, 7523.3229009389415, 7528.774136775905, 7614.2766437188375, 7637.236276372319, 7366.484346095722, 7415.036045314075, 7488.925110748823, 7498.32515073646, 7530.510516816608, 6940.27127151662, 7027.402670773042, 7104.786856394317, 7115.715371385087, 7120.216776154515, 4211.7472951623, 4334.739915605074, 4882.60939124862, 4914.582260507754, 4962.751862406937, 4730.9763451182525, 4920.901581968269, 4958.553730164303, 5093.439721630692, 5243.082460569309], "value": 4571.643542755557, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.4275590158209, 312.96400913894905, 321.9839008049597, 328.2958054972086, 331.58055060348664, 4502.236048998638, 5044.308111792772, 5420.159875219342, 5442.156465747806, 5452.263944367504, 4062.364184055276, 4811.037792441508, 5098.566621837139, 5192.221166824968, 5201.713905735183, 3502.684758371746, 3868.4197370394386, 3955.821988010532, 3991.92218101114, 4009.318415869302, 3730.4590834329006, 4081.857135000276, 4082.2243773683053, 4102.774347410891, 4110.321796903508, 3939.9212015759667, 4060.736052156615, 4074.1647962167685, 4076.1236334188143, 4078.5717499662405, 3489.145159435929, 4035.567373582395, 4046.332355161395, 4053.250148722481, 4061.0253539150685, 3031.787774855761, 3385.3229354129026, 3437.9656203437894, 3524.1718196223865, 3558.967899309197, 4463.598276155161, 4665.2535594304745, 4785.068704533751, 5095.363079615048, 5122.487751224848, 3819.0, 4079.9592004080173, 4505.459344878603, 4694.342792009122, 4710.387649605529, 4456.55434456555, 5464.114880366267, 5591.496765291103, 5606.299212598426, 5615.522680572156, 3886.689064874803, 3892.708046896494, 3895.3767397216593, 3910.8631197907935, 3956.140613911457, 5505.366882808448, 5828.0, 5901.203337549418, 5941.851453713649, 5961.0, 3197.376511580266, 3846.7114966377635, 3854.209886973164, 3868.709846761504, 3876.2635099331037, 3370.292238629915, 3924.0778417071965, 3953.9802300988313, 3955.248502784462, 3997.420374045747, 3907.1245802014623, 4416.53626369233, 4416.933745993812, 4641.419822522184, 4649.488556258809, 4600.677952543357, 4742.786574604151, 5413.431589683105, 5550.805721799798, 5675.772969081273, 1092.9180311476512, 1199.2504684572143, 1274.5857596281246, 1319.4854006937303, 1351.6958684295955, 715.4634024481638, 719.1909102259957, 737.5948817502666, 749.9152356449081, 767.7888580640257, 4590.655700822452, 5099.184130539096, 5178.741062946838, 5253.816116435906, 5284.0, 5048.667879199784, 5785.739641716133, 5788.681622510718, 5800.564957628195, 5811.970940145272, 4479.283314669638, 5018.2974383586325, 5254.816081437131, 5257.5005374489365, 5294.788208471618, 2516.987415062913, 2723.9025950018377, 2818.971810281912, 3001.5497675348875, 3034.9665723964995, 5040.420351659533, 5611.494965453129, 5970.283565972024, 6014.346054834868, 6034.396560344018, 5625.46558076982, 5673.546116310686, 5943.732532036069, 5974.118152781946, 6031.668258245751, 5279.788808447619, 5574.665520068809, 5780.1907732917425, 5855.970720146457, 6067.726952287246, 4429.68992170545, 5852.239208902815, 5862.94137058641, 5865.4134586541, 5881.794137205177, 5720.513756330759, 5748.798792042258, 5758.481736643722, 5758.827235182951, 5772.3650398456975, 5139.3297178416415, 5401.594880384066, 5543.667379957215, 5735.9139612905, 6054.66699331541, 3865.7822785822377, 3872.5933776953016, 3882.2429626223743, 3883.427246932772, 3894.123822139996, 4443.822247110079, 4763.1426343258545, 5169.92245116316, 5790.884182316414, 5907.232059832194, 5363.758630861543, 5728.388647139093, 5738.856528586819, 5753.884922301614, 5754.5684073695475, 5137.6660517066275, 5201.0, 5469.589780766379, 5617.662940223436, 5632.887342253214, 4575.24508456101, 5119.48805119485, 5282.867928301823, 5432.483914028161, 5435.87437223606, 4765.428148622187, 5168.012867582801, 5274.314339135888, 5677.68772717488, 5715.399883012224, 4564.748938808398, 4867.63492738039, 5541.778328866801, 5548.278723765885, 5569.832905012856], "value": 4037.0875685911205, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.552776875609, 750.0699133074988, 752.9872321727279, 755.6486233901235, 759.0625577411892, 15146.16696081715, 15453.922730386346, 15487.070775753462, 15685.607859803482, 15760.76358854618, 14283.642908927282, 14440.277986100678, 14487.275636218174, 14488.2755862207, 14497.347619357128, 750.1523278695095, 759.6012093650822, 922.2391526990226, 943.1040511514054, 944.655200851691, 751.9511231769944, 923.5474617437475, 926.9675561355353, 933.5099072986668, 938.5260443476035, 975.024975024975, 984.9409035457895, 987.6049580167954, 992.1814503034989, 992.4739887859446, 773.9303462688357, 953.7663272498282, 973.6884197056945, 981.4258658684678, 981.8036392721449, 728.9271072892722, 729.6789412658467, 730.9013283206806, 731.8536292741474, 732.1104857598044, 14923.776143357749, 14976.550703478913, 14976.850231497654, 14989.475368362055, 14991.100533967996, 12195.573154939624, 12359.62921112368, 12384.56654017105, 12469.937650311782, 12473.789340833016, 9357.578908949046, 9653.22774178071, 10079.949600251952, 10665.733356666067, 10901.1279097672, 4046.9392959105635, 4067.2272268268935, 4076.8980775480554, 4139.79301034947, 4162.604552567501, 70232.64883675548, 71227.0, 71347.0, 71361.28638713599, 71387.0, 9236.353455258202, 9248.213901818286, 9347.158755712018, 9516.809663806685, 9717.12545870861, 20682.0, 20696.379108626767, 20703.689444658343, 20711.482212944797, 20737.896310518652, 7319.963400182965, 7332.30343117403, 7383.2985866342615, 7435.814104647319, 7559.130699969464, 4018.276710192135, 4094.877153685394, 4097.8975525612095, 4101.733387329814, 4107.383892416162, 3396.9490457643155, 3472.461768425898, 3478.843452044664, 3487.250241198104, 3496.125968507873, 650.7397041183469, 675.3651567526509, 689.5896941319907, 694.7507172978985, 703.2229386527915, 566.9659820410789, 569.857535616096, 581.2095550051926, 604.2959951656271, 622.9065640154013, 8057.395695322874, 8449.366297527587, 8517.063123056583, 8530.14698530142, 8536.103709110579, 417.84957415330456, 487.53927538476506, 587.1427715535281, 597.9611325263844, 616.6885722710002, 3340.732741380684, 3850.4801851750217, 3941.3102707026155, 3998.6208206117312, 4021.0148513614354, 78417.21582784099, 81185.0, 81369.59315203504, 96121.0, 97813.0, 67536.0, 74040.40858569925, 77007.0, 77455.0, 80591.0, 72462.63768681229, 73162.99999999894, 73307.26692733004, 73878.63060684662, 74606.0, 8235.83528329442, 8287.83424331498, 8375.45559538628, 8515.574221288787, 8557.44376615518, 42443.633454982, 43917.0, 45720.0, 46059.0, 47114.52885471102, 58580.70709646425, 59263.51841203832, 59849.80300394055, 61572.84495042652, 62297.37702622916, 10249.12882405004, 10822.296550724177, 10961.725956851142, 11177.0, 11727.88272117302, 11923.284602923852, 11995.580154694544, 12301.954333881362, 12354.444050017592, 12890.613281601567, 11418.31490110596, 11482.138839586898, 11577.189596728149, 11842.171048026556, 12126.75746485083, 11357.716057098638, 11524.366159860952, 11544.0, 11587.942060289644, 11887.702807429883, 10654.200934929757, 10896.509657065293, 10902.345859248471, 10907.672769816918, 12325.75348493043, 9354.129134359831, 9546.045395460398, 9778.559964801743, 9787.559559819967, 9816.361936474106, 6952.895706564304, 9018.984196402156, 9405.811883762422, 9435.52822358893, 9518.095780900803], "value": 6862.79730192567, "unit": "score"}], "name": "dromaeo_css", "value": 7126.2552666953425, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 103.0, 112.0, 110.0, 112.0, 110.0, 111.0, 111.0, 111.0, 109.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 118.0, 141.0, 201.0, 141.0, 140.0, 138.0, 119.0, 169.0, 118.0], "value": 143.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 181.0, 184.0, 179.0, 188.0, 183.0, 179.0, 185.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 58.0, 155.0, 79.0, 83.0, 57.0, 59.0, 112.0, 56.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 134.0, 80.0, 78.0, 77.0, 104.0, 80.0, 85.0, 107.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 87.0, 84.0, 87.0, 83.0, 86.0, 83.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 85.0, 85.0, 85.0, 82.0, 92.0, 136.0, 132.0, 85.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 55.0, 76.0, 84.0, 87.0, 108.0, 77.0, 76.0], "value": 73.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 74.0, 74.0, 69.0, 68.0, 67.0, 69.0, 68.0, 67.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 83.0, 79.0, 83.0, 80.0, 79.0, 84.0, 86.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 137.0, 127.0, 147.0, 144.0, 142.0, 133.0, 136.0, 214.0, 156.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 163.0, 163.0, 165.0, 162.0, 161.0, 163.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 66.0, 65.0, 66.0, 63.0, 64.0, 62.0, 63.0, 63.0], "value": 64.3, "unit": "score"}], "name": "kraken", "value": 1484.1000000000001, "alertThreshold": 2.0}]} 16:37:40 INFO - Return code: 0 16:37:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:37:40 ERROR - # TBPL SUCCESS # 16:37:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:37:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:37:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:37:40 INFO - 2016-05-16 16:37:40.143 screenresolution[1683:10333] starting screenresolution argv=screenresolution get 16:37:40 INFO - 2016-05-16 16:37:40.146 screenresolution[1683:10333] Display 0: 1600x1200x32@60 16:37:40 INFO - 2016-05-16 16:37:40.152 screenresolution[1684:10336] starting screenresolution argv=screenresolution list 16:37:40 INFO - Available Modes on Display 0 16:37:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:37:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:37:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:37:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:37:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:37:40 INFO - Intel Iris: 16:37:40 INFO - Chipset Model: Intel Iris 16:37:40 INFO - Type: GPU 16:37:40 INFO - Bus: Built-In 16:37:40 INFO - VRAM (Dynamic, Max): 1536 MB 16:37:40 INFO - Vendor: Intel (0x8086) 16:37:40 INFO - Device ID: 0x0a2e 16:37:40 INFO - Revision ID: 0x0009 16:37:40 INFO - Displays: 16:37:40 INFO - Display: 16:37:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:37:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:37:40 INFO - Display Serial Number: 1600x1200 60 16:37:40 INFO - Main Display: Yes 16:37:40 INFO - Mirror: Off 16:37:40 INFO - Online: Yes 16:37:40 INFO - Rotation: Supported 16:37:40 INFO - Return code: 0 16:37:40 INFO - Running post-action listener: _resource_record_post_action 16:37:40 INFO - ##### 16:37:40 INFO - ##### Finished run-tests step (success) 16:37:40 INFO - ##### 16:37:40 INFO - Running post-run listener: _resource_record_post_run 16:37:40 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31038976; Write bytes: 694895616; Read time: 193; Write time: 1894 16:37:40 INFO - TinderboxPrint: CPU usage
53.5% 16:37:40 INFO - TinderboxPrint: I/O read bytes / time
31,038,976 / 193 16:37:40 INFO - TinderboxPrint: I/O write bytes / time
694,895,616 / 1,894 16:37:40 INFO - TinderboxPrint: CPU idle
2,252.0 (46.5%) 16:37:40 INFO - TinderboxPrint: CPU system
958.6 (19.8%) 16:37:40 INFO - TinderboxPrint: CPU user
1,635.1 (33.7%) 16:37:40 INFO - TinderboxPrint: Swap in / out
422,146,048 / 0 16:37:40 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382585344; Write bytes: 377853440; Read time: 28377; Write time: 773 16:37:40 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29277696; Write bytes: 295484928; Read time: 179; Write time: 1065 16:37:40 INFO - Running post-run listener: _upload_blobber_files 16:37:40 INFO - Blob upload gear active. 16:37:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:37:40 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 16:37:40 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:37:40 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:37:41 INFO - (blobuploader) - INFO - Open directory for files ... 16:37:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:37:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:37:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:41 INFO - (blobuploader) - INFO - Done attempting. 16:37:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:37:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:37:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:42 INFO - (blobuploader) - INFO - Done attempting. 16:37:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:37:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:37:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:45 INFO - (blobuploader) - INFO - Done attempting. 16:37:45 INFO - (blobuploader) - INFO - Iteration through files over. 16:37:45 INFO - Return code: 0 16:37:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:37:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:37:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a61ac432dbdba381bf919555405c82d767a89235ee4446a0bae9cee6a93ca27e125d4cba388bf4af4b75eff19d1b450a582dec99232b2b70b3b91d3ff47a217", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62c870ed2a99b7ba5ed91f26ff583790b8e31baee67de97171afdc192e181feee34788174fef9506f8541eb82dabf6c9361085ca1111c5a99b8506a6fe77cb25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/911c771993f0369a0461ad5859e81c6c2ecff30cf89945d269ee4e268a109229809617c195c341c287ad1d6e0a17497691c6396095713da2d5fd050681bfb26c"} 16:37:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:37:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:37:45 INFO - Contents: 16:37:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a61ac432dbdba381bf919555405c82d767a89235ee4446a0bae9cee6a93ca27e125d4cba388bf4af4b75eff19d1b450a582dec99232b2b70b3b91d3ff47a217", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62c870ed2a99b7ba5ed91f26ff583790b8e31baee67de97171afdc192e181feee34788174fef9506f8541eb82dabf6c9361085ca1111c5a99b8506a6fe77cb25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/911c771993f0369a0461ad5859e81c6c2ecff30cf89945d269ee4e268a109229809617c195c341c287ad1d6e0a17497691c6396095713da2d5fd050681bfb26c"} 16:37:45 INFO - Running post-run listener: copy_logs_to_upload_dir 16:37:45 INFO - Copying logs to upload dir... 16:37:45 INFO - mkdir: /builds/slave/test/build/upload/logs 16:37:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1294.001085 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-05-16 16:37:45.406386) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 16:37:45.410782) ========= 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.AijxbNf6mm/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.vl7LS2lEsT/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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/Firefox/sha512/9a61ac432dbdba381bf919555405c82d767a89235ee4446a0bae9cee6a93ca27e125d4cba388bf4af4b75eff19d1b450a582dec99232b2b70b3b91d3ff47a217", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62c870ed2a99b7ba5ed91f26ff583790b8e31baee67de97171afdc192e181feee34788174fef9506f8541eb82dabf6c9361085ca1111c5a99b8506a6fe77cb25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/911c771993f0369a0461ad5859e81c6c2ecff30cf89945d269ee4e268a109229809617c195c341c287ad1d6e0a17497691c6396095713da2d5fd050681bfb26c"} build_url:https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011352 build_url: 'https://queue.taskcluster.net/v1/task/aBeZ1AmoRcKTiN3Zb67zYA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a61ac432dbdba381bf919555405c82d767a89235ee4446a0bae9cee6a93ca27e125d4cba388bf4af4b75eff19d1b450a582dec99232b2b70b3b91d3ff47a217", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62c870ed2a99b7ba5ed91f26ff583790b8e31baee67de97171afdc192e181feee34788174fef9506f8541eb82dabf6c9361085ca1111c5a99b8506a6fe77cb25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/911c771993f0369a0461ad5859e81c6c2ecff30cf89945d269ee4e268a109229809617c195c341c287ad1d6e0a17497691c6396095713da2d5fd050681bfb26c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 16:37:45.442761) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 16:37:45.443106) ========= 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.AijxbNf6mm/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.vl7LS2lEsT/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.004560 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 16:37:45.491196) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 16:37:45.494540) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 16:37:45.494913) ========= ========= Total master_lag: 0.19 =========