builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0258 starttime: 1464389171.51 results: success (0) buildid: 20160527141751 builduid: e129a3a408ea48afb749986c78feb4e9 revision: 33c4562b47ce2cc5e2988d756c76fa79a36c469a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:11.508812) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:11.509581) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:11.510171) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:11.534271) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:11.534844) ========= 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.D60cu9MMT6/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.w4mazkQVXE/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-27 15:46:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 170M=0s 2016-05-27 15:46:11 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.164695 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:11.721283) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:11.721608) ========= 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.D60cu9MMT6/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.w4mazkQVXE/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.024693 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:11.770417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 15:46:11.770745) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 33c4562b47ce2cc5e2988d756c76fa79a36c469a --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 33c4562b47ce2cc5e2988d756c76fa79a36c469a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.D60cu9MMT6/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.w4mazkQVXE/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-27 15:46:11,856 truncating revision to first 12 chars 2016-05-27 15:46:11,856 Setting DEBUG logging. 2016-05-27 15:46:11,856 attempt 1/10 2016-05-27 15:46:11,856 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/33c4562b47ce?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 15:46:12,880 unpacking tar archive at: mozilla-central-33c4562b47ce/testing/mozharness/ program finished with exit code 0 elapsedTime=1.281624 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 15:46:13.074907) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:13.078290) ========= script_repo_revision: 33c4562b47ce2cc5e2988d756c76fa79a36c469a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:13.078778) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:13.079086) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 15:46:13.095231) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-27 15:46:13.095581) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.D60cu9MMT6/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.w4mazkQVXE/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:46:13 INFO - MultiFileLogger online at 20160527 15:46:13 in /builds/slave/test 15:46:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:46:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:46:13 INFO - {'append_to_log': False, 15:46:13 INFO - 'base_work_dir': '/builds/slave/test', 15:46:13 INFO - 'blob_upload_branch': 'Firefox', 15:46:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:46:13 INFO - 'branch': 'Firefox', 15:46:13 INFO - 'buildbot_json_path': 'buildprops.json', 15:46:13 INFO - 'config_files': ('talos/mac_config.py',), 15:46:13 INFO - 'default_actions': ('clobber', 15:46:13 INFO - 'read-buildbot-config', 15:46:13 INFO - 'download-and-extract', 15:46:13 INFO - 'populate-webroot', 15:46:13 INFO - 'create-virtualenv', 15:46:13 INFO - 'install', 15:46:13 INFO - 'run-tests'), 15:46:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:46:13 INFO - 'download_minidump_stackwalk': True, 15:46:13 INFO - 'download_symbols': 'ondemand', 15:46:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:46:13 INFO - 'tooltool.py': '/tools/tooltool.py', 15:46:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:46:13 INFO - '/tools/misc-python/virtualenv.py')}, 15:46:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:46:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:46:13 INFO - 'installer_path': 'installer.exe', 15:46:13 INFO - 'log_level': 'info', 15:46:13 INFO - 'log_name': 'talos', 15:46:13 INFO - 'log_to_console': True, 15:46:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:46:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:46:13 INFO - 'opt_config_files': (), 15:46:13 INFO - 'pip_index': False, 15:46:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:46:13 INFO - 'cmd': ('bash', 15:46:13 INFO - '-c', 15:46:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:46:13 INFO - 'enabled': True, 15:46:13 INFO - 'halt_on_failure': False, 15:46:13 INFO - 'name': 'check_screen_resolution'},), 15:46:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:46:13 INFO - 'cmd': ('bash', 15:46:13 INFO - '-c', 15:46:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:46:13 INFO - 'enabled': True, 15:46:13 INFO - 'halt_on_failure': False, 15:46:13 INFO - 'name': 'check_screen_resolution'},), 15:46:13 INFO - 'run_cmd_checks_enabled': True, 15:46:13 INFO - 'sps_profile': False, 15:46:13 INFO - 'sps_profile_interval': 0, 15:46:13 INFO - 'suite': 'dromaeojs-e10s', 15:46:13 INFO - 'system_bits': '32', 15:46:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:46:13 INFO - 'title': 't-yosemite-r7-0258', 15:46:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:46:13 INFO - 'use_talos_json': True, 15:46:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:46:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:46:13 INFO - 'work_dir': 'build'} 15:46:13 INFO - ##### 15:46:13 INFO - ##### Running clobber step. 15:46:13 INFO - ##### 15:46:13 INFO - Running pre-action listener: _resource_record_pre_action 15:46:13 INFO - Running main action method: clobber 15:46:13 INFO - rmtree: /builds/slave/test/build 15:46:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:46:14 INFO - Running post-action listener: _resource_record_post_action 15:46:14 INFO - ##### 15:46:14 INFO - ##### Finished clobber step (success) 15:46:14 INFO - ##### 15:46:14 INFO - ##### 15:46:14 INFO - ##### Running read-buildbot-config step. 15:46:14 INFO - ##### 15:46:14 INFO - Running pre-action listener: _resource_record_pre_action 15:46:14 INFO - Running main action method: read_buildbot_config 15:46:14 INFO - Using buildbot properties: 15:46:14 INFO - { 15:46:14 INFO - "project": "", 15:46:14 INFO - "product": "firefox", 15:46:14 INFO - "script_repo_revision": "production", 15:46:14 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:46:14 INFO - "repository": "", 15:46:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:46:14 INFO - "buildid": "20160527141751", 15:46:14 INFO - "pgo_build": "False", 15:46:14 INFO - "basedir": "/builds/slave/test", 15:46:14 INFO - "buildnumber": 18, 15:46:14 INFO - "slavename": "t-yosemite-r7-0258", 15:46:14 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:46:14 INFO - "platform": "yosemite_r7", 15:46:14 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:46:14 INFO - "branch": "mozilla-central", 15:46:14 INFO - "repo_path": "mozilla-central", 15:46:14 INFO - "revision": "33c4562b47ce2cc5e2988d756c76fa79a36c469a", 15:46:14 INFO - "stage_platform": "macosx64", 15:46:14 INFO - "builduid": "e129a3a408ea48afb749986c78feb4e9", 15:46:14 INFO - "slavebuilddir": "test" 15:46:14 INFO - } 15:46:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:46:14 INFO - Running post-action listener: _resource_record_post_action 15:46:14 INFO - ##### 15:46:14 INFO - ##### Finished read-buildbot-config step (success) 15:46:14 INFO - ##### 15:46:14 INFO - ##### 15:46:14 INFO - ##### Running download-and-extract step. 15:46:14 INFO - ##### 15:46:14 INFO - Running pre-action listener: _resource_record_pre_action 15:46:14 INFO - Running main action method: download_and_extract 15:46:14 INFO - mkdir: /builds/slave/test/build/tests 15:46: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')]} 15:46:14 INFO - https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:46:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:46:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:46:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/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 15:46:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/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 15:46:16 INFO - Downloaded 1199 bytes. 15:46:16 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:46:16 INFO - Using the following test package requirements: 15:46:16 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:46:16 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:46:16 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:46:16 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:46:16 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:46:16 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:46:16 INFO - u'jsshell-mac.zip'], 15:46:16 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:46:16 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:46:16 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:46:16 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:46:16 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:46:16 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:46:16 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:46:16 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:46:16 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:46:16 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:46:16 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:46:16 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:46:16 INFO - https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:46:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:46:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:46:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/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 15:46:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/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 15:46:18 INFO - Downloaded 18684676 bytes. 15:46:18 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'] 15:46:18 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 15:46:21 INFO - Return code: 0 15:46:21 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 15:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:46:21 INFO - https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:46:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:46:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:46:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/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 15:46:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/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 15:46:33 INFO - Downloaded 18684676 bytes. 15:46:33 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'] 15:46:33 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 15:46:36 INFO - Return code: 0 15:46:36 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')]} 15:46:36 INFO - https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:46:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:46:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:46:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/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 15:46:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/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 15:46:38 INFO - Downloaded 10834034 bytes. 15:46:38 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'] 15:46:38 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 15:46:38 INFO - Return code: 0 15:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:46:38 INFO - https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:46:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:46:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:46:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:46:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dVIU5dg6RTy1WKzdSMogig/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 15:46:41 INFO - Downloaded 110490463 bytes. 15:46:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:46:41 INFO - mkdir: /builds/slave/test/properties 15:46:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:46:41 INFO - Writing to file /builds/slave/test/properties/build_url 15:46:41 INFO - Contents: 15:46:41 INFO - build_url:https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:46:43 INFO - Running post-action listener: _resource_record_post_action 15:46:43 INFO - Running post-action listener: set_extra_try_arguments 15:46:43 INFO - ##### 15:46:43 INFO - ##### Finished download-and-extract step (success) 15:46:43 INFO - ##### 15:46:43 INFO - ##### 15:46:43 INFO - ##### Running populate-webroot step. 15:46:43 INFO - ##### 15:46:43 INFO - Running pre-action listener: _resource_record_pre_action 15:46:43 INFO - Running main action method: populate_webroot 15:46:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:46:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:46:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:46:43 INFO - '--tppagecycles', 15:46:43 INFO - '7'], 15:46:43 INFO - 'tests': ['tsvgm']}}, 15:46:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:46:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:46:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:46:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:46:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:46:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:46:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:43 INFO - 'talos_options': ['--disable-e10s'], 15:46:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:43 INFO - 'talos_options': ['--disable-e10s'], 15:46:43 INFO - 'tests': ['damp', 'tps']}, 15:46:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:43 INFO - 'tests': ['damp', 'tps']}, 15:46:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:46:43 INFO - 'tests': ['dromaeo_dom']}, 15:46:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:46:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:46:43 INFO - 'tests': ['a11yr', 15:46:43 INFO - 'ts_paint', 15:46:43 INFO - 'tpaint', 15:46:43 INFO - 'sessionrestore', 15:46:43 INFO - 'sessionrestore_no_auto_restore', 15:46:43 INFO - 'tabpaint']}, 15:46:43 INFO - 'other-e10s': {'tests': ['a11yr', 15:46:43 INFO - 'ts_paint', 15:46:43 INFO - 'tpaint', 15:46:43 INFO - 'sessionrestore', 15:46:43 INFO - 'sessionrestore_no_auto_restore', 15:46:43 INFO - 'tabpaint']}, 15:46:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:46:43 INFO - 'ts_paint', 15:46:43 INFO - 'tpaint', 15:46:43 INFO - 'sessionrestore', 15:46:43 INFO - 'sessionrestore_no_auto_restore', 15:46:43 INFO - 'tabpaint']}, 15:46:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:46:43 INFO - 'ts_paint', 15:46:43 INFO - 'tpaint', 15:46:43 INFO - 'sessionrestore', 15:46:43 INFO - 'sessionrestore_no_auto_restore', 15:46:43 INFO - 'tabpaint']}, 15:46:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:46:43 INFO - 'tests': ['a11yr', 15:46:43 INFO - 'ts_paint', 15:46:43 INFO - 'tpaint', 15:46:43 INFO - 'sessionrestore', 15:46:43 INFO - 'sessionrestore_no_auto_restore', 15:46:43 INFO - 'tabpaint']}, 15:46:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:46:43 INFO - 'tests': ['a11yr', 15:46:43 INFO - 'ts_paint', 15:46:43 INFO - 'tpaint', 15:46:43 INFO - 'sessionrestore', 15:46:43 INFO - 'sessionrestore_no_auto_restore', 15:46:43 INFO - 'tabpaint']}, 15:46:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:46:43 INFO - 'tests': ['tsvgx', 15:46:43 INFO - 'tsvgr_opacity', 15:46:43 INFO - 'tart', 15:46:43 INFO - 'tscrollx', 15:46:43 INFO - 'cart']}, 15:46:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:46:43 INFO - 'tsvgr_opacity', 15:46:43 INFO - 'tart', 15:46:43 INFO - 'tscrollx', 15:46:43 INFO - 'cart']}, 15:46:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:43 INFO - 'talos_options': ['--disable-e10s'], 15:46:43 INFO - 'tests': ['tp5o']}, 15:46:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:43 INFO - 'tests': ['tp5o']}, 15:46:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:43 INFO - 'talos_options': ['--disable-e10s', 15:46:43 INFO - '--xperf_path', 15:46:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:43 INFO - 'tests': ['tp5n']}, 15:46:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:43 INFO - 'talos_options': ['--xperf_path', 15:46:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:43 INFO - 'tests': ['tp5n']}}, 15:46:43 INFO - 'talos.zip': {'path': '', 15:46:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:46:43 INFO - Running post-action listener: _resource_record_post_action 15:46:43 INFO - ##### 15:46:43 INFO - ##### Finished populate-webroot step (success) 15:46:43 INFO - ##### 15:46:43 INFO - ##### 15:46:43 INFO - ##### Running create-virtualenv step. 15:46:43 INFO - ##### 15:46:43 INFO - Running pre-action listener: _resource_record_pre_action 15:46:43 INFO - Running main action method: create_virtualenv 15:46:43 INFO - Creating virtualenv /builds/slave/test/build/venv 15:46:43 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 15:46:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:46:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:46:43 INFO - Using real prefix '/tools/python27' 15:46:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:46:43 INFO - Installing distribute.............................................................................................................................................................................................done. 15:46:46 INFO - Installing pip.................done. 15:46:46 INFO - Return code: 0 15:46:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:46: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')]} 15:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46: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')]} 15:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:46 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 0x109ac3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ac9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd10c5d560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109967d30>, '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 0x10978bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995eb20>, '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.D60cu9MMT6/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.w4mazkQVXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:46:46 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 15:46:46 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 15:46:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D60cu9MMT6/Render', 15:46:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:46:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:46:46 INFO - 'HOME': '/Users/cltbld', 15:46:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:46:46 INFO - 'LOGNAME': 'cltbld', 15:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:46 INFO - 'MOZ_NO_REMOTE': '1', 15:46:46 INFO - 'NO_EM_RESTART': '1', 15:46:46 INFO - 'PAGER': '/bin/cat', 15:46:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:46 INFO - 'PWD': '/builds/slave/test', 15:46:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:46:46 INFO - 'SHELL': '/bin/bash', 15:46:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w4mazkQVXE/Listeners', 15:46:46 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:46:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:46:46 INFO - 'USER': 'cltbld', 15:46:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:46:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:46 INFO - 'XPC_FLAGS': '0x0', 15:46:46 INFO - 'XPC_SERVICE_NAME': '0', 15:46:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:47 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 15:46:47 INFO - Cleaning up... 15:46:47 INFO - Return code: 0 15:46:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:46:47 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')]} 15:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:47 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')]} 15:46:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:47 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 0x109ac3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ac9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd10c5d560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109967d30>, '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 0x10978bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995eb20>, '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.D60cu9MMT6/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.w4mazkQVXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:46:47 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 15:46:47 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 15:46:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D60cu9MMT6/Render', 15:46:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:46:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:46:47 INFO - 'HOME': '/Users/cltbld', 15:46:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:46:47 INFO - 'LOGNAME': 'cltbld', 15:46:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:47 INFO - 'MOZ_NO_REMOTE': '1', 15:46:47 INFO - 'NO_EM_RESTART': '1', 15:46:47 INFO - 'PAGER': '/bin/cat', 15:46:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:46:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:47 INFO - 'PWD': '/builds/slave/test', 15:46:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:46:47 INFO - 'SHELL': '/bin/bash', 15:46:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w4mazkQVXE/Listeners', 15:46:47 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:46:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:46:47 INFO - 'USER': 'cltbld', 15:46:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:46:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:46:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:47 INFO - 'XPC_FLAGS': '0x0', 15:46:47 INFO - 'XPC_SERVICE_NAME': '0', 15:46:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:47 INFO - Downloading/unpacking psutil>=3.1.1 15:46:47 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 15:46:47 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 15:46:47 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 15:46:47 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 15:46:47 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 15:46:47 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 15:46:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:46:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:50 INFO - Installing collected packages: psutil 15:46:50 INFO - Running setup.py install for psutil 15:46:50 INFO - building 'psutil._psutil_osx' extension 15:46:50 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 15:46:50 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 15:46:50 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 15:46:51 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 15:46:51 INFO - building 'psutil._psutil_posix' extension 15:46:51 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 15:46:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:46:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:46:51 INFO - ^ 15:46:51 INFO - 1 warning generated. 15:46:51 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 15:46:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:51 INFO - Successfully installed psutil 15:46:51 INFO - Cleaning up... 15:46:51 INFO - Return code: 0 15:46:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:51 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 0x109ac3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ac9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd10c5d560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109967d30>, '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 0x10978bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995eb20>, '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.D60cu9MMT6/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.w4mazkQVXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:46:51 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 15:46:51 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 15:46:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D60cu9MMT6/Render', 15:46:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:46:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:46:51 INFO - 'HOME': '/Users/cltbld', 15:46:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:46:51 INFO - 'LOGNAME': 'cltbld', 15:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:51 INFO - 'MOZ_NO_REMOTE': '1', 15:46:51 INFO - 'NO_EM_RESTART': '1', 15:46:51 INFO - 'PAGER': '/bin/cat', 15:46:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:51 INFO - 'PWD': '/builds/slave/test', 15:46:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:46:51 INFO - 'SHELL': '/bin/bash', 15:46:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w4mazkQVXE/Listeners', 15:46:51 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:46:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:46:51 INFO - 'USER': 'cltbld', 15:46:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:46:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:51 INFO - 'XPC_FLAGS': '0x0', 15:46:51 INFO - 'XPC_SERVICE_NAME': '0', 15:46:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:46:51 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 15:46:51 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 15:46:51 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 15:46:51 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 15:46:51 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 15:46:51 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 15:46:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:46:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:46:54 INFO - Installing collected packages: mozsystemmonitor 15:46:54 INFO - Running setup.py install for mozsystemmonitor 15:46:54 INFO - Successfully installed mozsystemmonitor 15:46:54 INFO - Cleaning up... 15:46:54 INFO - Return code: 0 15:46:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:46:54 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')]} 15:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:54 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')]} 15:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:54 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 0x109ac3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ac9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd10c5d560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109967d30>, '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 0x10978bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995eb20>, '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.D60cu9MMT6/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.w4mazkQVXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:46:54 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 15:46:54 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 15:46:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D60cu9MMT6/Render', 15:46:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:46:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:46:54 INFO - 'HOME': '/Users/cltbld', 15:46:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:46:54 INFO - 'LOGNAME': 'cltbld', 15:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:54 INFO - 'MOZ_NO_REMOTE': '1', 15:46:54 INFO - 'NO_EM_RESTART': '1', 15:46:54 INFO - 'PAGER': '/bin/cat', 15:46:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:54 INFO - 'PWD': '/builds/slave/test', 15:46:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:46:54 INFO - 'SHELL': '/bin/bash', 15:46:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w4mazkQVXE/Listeners', 15:46:54 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:46:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:46:54 INFO - 'USER': 'cltbld', 15:46:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:46:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:54 INFO - 'XPC_FLAGS': '0x0', 15:46:54 INFO - 'XPC_SERVICE_NAME': '0', 15:46:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:54 INFO - Downloading/unpacking blobuploader==1.2.4 15:46:54 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 15:46:54 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 15:46:54 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 15:46:54 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 15:46:54 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 15:46:54 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 15:46:57 INFO - Downloading blobuploader-1.2.4.tar.gz 15:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:46:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:46:57 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 15:46:57 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 15:46:57 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 15:46:57 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 15:46:57 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 15:46:57 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 15:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:46:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:46:57 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 15:46:57 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 15:46:57 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 15:46:57 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 15:46:57 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 15:46:57 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 15:46:58 INFO - Downloading docopt-0.6.1.tar.gz 15:46:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:46:58 INFO - Installing collected packages: blobuploader, requests, docopt 15:46:58 INFO - Running setup.py install for blobuploader 15:46:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:46:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:46:58 INFO - Running setup.py install for requests 15:46:59 INFO - Running setup.py install for docopt 15:46:59 INFO - Successfully installed blobuploader requests docopt 15:46:59 INFO - Cleaning up... 15:46:59 INFO - Return code: 0 15:46:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46:59 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')]} 15:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:59 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')]} 15:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:59 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 0x109ac3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ac9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd10c5d560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109967d30>, '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 0x10978bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995eb20>, '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.D60cu9MMT6/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.w4mazkQVXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:46:59 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 15:46:59 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 15:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D60cu9MMT6/Render', 15:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:46:59 INFO - 'HOME': '/Users/cltbld', 15:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:46:59 INFO - 'LOGNAME': 'cltbld', 15:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:59 INFO - 'MOZ_NO_REMOTE': '1', 15:46:59 INFO - 'NO_EM_RESTART': '1', 15:46:59 INFO - 'PAGER': '/bin/cat', 15:46:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:59 INFO - 'PWD': '/builds/slave/test', 15:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:46:59 INFO - 'SHELL': '/bin/bash', 15:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w4mazkQVXE/Listeners', 15:46:59 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:46:59 INFO - 'USER': 'cltbld', 15:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:59 INFO - 'XPC_FLAGS': '0x0', 15:46:59 INFO - 'XPC_SERVICE_NAME': '0', 15:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:46:59 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-e41O98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:46:59 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-qLRXgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:46:59 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-QvipND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:46:59 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-0FpCHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:46:59 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-k7ntxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-cf7a52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-BgMDUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-HgF4oM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-UOJZKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-dSgqz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-pAvXES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-o1uk6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-eDS3Yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-J9S323-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ULyKXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ojHsD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-GryFgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:47:01 INFO - Running setup.py install for manifestparser 15:47:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:47:01 INFO - Running setup.py install for mozcrash 15:47:01 INFO - Running setup.py install for mozdebug 15:47:01 INFO - Running setup.py install for mozdevice 15:47:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:47:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:47:01 INFO - Running setup.py install for mozfile 15:47:02 INFO - Running setup.py install for mozhttpd 15:47:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Running setup.py install for mozinfo 15:47:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Running setup.py install for mozInstall 15:47:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Running setup.py install for mozleak 15:47:02 INFO - Running setup.py install for mozlog 15:47:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Running setup.py install for moznetwork 15:47:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Running setup.py install for mozprocess 15:47:03 INFO - Running setup.py install for mozprofile 15:47:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Running setup.py install for mozrunner 15:47:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Running setup.py install for mozscreenshot 15:47:03 INFO - Running setup.py install for moztest 15:47:03 INFO - Running setup.py install for mozversion 15:47:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:47:03 INFO - Cleaning up... 15:47:03 INFO - Return code: 0 15:47:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:03 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')]} 15:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:03 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')]} 15:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:03 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 0x109ac3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ac9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd10c5d560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109967d30>, '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 0x10978bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995eb20>, '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.D60cu9MMT6/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.w4mazkQVXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:47:03 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 15:47:03 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 15:47:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D60cu9MMT6/Render', 15:47:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:03 INFO - 'HOME': '/Users/cltbld', 15:47:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:03 INFO - 'LOGNAME': 'cltbld', 15:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:03 INFO - 'MOZ_NO_REMOTE': '1', 15:47:03 INFO - 'NO_EM_RESTART': '1', 15:47:03 INFO - 'PAGER': '/bin/cat', 15:47:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:03 INFO - 'PWD': '/builds/slave/test', 15:47:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:03 INFO - 'SHELL': '/bin/bash', 15:47:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w4mazkQVXE/Listeners', 15:47:03 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:47:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:03 INFO - 'USER': 'cltbld', 15:47:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:03 INFO - 'XPC_FLAGS': '0x0', 15:47:03 INFO - 'XPC_SERVICE_NAME': '0', 15:47:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:04 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Z85kl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:04 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-bLMsuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:04 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-mw9EXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:04 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-NdRJZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:04 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Sj3weK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:04 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-tGhsJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:04 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-3uCjAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:04 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Yxa5wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:04 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Hng4fD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:05 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)) 15:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:05 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-fnTqZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:05 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)) 15:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:05 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-CVzu0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:05 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)) 15:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:05 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-7Tm0mC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:05 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)) 15:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:05 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-pJBcAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:05 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)) 15:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:05 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-n5eESH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:05 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)) 15:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:05 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-QGCWcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:05 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)) 15:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:05 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-8ATLTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:05 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)) 15:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:05 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Vq0W6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:05 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)) 15:47:05 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)) 15:47:05 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)) 15:47:05 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)) 15:47:05 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)) 15:47:05 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)) 15:47:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:08 INFO - Downloading blessings-1.6.tar.gz 15:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:47:08 INFO - Installing collected packages: blessings 15:47:08 INFO - Running setup.py install for blessings 15:47:08 INFO - Successfully installed blessings 15:47:08 INFO - Cleaning up... 15:47:08 INFO - Return code: 0 15:47:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:47:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:47:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:47:08 INFO - Reading from file tmpfile_stdout 15:47:08 INFO - Current package versions: 15:47:08 INFO - blessings == 1.6 15:47:08 INFO - blobuploader == 1.2.4 15:47:08 INFO - docopt == 0.6.1 15:47:08 INFO - manifestparser == 1.1 15:47:08 INFO - mozInstall == 1.12 15:47:08 INFO - mozcrash == 0.17 15:47:08 INFO - mozdebug == 0.1 15:47:08 INFO - mozdevice == 0.48 15:47:08 INFO - mozfile == 1.2 15:47:08 INFO - mozhttpd == 0.7 15:47:08 INFO - mozinfo == 0.9 15:47:08 INFO - mozleak == 0.1 15:47:08 INFO - mozlog == 3.2 15:47:08 INFO - moznetwork == 0.27 15:47:08 INFO - mozprocess == 0.22 15:47:08 INFO - mozprofile == 0.28 15:47:08 INFO - mozrunner == 6.11 15:47:08 INFO - mozscreenshot == 0.1 15:47:08 INFO - mozsystemmonitor == 0.3 15:47:08 INFO - moztest == 0.7 15:47:08 INFO - mozversion == 1.4 15:47:08 INFO - psutil == 3.1.1 15:47:08 INFO - requests == 1.2.3 15:47:08 INFO - wsgiref == 0.1.2 15:47:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:09 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 0x109ac3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ac9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd10c5d560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109967d30>, '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 0x10978bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995eb20>, '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.D60cu9MMT6/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.w4mazkQVXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:47:09 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 15:47:09 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 15:47:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D60cu9MMT6/Render', 15:47:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:09 INFO - 'HOME': '/Users/cltbld', 15:47:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:09 INFO - 'LOGNAME': 'cltbld', 15:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:09 INFO - 'MOZ_NO_REMOTE': '1', 15:47:09 INFO - 'NO_EM_RESTART': '1', 15:47:09 INFO - 'PAGER': '/bin/cat', 15:47:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:09 INFO - 'PWD': '/builds/slave/test', 15:47:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:09 INFO - 'SHELL': '/bin/bash', 15:47:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w4mazkQVXE/Listeners', 15:47:09 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:47:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:09 INFO - 'USER': 'cltbld', 15:47:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:09 INFO - 'XPC_FLAGS': '0x0', 15:47:09 INFO - 'XPC_SERVICE_NAME': '0', 15:47:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:09 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)) 15:47:09 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)) 15:47:09 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)) 15:47:09 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)) 15:47:09 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)) 15:47:09 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)) 15:47:09 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)) 15:47:09 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)) 15:47:09 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)) 15:47:09 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)) 15:47:09 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)) 15:47:09 INFO - Cleaning up... 15:47:09 INFO - Return code: 0 15:47:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ac3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ac9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd10c5d560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109967d30>, '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 0x10978bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995eb20>, '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.D60cu9MMT6/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.w4mazkQVXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:47:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:47:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D60cu9MMT6/Render', 15:47:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:09 INFO - 'HOME': '/Users/cltbld', 15:47:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:09 INFO - 'LOGNAME': 'cltbld', 15:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:09 INFO - 'MOZ_NO_REMOTE': '1', 15:47:09 INFO - 'NO_EM_RESTART': '1', 15:47:09 INFO - 'PAGER': '/bin/cat', 15:47:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:09 INFO - 'PWD': '/builds/slave/test', 15:47:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:09 INFO - 'SHELL': '/bin/bash', 15:47:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w4mazkQVXE/Listeners', 15:47:09 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:47:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:09 INFO - 'USER': 'cltbld', 15:47:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:09 INFO - 'XPC_FLAGS': '0x0', 15:47:09 INFO - 'XPC_SERVICE_NAME': '0', 15:47:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:09 INFO - Downloading/unpacking jsonschema 15:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:47:12 INFO - Downloading/unpacking functools32 (from jsonschema) 15:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:12 INFO - Downloading functools32-3.2.3-2.tar.gz 15:47:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:47:13 INFO - warning: no files found matching '*.txt' 15:47:13 INFO - no previously-included directories found matching 'build' 15:47:13 INFO - no previously-included directories found matching 'dist' 15:47:13 INFO - no previously-included directories found matching '.git*' 15:47:13 INFO - Installing collected packages: jsonschema, functools32 15:47:13 INFO - Running setup.py install for functools32 15:47:13 INFO - warning: no files found matching '*.txt' 15:47:13 INFO - no previously-included directories found matching 'build' 15:47:13 INFO - no previously-included directories found matching 'dist' 15:47:13 INFO - no previously-included directories found matching '.git*' 15:47:13 INFO - Successfully installed jsonschema functools32 15:47:13 INFO - Cleaning up... 15:47:13 INFO - Return code: 0 15:47:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:13 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 0x109ac3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ac9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd10c5d560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109967d30>, '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 0x10978bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995eb20>, '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.D60cu9MMT6/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.w4mazkQVXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 15:47:13 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 15:47:13 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 15:47:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D60cu9MMT6/Render', 15:47:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:13 INFO - 'HOME': '/Users/cltbld', 15:47:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:13 INFO - 'LOGNAME': 'cltbld', 15:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:13 INFO - 'MOZ_NO_REMOTE': '1', 15:47:13 INFO - 'NO_EM_RESTART': '1', 15:47:13 INFO - 'PAGER': '/bin/cat', 15:47:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:13 INFO - 'PWD': '/builds/slave/test', 15:47:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:13 INFO - 'SHELL': '/bin/bash', 15:47:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w4mazkQVXE/Listeners', 15:47:13 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:47:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:13 INFO - 'USER': 'cltbld', 15:47:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:13 INFO - 'XPC_FLAGS': '0x0', 15:47:13 INFO - 'XPC_SERVICE_NAME': '0', 15:47:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:13 INFO - Downloading/unpacking flake8 15:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:47:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:47:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:47:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:47:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:47:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:47:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:47:17 INFO - no previously-included directories found matching 'docs/_build' 15:47:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:47:17 INFO - Running setup.py install for pep8 15:47:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:47:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:47:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:47:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:47:17 INFO - no previously-included directories found matching 'docs/_build' 15:47:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:47:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:47:18 INFO - Cleaning up... 15:47:18 INFO - Return code: 0 15:47:18 INFO - Running post-action listener: _resource_record_post_action 15:47:18 INFO - Running post-action listener: _start_resource_monitoring 15:47:18 INFO - Starting resource monitoring. 15:47:18 INFO - ##### 15:47:18 INFO - ##### Finished create-virtualenv step (success) 15:47:18 INFO - ##### 15:47:18 INFO - ##### 15:47:18 INFO - ##### Running install step. 15:47:18 INFO - ##### 15:47:18 INFO - Running pre-action listener: _resource_record_pre_action 15:47:18 INFO - Running main action method: install 15:47:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:47:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:47:18 INFO - Reading from file tmpfile_stdout 15:47:18 INFO - Detecting whether we're running mozinstall >=1.0... 15:47:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:47:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:47:18 INFO - Reading from file tmpfile_stdout 15:47:18 INFO - Output received: 15:47:18 INFO - Usage: mozinstall [options] installer 15:47:18 INFO - Options: 15:47:18 INFO - -h, --help show this help message and exit 15:47:18 INFO - -d DEST, --destination=DEST 15:47:18 INFO - Directory to install application into. [default: 15:47:18 INFO - "/builds/slave/test"] 15:47:18 INFO - --app=APP Application being installed. [default: firefox] 15:47:18 INFO - mkdir: /builds/slave/test/build/application 15:47:18 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'] 15:47:18 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 15:47:52 INFO - Reading from file tmpfile_stdout 15:47:52 INFO - Output received: 15:47:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:47:52 INFO - Running post-action listener: _resource_record_post_action 15:47:52 INFO - ##### 15:47:52 INFO - ##### Finished install step (success) 15:47:52 INFO - ##### 15:47:52 INFO - ##### 15:47:52 INFO - ##### Running run-tests step. 15:47:52 INFO - ##### 15:47:52 INFO - Running pre-action listener: _resource_record_pre_action 15:47:52 INFO - Running main action method: run_tests 15:47:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:47:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:47:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:47:52 INFO - 2016-05-27 15:47:52.671 screenresolution[1667:7622] starting screenresolution argv=screenresolution get 15:47:52 INFO - 2016-05-27 15:47:52.673 screenresolution[1667:7622] Display 0: 1600x1200x32@60 15:47:52 INFO - 2016-05-27 15:47:52.679 screenresolution[1668:7624] starting screenresolution argv=screenresolution list 15:47:52 INFO - Available Modes on Display 0 15:47:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:47:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:47:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:47:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:47:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:47:52 INFO - Intel Iris: 15:47:52 INFO - Chipset Model: Intel Iris 15:47:52 INFO - Type: GPU 15:47:52 INFO - Bus: Built-In 15:47:52 INFO - VRAM (Dynamic, Max): 1536 MB 15:47:52 INFO - Vendor: Intel (0x8086) 15:47:52 INFO - Device ID: 0x0a2e 15:47:52 INFO - Revision ID: 0x0009 15:47:52 INFO - Displays: 15:47:52 INFO - Display: 15:47:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:47:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:47:52 INFO - Display Serial Number: 1600x1200 60 15:47:52 INFO - Main Display: Yes 15:47:52 INFO - Mirror: Off 15:47:52 INFO - Online: Yes 15:47:52 INFO - Rotation: Supported 15:47:52 INFO - Return code: 0 15:47:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:47:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:47:52 INFO - Python 2.7.3 15:47:52 INFO - Return code: 0 15:47:52 INFO - grabbing minidump binary from tooltool 15:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:52 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 0x109967d30>, '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 0x10978bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10995eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:47:52 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 15:47:52 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 15:47:52 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 15:47:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:47:53 INFO - Return code: 0 15:47:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:47:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:47:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:47:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:47:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:47:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:47:54 INFO - Return code: 0 15:47:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:47:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0258 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:47:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D60cu9MMT6/Render', 15:47:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:54 INFO - 'HOME': '/Users/cltbld', 15:47:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:54 INFO - 'LOGNAME': 'cltbld', 15:47:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:47:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:47:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:54 INFO - 'MOZ_NO_REMOTE': '1', 15:47:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:47:54 INFO - 'NO_EM_RESTART': '1', 15:47:54 INFO - 'PAGER': '/bin/cat', 15:47:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:54 INFO - 'PWD': '/builds/slave/test', 15:47:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:47:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:54 INFO - 'SHELL': '/bin/bash', 15:47:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w4mazkQVXE/Listeners', 15:47:54 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 15:47:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:54 INFO - 'USER': 'cltbld', 15:47:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:54 INFO - 'XPC_FLAGS': '0x0', 15:47:54 INFO - 'XPC_SERVICE_NAME': '0', 15:47:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:47:54 INFO - mozversion application_buildid: 20160527141751 15:47:54 INFO - mozversion application_changeset: 33c4562b47ce2cc5e2988d756c76fa79a36c469a 15:47:54 INFO - mozversion application_display_name: Nightly 15:47:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:47:54 INFO - mozversion application_name: Firefox 15:47:54 INFO - mozversion application_remotingname: firefox 15:47:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:47:54 INFO - mozversion application_vendor: Mozilla 15:47:54 INFO - mozversion application_version: 49.0a1 15:47:54 INFO - mozversion platform_buildid: 20160527141751 15:47:54 INFO - mozversion platform_changeset: 33c4562b47ce2cc5e2988d756c76fa79a36c469a 15:47:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:47:54 INFO - mozversion platform_version: 49.0a1 15:47:54 INFO - using testdate: 1464389274 15:47:54 INFO - actual date: 1464389274 15:47:54 INFO - starting webserver on 'localhost:49273' 15:47:54 INFO - SUITE-START | Running 2 tests 15:47:54 INFO - TEST-START | dromaeo_css 15:47:54 INFO - operating with platform_type : mac_ 15:47:54 INFO - Initialising browser for dromaeo_css test... 15:47:54 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpAHlfUQ/profile http://localhost:49273/getInfo.html) 15:47:57 INFO - PROCESS | 1686 | __metrics Screen width/height:1600/1200 15:47:57 INFO - PROCESS | 1686 | colorDepth:24 15:47:57 INFO - PROCESS | 1686 | Browser inner width/height: 1024/689 15:47:57 INFO - PROCESS | 1686 | __metrics 15:47:57 ERROR - PROCESS | 1686 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 15:47:58 INFO - TEST-INFO | 1686: exit 0 15:47:58 INFO - Browser initialized. 15:47:58 INFO - Running cycle 1/1 for dromaeo_css test... 15:47:58 INFO - TEST-INFO | started process 1689 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpAHlfUQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:10 INFO - PROCESS | 1689 | RSS: Main: 252731392 15:48:10 INFO - PROCESS | 1689 | 15:48:59 INFO - PROCESS | 1689 | 1464389339784 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] 15:51:10 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 15:51:11 INFO - PROCESS | 1689 | RSS: Main: 264781824 15:51:11 INFO - PROCESS | 1689 | 15:54:13 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 15:54:14 INFO - PROCESS | 1689 | RSS: Main: 276267008 15:54:14 INFO - PROCESS | 1689 | 15:57:16 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 15:57:16 INFO - PROCESS | 1689 | RSS: Main: 278417408 15:57:16 INFO - PROCESS | 1689 | 16:00:18 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 16:00:19 INFO - PROCESS | 1689 | RSS: Main: 279613440 16:00:19 INFO - PROCESS | 1689 | 16:03:21 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 16:03:22 INFO - PROCESS | 1689 | RSS: Main: 283561984 16:03:22 INFO - PROCESS | 1689 | 16:06:25 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 16:06:25 INFO - PROCESS | 1689 | RSS: Main: 284045312 16:06:25 INFO - PROCESS | 1689 | 16:06:25 INFO - PROCESS | 1689 | __start_tp_report 16:06:25 INFO - PROCESS | 1689 | _x_x_mozilla_page_load 16:06:25 INFO - PROCESS | 1689 | _x_x_mozilla_page_load_details 16:06:25 INFO - PROCESS | 1689 | |i|pagename|runs| 16:06:25 INFO - PROCESS | 1689 | |0;dojo.html;2898.434805212983;3456.5333679953214;3510.894673159806;3522.929541409171;3530.6469353064713;5657.3494048184475;5677.205191273225;5700.05949018412;5724.112762521805;5756.510696590794;5527.585431092664;5611.859703507414;5672.149177623359;5724.112762521805;5765.769369225236;5090.211017292326;5141.742912854361;5221.190715439113;5243.2659427680155;5283.313169288006;717.7315614718581;5097.3118628984985;5160.612954028463;5214.191800270964;5223.817166399177;5197.974010129925;5208.401033881114;5210.76551555181;5236.3978142513715;5249.1338929076655;5219.5041471060185;5249.028929648028;5261.263423120766;5267.209918512254;5320.17537281722;4082.4692789937417;4534.478534968488;5092.8217512387255;5143.022825663112;5143.485651434864;4307.569243075699;4559.794809233559;4805.663603547753;5082.65309692928;5672.5178359839465;6088.96955515224;6238;6283.8114856554375;6310.085037669558;6325.240971083454;6101.4896331263535;6108.0992441897915;6192.411720886508;6223.906641400383;6255.343188965138;5171.922421163686;5876.29484461863;5881.323647780518;5908.044151512731;5958.344582095966;5193.2989046479;6190.50475961922;6267.341929097379;6285.622862628257;6315.715792789336;6088.238970128734;6111.174991376196;6120.835012011849;6181.350958149419;6192.380761923771;6222.222222222223;6247.9062814057825;6258.780942666985;6281.497480201573;6315.652639104894;6098.390160983866;6106.915739268716;6159;6216.222972128484;6220.533459990519;6185.226846644169;6225.408586184305;6246.219222597176;6264.091706702503;6277.435030847246;4892.535209155125;4900.240462728283;4906;4911.4106307243355;4933.161362568372;4184.832606695698;4314.266574682311;4346.239408103569;4406.33904914259;4433.911321773547;5291.126964050965;5452.482014208644;5657.32112146545;5722.313322401338;5804.9419505805245;5707.286589176353;6305.243370795442;6324.588901721373;6328.272248691367;6339.778107766206;5622.690752008679;5699.886002279931;5730.283714535683;5771.59598828078;5793.565482588822;5351.197320401893;5376.7042812644895;5402.621816472888;5425.782968681208;5432.864178395572;6339.207599050119;6373.362663733682;6377.681115944277;6381.64900930444;6402.519811014193;6138.0179171332375;6223.564350495421;6260.937390626035;6274.152989346379;6285.340039295808;6255.499560035278;6365.077063825626;6375.298717141203;6400.423961843457;6455.515836312389;6224.813255602339;6247.12540244357;6252;6254.343293954252;6258;6139.1098290746695;6151.3541078186145;6212.596181248205;6262.185915830913;6285.5600107992;5306.761195746123;5578.77267001259;6148.262208535004;6173.197484327188;6190.2571691397325;5291.259223708606;6110.877782444415;6155.230596175478;6179.196704428396;6190.845228869314;5802.535797136304;5841.094630332305;5954.702264886825;5976.103584462366;6006.219191505076;5036.496350365081;6142.57002009855;6155.47678447337;6171.9691401542705;6188.969055154876;4907.877303067452;5782.045962416154;5861.443162899518;6007.8197654070445;6008.819735407945 16:06:25 INFO - PROCESS | 1689 | |1;ext.html;518.4556215973228;518.7924830067973;522.2010324203967;528.5639347538282;544.425630959338;38162.04594885129;41253.79373103134;41325.586744132546;41360;41398;41951.16097678052;42582.14835703284;42660.14679706404;42695.93260168497;42729.35905961413;9459.574319155654;9569.760755981122;9662.17871480925;9666.468344241064;9675;9502;9530.80938381232;9571.712848614552;9598.280128990318;9613.471259080748;9458.432494050345;9489.430634161938;9568.52157392131;9599.136077753;9600.80798384032;8415.915840841575;9384.343095983284;9449.574769135337;9569.090936361035;9622.326437149368;9419.576119074658;9519.952400238024;9554;9566.665166719202;9582.281328900292;38655.806720966495;38664.613353866385;38738;39222;39519.61681341168;2974.925626859324;2995.5806187133817;3311.2549676322874;3352.6312105668358;3479.162409095649;29151;29501;30274.54588181179;30989.690103099132;31231.219219519466;3171.1437911763696;3179.71382575567;3182.10900947735;3183.697548732867;3293.110860067792;466850.6657466725;469022;471875;472392;472629;33754.66245337564;33974.49038264428;34022.97931062072;34046;34199.31601368009;32466.863659771796;32681.836590816893;32802.85190018338;32844.507332390036;32851.1787205317;16671.91664041672;16681.582960426087;16836.24236909342;16837.989720616803;16911.746323805153;11708.238964467282;11717.652469965893;11771.587994420326;11866.050715942703;12912.612621621367;16431.835681643268;20007.899565524036;20163.495912602302;20269.594608107756;20292.594148116954;8297.377696672773;8314.461824562475;8445.282151017109;8452.57737113142;8457.40798144124;922.4926290540176;979.7844474215661;1070.4701172919454;1084.203110713625;1161.5027690465568;30415.543766843515;30628.846855766024;30665;30926.453677316043;31086.378272434424;910.0353625157354;918.7060140754894;1054.2462139570243;1075.2150929821273;1096.4517741129434;13537.526186583422;14618.26908654563;16220.918895405448;16471.588210294838;16568.585785355463;574902;688547;698919;700913;703950;60460.69769651123;60758.69620651957;61097.69451152716;61312.46718831976;61365.69317153386;1380.4478208716394;1409.26718106584;1438.0940007795023;1442.019426789793;1446.2840893757657;26436;30376.17742935431;30402.543961840147;30601.693983059886;31031.224219394695;51884.48115518797;52521.737391312796;52841.735791320796;52880;52914;489412;492456;492462;492572;493659;26894.32764180911;27003.594946076217;27062;27463.725362746118;27602.447951040464;48778.26832597585;49599.50400495949;49853.25220121629;49914.750426247636;49974;39890.202195956495;40612.984675383355;40873.77378678644;41477.37783933183;41865;40108.39837402383;40277.39583906302;40323;40790.9802254946;41250.58749412587;37717.62282377141;37918.81040594779;39059.023524412114;39064.41403378895;39091.41362879616;33815.49276760801;34936;35698;35768.463473047406;36023.459648105825;25567.616485752358;25909.352266193495;26186;26477.205683829518;26513.469730605666 16:06:25 INFO - PROCESS | 1689 | |2;jquery.html;382.4129960510616;383.56546516030056;384.7806750152414;390.84115597254134;406.686851124634;16721.163941802908;19992.50018749532;21135.943202839855;21663.67504487432;21691.3492595222;11469.59856405026;11753.294802311868;12266.632001039961;12287.01703863691;12291;5318.680879147254;5548.112302031675;5560.888782224353;5573.693446860421;5587.106063029916;3252.4633435483115;5745.798897038604;6061.090836374557;6066.454019138277;6135.785247516338;5643.746031428575;5958.37437069108;6110.20567326247;6113.388661133896;6138.232720909887;5970.283565972068;6015.097735339712;6064.120702498113;6100.420460056265;6113.358097399753;5703.714814259312;5745.655260684331;5797.594168408213;5852.795152169653;5859.355470898197;10766.4616769162;12717.109802313842;12885.226886386847;13021.86978130216;13073.411696473682;2061.742282214723;2306.8731219782903;2343.7773411525877;2540.3776074861776;2694.434168824549;10070.446125463097;10494.737631559196;13529.932350338284;15502.689946201126;16199.271032803434;1436.864017088096;1811.2030706489102;1843.6957901946162;1844.1240410804894;1894.1003023563835;94831;112676;113657.43171284173;114926;115296;11235.157363197794;11642.88357116418;11750.412479375997;11802.288668143072;11851.88148118525;14823.481178158712;14879;14911.776323355158;14917.403303867724;14932.178730169728;7679.731209407644;8150.633221505046;8521.573921304034;8743.038265790758;8777.29781617469;7503.7748867534065;8305.501669899826;8467.32261419085;8546.273566746771;8646.308295336357;3976.58245884178;4511.503734589192;4602.815887364535;4626.745528995937;4640.419947506562;1515.3484001879299;1761.8414342709218;1913.3494611832043;1921.2315073970244;1929.2958070304237;843.767963809945;916.2852974679761;955.4028732042474;1004.3170643962176;1034.462079718542;20376.388708338774;20526.897365513076;20691.79308206929;21031.78968210329;21250.89374553117;942.0579420579421;962.0956301076965;967.4582233949011;1029.8146333660013;1034.5292891734393;11036.28264162843;11116.721577018585;11121.943415375526;11140.164487663458;11168.832467512993;480414;505046;537028;542572;563884;108688;109987;112917;113654;114222.85777142122;1030.1449796668746;1030.3302853145515;1049.2550289294686;1059.1050562274866;1060.0353678152999;15389;15553.300101495686;18130.909345453714;18503.982280974677;18675.15961781696;49022.75488622534;52294.215586765466;52435.73782131218;52501;52846;74695;75347.62326188335;75792;76907;77044;7761;7845.490043147177;7883.921160788319;7908.4068694846965;8049.83900322002;9860.457674827667;10559.894401055892;11903.82144267854;12684.936575317064;12763.298018608693;11024.118070554498;11043.72390690207;11128.109751220045;11251.437428128396;11290.717732056435;8993.28053755711;10883.891161088288;10991.61529346469;11081.224314297922;11085.944570277097;9514.762130946781;10817.459127043774;11382.430878456209;11524.884751152382;11579.07367410622;6946.722131114901;7375.004374409386;8136.75589732309;8264.876026859722;8276.255137037695;10631.681049568526;10991.505382257978;11179.105671546095;11509.424528773361;11577.189596728485 16:06:25 INFO - PROCESS | 1689 | |3;mootools.html;1553.246675362449;1596.114156643063;1781.7238328059154;1859.172668162668;1916.492129585659;7843.568603726807;7966.840663186733;8268.669253229871;8712.651493940242;8742.300615950717;7289.343959043685;8444.113368096354;8576.356773242016;8645.654173833054;8728.60721267543;1923.4998900285957;2302.4359032037173;2356.646503024543;2362.61016932206;2363.917262895799;2335.4278201840575;2338.8479748816358;2353.599888019041;2369.1589485732575;2387.9761202388013;1938.980610193894;2334.4630734930975;2358.445765245166;2368.2539999900064;2374.620060790265;2335.8598484090926;2337.439014636492;2338.847974881627;2338.9298321050396;2346.343023953296;1817.3366721146817;2133.4666333416644;2321.628539433699;2361.0791791201445;2369.4905595297096;8643.870341944876;8692.56537173145;8716;8733.475991440471;8750.956245218733;5229.267902493654;5343.145096784496;5359.866003349947;5381.112116500773;5391.406945236021;3522.489239060322;3566.5720113586276;3698.408254679265;3725.105974566112;3742.3450896093077;1068.4924660786141;1072.6728347853903;1089.629525961169;1101.427257825926;1121.2767764791745;11533.769324613544;11572.59495917639;11691.88308116925;11795.46920388576;11834.349110798903;4731.33761273422;4733.171694953369;4838.2016967200725;4852.296417019583;4859.07677541266;10122.44326562046;10125.139363154214;10160.441175735405;10163.796724065478;10184.338018028953;5302.867428314323;5331.280277162611;5333.786648534093;5371.838844834661;5419.458054194628;4427.136708341907;4734.479207287196;4895.192293271642;4919.606431485473;4941.160002799533;3488.8255587220538;3988.282109220368;3999.4000899865255;4043.1913617276664;4118.6499147572795;1294.4304506017322;1318.9802152967713;1322.9801552976712;1332.327174776751;1333.8399392073013;824.6124321568853;931.7158266728577;939.1500691873865;948.1892981500855;981.9116279534876;5912.113183022581;5963;6045.78839740607;6102.084687296941;6194.34959329273;423.1389123134407;438.50667998501683;992.4591097851688;1000.7598176437748;1031.2368847053126;3570.553680789901;3728.3848165053005;4015;4047.3928910663635;4050.10897602527;10387.84418233727;10459.320144190755;10691.144708423308;10698.358098513958;10728.570857165625;10556.049955504042;10598.841017384746;10636;10640.04239618438;10722.249442538947;7740.297134630372;8025.23760242776;8041.236082572146;8124.350051995944;8258.339332853357;5994.160817485468;6015.879682406415;6098.878022439615;6144.170536977451;6161.229846269216;9672.75818104553;9846.753831154278;9885.209183265466;9890.258230632875;9902.900970990486;9559.139677428786;9662.13040826329;10197.23520735933;10214.540345684314;10229.028242316968;6974;7416.184219735933;7556.055493063367;7653.540787552764;7773.222677732181;8471.915280847114;8497.065322814362;8528.275096616857;8569.10024447449;8586.828263434822;7250.854982900418;7493.175750667313;7684.884726728992;7690.615469226628;7711.228877112469;6252.374762523711;6367.9044814328745;7336.926630733625;7511.812204694927;7595.3164215220895;7077.115360579927;7113.75101871432;7139.286071392819;7150.463715221484;7153.892691609734;4214.388913607571;4328.502222244482;5058.3929928408825;5071.2139618359215;5083.593312535062;5089.414717307557;5109.6678715883345;5123;5142.6914385137015;5249.632525723163 16:06:25 INFO - PROCESS | 1689 | |4;prototype.html;255.37433288443313;300.83003103246665;305.18819938962366;310.06515356201055;313.9136737397217;4699.694519856207;5595.524380427663;5664.915026274609;5668.376478587357;5687.7156142192935;4330.437043184382;5425.864353391167;5546.362168350631;5553.583481238903;5572.300448363258;3195.872165113393;3789.844097550253;4175.415441838145;4179.74607617715;4190.203861266365;3733.7199710021723;4079.551759125512;4112.355057976803;4136.689748268876;4206.495220573535;3501.562304711911;3878.45701601776;4188.958110418903;4202.957970420287;4216.069831467132;3212.903612891617;3856.5179352580926;3883.7087218458446;4165.416841642173;4171.311733563958;3090.922726931822;3544;3547.3859393975936;3560.287942411502;3569.625189355106;5049;5114.974425127888;5362.651427657229;5363.088274993261;5366.914024435926;3869.7484663496784;4070.921205880444;4094.344904815215;4729.858104256843;4802.975985120087;5818.730695028942;5853.356130825606;5854.560907931879;5873.647581145145;5906.852328691774;3468.7051600613977;3866.6906647468136;3884.2615955751126;3908.863189788344;3910.5559025608995;4540.432445944257;5700.7719691212305;6117.418845209698;6126.499007743131;6161.445469907685;3350.564426624523;3709.9072523187137;3925.980370098131;3969.7221194516105;3994.4407782910416;3203.4073696366245;4021.4369988201674;4025.215083058834;4045.86715719599;4046.1185548719986;3992.241474119966;4694.36626055485;4709.458412282563;4724.527547245317;4770.546798054179;4850.13841833313;5015.974920125376;5258.579313654898;5989.9101513477335;6007.189029481052;1360.8979326550548;1385.9882285931326;1387.167699380384;1389.6873203529328;1391.3877893726728;741.2624438683545;761.7143571160815;844.531285136755;855.5380094748766;889.8620713789373;4069.5726948670554;5099.541041306226;5380.838574842761;5427.99582077317;5466.781328746885;5188.558972487305;5193.831387937684;5728.796952639992;5819.563532735062;5948.156071617812;5230.293910322134;5263.578913686896;5449.891002180014;5459.617826752169;5499.010178167888;2694.6901106373016;3127.248740705212;3152.6689697581883;3175.682431756852;3188.537662038991;5556.444355564411;5774.82675519735;6184.474319682878;6186.133941248228;6235.285296543396;5027.522385373383;5688.9715551422805;6244.128640242988;6332.588381755171;6344.841378965562;5206.869828254324;6348.809535713936;6352.428281454691;6426;6452.709628066655;5657.971710141587;6099.268087829489;6123.785667501616;6163.137160797582;6178.876422471435;5788.507976822018;6008.218931538872;6078;6088.81268152706;6095.49509032317;6128.417800308964;6375.394337537927;6397.3366924599;6398.744050238124;6399.928034543352;3390.8643654253556;3479.1997840497334;3949.980250098731;3976.940345894872;3979.582143874851;5755.618651523688;5868.178455016216;5886.852828679317;5993;6183.536234782499;4721.693089949143;4865.513448655248;5794.62334948227;5942.811437712388;5972.731227094878;4927.8275260367145;5209.71346575942;5652.830415087554;5857.677827719346;5902.822915312548;5016.398032236155;5523.6467065569195;5678.006348889011;5806.9419305806405;5815.79644712433;5530.866172434722;5730.025895597842;5746.270521502253;5849.64902105875;5916.645001299936;5753.295950903069;5784.599391252583;5813.331466881363;5825.970870145622;5856.180134781049 16:06:25 INFO - PROCESS | 1689 | |5;yui.html;690.7996680962522;705.2947052947053;823.6129019360899;826.2233500509519;829.9543525106117;12476.875231247684;14692.853071469295;15002.849971500282;15196.392144314228;15206.935514513989;11758.294502329845;13041.60875173744;13937.51218707343;13951.093178943387;13963.511277105303;689.1936434371772;845.0788640381983;862.3230763850379;878.5168157513374;888.7422647432269;862.5816479007676;875.5490922175085;876.2376732242958;877.714742708619;883.0507204754914;910.2945217456459;922.903095175007;929.6699671616581;929.7861491856877;930.4975313330793;892.4823602310677;903.8870141232346;907.2605826251616;927.3184209605935;931.6413180925358;663.9136912201383;682.6791408038214;684.6918886501045;688.683205725367;690.1717938473802;14439.855601443956;14454.927725361411;14777.187254700988;14921.328540215607;14939.775903361458;14162.36269367881;14211.644708882257;14274.64313392163;14300.284985750775;14311.856881431157;10104.09063184317;11629;11862.288262704265;11876.465559049864;12018.579349722806;3298.983505082483;3842.615738426163;3896.4544963705102;3902.2390633826417;4012.478377810895;59135.22594322177;79751.60124199341;80736.78894816582;83563.5821820893;83945.90135246607;10853.45732713377;11064.225504214628;11150.107991360672;11275.943620281847;11544.769104618028;21533;21779;21784.673229901564;21801.67297490539;21805.67291490629;7969.242921922408;7996.480228785111;8032.0361556612415;8133.877991830127;8234.341252699769;4445.888852778642;4451.176532341462;4460.286354183315;4481.103779244164;4606.976965115153;3445.9655403445645;3450.603180634209;3539.2213712983103;3542.557180352456;3553.200529880808;604.7792555717219;618.935011823761;657.2310396835713;675.9932400676029;682.6586706646676;605.9939400606025;652.1847690387016;661.4443867151616;662.5196732368994;677.5189615373042;8735.34484913634;8767.912320876838;8848.64605415789;8869.113088691209;8992.730218093468;572.4704648200482;624.5846512069423;641.9358064193636;659.2385794407467;662.6587307536622;4116.300228961084;4126.855560055384;4151.377293406013;4179.414881916534;4250.851220207278;83334;88378.67431988397;88545;89214;90340.09659903448;79128.20871791209;79378;79687.40625187464;80857.59571202107;81507;57918.55203619859;63368;64038;64355;70714.64642676753;7688.654010569426;9574.760630984281;9594.184494317782;9677.516124193902;9712.9028709712;44980.77509612562;45103.54896451125;45332;45436.77281613571;46147.76926115348;61652;61722.69138654278;62308.68845655743;63054.68472657607;63399.68300158469;12587;12656.810147847606;12801.423935923092;12837.550685725955;13374.197548147142;11064.557417703523;12352.764723527574;14201.218932958787;14463.566093017227;14892;13124.934375328063;13133.146345487512;13506.736918713425;13681.47459758162;13749.851385090857;13151.0136739743;13155.696957645807;13338.30400636254;13339.53311634127;13519.729605408034;12778.424970876518;13069.215847049207;13547.525836595672;13569.253691047088;13577;9639.325247232626;11019.393933333424;11090.279131856403;11373.715657108638;11635.185537012328;10900.454977251264;11169.553217871195;11176.161787866105;11319.377434241196;11418.31490110596 16:06:25 INFO - PROCESS | 1689 | __end_tp_report 16:06:25 INFO - PROCESS | 1689 | __start_cc_report 16:06:25 INFO - PROCESS | 1689 | _x_x_mozilla_cycle_collect,424 16:06:25 INFO - PROCESS | 1689 | __end_cc_report 16:06:25 INFO - PROCESS | 1689 | __startTimestamp1464390385663__endTimestamp 16:06:25 INFO - PROCESS | 1689 | 16:06:25 INFO - PROCESS | 1689 | ------- Summary: start ------- 16:06:25 INFO - PROCESS | 1689 | Number of tests: 6 16:06:25 INFO - PROCESS | 1689 | 16:06:25 INFO - PROCESS | 1689 | [#0] dojo.html Cycles:165 Average:5650.32 Median:5881.32 stddev:792.95 (13.5%) stddev-sans-first:765.43 16:06:25 INFO - PROCESS | 1689 | Values: 2898.4 3456.5 3510.9 3522.9 3530.6 5657.3 5677.2 5700.1 5724.1 5756.5 5527.6 5611.9 5672.1 5724.1 5765.8 5090.2 5141.7 5221.2 5243.3 5283.3 717.7 5097.3 5160.6 5214.2 5223.8 5198.0 5208.4 5210.8 5236.4 5249.1 5219.5 5249.0 5261.3 5267.2 5320.2 4082.5 4534.5 5092.8 5143.0 5143.5 4307.6 4559.8 4805.7 5082.7 5672.5 6089.0 6238.0 6283.8 6310.1 6325.2 6101.5 6108.1 6192.4 6223.9 6255.3 5171.9 5876.3 5881.3 5908.0 5958.3 5193.3 6190.5 6267.3 6285.6 6315.7 6088.2 6111.2 6120.8 6181.4 6192.4 6222.2 6247.9 6258.8 6281.5 6315.7 6098.4 6106.9 6159.0 6216.2 6220.5 6185.2 6225.4 6246.2 6264.1 6277.4 4892.5 4900.2 4906.0 4911.4 4933.2 4184.8 4314.3 4346.2 4406.3 4433.9 5291.1 5452.5 5657.3 5722.3 5804.9 5707.3 6305.2 6324.6 6328.3 6339.8 5622.7 5699.9 5730.3 5771.6 5793.6 5351.2 5376.7 5402.6 5425.8 5432.9 6339.2 6373.4 6377.7 6381.6 6402.5 6138.0 6223.6 6260.9 6274.2 6285.3 6255.5 6365.1 6375.3 6400.4 6455.5 6224.8 6247.1 6252.0 6254.3 6258.0 6139.1 6151.4 6212.6 6262.2 6285.6 5306.8 5578.8 6148.3 6173.2 6190.3 5291.3 6110.9 6155.2 6179.2 6190.8 5802.5 5841.1 5954.7 5976.1 6006.2 5036.5 6142.6 6155.5 6172.0 6189.0 4907.9 5782.0 5861.4 6007.8 6008.8 16:06:25 INFO - PROCESS | 1689 | 16:06:25 INFO - PROCESS | 1689 | [#1] ext.html Cycles:180 Average:67158.33 Median:39370.81 stddev:148097.84 (376.2%) stddev-sans-first:148428.77 16:06:25 INFO - PROCESS | 1689 | Values: 518.5 518.8 522.2 528.6 544.4 38162.0 41253.8 41325.6 41360.0 41398.0 41951.2 42582.1 42660.1 42695.9 42729.4 9459.6 9569.8 9662.2 9666.5 9675.0 9502.0 9530.8 9571.7 9598.3 9613.5 9458.4 9489.4 9568.5 9599.1 9600.8 8415.9 9384.3 9449.6 9569.1 9622.3 9419.6 9520.0 9554.0 9566.7 9582.3 38655.8 38664.6 38738.0 39222.0 39519.6 2974.9 2995.6 3311.3 3352.6 3479.2 29151.0 29501.0 30274.5 30989.7 31231.2 3171.1 3179.7 3182.1 3183.7 3293.1 466850.7 469022.0 471875.0 472392.0 472629.0 33754.7 33974.5 34023.0 34046.0 34199.3 32466.9 32681.8 32802.9 32844.5 32851.2 16671.9 16681.6 16836.2 16838.0 16911.7 11708.2 11717.7 11771.6 11866.1 12912.6 16431.8 20007.9 20163.5 20269.6 20292.6 8297.4 8314.5 8445.3 8452.6 8457.4 922.5 979.8 1070.5 1084.2 1161.5 30415.5 30628.8 30665.0 30926.5 31086.4 910.0 918.7 1054.2 1075.2 1096.5 13537.5 14618.3 16220.9 16471.6 16568.6 574902.0 688547.0 698919.0 700913.0 703950.0 60460.7 60758.7 61097.7 61312.5 61365.7 1380.4 1409.3 1438.1 1442.0 1446.3 26436.0 30376.2 30402.5 30601.7 31031.2 51884.5 52521.7 52841.7 52880.0 52914.0 489412.0 492456.0 492462.0 492572.0 493659.0 26894.3 27003.6 27062.0 27463.7 27602.4 48778.3 49599.5 49853.3 49914.8 49974.0 39890.2 40613.0 40873.8 41477.4 41865.0 40108.4 40277.4 40323.0 40791.0 41250.6 37717.6 37918.8 39059.0 39064.4 39091.4 33815.5 34936.0 35698.0 35768.5 36023.5 25567.6 25909.4 26186.0 26477.2 26513.5 16:06:25 INFO - PROCESS | 1689 | 16:06:25 INFO - PROCESS | 1689 | [#2] jquery.html Cycles:180 Average:31660.01 Median:20861.79 stddev:88063.15 (422.1%) stddev-sans-first:88278.87 16:06:25 INFO - PROCESS | 1689 | Values: 382.4 383.6 384.8 390.8 406.7 16721.2 19992.5 21135.9 21663.7 21691.3 11469.6 11753.3 12266.6 12287.0 12291.0 5318.7 5548.1 5560.9 5573.7 5587.1 3252.5 5745.8 6061.1 6066.5 6135.8 5643.7 5958.4 6110.2 6113.4 6138.2 5970.3 6015.1 6064.1 6100.4 6113.4 5703.7 5745.7 5797.6 5852.8 5859.4 10766.5 12717.1 12885.2 13021.9 13073.4 2061.7 2306.9 2343.8 2540.4 2694.4 10070.4 10494.7 13529.9 15502.7 16199.3 1436.9 1811.2 1843.7 1844.1 1894.1 94831.0 112676.0 113657.4 114926.0 115296.0 11235.2 11642.9 11750.4 11802.3 11851.9 14823.5 14879.0 14911.8 14917.4 14932.2 7679.7 8150.6 8521.6 8743.0 8777.3 7503.8 8305.5 8467.3 8546.3 8646.3 3976.6 4511.5 4602.8 4626.7 4640.4 1515.3 1761.8 1913.3 1921.2 1929.3 843.8 916.3 955.4 1004.3 1034.5 20376.4 20526.9 20691.8 21031.8 21250.9 942.1 962.1 967.5 1029.8 1034.5 11036.3 11116.7 11121.9 11140.2 11168.8 480414.0 505046.0 537028.0 542572.0 563884.0 108688.0 109987.0 112917.0 113654.0 114222.9 1030.1 1030.3 1049.3 1059.1 1060.0 15389.0 15553.3 18130.9 18504.0 18675.2 49022.8 52294.2 52435.7 52501.0 52846.0 74695.0 75347.6 75792.0 76907.0 77044.0 7761.0 7845.5 7883.9 7908.4 8049.8 9860.5 10559.9 11903.8 12684.9 12763.3 11024.1 11043.7 11128.1 11251.4 11290.7 8993.3 10883.9 10991.6 11081.2 11085.9 9514.8 10817.5 11382.4 11524.9 11579.1 6946.7 7375.0 8136.8 8264.9 8276.3 10631.7 10991.5 11179.1 11509.4 11577.2 16:06:25 INFO - PROCESS | 1689 | 16:06:25 INFO - PROCESS | 1689 | [#3] mootools.html Cycles:180 Average:5695.05 Median:4877.13 stddev:3183.88 (65.3%) stddev-sans-first:3177.60 16:06:25 INFO - PROCESS | 1689 | Values: 1553.2 1596.1 1781.7 1859.2 1916.5 7843.6 7966.8 8268.7 8712.7 8742.3 7289.3 8444.1 8576.4 8645.7 8728.6 1923.5 2302.4 2356.6 2362.6 2363.9 2335.4 2338.8 2353.6 2369.2 2388.0 1939.0 2334.5 2358.4 2368.3 2374.6 2335.9 2337.4 2338.8 2338.9 2346.3 1817.3 2133.5 2321.6 2361.1 2369.5 8643.9 8692.6 8716.0 8733.5 8751.0 5229.3 5343.1 5359.9 5381.1 5391.4 3522.5 3566.6 3698.4 3725.1 3742.3 1068.5 1072.7 1089.6 1101.4 1121.3 11533.8 11572.6 11691.9 11795.5 11834.3 4731.3 4733.2 4838.2 4852.3 4859.1 10122.4 10125.1 10160.4 10163.8 10184.3 5302.9 5331.3 5333.8 5371.8 5419.5 4427.1 4734.5 4895.2 4919.6 4941.2 3488.8 3988.3 3999.4 4043.2 4118.6 1294.4 1319.0 1323.0 1332.3 1333.8 824.6 931.7 939.2 948.2 981.9 5912.1 5963.0 6045.8 6102.1 6194.3 423.1 438.5 992.5 1000.8 1031.2 3570.6 3728.4 4015.0 4047.4 4050.1 10387.8 10459.3 10691.1 10698.4 10728.6 10556.0 10598.8 10636.0 10640.0 10722.2 7740.3 8025.2 8041.2 8124.4 8258.3 5994.2 6015.9 6098.9 6144.2 6161.2 9672.8 9846.8 9885.2 9890.3 9902.9 9559.1 9662.1 10197.2 10214.5 10229.0 6974.0 7416.2 7556.1 7653.5 7773.2 8471.9 8497.1 8528.3 8569.1 8586.8 7250.9 7493.2 7684.9 7690.6 7711.2 6252.4 6367.9 7336.9 7511.8 7595.3 7077.1 7113.8 7139.3 7150.5 7153.9 4214.4 4328.5 5058.4 5071.2 5083.6 5089.4 5109.7 5123.0 5142.7 5249.6 16:06:25 INFO - PROCESS | 1689 | 16:06:25 INFO - PROCESS | 1689 | [#4] prototype.html Cycles:180 Average:4681.22 Median:5313.12 stddev:1510.84 (28.4%) stddev-sans-first:1478.11 16:06:25 INFO - PROCESS | 1689 | Values: 255.4 300.8 305.2 310.1 313.9 4699.7 5595.5 5664.9 5668.4 5687.7 4330.4 5425.9 5546.4 5553.6 5572.3 3195.9 3789.8 4175.4 4179.7 4190.2 3733.7 4079.6 4112.4 4136.7 4206.5 3501.6 3878.5 4189.0 4203.0 4216.1 3212.9 3856.5 3883.7 4165.4 4171.3 3090.9 3544.0 3547.4 3560.3 3569.6 5049.0 5115.0 5362.7 5363.1 5366.9 3869.7 4070.9 4094.3 4729.9 4803.0 5818.7 5853.4 5854.6 5873.6 5906.9 3468.7 3866.7 3884.3 3908.9 3910.6 4540.4 5700.8 6117.4 6126.5 6161.4 3350.6 3709.9 3926.0 3969.7 3994.4 3203.4 4021.4 4025.2 4045.9 4046.1 3992.2 4694.4 4709.5 4724.5 4770.5 4850.1 5016.0 5258.6 5989.9 6007.2 1360.9 1386.0 1387.2 1389.7 1391.4 741.3 761.7 844.5 855.5 889.9 4069.6 5099.5 5380.8 5428.0 5466.8 5188.6 5193.8 5728.8 5819.6 5948.2 5230.3 5263.6 5449.9 5459.6 5499.0 2694.7 3127.2 3152.7 3175.7 3188.5 5556.4 5774.8 6184.5 6186.1 6235.3 5027.5 5689.0 6244.1 6332.6 6344.8 5206.9 6348.8 6352.4 6426.0 6452.7 5658.0 6099.3 6123.8 6163.1 6178.9 5788.5 6008.2 6078.0 6088.8 6095.5 6128.4 6375.4 6397.3 6398.7 6399.9 3390.9 3479.2 3950.0 3976.9 3979.6 5755.6 5868.2 5886.9 5993.0 6183.5 4721.7 4865.5 5794.6 5942.8 5972.7 4927.8 5209.7 5652.8 5857.7 5902.8 5016.4 5523.6 5678.0 5806.9 5815.8 5530.9 5730.0 5746.3 5849.6 5916.6 5753.3 5784.6 5813.3 5826.0 5856.2 16:06:25 INFO - PROCESS | 1689 | 16:06:25 INFO - PROCESS | 1689 | [#5] yui.html Cycles:180 Average:18225.60 Median:45384.39 stddev:24411.03 (53.8%) stddev-sans-first:24444.00 16:06:25 INFO - PROCESS | 1689 | Values: 690.8 705.3 823.6 826.2 830.0 12476.9 14692.9 15002.8 15196.4 15206.9 11758.3 13041.6 13937.5 13951.1 13963.5 689.2 845.1 862.3 878.5 888.7 862.6 875.5 876.2 877.7 883.1 910.3 922.9 929.7 929.8 930.5 892.5 903.9 907.3 927.3 931.6 663.9 682.7 684.7 688.7 690.2 14439.9 14454.9 14777.2 14921.3 14939.8 14162.4 14211.6 14274.6 14300.3 14311.9 10104.1 11629.0 11862.3 11876.5 12018.6 3299.0 3842.6 3896.5 3902.2 4012.5 59135.2 79751.6 80736.8 83563.6 83945.9 10853.5 11064.2 11150.1 11275.9 11544.8 21533.0 21779.0 21784.7 21801.7 21805.7 7969.2 7996.5 8032.0 8133.9 8234.3 4445.9 4451.2 4460.3 4481.1 4607.0 3446.0 3450.6 3539.2 3542.6 3553.2 604.8 618.9 657.2 676.0 682.7 606.0 652.2 661.4 662.5 677.5 8735.3 8767.9 8848.6 8869.1 8992.7 572.5 624.6 641.9 659.2 662.7 4116.3 4126.9 4151.4 4179.4 4250.9 83334.0 88378.7 88545.0 89214.0 90340.1 79128.2 79378.0 79687.4 80857.6 81507.0 57918.6 63368.0 64038.0 64355.0 70714.6 7688.7 9574.8 9594.2 9677.5 9712.9 44980.8 45103.5 45332.0 45436.8 46147.8 61652.0 61722.7 62308.7 63054.7 63399.7 12587.0 12656.8 12801.4 12837.6 13374.2 11064.6 12352.8 14201.2 14463.6 14892.0 13124.9 13133.1 13506.7 13681.5 13749.9 13151.0 13155.7 13338.3 13339.5 13519.7 12778.4 13069.2 13547.5 13569.3 13577.0 9639.3 11019.4 11090.3 11373.7 11635.2 10900.5 11169.6 11176.2 11319.4 11418.3 16:06:25 INFO - PROCESS | 1689 | -------- Summary: end -------- 16:06:25 INFO - PROCESS | 1689 | 16:06:26 INFO - TEST-INFO | 1689: exit 0 16:06:26 INFO - TEST-OK | dromaeo_css | took 1111835ms 16:06:26 INFO - TEST-START | kraken 16:06:26 INFO - operating with platform_type : mac_ 16:06:26 INFO - Initialising browser for kraken test... 16:06:26 INFO - TEST-INFO | started process 1809 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpta8WZi/profile http://localhost:49273/getInfo.html) 16:06:28 INFO - PROCESS | 1809 | __metrics Screen width/height:1600/1200 16:06:28 INFO - PROCESS | 1809 | colorDepth:24 16:06:28 INFO - PROCESS | 1809 | Browser inner width/height: 1024/689 16:06:28 INFO - PROCESS | 1809 | __metrics 16:06:29 ERROR - PROCESS | 1809 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 16:06:29 INFO - TEST-INFO | 1809: exit 0 16:06:29 INFO - Browser initialized. 16:06:29 INFO - Running cycle 1/1 for kraken test... 16:06:29 INFO - TEST-INFO | started process 1811 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpta8WZi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:06:41 INFO - PROCESS | 1811 | RSS: Main: 252391424 16:06:41 INFO - PROCESS | 1811 | 16:06:44 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 16:06:45 INFO - PROCESS | 1811 | RSS: Main: 265306112 16:06:45 INFO - PROCESS | 1811 | 16:06:47 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 16:06:48 INFO - PROCESS | 1811 | RSS: Main: 269598720 16:06:48 INFO - PROCESS | 1811 | 16:06:51 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 16:06:52 INFO - PROCESS | 1811 | RSS: Main: 270299136 16:06:52 INFO - PROCESS | 1811 | 16:06:54 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 16:06:54 INFO - PROCESS | 1811 | RSS: Main: 271208448 16:06:54 INFO - PROCESS | 1811 | 16:06:57 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:06:57 INFO - PROCESS | 1811 | RSS: Main: 271818752 16:06:57 INFO - PROCESS | 1811 | 16:07:00 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 16:07:01 INFO - PROCESS | 1811 | RSS: Main: 271962112 16:07:01 INFO - PROCESS | 1811 | 16:07:04 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 16:07:04 INFO - PROCESS | 1811 | RSS: Main: 272474112 16:07:04 INFO - PROCESS | 1811 | 16:07:07 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 16:07:08 INFO - PROCESS | 1811 | RSS: Main: 273014784 16:07:08 INFO - PROCESS | 1811 | 16:07:09 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:07:10 INFO - PROCESS | 1811 | RSS: Main: 272195584 16:07:10 INFO - PROCESS | 1811 | 16:07:12 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:07:13 INFO - PROCESS | 1811 | RSS: Main: 272449536 16:07:13 INFO - PROCESS | 1811 | 16:07:15 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:07:16 INFO - PROCESS | 1811 | RSS: Main: 272678912 16:07:16 INFO - PROCESS | 1811 | 16:07:19 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:07:19 INFO - PROCESS | 1811 | RSS: Main: 272822272 16:07:19 INFO - PROCESS | 1811 | 16:07:22 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:07:23 INFO - PROCESS | 1811 | RSS: Main: 272531456 16:07:23 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 16:07:25 INFO - PROCESS | 1811 | RSS: Main: 273436672 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | __start_tp_report 16:07:25 INFO - PROCESS | 1811 | _x_x_mozilla_page_load 16:07:25 INFO - PROCESS | 1811 | _x_x_mozilla_page_load_details 16:07:25 INFO - PROCESS | 1811 | |i|pagename|runs| 16:07:25 INFO - PROCESS | 1811 | |0;ai-astar;118;114;116;116;117;117;115;114;118;115 16:07:25 INFO - PROCESS | 1811 | |1;audio-beat-detection;167;143;169;163;119;140;138;123;173;118 16:07:25 INFO - PROCESS | 1811 | |2;audio-dft;204;210;244;222;205;195;196;195;197;195 16:07:25 INFO - PROCESS | 1811 | |3;audio-fft;69;75;70;107;58;57;58;60;125;59 16:07:25 INFO - PROCESS | 1811 | |4;audio-oscillator;71;72;118;70;67;90;130;101;103;147 16:07:25 INFO - PROCESS | 1811 | |5;imaging-gaussian-blur;92;89;91;90;94;119;92;95;92;95 16:07:25 INFO - PROCESS | 1811 | |6;imaging-darkroom;118;123;96;97;97;97;101;96;96;96 16:07:25 INFO - PROCESS | 1811 | |7;imaging-desaturate;93;115;145;111;90;90;89;123;149;95 16:07:25 INFO - PROCESS | 1811 | |8;json-parse-financial;58;57;59;57;57;57;58;57;58;58 16:07:25 INFO - PROCESS | 1811 | |9;json-stringify-tinderbox;69;68;67;66;78;73;77;70;71;70 16:07:25 INFO - PROCESS | 1811 | |10;stanford-crypto-aes;89;91;108;107;111;73;98;107;132;110 16:07:25 INFO - PROCESS | 1811 | |11;stanford-crypto-ccm;139;138;146;155;189;147;149;152;160;145 16:07:25 INFO - PROCESS | 1811 | |12;stanford-crypto-pbkdf2;154;232;228;162;225;164;198;213;179;161 16:07:25 INFO - PROCESS | 1811 | |13;stanford-crypto-sha256-iterative;74;89;80;67;58;66;69;67;66;66 16:07:25 INFO - PROCESS | 1811 | __end_tp_report 16:07:25 INFO - PROCESS | 1811 | __start_cc_report 16:07:25 INFO - PROCESS | 1811 | _x_x_mozilla_cycle_collect,1077 16:07:25 INFO - PROCESS | 1811 | __end_cc_report 16:07:25 INFO - PROCESS | 1811 | __startTimestamp1464390445388__endTimestamp 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | ------- Summary: start ------- 16:07:25 INFO - PROCESS | 1811 | Number of tests: 14 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#0] ai-astar Cycles:10 Average:116.00 Median:116.50 stddev:1.49 (1.3%) stddev-sans-first:1.39 16:07:25 INFO - PROCESS | 1811 | Values: 118.0 114.0 116.0 116.0 117.0 117.0 115.0 114.0 118.0 115.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#1] audio-beat-detection Cycles:10 Average:145.30 Median:153.00 stddev:21.43 (14.0%) stddev-sans-first:21.25 16:07:25 INFO - PROCESS | 1811 | Values: 167.0 143.0 169.0 163.0 119.0 140.0 138.0 123.0 173.0 118.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#2] audio-dft Cycles:10 Average:206.30 Median:204.50 stddev:15.86 (7.8%) stddev-sans-first:16.80 16:07:25 INFO - PROCESS | 1811 | Values: 204.0 210.0 244.0 222.0 205.0 195.0 196.0 195.0 197.0 195.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#3] audio-fft Cycles:10 Average:73.80 Median:59.50 stddev:23.46 (39.4%) stddev-sans-first:24.82 16:07:25 INFO - PROCESS | 1811 | Values: 69.0 75.0 70.0 107.0 58.0 57.0 58.0 60.0 125.0 59.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:68.50 stddev:28.01 (40.9%) stddev-sans-first:28.10 16:07:25 INFO - PROCESS | 1811 | Values: 71.0 72.0 118.0 70.0 67.0 90.0 130.0 101.0 103.0 147.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:92.00 stddev:8.70 (9.5%) stddev-sans-first:9.16 16:07:25 INFO - PROCESS | 1811 | Values: 92.0 89.0 91.0 90.0 94.0 119.0 92.0 95.0 92.0 95.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#6] imaging-darkroom Cycles:10 Average:101.70 Median:96.00 stddev:10.09 (10.5%) stddev-sans-first:8.81 16:07:25 INFO - PROCESS | 1811 | Values: 118.0 123.0 96.0 97.0 97.0 97.0 101.0 96.0 96.0 96.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#7] imaging-desaturate Cycles:10 Average:110.00 Median:89.50 stddev:22.84 (25.5%) stddev-sans-first:23.39 16:07:25 INFO - PROCESS | 1811 | Values: 93.0 115.0 145.0 111.0 90.0 90.0 89.0 123.0 149.0 95.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:0.70 (1.2%) stddev-sans-first:0.73 16:07:25 INFO - PROCESS | 1811 | Values: 58.0 57.0 59.0 57.0 57.0 57.0 58.0 57.0 58.0 58.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:70.50 stddev:4.01 (5.7%) stddev-sans-first:4.20 16:07:25 INFO - PROCESS | 1811 | Values: 69.0 68.0 67.0 66.0 78.0 73.0 77.0 70.0 71.0 70.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#10] stanford-crypto-aes Cycles:10 Average:102.60 Median:102.50 stddev:15.90 (15.5%) stddev-sans-first:16.08 16:07:25 INFO - PROCESS | 1811 | Values: 89.0 91.0 108.0 107.0 111.0 73.0 98.0 107.0 132.0 110.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#11] stanford-crypto-ccm Cycles:10 Average:152.00 Median:150.50 stddev:14.63 (9.7%) stddev-sans-first:14.74 16:07:25 INFO - PROCESS | 1811 | Values: 139.0 138.0 146.0 155.0 189.0 147.0 149.0 152.0 160.0 145.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.60 Median:205.50 stddev:31.12 (15.1%) stddev-sans-first:29.89 16:07:25 INFO - PROCESS | 1811 | Values: 154.0 232.0 228.0 162.0 225.0 164.0 198.0 213.0 179.0 161.0 16:07:25 INFO - PROCESS | 1811 | 16:07:25 INFO - PROCESS | 1811 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:68.00 stddev:8.74 (12.9%) stddev-sans-first:9.16 16:07:25 INFO - PROCESS | 1811 | Values: 74.0 89.0 80.0 67.0 58.0 66.0 69.0 67.0 66.0 66.0 16:07:25 INFO - PROCESS | 1811 | -------- Summary: end -------- 16:07:25 INFO - PROCESS | 1811 | 16:07:26 INFO - TEST-INFO | 1811: exit 0 16:07:26 INFO - TEST-OK | kraken | took 60013ms 16:07:26 INFO - SUITE-END | took 1171s 16:07:26 INFO - Completed test suite (00:19:32) 16:07:26 INFO - javascript benchmark 16:07:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7190.494932162337, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2898.434805212983, 3456.5333679953214, 3510.894673159806, 3522.929541409171, 3530.6469353064713, 5657.3494048184475, 5677.205191273225, 5700.05949018412, 5724.112762521805, 5756.510696590794, 5527.585431092664, 5611.859703507414, 5672.149177623359, 5724.112762521805, 5765.769369225236, 5090.211017292326, 5141.742912854361, 5221.190715439113, 5243.2659427680155, 5283.313169288006, 717.7315614718581, 5097.3118628984985, 5160.612954028463, 5214.191800270964, 5223.817166399177, 5197.974010129925, 5208.401033881114, 5210.76551555181, 5236.3978142513715, 5249.1338929076655, 5219.5041471060185, 5249.028929648028, 5261.263423120766, 5267.209918512254, 5320.17537281722, 4082.4692789937417, 4534.478534968488, 5092.8217512387255, 5143.022825663112, 5143.485651434864, 4307.569243075699, 4559.794809233559, 4805.663603547753, 5082.65309692928, 5672.5178359839465, 6088.96955515224, 6238.0, 6283.8114856554375, 6310.085037669558, 6325.240971083454, 6101.4896331263535, 6108.0992441897915, 6192.411720886508, 6223.906641400383, 6255.343188965138, 5171.922421163686, 5876.29484461863, 5881.323647780518, 5908.044151512731, 5958.344582095966, 5193.2989046479, 6190.50475961922, 6267.341929097379, 6285.622862628257, 6315.715792789336, 6088.238970128734, 6111.174991376196, 6120.835012011849, 6181.350958149419, 6192.380761923771, 6222.222222222223, 6247.9062814057825, 6258.780942666985, 6281.497480201573, 6315.652639104894, 6098.390160983866, 6106.915739268716, 6159.0, 6216.222972128484, 6220.533459990519, 6185.226846644169, 6225.408586184305, 6246.219222597176, 6264.091706702503, 6277.435030847246, 4892.535209155125, 4900.240462728283, 4906.0, 4911.4106307243355, 4933.161362568372, 4184.832606695698, 4314.266574682311, 4346.239408103569, 4406.33904914259, 4433.911321773547, 5291.126964050965, 5452.482014208644, 5657.32112146545, 5722.313322401338, 5804.9419505805245, 5707.286589176353, 6305.243370795442, 6324.588901721373, 6328.272248691367, 6339.778107766206, 5622.690752008679, 5699.886002279931, 5730.283714535683, 5771.59598828078, 5793.565482588822, 5351.197320401893, 5376.7042812644895, 5402.621816472888, 5425.782968681208, 5432.864178395572, 6339.207599050119, 6373.362663733682, 6377.681115944277, 6381.64900930444, 6402.519811014193, 6138.0179171332375, 6223.564350495421, 6260.937390626035, 6274.152989346379, 6285.340039295808, 6255.499560035278, 6365.077063825626, 6375.298717141203, 6400.423961843457, 6455.515836312389, 6224.813255602339, 6247.12540244357, 6252.0, 6254.343293954252, 6258.0, 6139.1098290746695, 6151.3541078186145, 6212.596181248205, 6262.185915830913, 6285.5600107992, 5306.761195746123, 5578.77267001259, 6148.262208535004, 6173.197484327188, 6190.2571691397325, 5291.259223708606, 6110.877782444415, 6155.230596175478, 6179.196704428396, 6190.845228869314, 5802.535797136304, 5841.094630332305, 5954.702264886825, 5976.103584462366, 6006.219191505076, 5036.496350365081, 6142.57002009855, 6155.47678447337, 6171.9691401542705, 6188.969055154876, 4907.877303067452, 5782.045962416154, 5861.443162899518, 6007.8197654070445, 6008.819735407945], "value": 5600.854376028003, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.4556215973228, 518.7924830067973, 522.2010324203967, 528.5639347538282, 544.425630959338, 38162.04594885129, 41253.79373103134, 41325.586744132546, 41360.0, 41398.0, 41951.16097678052, 42582.14835703284, 42660.14679706404, 42695.93260168497, 42729.35905961413, 9459.574319155654, 9569.760755981122, 9662.17871480925, 9666.468344241064, 9675.0, 9502.0, 9530.80938381232, 9571.712848614552, 9598.280128990318, 9613.471259080748, 9458.432494050345, 9489.430634161938, 9568.52157392131, 9599.136077753, 9600.80798384032, 8415.915840841575, 9384.343095983284, 9449.574769135337, 9569.090936361035, 9622.326437149368, 9419.576119074658, 9519.952400238024, 9554.0, 9566.665166719202, 9582.281328900292, 38655.806720966495, 38664.613353866385, 38738.0, 39222.0, 39519.61681341168, 2974.925626859324, 2995.5806187133817, 3311.2549676322874, 3352.6312105668358, 3479.162409095649, 29151.0, 29501.0, 30274.54588181179, 30989.690103099132, 31231.219219519466, 3171.1437911763696, 3179.71382575567, 3182.10900947735, 3183.697548732867, 3293.110860067792, 466850.6657466725, 469022.0, 471875.0, 472392.0, 472629.0, 33754.66245337564, 33974.49038264428, 34022.97931062072, 34046.0, 34199.31601368009, 32466.863659771796, 32681.836590816893, 32802.85190018338, 32844.507332390036, 32851.1787205317, 16671.91664041672, 16681.582960426087, 16836.24236909342, 16837.989720616803, 16911.746323805153, 11708.238964467282, 11717.652469965893, 11771.587994420326, 11866.050715942703, 12912.612621621367, 16431.835681643268, 20007.899565524036, 20163.495912602302, 20269.594608107756, 20292.594148116954, 8297.377696672773, 8314.461824562475, 8445.282151017109, 8452.57737113142, 8457.40798144124, 922.4926290540176, 979.7844474215661, 1070.4701172919454, 1084.203110713625, 1161.5027690465568, 30415.543766843515, 30628.846855766024, 30665.0, 30926.453677316043, 31086.378272434424, 910.0353625157354, 918.7060140754894, 1054.2462139570243, 1075.2150929821273, 1096.4517741129434, 13537.526186583422, 14618.26908654563, 16220.918895405448, 16471.588210294838, 16568.585785355463, 574902.0, 688547.0, 698919.0, 700913.0, 703950.0, 60460.69769651123, 60758.69620651957, 61097.69451152716, 61312.46718831976, 61365.69317153386, 1380.4478208716394, 1409.26718106584, 1438.0940007795023, 1442.019426789793, 1446.2840893757657, 26436.0, 30376.17742935431, 30402.543961840147, 30601.693983059886, 31031.224219394695, 51884.48115518797, 52521.737391312796, 52841.735791320796, 52880.0, 52914.0, 489412.0, 492456.0, 492462.0, 492572.0, 493659.0, 26894.32764180911, 27003.594946076217, 27062.0, 27463.725362746118, 27602.447951040464, 48778.26832597585, 49599.50400495949, 49853.25220121629, 49914.750426247636, 49974.0, 39890.202195956495, 40612.984675383355, 40873.77378678644, 41477.37783933183, 41865.0, 40108.39837402383, 40277.39583906302, 40323.0, 40790.9802254946, 41250.58749412587, 37717.62282377141, 37918.81040594779, 39059.023524412114, 39064.41403378895, 39091.41362879616, 33815.49276760801, 34936.0, 35698.0, 35768.463473047406, 36023.459648105825, 25567.616485752358, 25909.352266193495, 26186.0, 26477.205683829518, 26513.469730605666], "value": 19482.810226073485, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.4129960510616, 383.56546516030056, 384.7806750152414, 390.84115597254134, 406.686851124634, 16721.163941802908, 19992.50018749532, 21135.943202839855, 21663.67504487432, 21691.3492595222, 11469.59856405026, 11753.294802311868, 12266.632001039961, 12287.01703863691, 12291.0, 5318.680879147254, 5548.112302031675, 5560.888782224353, 5573.693446860421, 5587.106063029916, 3252.4633435483115, 5745.798897038604, 6061.090836374557, 6066.454019138277, 6135.785247516338, 5643.746031428575, 5958.37437069108, 6110.20567326247, 6113.388661133896, 6138.232720909887, 5970.283565972068, 6015.097735339712, 6064.120702498113, 6100.420460056265, 6113.358097399753, 5703.714814259312, 5745.655260684331, 5797.594168408213, 5852.795152169653, 5859.355470898197, 10766.4616769162, 12717.109802313842, 12885.226886386847, 13021.86978130216, 13073.411696473682, 2061.742282214723, 2306.8731219782903, 2343.7773411525877, 2540.3776074861776, 2694.434168824549, 10070.446125463097, 10494.737631559196, 13529.932350338284, 15502.689946201126, 16199.271032803434, 1436.864017088096, 1811.2030706489102, 1843.6957901946162, 1844.1240410804894, 1894.1003023563835, 94831.0, 112676.0, 113657.43171284173, 114926.0, 115296.0, 11235.157363197794, 11642.88357116418, 11750.412479375997, 11802.288668143072, 11851.88148118525, 14823.481178158712, 14879.0, 14911.776323355158, 14917.403303867724, 14932.178730169728, 7679.731209407644, 8150.633221505046, 8521.573921304034, 8743.038265790758, 8777.29781617469, 7503.7748867534065, 8305.501669899826, 8467.32261419085, 8546.273566746771, 8646.308295336357, 3976.58245884178, 4511.503734589192, 4602.815887364535, 4626.745528995937, 4640.419947506562, 1515.3484001879299, 1761.8414342709218, 1913.3494611832043, 1921.2315073970244, 1929.2958070304237, 843.767963809945, 916.2852974679761, 955.4028732042474, 1004.3170643962176, 1034.462079718542, 20376.388708338774, 20526.897365513076, 20691.79308206929, 21031.78968210329, 21250.89374553117, 942.0579420579421, 962.0956301076965, 967.4582233949011, 1029.8146333660013, 1034.5292891734393, 11036.28264162843, 11116.721577018585, 11121.943415375526, 11140.164487663458, 11168.832467512993, 480414.0, 505046.0, 537028.0, 542572.0, 563884.0, 108688.0, 109987.0, 112917.0, 113654.0, 114222.85777142122, 1030.1449796668746, 1030.3302853145515, 1049.2550289294686, 1059.1050562274866, 1060.0353678152999, 15389.0, 15553.300101495686, 18130.909345453714, 18503.982280974677, 18675.15961781696, 49022.75488622534, 52294.215586765466, 52435.73782131218, 52501.0, 52846.0, 74695.0, 75347.62326188335, 75792.0, 76907.0, 77044.0, 7761.0, 7845.490043147177, 7883.921160788319, 7908.4068694846965, 8049.83900322002, 9860.457674827667, 10559.894401055892, 11903.82144267854, 12684.936575317064, 12763.298018608693, 11024.118070554498, 11043.72390690207, 11128.109751220045, 11251.437428128396, 11290.717732056435, 8993.28053755711, 10883.891161088288, 10991.61529346469, 11081.224314297922, 11085.944570277097, 9514.762130946781, 10817.459127043774, 11382.430878456209, 11524.884751152382, 11579.07367410622, 6946.722131114901, 7375.004374409386, 8136.75589732309, 8264.876026859722, 8276.255137037695, 10631.681049568526, 10991.505382257978, 11179.105671546095, 11509.424528773361, 11577.189596728485], "value": 9156.631692660454, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1553.246675362449, 1596.114156643063, 1781.7238328059154, 1859.172668162668, 1916.492129585659, 7843.568603726807, 7966.840663186733, 8268.669253229871, 8712.651493940242, 8742.300615950717, 7289.343959043685, 8444.113368096354, 8576.356773242016, 8645.654173833054, 8728.60721267543, 1923.4998900285957, 2302.4359032037173, 2356.646503024543, 2362.61016932206, 2363.917262895799, 2335.4278201840575, 2338.8479748816358, 2353.599888019041, 2369.1589485732575, 2387.9761202388013, 1938.980610193894, 2334.4630734930975, 2358.445765245166, 2368.2539999900064, 2374.620060790265, 2335.8598484090926, 2337.439014636492, 2338.847974881627, 2338.9298321050396, 2346.343023953296, 1817.3366721146817, 2133.4666333416644, 2321.628539433699, 2361.0791791201445, 2369.4905595297096, 8643.870341944876, 8692.56537173145, 8716.0, 8733.475991440471, 8750.956245218733, 5229.267902493654, 5343.145096784496, 5359.866003349947, 5381.112116500773, 5391.406945236021, 3522.489239060322, 3566.5720113586276, 3698.408254679265, 3725.105974566112, 3742.3450896093077, 1068.4924660786141, 1072.6728347853903, 1089.629525961169, 1101.427257825926, 1121.2767764791745, 11533.769324613544, 11572.59495917639, 11691.88308116925, 11795.46920388576, 11834.349110798903, 4731.33761273422, 4733.171694953369, 4838.2016967200725, 4852.296417019583, 4859.07677541266, 10122.44326562046, 10125.139363154214, 10160.441175735405, 10163.796724065478, 10184.338018028953, 5302.867428314323, 5331.280277162611, 5333.786648534093, 5371.838844834661, 5419.458054194628, 4427.136708341907, 4734.479207287196, 4895.192293271642, 4919.606431485473, 4941.160002799533, 3488.8255587220538, 3988.282109220368, 3999.4000899865255, 4043.1913617276664, 4118.6499147572795, 1294.4304506017322, 1318.9802152967713, 1322.9801552976712, 1332.327174776751, 1333.8399392073013, 824.6124321568853, 931.7158266728577, 939.1500691873865, 948.1892981500855, 981.9116279534876, 5912.113183022581, 5963.0, 6045.78839740607, 6102.084687296941, 6194.34959329273, 423.1389123134407, 438.50667998501683, 992.4591097851688, 1000.7598176437748, 1031.2368847053126, 3570.553680789901, 3728.3848165053005, 4015.0, 4047.3928910663635, 4050.10897602527, 10387.84418233727, 10459.320144190755, 10691.144708423308, 10698.358098513958, 10728.570857165625, 10556.049955504042, 10598.841017384746, 10636.0, 10640.04239618438, 10722.249442538947, 7740.297134630372, 8025.23760242776, 8041.236082572146, 8124.350051995944, 8258.339332853357, 5994.160817485468, 6015.879682406415, 6098.878022439615, 6144.170536977451, 6161.229846269216, 9672.75818104553, 9846.753831154278, 9885.209183265466, 9890.258230632875, 9902.900970990486, 9559.139677428786, 9662.13040826329, 10197.23520735933, 10214.540345684314, 10229.028242316968, 6974.0, 7416.184219735933, 7556.055493063367, 7653.540787552764, 7773.222677732181, 8471.915280847114, 8497.065322814362, 8528.275096616857, 8569.10024447449, 8586.828263434822, 7250.854982900418, 7493.175750667313, 7684.884726728992, 7690.615469226628, 7711.228877112469, 6252.374762523711, 6367.9044814328745, 7336.926630733625, 7511.812204694927, 7595.3164215220895, 7077.115360579927, 7113.75101871432, 7139.286071392819, 7150.463715221484, 7153.892691609734, 4214.388913607571, 4328.502222244482, 5058.3929928408825, 5071.2139618359215, 5083.593312535062, 5089.414717307557, 5109.6678715883345, 5123.0, 5142.6914385137015, 5249.632525723163], "value": 4569.258219773291, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.37433288443313, 300.83003103246665, 305.18819938962366, 310.06515356201055, 313.9136737397217, 4699.694519856207, 5595.524380427663, 5664.915026274609, 5668.376478587357, 5687.7156142192935, 4330.437043184382, 5425.864353391167, 5546.362168350631, 5553.583481238903, 5572.300448363258, 3195.872165113393, 3789.844097550253, 4175.415441838145, 4179.74607617715, 4190.203861266365, 3733.7199710021723, 4079.551759125512, 4112.355057976803, 4136.689748268876, 4206.495220573535, 3501.562304711911, 3878.45701601776, 4188.958110418903, 4202.957970420287, 4216.069831467132, 3212.903612891617, 3856.5179352580926, 3883.7087218458446, 4165.416841642173, 4171.311733563958, 3090.922726931822, 3544.0, 3547.3859393975936, 3560.287942411502, 3569.625189355106, 5049.0, 5114.974425127888, 5362.651427657229, 5363.088274993261, 5366.914024435926, 3869.7484663496784, 4070.921205880444, 4094.344904815215, 4729.858104256843, 4802.975985120087, 5818.730695028942, 5853.356130825606, 5854.560907931879, 5873.647581145145, 5906.852328691774, 3468.7051600613977, 3866.6906647468136, 3884.2615955751126, 3908.863189788344, 3910.5559025608995, 4540.432445944257, 5700.7719691212305, 6117.418845209698, 6126.499007743131, 6161.445469907685, 3350.564426624523, 3709.9072523187137, 3925.980370098131, 3969.7221194516105, 3994.4407782910416, 3203.4073696366245, 4021.4369988201674, 4025.215083058834, 4045.86715719599, 4046.1185548719986, 3992.241474119966, 4694.36626055485, 4709.458412282563, 4724.527547245317, 4770.546798054179, 4850.13841833313, 5015.974920125376, 5258.579313654898, 5989.9101513477335, 6007.189029481052, 1360.8979326550548, 1385.9882285931326, 1387.167699380384, 1389.6873203529328, 1391.3877893726728, 741.2624438683545, 761.7143571160815, 844.531285136755, 855.5380094748766, 889.8620713789373, 4069.5726948670554, 5099.541041306226, 5380.838574842761, 5427.99582077317, 5466.781328746885, 5188.558972487305, 5193.831387937684, 5728.796952639992, 5819.563532735062, 5948.156071617812, 5230.293910322134, 5263.578913686896, 5449.891002180014, 5459.617826752169, 5499.010178167888, 2694.6901106373016, 3127.248740705212, 3152.6689697581883, 3175.682431756852, 3188.537662038991, 5556.444355564411, 5774.82675519735, 6184.474319682878, 6186.133941248228, 6235.285296543396, 5027.522385373383, 5688.9715551422805, 6244.128640242988, 6332.588381755171, 6344.841378965562, 5206.869828254324, 6348.809535713936, 6352.428281454691, 6426.0, 6452.709628066655, 5657.971710141587, 6099.268087829489, 6123.785667501616, 6163.137160797582, 6178.876422471435, 5788.507976822018, 6008.218931538872, 6078.0, 6088.81268152706, 6095.49509032317, 6128.417800308964, 6375.394337537927, 6397.3366924599, 6398.744050238124, 6399.928034543352, 3390.8643654253556, 3479.1997840497334, 3949.980250098731, 3976.940345894872, 3979.582143874851, 5755.618651523688, 5868.178455016216, 5886.852828679317, 5993.0, 6183.536234782499, 4721.693089949143, 4865.513448655248, 5794.62334948227, 5942.811437712388, 5972.731227094878, 4927.8275260367145, 5209.71346575942, 5652.830415087554, 5857.677827719346, 5902.822915312548, 5016.398032236155, 5523.6467065569195, 5678.006348889011, 5806.9419305806405, 5815.79644712433, 5530.866172434722, 5730.025895597842, 5746.270521502253, 5849.64902105875, 5916.645001299936, 5753.295950903069, 5784.599391252583, 5813.331466881363, 5825.970870145622, 5856.180134781049], "value": 4192.196983540201, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.7996680962522, 705.2947052947053, 823.6129019360899, 826.2233500509519, 829.9543525106117, 12476.875231247684, 14692.853071469295, 15002.849971500282, 15196.392144314228, 15206.935514513989, 11758.294502329845, 13041.60875173744, 13937.51218707343, 13951.093178943387, 13963.511277105303, 689.1936434371772, 845.0788640381983, 862.3230763850379, 878.5168157513374, 888.7422647432269, 862.5816479007676, 875.5490922175085, 876.2376732242958, 877.714742708619, 883.0507204754914, 910.2945217456459, 922.903095175007, 929.6699671616581, 929.7861491856877, 930.4975313330793, 892.4823602310677, 903.8870141232346, 907.2605826251616, 927.3184209605935, 931.6413180925358, 663.9136912201383, 682.6791408038214, 684.6918886501045, 688.683205725367, 690.1717938473802, 14439.855601443956, 14454.927725361411, 14777.187254700988, 14921.328540215607, 14939.775903361458, 14162.36269367881, 14211.644708882257, 14274.64313392163, 14300.284985750775, 14311.856881431157, 10104.09063184317, 11629.0, 11862.288262704265, 11876.465559049864, 12018.579349722806, 3298.983505082483, 3842.615738426163, 3896.4544963705102, 3902.2390633826417, 4012.478377810895, 59135.22594322177, 79751.60124199341, 80736.78894816582, 83563.5821820893, 83945.90135246607, 10853.45732713377, 11064.225504214628, 11150.107991360672, 11275.943620281847, 11544.769104618028, 21533.0, 21779.0, 21784.673229901564, 21801.67297490539, 21805.67291490629, 7969.242921922408, 7996.480228785111, 8032.0361556612415, 8133.877991830127, 8234.341252699769, 4445.888852778642, 4451.176532341462, 4460.286354183315, 4481.103779244164, 4606.976965115153, 3445.9655403445645, 3450.603180634209, 3539.2213712983103, 3542.557180352456, 3553.200529880808, 604.7792555717219, 618.935011823761, 657.2310396835713, 675.9932400676029, 682.6586706646676, 605.9939400606025, 652.1847690387016, 661.4443867151616, 662.5196732368994, 677.5189615373042, 8735.34484913634, 8767.912320876838, 8848.64605415789, 8869.113088691209, 8992.730218093468, 572.4704648200482, 624.5846512069423, 641.9358064193636, 659.2385794407467, 662.6587307536622, 4116.300228961084, 4126.855560055384, 4151.377293406013, 4179.414881916534, 4250.851220207278, 83334.0, 88378.67431988397, 88545.0, 89214.0, 90340.09659903448, 79128.20871791209, 79378.0, 79687.40625187464, 80857.59571202107, 81507.0, 57918.55203619859, 63368.0, 64038.0, 64355.0, 70714.64642676753, 7688.654010569426, 9574.760630984281, 9594.184494317782, 9677.516124193902, 9712.9028709712, 44980.77509612562, 45103.54896451125, 45332.0, 45436.77281613571, 46147.76926115348, 61652.0, 61722.69138654278, 62308.68845655743, 63054.68472657607, 63399.68300158469, 12587.0, 12656.810147847606, 12801.423935923092, 12837.550685725955, 13374.197548147142, 11064.557417703523, 12352.764723527574, 14201.218932958787, 14463.566093017227, 14892.0, 13124.934375328063, 13133.146345487512, 13506.736918713425, 13681.47459758162, 13749.851385090857, 13151.0136739743, 13155.696957645807, 13338.30400636254, 13339.53311634127, 13519.729605408034, 12778.424970876518, 13069.215847049207, 13547.525836595672, 13569.253691047088, 13577.0, 9639.325247232626, 11019.393933333424, 11090.279131856403, 11373.715657108638, 11635.185537012328, 10900.454977251264, 11169.553217871195, 11176.161787866105, 11319.377434241196, 11418.31490110596], "value": 7220.712430905738, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 114.0, 116.0, 116.0, 117.0, 117.0, 115.0, 114.0, 118.0, 115.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 143.0, 169.0, 163.0, 119.0, 140.0, 138.0, 123.0, 173.0, 118.0], "value": 145.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 210.0, 244.0, 222.0, 205.0, 195.0, 196.0, 195.0, 197.0, 195.0], "value": 206.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 75.0, 70.0, 107.0, 58.0, 57.0, 58.0, 60.0, 125.0, 59.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 118.0, 70.0, 67.0, 90.0, 130.0, 101.0, 103.0, 147.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 91.0, 90.0, 94.0, 119.0, 92.0, 95.0, 92.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 123.0, 96.0, 97.0, 97.0, 97.0, 101.0, 96.0, 96.0, 96.0], "value": 101.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 115.0, 145.0, 111.0, 90.0, 90.0, 89.0, 123.0, 149.0, 95.0], "value": 110.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 59.0, 57.0, 57.0, 57.0, 58.0, 57.0, 58.0, 58.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 67.0, 66.0, 78.0, 73.0, 77.0, 70.0, 71.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 108.0, 107.0, 111.0, 73.0, 98.0, 107.0, 132.0, 110.0], "value": 102.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 146.0, 155.0, 189.0, 147.0, 149.0, 152.0, 160.0, 145.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 232.0, 228.0, 162.0, 225.0, 164.0, 198.0, 213.0, 179.0, 161.0], "value": 191.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 89.0, 80.0, 67.0, 58.0, 66.0, 69.0, 67.0, 66.0, 66.0], "value": 70.2, "unit": "score"}]}]} 16:07:26 INFO - Return code: 0 16:07:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:07:26 ERROR - # TBPL SUCCESS # 16:07:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:07:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:07:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:07:26 INFO - 2016-05-27 16:07:26.681 screenresolution[1822:10627] starting screenresolution argv=screenresolution get 16:07:26 INFO - 2016-05-27 16:07:26.684 screenresolution[1822:10627] Display 0: 1600x1200x32@60 16:07:26 INFO - 2016-05-27 16:07:26.690 screenresolution[1823:10630] starting screenresolution argv=screenresolution list 16:07:26 INFO - Available Modes on Display 0 16:07:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:07:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:07:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:07:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:07:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:07:26 INFO - Intel Iris: 16:07:26 INFO - Chipset Model: Intel Iris 16:07:26 INFO - Type: GPU 16:07:26 INFO - Bus: Built-In 16:07:26 INFO - VRAM (Dynamic, Max): 1536 MB 16:07:26 INFO - Vendor: Intel (0x8086) 16:07:26 INFO - Device ID: 0x0a2e 16:07:26 INFO - Revision ID: 0x0009 16:07:26 INFO - Displays: 16:07:26 INFO - Display: 16:07:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:07:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:07:26 INFO - Display Serial Number: 1600x1200 60 16:07:26 INFO - Main Display: Yes 16:07:26 INFO - Mirror: Off 16:07:26 INFO - Online: Yes 16:07:26 INFO - Rotation: Supported 16:07:26 INFO - Return code: 0 16:07:26 INFO - Running post-action listener: _resource_record_post_action 16:07:26 INFO - ##### 16:07:26 INFO - ##### Finished run-tests step (success) 16:07:26 INFO - ##### 16:07:26 INFO - Running post-run listener: _resource_record_post_run 16:07:26 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 36836864; Write bytes: 704487936; Read time: 237; Write time: 2081 16:07:26 INFO - TinderboxPrint: CPU usage
54.4% 16:07:26 INFO - TinderboxPrint: I/O read bytes / time
36,836,864 / 237 16:07:26 INFO - TinderboxPrint: I/O write bytes / time
704,487,936 / 2,081 16:07:26 INFO - TinderboxPrint: CPU idle
2,203.8 (45.6%) 16:07:26 INFO - TinderboxPrint: CPU system
974.9 (20.2%) 16:07:26 INFO - TinderboxPrint: CPU user
1,654.7 (34.2%) 16:07:26 INFO - TinderboxPrint: Swap in / out
435,134,464 / 0 16:07:26 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392909312; Write bytes: 385994240; Read time: 29308; Write time: 847 16:07:27 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 29732352; Write bytes: 298992640; Read time: 179; Write time: 1180 16:07:27 INFO - Running post-run listener: _upload_blobber_files 16:07:27 INFO - Blob upload gear active. 16:07:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:07:27 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:07:27 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:07:27 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:07:27 INFO - (blobuploader) - INFO - Open directory for files ... 16:07:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:07:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:07:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:29 INFO - (blobuploader) - INFO - Done attempting. 16:07:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:07:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:07:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:30 INFO - (blobuploader) - INFO - Done attempting. 16:07:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:07:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:07:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:32 INFO - (blobuploader) - INFO - Done attempting. 16:07:32 INFO - (blobuploader) - INFO - Iteration through files over. 16:07:32 INFO - Return code: 0 16:07:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:07:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:07:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b86df15c897c0009e700ed8b9cb261aaf9a83ba32455a9810970a5b43eebe221223f0b589597ed2604dec1f564495a94418128ccc2b7d238aca22466b00616e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29604e18eea4b97b40e5c308332fd716e39aab5e5d721b1eee27dd4d27ea26645ddca9a3bf425c27b5df7a5dc2fb9ed5627cb179f97e8c8c7e47fc750d9b2bb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68405a8b03e1435e6f1138f162e8cd8c62d0f63815ec4f0a97a6446e3a443369137a7bd253ad5d66f62c35fda5c4dfdcbc9157191a621bde086dbf4f95bc4990"} 16:07:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:07:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:07:32 INFO - Contents: 16:07:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b86df15c897c0009e700ed8b9cb261aaf9a83ba32455a9810970a5b43eebe221223f0b589597ed2604dec1f564495a94418128ccc2b7d238aca22466b00616e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29604e18eea4b97b40e5c308332fd716e39aab5e5d721b1eee27dd4d27ea26645ddca9a3bf425c27b5df7a5dc2fb9ed5627cb179f97e8c8c7e47fc750d9b2bb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68405a8b03e1435e6f1138f162e8cd8c62d0f63815ec4f0a97a6446e3a443369137a7bd253ad5d66f62c35fda5c4dfdcbc9157191a621bde086dbf4f95bc4990"} 16:07:32 INFO - Running post-run listener: copy_logs_to_upload_dir 16:07:32 INFO - Copying logs to upload dir... 16:07:32 INFO - mkdir: /builds/slave/test/build/upload/logs 16:07:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.710830 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-27 16:07:32.844894) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 16:07:32.848527) ========= 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.D60cu9MMT6/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.w4mazkQVXE/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b86df15c897c0009e700ed8b9cb261aaf9a83ba32455a9810970a5b43eebe221223f0b589597ed2604dec1f564495a94418128ccc2b7d238aca22466b00616e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29604e18eea4b97b40e5c308332fd716e39aab5e5d721b1eee27dd4d27ea26645ddca9a3bf425c27b5df7a5dc2fb9ed5627cb179f97e8c8c7e47fc750d9b2bb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68405a8b03e1435e6f1138f162e8cd8c62d0f63815ec4f0a97a6446e3a443369137a7bd253ad5d66f62c35fda5c4dfdcbc9157191a621bde086dbf4f95bc4990"} build_url:https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011089 build_url: 'https://queue.taskcluster.net/v1/task/dVIU5dg6RTy1WKzdSMogig/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b86df15c897c0009e700ed8b9cb261aaf9a83ba32455a9810970a5b43eebe221223f0b589597ed2604dec1f564495a94418128ccc2b7d238aca22466b00616e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29604e18eea4b97b40e5c308332fd716e39aab5e5d721b1eee27dd4d27ea26645ddca9a3bf425c27b5df7a5dc2fb9ed5627cb179f97e8c8c7e47fc750d9b2bb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68405a8b03e1435e6f1138f162e8cd8c62d0f63815ec4f0a97a6446e3a443369137a7bd253ad5d66f62c35fda5c4dfdcbc9157191a621bde086dbf4f95bc4990"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 16:07:32.879325) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:07:32.879664) ========= 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.D60cu9MMT6/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.w4mazkQVXE/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.005060 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:07:32.927903) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 16:07:32.928225) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 16:07:32.928566) ========= ========= Total master_lag: 0.17 =========