builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0226 starttime: 1490704545.99 results: success (0) buildid: 20170328040421 builduid: 7c8430e0f80548e687b18f109917f99a revision: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:45.988511) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:45.988963) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:45.989248) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:46.016444) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:46.016832) ========= 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.G36R2VMfjJ/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.JixhTXwQeE/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 --2017-03-28 05:35:46-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.9M=0.001s 2017-03-28 05:35:46 (20.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145084 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:46.183709) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:46.184027) ========= 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.G36R2VMfjJ/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.JixhTXwQeE/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.026749 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:46.233211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-28 05:35:46.233570) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 --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 d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.G36R2VMfjJ/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.JixhTXwQeE/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 2017-03-28 05:35:46,335 truncating revision to first 12 chars 2017-03-28 05:35:46,335 Setting DEBUG logging. 2017-03-28 05:35:46,335 attempt 1/10 2017-03-28 05:35:46,335 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d4af7ec6cfcd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 05:35:48,477 unpacking tar archive at: mozilla-central-d4af7ec6cfcd/testing/mozharness/ program finished with exit code 0 elapsedTime=2.728436 ========= master_lag: 0.31 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-28 05:35:49.274180) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:49.274449) ========= script_repo_revision: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:49.274811) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:49.275074) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 05:35:49.298095) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-28 05:35:49.298414) ========= /tools/buildbot/bin/python -u 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', '-u', '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.G36R2VMfjJ/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.JixhTXwQeE/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 05:35:49 INFO - MultiFileLogger online at 20170328 05:35:49 in /builds/slave/test 05:35:49 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 05:35:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:35:49 INFO - {'append_to_log': False, 05:35:49 INFO - 'base_work_dir': '/builds/slave/test', 05:35:49 INFO - 'blob_upload_branch': 'Firefox', 05:35:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:35:49 INFO - 'branch': 'Firefox', 05:35:49 INFO - 'buildbot_json_path': 'buildprops.json', 05:35:49 INFO - 'config_files': ('talos/mac_config.py',), 05:35:49 INFO - 'default_actions': ('clobber', 05:35:49 INFO - 'read-buildbot-config', 05:35:49 INFO - 'download-and-extract', 05:35:49 INFO - 'populate-webroot', 05:35:49 INFO - 'create-virtualenv', 05:35:49 INFO - 'install', 05:35:49 INFO - 'run-tests'), 05:35:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:35:49 INFO - 'download_minidump_stackwalk': True, 05:35:49 INFO - 'download_symbols': 'ondemand', 05:35:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:35:49 INFO - 'tooltool.py': '/tools/tooltool.py', 05:35:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:35:49 INFO - '/tools/misc-python/virtualenv.py')}, 05:35:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:35:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:35:49 INFO - 'gecko_profile': False, 05:35:49 INFO - 'gecko_profile_interval': 0, 05:35:49 INFO - 'installer_path': 'installer.exe', 05:35:49 INFO - 'log_level': 'info', 05:35:49 INFO - 'log_name': 'talos', 05:35:49 INFO - 'log_to_console': True, 05:35:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:35:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:35:49 INFO - 'opt_config_files': (), 05:35:49 INFO - 'pip_index': False, 05:35:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:35:49 INFO - 'cmd': ('bash', 05:35:49 INFO - '-c', 05:35:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:35:49 INFO - 'enabled': True, 05:35:49 INFO - 'halt_on_failure': False, 05:35:49 INFO - 'name': 'check_screen_resolution'},), 05:35:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:35:49 INFO - 'cmd': ('bash', 05:35:49 INFO - '-c', 05:35:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:35:49 INFO - 'enabled': True, 05:35:49 INFO - 'halt_on_failure': False, 05:35:49 INFO - 'name': 'check_screen_resolution'},), 05:35:49 INFO - 'run_cmd_checks_enabled': True, 05:35:49 INFO - 'suite': 'dromaeojs-e10s', 05:35:49 INFO - 'system_bits': '32', 05:35:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:35:49 INFO - 'title': 't-yosemite-r7-0226', 05:35:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:35:49 INFO - 'use_talos_json': True, 05:35:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:35:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:35:49 INFO - 'work_dir': 'build'} 05:35:49 INFO - [mozharness: 2017-03-28 12:35:49.508298Z] Running clobber step. 05:35:49 INFO - Running pre-action listener: _resource_record_pre_action 05:35:49 INFO - Running main action method: clobber 05:35:49 INFO - rmtree: /builds/slave/test/build 05:35:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:35:51 INFO - Running post-action listener: _resource_record_post_action 05:35:51 INFO - [mozharness: 2017-03-28 12:35:51.910383Z] Finished clobber step (success) 05:35:51 INFO - [mozharness: 2017-03-28 12:35:51.910525Z] Running read-buildbot-config step. 05:35:51 INFO - Running pre-action listener: _resource_record_pre_action 05:35:51 INFO - Running main action method: read_buildbot_config 05:35:51 INFO - Using buildbot properties: 05:35:51 INFO - { 05:35:51 INFO - "project": "", 05:35:51 INFO - "product": "firefox", 05:35:51 INFO - "script_repo_revision": "production", 05:35:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:35:51 INFO - "repository": "", 05:35:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:35:51 INFO - "buildid": "20170328040421", 05:35:51 INFO - "pgo_build": "False", 05:35:51 INFO - "basedir": "/builds/slave/test", 05:35:51 INFO - "buildnumber": 32, 05:35:51 INFO - "slavename": "t-yosemite-r7-0226", 05:35:51 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:35:51 INFO - "platform": "yosemite_r7", 05:35:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:35:51 INFO - "branch": "mozilla-central", 05:35:51 INFO - "repo_path": "mozilla-central", 05:35:51 INFO - "revision": "d4af7ec6cfcd9b81cd1f433a00b412de61e95b62", 05:35:51 INFO - "stage_platform": "macosx64", 05:35:51 INFO - "builduid": "7c8430e0f80548e687b18f109917f99a", 05:35:51 INFO - "slavebuilddir": "test" 05:35:51 INFO - } 05:35:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:35:51 INFO - Running post-action listener: _resource_record_post_action 05:35:51 INFO - [mozharness: 2017-03-28 12:35:51.913777Z] Finished read-buildbot-config step (success) 05:35:51 INFO - [mozharness: 2017-03-28 12:35:51.913888Z] Running download-and-extract step. 05:35:51 INFO - Running pre-action listener: _resource_record_pre_action 05:35:51 INFO - Running main action method: download_and_extract 05:35:51 INFO - mkdir: /builds/slave/test/build/tests 05:35: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:51 INFO - trying https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:35:51 INFO - Downloading https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:35:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 05:35:52 INFO - Downloaded 1325 bytes. 05:35:52 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:35:52 INFO - Using the following test package requirements: 05:35:52 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:52 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:35:52 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:35:52 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:52 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:35:52 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:52 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:35:52 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:52 INFO - u'jsshell-mac.zip'], 05:35:52 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:52 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:35:52 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:35:52 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:52 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:35:52 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:52 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:35:52 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:52 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:35:52 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:52 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:35:52 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:35:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:35:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:35:52 INFO - Fetch https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:35:54 INFO - Expected file size: 52505097 05:35:54 INFO - Obtained file size: 52505097 05:36:10 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:36:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:36:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:36:10 INFO - Fetch https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:36:12 INFO - Expected file size: 52505097 05:36:12 INFO - Obtained file size: 52505097 05:36:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:36:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:36:27 INFO - Fetch https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:36:28 INFO - Expected file size: 14404171 05:36:28 INFO - Obtained file size: 14404171 05:36:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:29 INFO - trying https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:36:29 INFO - Downloading https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:36:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 05:36:31 INFO - Downloaded 75581100 bytes. 05:36:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:36:31 INFO - mkdir: /builds/slave/test/properties 05:36:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:36:31 INFO - Writing to file /builds/slave/test/properties/build_url 05:36:31 INFO - Contents: 05:36:31 INFO - build_url:https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:36:32 INFO - Running post-action listener: _resource_record_post_action 05:36:32 INFO - Running post-action listener: set_extra_try_arguments 05:36:32 INFO - [mozharness: 2017-03-28 12:36:32.472626Z] Finished download-and-extract step (success) 05:36:32 INFO - [mozharness: 2017-03-28 12:36:32.472753Z] Running populate-webroot step. 05:36:32 INFO - Running pre-action listener: _resource_record_pre_action 05:36:32 INFO - Running main action method: populate_webroot 05:36:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:36:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:36:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:36:32 INFO - '--tppagecycles', 05:36:32 INFO - '7'], 05:36:32 INFO - 'tests': ['tsvgm']}}, 05:36:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:36:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:36:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:36:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:36:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:36:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:36:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:36:32 INFO - 'talos_options': ['--disable-e10s'], 05:36:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:36:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:36:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:36:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:36:32 INFO - 'talos_options': ['--disable-e10s'], 05:36:32 INFO - 'tests': ['damp', 'tps']}, 05:36:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:36:32 INFO - 'tests': ['damp', 'tps']}, 05:36:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:36:32 INFO - 'tests': ['dromaeo_dom']}, 05:36:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:36:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:36:32 INFO - 'tests': ['basic_compositor_video']}, 05:36:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:36:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:36:32 INFO - 'tests': ['a11yr', 05:36:32 INFO - 'ts_paint', 05:36:32 INFO - 'tpaint', 05:36:32 INFO - 'sessionrestore', 05:36:32 INFO - 'sessionrestore_no_auto_restore', 05:36:32 INFO - 'tabpaint']}, 05:36:32 INFO - 'other-e10s': {'tests': ['a11yr', 05:36:32 INFO - 'ts_paint', 05:36:32 INFO - 'tpaint', 05:36:32 INFO - 'sessionrestore', 05:36:32 INFO - 'sessionrestore_no_auto_restore', 05:36:32 INFO - 'tabpaint']}, 05:36:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:36:32 INFO - 'ts_paint', 05:36:32 INFO - 'tpaint', 05:36:32 INFO - 'sessionrestore', 05:36:32 INFO - 'sessionrestore_no_auto_restore', 05:36:32 INFO - 'tabpaint']}, 05:36:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:36:32 INFO - 'ts_paint', 05:36:32 INFO - 'tpaint', 05:36:32 INFO - 'sessionrestore', 05:36:32 INFO - 'sessionrestore_no_auto_restore', 05:36:32 INFO - 'tabpaint']}, 05:36:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:36:32 INFO - 'tests': ['a11yr', 05:36:32 INFO - 'ts_paint', 05:36:32 INFO - 'tpaint', 05:36:32 INFO - 'sessionrestore', 05:36:32 INFO - 'sessionrestore_no_auto_restore', 05:36:32 INFO - 'tabpaint']}, 05:36:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:36:32 INFO - 'tests': ['a11yr', 05:36:32 INFO - 'ts_paint', 05:36:32 INFO - 'tpaint', 05:36:32 INFO - 'sessionrestore', 05:36:32 INFO - 'sessionrestore_no_auto_restore', 05:36:32 INFO - 'tabpaint']}, 05:36:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:36:32 INFO - 'tests': ['tsvgx', 05:36:32 INFO - 'tsvgr_opacity', 05:36:32 INFO - 'tart', 05:36:32 INFO - 'tscrollx', 05:36:32 INFO - 'cart', 05:36:32 INFO - 'tsvg_static']}, 05:36:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:36:32 INFO - 'tsvgr_opacity', 05:36:32 INFO - 'tart', 05:36:32 INFO - 'tscrollx', 05:36:32 INFO - 'cart', 05:36:32 INFO - 'tsvg_static']}, 05:36:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:36:32 INFO - 'talos_options': ['--disable-e10s'], 05:36:32 INFO - 'tests': ['tp5o']}, 05:36:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:36:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:36:32 INFO - 'talos_options': ['--disable-e10s', 05:36:32 INFO - '--xperf_path', 05:36:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:36:32 INFO - 'tests': ['tp5n']}, 05:36:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:36:32 INFO - 'talos_options': ['--xperf_path', 05:36:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:36:32 INFO - 'tests': ['tp5n']}}, 05:36:32 INFO - 'talos.zip': {'path': '', 05:36:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:36:32 INFO - Running post-action listener: _resource_record_post_action 05:36:32 INFO - [mozharness: 2017-03-28 12:36:32.490953Z] Finished populate-webroot step (success) 05:36:32 INFO - [mozharness: 2017-03-28 12:36:32.491085Z] Running create-virtualenv step. 05:36:32 INFO - Running pre-action listener: _resource_record_pre_action 05:36:32 INFO - Running main action method: create_virtualenv 05:36:32 INFO - Creating virtualenv /builds/slave/test/build/venv 05:36:32 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 05:36:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:36:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:36:32 INFO - Using real prefix '/tools/python27' 05:36:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:36:33 INFO - Installing distribute.............................................................................................................................................................................................done. 05:36:36 INFO - Installing pip.................done. 05:36:36 INFO - Return code: 0 05:36:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:36:36 INFO - Reading from file tmpfile_stdout 05:36:36 INFO - Output received: 05:36:36 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:36:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:36 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 0x108e6fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef404a1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c4dd30>, '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 0x108b74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c42be8>, '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.G36R2VMfjJ/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.JixhTXwQeE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 05:36:36 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 05:36:36 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 05:36:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G36R2VMfjJ/Render', 05:36:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:36 INFO - 'HOME': '/Users/cltbld', 05:36:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:36 INFO - 'LOGNAME': 'cltbld', 05:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:36 INFO - 'MOZ_NO_REMOTE': '1', 05:36:36 INFO - 'NO_EM_RESTART': '1', 05:36:36 INFO - 'PAGER': '/bin/cat', 05:36:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:36 INFO - 'PWD': '/builds/slave/test', 05:36:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:36 INFO - 'SHELL': '/bin/bash', 05:36:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JixhTXwQeE/Listeners', 05:36:36 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:36:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:36 INFO - 'USER': 'cltbld', 05:36:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:36 INFO - 'XPC_FLAGS': '0x0', 05:36:36 INFO - 'XPC_SERVICE_NAME': '0', 05:36:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:36 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 05:36:36 INFO - Cleaning up... 05:36:36 INFO - Return code: 0 05:36:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:36 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 0x108e6fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef404a1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c4dd30>, '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 0x108b74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c42be8>, '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.G36R2VMfjJ/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.JixhTXwQeE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 05:36:36 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 05:36:36 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 05:36:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G36R2VMfjJ/Render', 05:36:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:36 INFO - 'HOME': '/Users/cltbld', 05:36:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:36 INFO - 'LOGNAME': 'cltbld', 05:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:36 INFO - 'MOZ_NO_REMOTE': '1', 05:36:36 INFO - 'NO_EM_RESTART': '1', 05:36:36 INFO - 'PAGER': '/bin/cat', 05:36:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:36 INFO - 'PWD': '/builds/slave/test', 05:36:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:36 INFO - 'SHELL': '/bin/bash', 05:36:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JixhTXwQeE/Listeners', 05:36:36 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:36:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:36 INFO - 'USER': 'cltbld', 05:36:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:36 INFO - 'XPC_FLAGS': '0x0', 05:36:36 INFO - 'XPC_SERVICE_NAME': '0', 05:36:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:36 INFO - Downloading/unpacking psutil>=3.1.1 05:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:36:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:36:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:36:40 INFO - Installing collected packages: psutil 05:36:40 INFO - Running setup.py install for psutil 05:36:40 INFO - building 'psutil._psutil_osx' extension 05:36:40 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 05:36:41 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 05:36:41 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 05:36:41 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 05:36:41 INFO - building 'psutil._psutil_posix' extension 05:36:41 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 05:36:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:36:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:36:41 INFO - ^ 05:36:41 INFO - 1 warning generated. 05:36:41 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 05:36:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:36:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:36:41 INFO - Successfully installed psutil 05:36:41 INFO - Cleaning up... 05:36:41 INFO - Return code: 0 05:36:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:36:41 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:41 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:41 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 0x108e6fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef404a1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c4dd30>, '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 0x108b74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c42be8>, '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.G36R2VMfjJ/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.JixhTXwQeE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 05:36:41 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 05:36:41 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 05:36:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G36R2VMfjJ/Render', 05:36:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:41 INFO - 'HOME': '/Users/cltbld', 05:36:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:41 INFO - 'LOGNAME': 'cltbld', 05:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:41 INFO - 'MOZ_NO_REMOTE': '1', 05:36:41 INFO - 'NO_EM_RESTART': '1', 05:36:41 INFO - 'PAGER': '/bin/cat', 05:36:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:41 INFO - 'PWD': '/builds/slave/test', 05:36:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:41 INFO - 'SHELL': '/bin/bash', 05:36:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JixhTXwQeE/Listeners', 05:36:41 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:36:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:41 INFO - 'USER': 'cltbld', 05:36:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:41 INFO - 'XPC_FLAGS': '0x0', 05:36:41 INFO - 'XPC_SERVICE_NAME': '0', 05:36:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:36:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:36:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:36:45 INFO - Installing collected packages: mozsystemmonitor 05:36:45 INFO - Running setup.py install for mozsystemmonitor 05:36:45 INFO - Successfully installed mozsystemmonitor 05:36:45 INFO - Cleaning up... 05:36:45 INFO - Return code: 0 05:36:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:36:45 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:45 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:45 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108e6fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef404a1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c4dd30>, '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 0x108b74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c42be8>, '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.G36R2VMfjJ/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.JixhTXwQeE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 05:36:45 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==2.5.1'] in /builds/slave/test/build 05:36:45 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==2.5.1 05:36:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G36R2VMfjJ/Render', 05:36:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:45 INFO - 'HOME': '/Users/cltbld', 05:36:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:45 INFO - 'LOGNAME': 'cltbld', 05:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:45 INFO - 'MOZ_NO_REMOTE': '1', 05:36:45 INFO - 'NO_EM_RESTART': '1', 05:36:45 INFO - 'PAGER': '/bin/cat', 05:36:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:45 INFO - 'PWD': '/builds/slave/test', 05:36:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:45 INFO - 'SHELL': '/bin/bash', 05:36:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JixhTXwQeE/Listeners', 05:36:45 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:36:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:45 INFO - 'USER': 'cltbld', 05:36:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:45 INFO - 'XPC_FLAGS': '0x0', 05:36:45 INFO - 'XPC_SERVICE_NAME': '0', 05:36:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:45 INFO - Downloading/unpacking jsonschema==2.5.1 05:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:36:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:36:48 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 05:36:48 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 05:36:48 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 05:36:48 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 05:36:48 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 05:36:48 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 05:36:49 INFO - Downloading functools32-3.2.3-2.tar.gz 05:36:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:36:49 INFO - warning: no files found matching '*.txt' 05:36:49 INFO - no previously-included directories found matching 'build' 05:36:49 INFO - no previously-included directories found matching 'dist' 05:36:49 INFO - no previously-included directories found matching '.git*' 05:36:49 INFO - Installing collected packages: jsonschema, functools32 05:36:49 INFO - Running setup.py install for functools32 05:36:49 INFO - warning: no files found matching '*.txt' 05:36:49 INFO - no previously-included directories found matching 'build' 05:36:49 INFO - no previously-included directories found matching 'dist' 05:36:49 INFO - no previously-included directories found matching '.git*' 05:36:49 INFO - Successfully installed jsonschema functools32 05:36:49 INFO - Cleaning up... 05:36:49 INFO - Return code: 0 05:36:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:36:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:49 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108e6fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef404a1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c4dd30>, '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 0x108b74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c42be8>, '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.G36R2VMfjJ/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.JixhTXwQeE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 05:36:49 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:36:49 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 functools32==3.2.3-2 05:36:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G36R2VMfjJ/Render', 05:36:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:49 INFO - 'HOME': '/Users/cltbld', 05:36:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:49 INFO - 'LOGNAME': 'cltbld', 05:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:49 INFO - 'MOZ_NO_REMOTE': '1', 05:36:49 INFO - 'NO_EM_RESTART': '1', 05:36:49 INFO - 'PAGER': '/bin/cat', 05:36:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:49 INFO - 'PWD': '/builds/slave/test', 05:36:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:49 INFO - 'SHELL': '/bin/bash', 05:36:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JixhTXwQeE/Listeners', 05:36:49 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:36:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:49 INFO - 'USER': 'cltbld', 05:36:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:49 INFO - 'XPC_FLAGS': '0x0', 05:36:49 INFO - 'XPC_SERVICE_NAME': '0', 05:36:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:36:49 INFO - Cleaning up... 05:36:49 INFO - Return code: 0 05:36:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:36:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:49 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 0x108e6fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef404a1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c4dd30>, '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 0x108b74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c42be8>, '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.G36R2VMfjJ/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.JixhTXwQeE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 05:36:49 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 05:36:49 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 05:36:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G36R2VMfjJ/Render', 05:36:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:49 INFO - 'HOME': '/Users/cltbld', 05:36:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:49 INFO - 'LOGNAME': 'cltbld', 05:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:49 INFO - 'MOZ_NO_REMOTE': '1', 05:36:49 INFO - 'NO_EM_RESTART': '1', 05:36:49 INFO - 'PAGER': '/bin/cat', 05:36:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:49 INFO - 'PWD': '/builds/slave/test', 05:36:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:49 INFO - 'SHELL': '/bin/bash', 05:36:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JixhTXwQeE/Listeners', 05:36:49 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:36:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:49 INFO - 'USER': 'cltbld', 05:36:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:49 INFO - 'XPC_FLAGS': '0x0', 05:36:49 INFO - 'XPC_SERVICE_NAME': '0', 05:36:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:49 INFO - Downloading/unpacking blobuploader==1.2.4 05:36:49 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 05:36:49 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 05:36:49 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 05:36:49 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 05:36:49 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 05:36:49 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 05:36:52 INFO - Downloading blobuploader-1.2.4.tar.gz 05:36:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:36:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:36:52 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 05:36:52 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 05:36:52 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 05:36:52 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 05:36:52 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 05:36:52 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 05:36:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:36:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:54 INFO - Downloading docopt-0.6.1.tar.gz 05:36:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:36:54 INFO - Installing collected packages: blobuploader, requests, docopt 05:36:54 INFO - Running setup.py install for blobuploader 05:36:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:36:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:36:54 INFO - Running setup.py install for requests 05:36:54 INFO - Running setup.py install for docopt 05:36:54 INFO - Successfully installed blobuploader requests docopt 05:36:54 INFO - Cleaning up... 05:36:54 INFO - Return code: 0 05:36:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:54 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 0x108e6fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef404a1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c4dd30>, '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 0x108b74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c42be8>, '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.G36R2VMfjJ/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.JixhTXwQeE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 05:36:54 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 05:36:54 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 05:36:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G36R2VMfjJ/Render', 05:36:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:54 INFO - 'HOME': '/Users/cltbld', 05:36:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:54 INFO - 'LOGNAME': 'cltbld', 05:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:54 INFO - 'MOZ_NO_REMOTE': '1', 05:36:54 INFO - 'NO_EM_RESTART': '1', 05:36:54 INFO - 'PAGER': '/bin/cat', 05:36:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:54 INFO - 'PWD': '/builds/slave/test', 05:36:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:54 INFO - 'SHELL': '/bin/bash', 05:36:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JixhTXwQeE/Listeners', 05:36:54 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:36:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:54 INFO - 'USER': 'cltbld', 05:36:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:54 INFO - 'XPC_FLAGS': '0x0', 05:36:54 INFO - 'XPC_SERVICE_NAME': '0', 05:36:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:36:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-KfGhdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:36:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-xQxonr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:36:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-vbaovS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:36:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-RuWrgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:36:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-mqxrjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:36:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-rPBHb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:36:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-TCkbmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:36:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-WJejW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:36:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-z6f1Gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:36:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Exi18q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:36:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-32Ic8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:36:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-95stFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:36:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-bjKlbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:36:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-zg3l2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:36:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-JtXIrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:36:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-CBJslS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:36:56 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-gMwpco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:36:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:36:56 INFO - Running setup.py install for manifestparser 05:36:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:36:57 INFO - Running setup.py install for mozcrash 05:36:57 INFO - Running setup.py install for mozdebug 05:36:57 INFO - Running setup.py install for mozdevice 05:36:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:36:57 INFO - Running setup.py install for mozfile 05:36:57 INFO - Running setup.py install for mozhttpd 05:36:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:36:57 INFO - Running setup.py install for mozinfo 05:36:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:36:57 INFO - Running setup.py install for mozInstall 05:36:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:36:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:36:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:36:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:36:58 INFO - Running setup.py install for mozleak 05:36:58 INFO - Running setup.py install for mozlog 05:36:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:36:58 INFO - Running setup.py install for moznetwork 05:36:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:36:58 INFO - Running setup.py install for mozprocess 05:36:58 INFO - Running setup.py install for mozprofile 05:36:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:36:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:36:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:36:58 INFO - Running setup.py install for mozrunner 05:36:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:36:59 INFO - Running setup.py install for mozscreenshot 05:36:59 INFO - Running setup.py install for moztest 05:36:59 INFO - Running setup.py install for mozversion 05:36:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:36:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:36:59 INFO - Cleaning up... 05:36:59 INFO - Return code: 0 05:36:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:59 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 0x108e6fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef404a1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c4dd30>, '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 0x108b74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c42be8>, '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.G36R2VMfjJ/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.JixhTXwQeE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 05:36:59 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 05:36:59 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 05:36:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G36R2VMfjJ/Render', 05:36:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:59 INFO - 'HOME': '/Users/cltbld', 05:36:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:59 INFO - 'LOGNAME': 'cltbld', 05:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:59 INFO - 'MOZ_NO_REMOTE': '1', 05:36:59 INFO - 'NO_EM_RESTART': '1', 05:36:59 INFO - 'PAGER': '/bin/cat', 05:36:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:59 INFO - 'PWD': '/builds/slave/test', 05:36:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:59 INFO - 'SHELL': '/bin/bash', 05:36:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JixhTXwQeE/Listeners', 05:36:59 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:36:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:59 INFO - 'USER': 'cltbld', 05:36:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:59 INFO - 'XPC_FLAGS': '0x0', 05:36:59 INFO - 'XPC_SERVICE_NAME': '0', 05:36:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:36:59 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-F5Vqtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:36:59 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)) 05:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:36:59 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-IquJ8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 05:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:37:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Xx1zAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:37:00 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)) 05:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:37:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-6d13Gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 05:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:37:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ZbX_I7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:37:00 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)) 05:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:37:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-EBWsPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:37:00 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)) 05:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:37:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-WSFbEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:37:00 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)) 05:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:37:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-OTG4dP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:37:00 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)) 05:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:37:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-sZN34t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:37:00 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)) 05:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:37:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-4zxVav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 05:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:37:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-k5D_5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:37:00 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)) 05:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:37:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-LBhLtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:37:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 05:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:37:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-6NXWix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:37:01 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)) 05:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:37:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-JeZJ8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:37:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 05:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:37:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-A1Mo1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:37:01 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)) 05:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:37:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-h8WhCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:37:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 05:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:37:01 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ZmZiO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:37:01 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)) 05:37:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:37:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:37:01 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)) 05:37:01 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:37:01 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:37:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:37:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:37:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:37:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:37:04 INFO - Downloading blessings-1.6.tar.gz 05:37:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:37:04 INFO - Installing collected packages: blessings 05:37:04 INFO - Running setup.py install for blessings 05:37:04 INFO - Successfully installed blessings 05:37:04 INFO - Cleaning up... 05:37:04 INFO - Return code: 0 05:37:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:37:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:37:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:37:04 INFO - Reading from file tmpfile_stdout 05:37:04 INFO - Current package versions: 05:37:04 INFO - blessings == 1.6 05:37:04 INFO - blobuploader == 1.2.4 05:37:04 INFO - docopt == 0.6.1 05:37:04 INFO - functools32 == 3.2.3-2 05:37:04 INFO - jsonschema == 2.5.1 05:37:04 INFO - manifestparser == 1.1 05:37:04 INFO - mozInstall == 1.12 05:37:04 INFO - mozcrash == 1.0 05:37:04 INFO - mozdebug == 0.1 05:37:04 INFO - mozdevice == 0.49 05:37:04 INFO - mozfile == 1.2 05:37:04 INFO - mozhttpd == 0.7 05:37:04 INFO - mozinfo == 0.9 05:37:04 INFO - mozleak == 0.1 05:37:04 INFO - mozlog == 3.4 05:37:04 INFO - moznetwork == 0.27 05:37:04 INFO - mozprocess == 0.25 05:37:04 INFO - mozprofile == 0.28 05:37:04 INFO - mozrunner == 6.13 05:37:04 INFO - mozscreenshot == 0.1 05:37:04 INFO - mozsystemmonitor == 0.3 05:37:04 INFO - moztest == 0.8 05:37:04 INFO - mozversion == 1.4 05:37:04 INFO - psutil == 3.1.1 05:37:04 INFO - requests == 1.2.3 05:37:04 INFO - wsgiref == 0.1.2 05:37:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:04 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 0x108e6fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef404a1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c4dd30>, '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 0x108b74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c42be8>, '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.G36R2VMfjJ/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.JixhTXwQeE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 05:37:04 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 05:37:04 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 05:37:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G36R2VMfjJ/Render', 05:37:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:05 INFO - 'HOME': '/Users/cltbld', 05:37:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:05 INFO - 'LOGNAME': 'cltbld', 05:37:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:05 INFO - 'MOZ_NO_REMOTE': '1', 05:37:05 INFO - 'NO_EM_RESTART': '1', 05:37:05 INFO - 'PAGER': '/bin/cat', 05:37:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:05 INFO - 'PWD': '/builds/slave/test', 05:37:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:05 INFO - 'SHELL': '/bin/bash', 05:37:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JixhTXwQeE/Listeners', 05:37:05 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:37:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:05 INFO - 'USER': 'cltbld', 05:37:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:05 INFO - 'XPC_FLAGS': '0x0', 05:37:05 INFO - 'XPC_SERVICE_NAME': '0', 05:37:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 INFO - Cleaning up... 05:37:05 INFO - Return code: 0 05:37:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:37:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:05 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 0x108e6fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e72768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef404a1ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c4dd30>, '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 0x108b74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c42be8>, '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.G36R2VMfjJ/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.JixhTXwQeE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 05:37:05 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 05:37:05 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 05:37:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G36R2VMfjJ/Render', 05:37:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:05 INFO - 'HOME': '/Users/cltbld', 05:37:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:05 INFO - 'LOGNAME': 'cltbld', 05:37:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:05 INFO - 'MOZ_NO_REMOTE': '1', 05:37:05 INFO - 'NO_EM_RESTART': '1', 05:37:05 INFO - 'PAGER': '/bin/cat', 05:37:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:05 INFO - 'PWD': '/builds/slave/test', 05:37:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:05 INFO - 'SHELL': '/bin/bash', 05:37:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JixhTXwQeE/Listeners', 05:37:05 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:37:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:05 INFO - 'USER': 'cltbld', 05:37:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:05 INFO - 'XPC_FLAGS': '0x0', 05:37:05 INFO - 'XPC_SERVICE_NAME': '0', 05:37:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:37:05 INFO - Cleaning up... 05:37:05 INFO - Return code: 0 05:37:05 INFO - Running post-action listener: _resource_record_post_action 05:37:05 INFO - Running post-action listener: _start_resource_monitoring 05:37:05 INFO - Starting resource monitoring. 05:37:05 INFO - [mozharness: 2017-03-28 12:37:05.559986Z] Finished create-virtualenv step (success) 05:37:05 INFO - [mozharness: 2017-03-28 12:37:05.560936Z] Running install step. 05:37:05 INFO - Running pre-action listener: _resource_record_pre_action 05:37:05 INFO - Running main action method: install 05:37:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:37:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:37:05 INFO - Reading from file tmpfile_stdout 05:37:05 INFO - Detecting whether we're running mozinstall >=1.0... 05:37:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:37:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:37:05 INFO - Reading from file tmpfile_stdout 05:37:05 INFO - Output received: 05:37:05 INFO - Usage: mozinstall [options] installer 05:37:05 INFO - Options: 05:37:05 INFO - -h, --help show this help message and exit 05:37:05 INFO - -d DEST, --destination=DEST 05:37:05 INFO - Directory to install application into. [default: 05:37:05 INFO - "/builds/slave/test"] 05:37:05 INFO - --app=APP Application being installed. [default: firefox] 05:37:05 INFO - mkdir: /builds/slave/test/build/application 05:37:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:37:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:37:26 INFO - Reading from file tmpfile_stdout 05:37:26 INFO - Output received: 05:37:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:37:26 INFO - Running post-action listener: _resource_record_post_action 05:37:26 INFO - [mozharness: 2017-03-28 12:37:26.879731Z] Finished install step (success) 05:37:26 INFO - [mozharness: 2017-03-28 12:37:26.879845Z] Running run-tests step. 05:37:26 INFO - Running pre-action listener: _resource_record_pre_action 05:37:26 INFO - Running main action method: run_tests 05:37:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:37:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:37:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:37:26 INFO - 2017-03-28 05:37:26.894 screenresolution[1625:7464] starting screenresolution argv=screenresolution get 05:37:26 INFO - 2017-03-28 05:37:26.897 screenresolution[1625:7464] Display 0: 1600x1200x32@60 05:37:26 INFO - 2017-03-28 05:37:26.902 screenresolution[1626:7467] starting screenresolution argv=screenresolution list 05:37:26 INFO - Available Modes on Display 0 05:37:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:37:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:37:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:37:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:37:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:37:27 INFO - Intel Iris: 05:37:27 INFO - Chipset Model: Intel Iris 05:37:27 INFO - Type: GPU 05:37:27 INFO - Bus: Built-In 05:37:27 INFO - VRAM (Dynamic, Max): 1536 MB 05:37:27 INFO - Vendor: Intel (0x8086) 05:37:27 INFO - Device ID: 0x0a2e 05:37:27 INFO - Revision ID: 0x0009 05:37:27 INFO - Displays: 05:37:27 INFO - Display: 05:37:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:37:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:37:27 INFO - Display Serial Number: 1600x1200 60 05:37:27 INFO - Main Display: Yes 05:37:27 INFO - Mirror: Off 05:37:27 INFO - Online: Yes 05:37:27 INFO - Rotation: Supported 05:37:27 INFO - Return code: 0 05:37:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:37:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:37:27 INFO - Python 2.7.3 05:37:27 INFO - Return code: 0 05:37:27 INFO - grabbing minidump binary from tooltool 05:37:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:27 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 0x108c4dd30>, '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 0x108b74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c42be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:37:27 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 05:37:27 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 05:37:27 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 05:37:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:37:27 INFO - Return code: 0 05:37:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:37:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:37:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:37:27 INFO - ENV: RUST_BACKTRACE is now 1 05:37:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:37:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:37:27 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/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 05:37:27 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/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0226 --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 05:37:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G36R2VMfjJ/Render', 05:37:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:27 INFO - 'HOME': '/Users/cltbld', 05:37:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:27 INFO - 'LOGNAME': 'cltbld', 05:37:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:37:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:37:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:27 INFO - 'MOZ_NO_REMOTE': '1', 05:37:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:37:27 INFO - 'NO_EM_RESTART': '1', 05:37:27 INFO - 'PAGER': '/bin/cat', 05:37:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:27 INFO - 'PWD': '/builds/slave/test', 05:37:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:37:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:27 INFO - 'RUST_BACKTRACE': '1', 05:37:27 INFO - 'SHELL': '/bin/bash', 05:37:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JixhTXwQeE/Listeners', 05:37:27 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:37:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:27 INFO - 'USER': 'cltbld', 05:37:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:27 INFO - 'XPC_FLAGS': '0x0', 05:37:27 INFO - 'XPC_SERVICE_NAME': '0', 05:37:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:27 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/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 05:37:27 INFO - mozversion application_buildid: 20170328040421 05:37:27 INFO - mozversion application_changeset: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 05:37:27 INFO - mozversion application_display_name: Nightly 05:37:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:37:27 INFO - mozversion application_name: Firefox 05:37:27 INFO - mozversion application_remotingname: firefox 05:37:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:37:27 INFO - mozversion application_vendor: Mozilla 05:37:27 INFO - mozversion application_version: 55.0a1 05:37:27 INFO - mozversion platform_buildid: 20170328040421 05:37:27 INFO - mozversion platform_changeset: d4af7ec6cfcd9b81cd1f433a00b412de61e95b62 05:37:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:37:27 INFO - mozversion platform_version: 55.0a1 05:37:27 INFO - using testdate: 1490704647 05:37:27 INFO - actual date: 1490704647 05:37:27 INFO - starting webserver on 'localhost:49236' 05:37:27 INFO - SUITE-START | Running 2 tests 05:37:27 INFO - TEST-START | dromaeo_css 05:37:27 INFO - operating with platform_type : mac_ 05:37:27 INFO - Initialising browser for dromaeo_css test... 05:37:27 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpIHzCkK/profile http://localhost:49236/getInfo.html) 05:37:30 INFO - PID 1636 | 2017-03-28 05:37:30.329 plugin-container[1638:7644] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 05:37:30 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:37:30 INFO - PID 1636 | 2017-03-28 05:37:30.334 plugin-container[1638:7644] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1b, name = 'com.apple.CFPasteboardClient' 05:37:30 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:37:30 INFO - PID 1636 | 2017-03-28 05:37:30.334 plugin-container[1638:7644] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:37:30 INFO - PID 1636 | __metrics Screen width/height:1600/1200 05:37:30 INFO - PID 1636 | colorDepth:24 05:37:30 INFO - PID 1636 | Browser inner width/height: 1024/689 05:37:30 INFO - PID 1636 | __metrics 05:37:30 ERROR - PID 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 05:37:30 ERROR - PID 1636 | 1490704650576 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpIHzCkK/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpIHzCkK/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpIHzCkK/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpIHzCkK/profile/extensions/talos-powers@TalosPowersService.js:55:9 05:37:31 INFO - TEST-INFO | 1636: exit 0 05:37:31 INFO - Browser initialized. 05:37:31 INFO - Running cycle 1/1 for dromaeo_css test... 05:37:31 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpIHzCkK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:34 INFO - PID 1639 | 2017-03-28 05:37:34.041 plugin-container[1640:7768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 05:37:34 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:37:34 INFO - PID 1639 | 2017-03-28 05:37:34.042 plugin-container[1640:7768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 05:37:34 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:37:34 INFO - PID 1639 | 2017-03-28 05:37:34.042 plugin-container[1640:7768] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:37:43 INFO - PID 1639 | RSS: Main: 228167680 05:37:43 INFO - PID 1639 | 05:40:48 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 05:40:49 INFO - PID 1639 | RSS: Main: 251154432 05:40:49 INFO - PID 1639 | 05:42:31 INFO - PID 1639 | ************************* 05:42:31 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 05:42:31 INFO - PID 1639 | 05:42:31 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:42:31 INFO - PID 1639 | Full stack: 05:42:31 INFO - PID 1639 | ************************* 05:42:31 INFO - PID 1639 | ************************* 05:42:31 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 05:42:31 INFO - PID 1639 | 05:42:31 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:42:31 INFO - PID 1639 | Full stack: 05:42:31 INFO - PID 1639 | ************************* 05:42:31 INFO - PID 1639 | ************************* 05:42:31 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 05:42:31 INFO - PID 1639 | 05:42:31 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:42:31 INFO - PID 1639 | Full stack: 05:42:31 INFO - PID 1639 | ************************* 05:42:31 INFO - PID 1639 | ************************* 05:42:31 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 05:42:31 INFO - PID 1639 | 05:42:31 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:42:31 INFO - PID 1639 | Full stack: 05:42:31 INFO - PID 1639 | ************************* 05:43:52 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 05:43:52 INFO - PID 1639 | RSS: Main: 259440640 05:43:52 INFO - PID 1639 | 05:46:54 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 05:46:55 INFO - PID 1639 | RSS: Main: 270880768 05:46:55 INFO - PID 1639 | 05:49:57 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 05:49:58 INFO - PID 1639 | RSS: Main: 263811072 05:49:58 INFO - PID 1639 | 05:53:01 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 05:53:01 INFO - PID 1639 | RSS: Main: 273702912 05:53:01 INFO - PID 1639 | 05:56:04 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 05:56:05 INFO - PID 1639 | RSS: Main: 267714560 05:56:05 INFO - PID 1639 | 05:56:05 INFO - PID 1639 | __start_tp_report 05:56:05 INFO - PID 1639 | _x_x_mozilla_page_load 05:56:05 INFO - PID 1639 | _x_x_mozilla_page_load_details 05:56:05 INFO - PID 1639 | |i|pagename|runs| 05:56:05 INFO - PID 1639 | |0;dojo.html;3441.9311613767727;3531.081918701137;3552.964470355299;3576.839042243099;3588.443791212363;5886.205362276091;6088.969555152224;6100.054491553817;6128.387161283875;6169.876602467948;6116.092308610896;6118.418750218733;6164.013757798753;6191.597546159496;6203.596766210193;5580.079286917654;5743.913841292384;5799.942000580004;5847.122931560258;5893.292804863401;5631.099024156136;5839.328477225131;5882.205902203191;5903.970480147594;5925.5555833312455;4843.200871856139;5684.857878553028;5822.534197264209;5851.882962340751;5904.911426328608;5747.2241247431475;5806.477417032445;5812.302523697141;5832.620879642852;5874.97062514689;5641.774329026834;5655.123455864347;5686.488216060575;5707.800226992035;5758.078707406837;6049.334573196945;6118.204633397674;6125.78559750411;6133.754649814002;6188.84527886802;6505.284418713938;6624.403803657646;6656.667166641697;6667.533272670915;6691.531592788507;6769.289224631441;6778.084958530584;6799.218089919673;6810.250872404032;6811.4550835933005;6219.098230756515;6278.591891527128;6291.559590828644;6301.897167995582;6303.054541818672;6629.171353580802;6730.697118629673;6786.219584747817;6831.6242606656115;6859.657017149123;5515.944840551623;5956.404359564009;6306.842328941813;6396.200474940632;6497.15536980199;5741.971290143606;6667.86664266712;6694.966525167343;6763.526553141233;6774.187097548326;5291.100512912814;6675.165604299463;6675.599464032173;6676.799696009128;6719.395254427125;6444.7744328948265;6492.123563318986;6589.275179730226;6629;6647.401733843977;5389.380221274525;5393.6224464287125;5394.595405344537;5425.104857698515;5435.537979271806;4701.8354357598;4713.222318317507;4727.527247275245;4728.905421891612;4744.051189762062;6131.754729810757;6154.846128846814;6161.907571386433;6180.47465965389;6206.162168107338;5649.4633009864;6714.462842972551;6746.561473504305;6758.256591774901;6791.660416979132;6140.907886381708;6141.96929015361;6194.4115309045565;6207.503399728011;6215.595986260879;4840.951590484121;5134.7946082156295;5843.357230704534;6016.7292471838;6019.127226552126;6629.668516574248;6733.696983635846;6760.898586521304;6793.116894803644;6818.897716534355;5626.409227031103;6725.764598239038;6752.966235168793;6767.86464270702;6769.559978601375;6692.330766923269;6765.255821859493;6803.319668033355;6826.385625293748;6833.521653484408;6385.904211436739;6454.935450645434;6578.8355291118105;6627;6665.266820649822;6650.201975762746;6717.395434410926;6722.596644201363;6760.830979225362;6770.52606317553;5456.809011684572;6217.875642487034;6253.3121356651645;6326.683665816783;6530.902036469552;6352.777652782131;6375.266844312963;6540.476761858945;6561.081448597105;6644.169478815148;5408.918866217088;6181.072839074175;6217.813465596039;6357.774181501307;6416.101745755504;6457;6463.61218326883;6467.514936379696;6551.377619126175;6574.243961944438;5775.700467394802;6000.69996500182;6349.238091429058;6349.746010159542;6366.204224471941 05:56:05 INFO - PID 1639 | |1;ext.html;698.3540225291606;1090.144236774132;1108.2463924531319;1203.1758245601768;1283.7432513497304;37073.258534829285;37764.43353349702;37797.811010944904;37808.432873506885;38000.61999380006;37520.62479375205;37884.62115378845;37921;38009.85970420885;38047.61952380475;7990.440669153147;8153.633086511091;8529.147085291483;8814.603342849587;8836;8735.95632021839;8835.46987180768;8841.95579022104;8855.15875991783;8877.95561022194;8770.473771573694;8865.157810008039;8936.865947010767;8956.552172391355;8976.416532925372;8880.73357799267;8899.02110767815;8909.153630405166;8915.197632213132;8923.955380223122;8674.479531228084;8694.000189978171;8754.343424243143;8762.12378762125;8795.516246606434;39301.60698393037;39357.60642393568;39507;39537.20925581472;39674.80975570738;2774.4451109777924;2975.0777259049764;3049.259564988221;3064.4637188491924;3064.5709600655705;28567.142985710463;28709.856450717612;28717.712822871712;28846.42307153845;29688.40623187546;2702.2028501592076;2789.246903336108;2790.441911617664;2816.0143949617673;2833.6457942757156;352399.2380038109;352953;355432;355680;360874;38400.42399364012;39690.60309396927;39777.60222397797;47730.28404573934;51457.742711286955;40234;50538.736531587005;50589.74705126451;50721.23918141231;50925;11685.948264656221;12849.293288869016;13494.797578036338;14396.712065758626;14472.914531410186;11440.485178167002;11620.302781833117;11628.709282268012;11630.94184529072;11770.058395328353;14095.013349065466;14118.294085295694;14140.080894741808;14176.503822366167;14273.64315892111;5341.851501927026;6164.630122192593;6195.636959868822;6196.8760624787255;6207.193064901624;865.8095219051798;983.4885859353096;991.306085739985;1003;1020.8476699226272;24479;24502;24622.261332160066;24769.62845557318;24976.37559061038;1011.9139873110868;1097.6048622495896;1115.1524841120645;1135.9488823002985;1151.717829131868;12456.81314780279;12721.809172862415;12729.55446559366;12742;12782.616521504184;454935.7253213713;456763;456869;457118;457858;54518.72740636271;54859;55171.72414137904;55284.72357638186;55294.44705552974;1456.424712238681;1489.3319482180027;1494.3873012065;1530.7091652585973;1542.8071491063617;22000.229991950204;23875.758416941117;23997.640035399134;24163.15428959978;24252.51494970126;39692.20615587614;40096.1980760389;40153.79923100366;40244.3963340556;40300.3954940682;420331;420455;420473;420879;421517;33243.833780830944;33420.49869251915;33820;33917.49123763096;33934.66065339414;42406.7277981661;43190.13619727651;43372;44341.77829110834;44463.33305000492;29659.851700741357;30587.929422470108;30600.387992240478;31068.84465577748;31169.688303117586;29399.853000735715;29872.402551948406;30541.54187687142;30617.693823061483;30809.8459507701;35578.644213557534;36081.639183607826;36443.3600487984;36448.63551364453;36549.08627284339;30694.539581905843;30899.227519312197;31202.843985779928;31262.21648994943;31551.053468395985;24241;24258.878705607065;24291.149809756575;24330.14844480435;24436.51126977415 05:56:05 INFO - PID 1639 | |2;jquery.html;383.5091083413231;415.56158253043026;421.33849855726504;425.9063006138649;442.80516572708007;19374.19074234235;21073.57852842942;21842.563148737016;22517.54964900701;22689.773102268973;17542.64914701705;21862.781372186273;22431.102755889744;22551.887240563774;22659.886700566516;6180.690965451732;6399.968000159993;6433.710483028275;6454.419102280794;6484.027395890607;6094.908576371358;6267.623942563438;6319.936800632004;6362.395572420613;6367.554271201017;6261.655608941506;6298.905516417258;6326.65203413812;6332.651704156269;6334.271558770732;6073.574849760518;6311.526635502356;6362.840928976766;6379.298277189506;6387.169667943161;5556.638818476786;5582.9950608890385;5593.496585307342;5594.916076258859;5655.557832752663;19641.41075767729;23389.76610233893;24272.757272427225;27861.16416507488;29179.562306565418;1902.4298361273188;1917.3960202536157;1920.4238728381429;1956.3544030469911;1984.1666500070005;15468.690626187527;16601.50195494137;16681.749773753403;16869.24088416024;17360.39238626642;1635.4112519492974;1671.4901954903748;1715.305301352954;1725.775649165613;1765.7174852023616;119643;131933;158741;160018.19990900086;165873.17063414727;11553.653390398302;11889.989350905096;12896.548620798414;12899.742005160031;12957;15877.285522151531;16195.757063644054;16202.837971620369;16224.432144875109;16225.51323460298;8753;9100;9123.17891389765;9152.496612686366;9261.67584134552;8744.125587441333;8774.956125219333;8800.603972821238;8949.552522373855;8965.327600429864;7765.495242809199;7819.139894611588;7832.921670783333;7855.607219638995;7908.604569771604;2104.515961328903;2123.7363768557684;2161.3407910587107;2215.0696707382617;2215.080741492263;879.2789912271876;918.6922381002305;923.2337160157053;945.9952700236593;962.3023308101571;19311.137772445567;20326.491837704176;20688.58622827535;20985.265515706877;21172.420674516125;953.6376177052675;977.2572844638127;978.8042391521724;979.8236317462926;981.1022914033722;8969.641214351353;9985.700428987142;10197.235207359477;10753.892461075446;10843.783124337628;681204;684917;691821;707997;708317;130564;134357;147210.7918381225;157407;159751;1162.4652659776427;1180.2918249050672;1200.327816422806;1202.837616921722;1209.4617895036624;17905;18275.45173644793;18328;18387.632247355246;18402.539936501693;52360.21459678184;62388;63827.680861597255;65066;65274;87645.56177219073;88367.55816221135;89307.55346223227;89961;90788.5460572693;8349.290310323691;8496.745097647081;8610.311175105851;8656.26421754158;8918.598663060047;13206;13290.667733306746;13505.32473376347;13695.520656776966;14016.229107399191;10089.394636321844;10767.946160269148;11505.906938840797;11595.8260626089;11704.414779260833;8995;10460.424676642857;10627.946860265649;10763.730906727395;11172.720681983015;10263.692089237335;10271.794564108826;11046;11438.656840294805;11522.711932201426;7389.556626602206;8260.463069900437;8577.35669824778;8606.483610983361;8725.345599079967;10801.513931872927;11480;11719.707007324885;11744.17790754639;11791.115666324888 05:56:05 INFO - PID 1639 | |3;mootools.html;1370.3490841850116;1426.4365575597637;1469.3975470057278;1487.256371814093;1497.9625509362265;10233.795324093513;10671.306365086273;10724.195685323612;10887.891121088776;11062.004419602234;10744.409057501834;10856.565737370496;10905.509252083655;10931.56273749049;11023.283486573368;2452.4236804350962;2485.291691867818;2486.3286912533604;2491.8754062296903;2498.025769949721;2405.025964484387;2422.5760491913934;2463.8152138589676;2470.6788117544784;2476.0838489758817;2169.5443956769095;2431.501542183848;2444.095684596693;2455.484348286862;2469.09877894569;2376.9524609507885;2442.120836498859;2442.1452491627965;2450.546648869965;2452.8160387970884;2435.038159926819;2436.951260974771;2453.865037422941;2457.3242358351417;2465.235776909164;10649.201309901788;10848.891511084947;10850.077743391821;10851.565937362497;10865.728356791064;6246.281677607088;6455.806325810233;6458;6461.321561236096;6468.902966455507;3822.8470861165797;3919.2173556774724;3956.1296514766705;3998.280309544281;4032.4354590357375;1063.622414043019;1175.383847210086;1201.9819702704467;1226.2519862883635;1228.9078319126102;13412.597622071353;13531.120477168952;14531.709365812625;14921.850781492263;15135.621609459742;5237.711925844115;5281.815136470205;5435.0488664483555;5457.426970168154;5462.672239665632;11048.889511104946;11113.555457781598;11185.66443006711;11187.105031597453;11305.660830175108;5535.861603459945;6255.061740738925;6272.372762723691;6308.936910630835;6326.398992095744;5592.608517403824;5887.735051922588;5893.96855550277;5929.466348028698;5987.31145918225;4175.853845115467;4248.638865696388;4302.483701955785;4359.825606975749;4365.3670217818235;1203.4945322964377;1216.1486959128645;1226.625879106863;1228.2814553486132;1229.1150371732338;750.572173860894;753.9660715267717;768.8539177556246;771.155584634824;799.7320897499286;6331.96834015827;6393.976963685788;6818.693158807866;6834.179898412222;6865.5537390069485;868.748063061705;899.2131884600974;923.4136323434667;935.1256575102301;946.97253479975;3893.4159876018257;4058.837646494166;4060.127072679388;4160.854370097092;4192.24539582878;13205.20768753878;13619.046666733233;13684.93157534206;13705.588832335046;13733.313334333243;12630.494780208872;12844.935775321064;13139.474421023244;13145;13168.407421666048;8698.173673501007;9667.323287369958;9741.464219467856;10004.399736015863;10064.993500649876;6042.305134909541;6344.397282258178;6541.116949211986;6606.933930660632;6693.196816382066;11691.181617286707;11749.530018799152;11832.112591555495;11925.284482931052;12058.397080146133;11816.231944923553;11828.822567661664;11867.406629668309;11952.342621155572;12099.33453660057;9686.612535498502;10026.248031397528;10045.296829221885;10066.29535932507;10092.949535252277;9663.951680241555;9914.454704991294;9978.550965206729;10079.193664506969;10109.73628296463;7585.975893254561;8328.625211865601;8486.321094312316;8663;8730.17063378978;8245.670173193004;8249.546274954935;8381.287590554628;8447.45091569046;8565.314774818124;6543.854825405439;7665.6933722650465;7702.691892324468;7711.575863327346;7742.729004484816;5763.4812866842185;5809.883802324014;5823.70881455934;5871.324797648154;5961.318328452259;5579.744557474536;5744.885102298014;5957.404259574181;5980.312264089685;6043.184170136975 05:56:05 INFO - PID 1639 | |4;prototype.html;316.9857356418961;401.672636801007;401.7067540695292;409.1122264685631;410.4274537020856;4732.905341893164;6442.484601231902;6467.059882035394;6481.157449531554;6561.573497722657;4209.747415155085;5297.125974214259;6186.350433204516;6210.78262260821;6348.238211414637;4876.366072410582;4901.1177987962155;4903.705777653343;4910.901781964359;4921.311016457699;3993.3643795095245;4359.629431498326;4914.950850491503;4925.950740492603;4939.160342741725;4668.393108895839;4817.9759101204445;4867.367242258501;4912.501686985468;4915.139850526161;4217.472815898012;4709.835155769566;4830.227163653816;4832.1543729847135;4849.199882019462;3418.9487157692656;3595.334863050344;4273.722208056466;4327.2643650579475;4327.805248763813;5245.501677340647;5937.970310148422;5960.403959604048;6079.817605471843;6184.886720390328;4564.794584243717;5607.663540187602;5660.5471562274915;5667.574931880125;5694.288213973254;6364.71358788851;6632.369924857131;6701.195856497252;6716.429103526206;6724.3611856874;4784.545468180518;4786.664933454659;4805.423349198084;4832.240243360287;4844.885676294437;5843.0066888629035;6613.3056029117215;6911.723531058751;6973.965130174317;7001.82495437613;4644.605208557243;4777.4983626718695;4795.688280261772;4963.230699241623;4998.325226094504;4683.470925700084;5065.138926382524;5111.361079865017;5135.281060651511;5137.717425541557;4602.171609110325;4724.43306803179;5180.197069454241;5434.91847622286;5676.716164191774;5647.29408823897;5935.901858156169;6611.776821288077;6818.4886133540185;6846.863062738717;1560.1809050248482;1575.3462313140149;1578.936842526309;1587.706274339251;1594.3303812398588;938.63428711225;990.8414653655525;1034.5861655337774;1040.6565833275001;1045.325764881647;5820.796272130539;6008.188894499274;6043.425874541911;6282.769616786067;6302.905456418157;6132.018876979751;6682.532222744361;6962.2341542430295;6966.79099627012;6989.615571143534;5299.270875499121;6247.437730604267;6271.216097987752;6282.1519094921605;6322.114903913456;3457.7060949819547;3629.219717760694;3634.618365071682;3640.308341415123;3642.9453558196647;6452.774152904626;6785.321467853175;7041.4718896082995;7075.186353569303;7090.574565526085;7096.680649370791;7176.318249766368;7184.820379490555;7189.173245076779;7198;6202.379762023852;6447.484201263887;7116.146062472537;7176.725107046317;7186.347140157688;6160.872215615459;6719.4624430044505;7026.8945965809535;7035.33164349386;7065.364117229474;6543.378379053982;6704.7988560343265;6900.7929762107215;6918.757843475454;6996.475264355296;5382.784688612568;6018.849528761816;6919.20429150634;7034.437245020488;7077.681504332214;4485.416895803524;4507.7295362277055;4516.299973504112;4516.954830451654;4564.224081906017;6623.238327592196;6865.244823069558;6887.072993977639;6931.7227310907;6973.3375329343635;5498.037843377313;5559.916601251065;6145.354737752472;6559.4752419807255;6710.530262881747;5890.705464726832;6291.685415729286;6582.650556635974;6683.331666833278;6728.158980127484;6506.893828049338;6528.869422611616;6531.706073226621;6533.967330163318;6561.868762624817;5837.686520532846;6608.339166083353;6706.396424321834;6713.395794378529;6748.358905903931;6406.430424546016;6423.518236132215;6431.646259455775;6500.70746816385;6512.251091124582 05:56:05 INFO - PID 1639 | |5;yui.html;630.2122347066166;689.886168782151;696.6238231355069;698.6122701900447;718.3175982816326;14909.62725931851;15774.21128943554;16116.919415402936;16528.33886644534;16784.244708988095;14924.403023879031;15207.163606001694;15356.308966096525;15357.232138393063;15542.84457155428;912.2701838529183;928.3872644054925;931.585444477208;933.743220614333;934.9205317548017;930.2604429478581;933.12752576341;940.9247260219166;953.7091187187905;964.7346979580636;894.5795476126243;1017.5421060522795;1023.9078482936534;1024.87701475823;1026.686860507545;997.5860018092483;1008.7427705935013;1009.0464511037073;1010.408910787183;1011.2567263061644;743.2827321634594;751.4439314906984;755.2862544895077;755.3579457461169;755.4334249313015;15779.368825246976;16156.515304540882;16183.271752771154;16850.410235641815;17091.401800936907;14188.787168192486;14378.28108594566;14395.136291822524;14501.927490362586;14544.981851270415;11249.55001799927;11364.261323013978;11504.367259800707;11540.695221407614;11849;2817.957730634042;2970.376220993594;3030.2878823476467;3132.3265497918055;3141.575887255214;116041;120079.79920200861;120603;121128.39435802853;123188.38405807913;10819;10927.562897484011;10990.670279891616;11180.776384472265;11881.108916831272;25129.120480783084;25558.616620750705;25678.48643027129;25740.356491087874;25766;7725.304722574995;7777.261160189773;7906.972093627906;7974.920250797418;8010.679572817138;3978.840846366113;4024.215278020816;4035.5157381114454;4065.9390109148385;4075.7962101894786;3797.2785170817456;4173.499180098347;4269.530351661315;4269.701120921568;4277.6791740618955;791.3115589437226;796.3907610677836;799.8400319936036;819.8770184472377;822.0341099208406;515.487090345109;557.8438037349549;559.8628336057692;568.9971550142222;588.2499812738793;9533.809323813484;9595;10086;10120.866249657456;10125.15525936424;572.9598928074995;591.2343515147894;593.8812237552506;603.1104121420957;614.3549273263056;4395.494518130392;4632.791524381411;4670.486246512881;4677;4690;76334.618326908;94300.11399771966;95966.60083497969;96289;98230.5088474553;69674.65162674256;72332.63833680903;73630;89982;90112;74848;78413.21586784173;79867.20132798713;80158;81601;9473.47895865734;9536.33245672824;9580.76048098803;9604.807903841744;9606.903930960601;28175.013874514294;33774.14754310896;35378.64621353753;35918.10204744902;37884.62115378811;60936;66454.00637980868;68770;71357;71470.28529714636;12265.877341226831;13835.792463113263;13962.394324652794;13970.161790292208;14403.855961440671;14729.914998628754;14733.631659208177;14833.851661483679;15271.389144434543;15648.765268520754;13714.062937342349;13720.794188087386;13971.09187902783;14002.57992260234;14389.424423022961;13497.527586534425;13626.1143025703;13645.931770341085;13651.639242239506;14541.636459088608;13115.803262951255;13329.933350333185;13506;13953.860461395256;13988.930055349658;9594.328397012143;9845.901540984498;9898.158656514277;10056.446895420822;10497.737556561147;10374.17006639452;10730.302530335815;11196.38419886914;11234.606788762354;11263.774724505627 05:56:05 INFO - PID 1639 | __end_tp_report 05:56:05 INFO - PID 1639 | __start_cc_report 05:56:05 INFO - PID 1639 | _x_x_mozilla_cycle_collect,428 05:56:05 INFO - PID 1639 | __end_cc_report 05:56:05 INFO - PID 1639 | __startTimestamp1490705765049__endTimestamp 05:56:05 INFO - PID 1639 | 05:56:05 INFO - PID 1639 | ------- Summary: start ------- 05:56:05 INFO - PID 1639 | Number of tests: 6 05:56:05 INFO - PID 1639 | 05:56:05 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6123.55 Median:6217.88 stddev:689.28 (11.1%) stddev-sans-first:658.51 05:56:05 INFO - PID 1639 | Values: 3441.9 3531.1 3553.0 3576.8 3588.4 5886.2 6089.0 6100.1 6128.4 6169.9 6116.1 6118.4 6164.0 6191.6 6203.6 5580.1 5743.9 5799.9 5847.1 5893.3 5631.1 5839.3 5882.2 5904.0 5925.6 4843.2 5684.9 5822.5 5851.9 5904.9 5747.2 5806.5 5812.3 5832.6 5875.0 5641.8 5655.1 5686.5 5707.8 5758.1 6049.3 6118.2 6125.8 6133.8 6188.8 6505.3 6624.4 6656.7 6667.5 6691.5 6769.3 6778.1 6799.2 6810.3 6811.5 6219.1 6278.6 6291.6 6301.9 6303.1 6629.2 6730.7 6786.2 6831.6 6859.7 5515.9 5956.4 6306.8 6396.2 6497.2 5742.0 6667.9 6695.0 6763.5 6774.2 5291.1 6675.2 6675.6 6676.8 6719.4 6444.8 6492.1 6589.3 6629.0 6647.4 5389.4 5393.6 5394.6 5425.1 5435.5 4701.8 4713.2 4727.5 4728.9 4744.1 6131.8 6154.8 6161.9 6180.5 6206.2 5649.5 6714.5 6746.6 6758.3 6791.7 6140.9 6142.0 6194.4 6207.5 6215.6 4841.0 5134.8 5843.4 6016.7 6019.1 6629.7 6733.7 6760.9 6793.1 6818.9 5626.4 6725.8 6753.0 6767.9 6769.6 6692.3 6765.3 6803.3 6826.4 6833.5 6385.9 6454.9 6578.8 6627.0 6665.3 6650.2 6717.4 6722.6 6760.8 6770.5 5456.8 6217.9 6253.3 6326.7 6530.9 6352.8 6375.3 6540.5 6561.1 6644.2 5408.9 6181.1 6217.8 6357.8 6416.1 6457.0 6463.6 6467.5 6551.4 6574.2 5775.7 6000.7 6349.2 6349.7 6366.2 05:56:05 INFO - PID 1639 | 05:56:05 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:54283.06 Median:35830.14 stddev:109609.08 (305.9%) stddev-sans-first:109842.72 05:56:05 INFO - PID 1639 | Values: 698.4 1090.1 1108.2 1203.2 1283.7 37073.3 37764.4 37797.8 37808.4 38000.6 37520.6 37884.6 37921.0 38009.9 38047.6 7990.4 8153.6 8529.1 8814.6 8836.0 8736.0 8835.5 8842.0 8855.2 8878.0 8770.5 8865.2 8936.9 8956.6 8976.4 8880.7 8899.0 8909.2 8915.2 8924.0 8674.5 8694.0 8754.3 8762.1 8795.5 39301.6 39357.6 39507.0 39537.2 39674.8 2774.4 2975.1 3049.3 3064.5 3064.6 28567.1 28709.9 28717.7 28846.4 29688.4 2702.2 2789.2 2790.4 2816.0 2833.6 352399.2 352953.0 355432.0 355680.0 360874.0 38400.4 39690.6 39777.6 47730.3 51457.7 40234.0 50538.7 50589.7 50721.2 50925.0 11685.9 12849.3 13494.8 14396.7 14472.9 11440.5 11620.3 11628.7 11630.9 11770.1 14095.0 14118.3 14140.1 14176.5 14273.6 5341.9 6164.6 6195.6 6196.9 6207.2 865.8 983.5 991.3 1003.0 1020.8 24479.0 24502.0 24622.3 24769.6 24976.4 1011.9 1097.6 1115.2 1135.9 1151.7 12456.8 12721.8 12729.6 12742.0 12782.6 454935.7 456763.0 456869.0 457118.0 457858.0 54518.7 54859.0 55171.7 55284.7 55294.4 1456.4 1489.3 1494.4 1530.7 1542.8 22000.2 23875.8 23997.6 24163.2 24252.5 39692.2 40096.2 40153.8 40244.4 40300.4 420331.0 420455.0 420473.0 420879.0 421517.0 33243.8 33420.5 33820.0 33917.5 33934.7 42406.7 43190.1 43372.0 44341.8 44463.3 29659.9 30587.9 30600.4 31068.8 31169.7 29399.9 29872.4 30541.5 30617.7 30809.8 35578.6 36081.6 36443.4 36448.6 36549.1 30694.5 30899.2 31202.8 31262.2 31551.1 24241.0 24258.9 24291.1 24330.1 24436.5 05:56:05 INFO - PID 1639 | 05:56:05 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:40032.88 Median:22605.89 stddev:116372.07 (514.8%) stddev-sans-first:116660.45 05:56:05 INFO - PID 1639 | Values: 383.5 415.6 421.3 425.9 442.8 19374.2 21073.6 21842.6 22517.5 22689.8 17542.6 21862.8 22431.1 22551.9 22659.9 6180.7 6400.0 6433.7 6454.4 6484.0 6094.9 6267.6 6319.9 6362.4 6367.6 6261.7 6298.9 6326.7 6332.7 6334.3 6073.6 6311.5 6362.8 6379.3 6387.2 5556.6 5583.0 5593.5 5594.9 5655.6 19641.4 23389.8 24272.8 27861.2 29179.6 1902.4 1917.4 1920.4 1956.4 1984.2 15468.7 16601.5 16681.7 16869.2 17360.4 1635.4 1671.5 1715.3 1725.8 1765.7 119643.0 131933.0 158741.0 160018.2 165873.2 11553.7 11890.0 12896.5 12899.7 12957.0 15877.3 16195.8 16202.8 16224.4 16225.5 8753.0 9100.0 9123.2 9152.5 9261.7 8744.1 8775.0 8800.6 8949.6 8965.3 7765.5 7819.1 7832.9 7855.6 7908.6 2104.5 2123.7 2161.3 2215.1 2215.1 879.3 918.7 923.2 946.0 962.3 19311.1 20326.5 20688.6 20985.3 21172.4 953.6 977.3 978.8 979.8 981.1 8969.6 9985.7 10197.2 10753.9 10843.8 681204.0 684917.0 691821.0 707997.0 708317.0 130564.0 134357.0 147210.8 157407.0 159751.0 1162.5 1180.3 1200.3 1202.8 1209.5 17905.0 18275.5 18328.0 18387.6 18402.5 52360.2 62388.0 63827.7 65066.0 65274.0 87645.6 88367.6 89307.6 89961.0 90788.5 8349.3 8496.7 8610.3 8656.3 8918.6 13206.0 13290.7 13505.3 13695.5 14016.2 10089.4 10767.9 11505.9 11595.8 11704.4 8995.0 10460.4 10627.9 10763.7 11172.7 10263.7 10271.8 11046.0 11438.7 11522.7 7389.6 8260.5 8577.4 8606.5 8725.3 10801.5 11480.0 11719.7 11744.2 11791.1 05:56:05 INFO - PID 1639 | 05:56:05 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:6684.88 Median:2489.10 stddev:4021.60 (161.6%) stddev-sans-first:4013.05 05:56:05 INFO - PID 1639 | Values: 1370.3 1426.4 1469.4 1487.3 1498.0 10233.8 10671.3 10724.2 10887.9 11062.0 10744.4 10856.6 10905.5 10931.6 11023.3 2452.4 2485.3 2486.3 2491.9 2498.0 2405.0 2422.6 2463.8 2470.7 2476.1 2169.5 2431.5 2444.1 2455.5 2469.1 2377.0 2442.1 2442.1 2450.5 2452.8 2435.0 2437.0 2453.9 2457.3 2465.2 10649.2 10848.9 10850.1 10851.6 10865.7 6246.3 6455.8 6458.0 6461.3 6468.9 3822.8 3919.2 3956.1 3998.3 4032.4 1063.6 1175.4 1202.0 1226.3 1228.9 13412.6 13531.1 14531.7 14921.9 15135.6 5237.7 5281.8 5435.0 5457.4 5462.7 11048.9 11113.6 11185.7 11187.1 11305.7 5535.9 6255.1 6272.4 6308.9 6326.4 5592.6 5887.7 5894.0 5929.5 5987.3 4175.9 4248.6 4302.5 4359.8 4365.4 1203.5 1216.1 1226.6 1228.3 1229.1 750.6 754.0 768.9 771.2 799.7 6332.0 6394.0 6818.7 6834.2 6865.6 868.7 899.2 923.4 935.1 947.0 3893.4 4058.8 4060.1 4160.9 4192.2 13205.2 13619.0 13684.9 13705.6 13733.3 12630.5 12844.9 13139.5 13145.0 13168.4 8698.2 9667.3 9741.5 10004.4 10065.0 6042.3 6344.4 6541.1 6606.9 6693.2 11691.2 11749.5 11832.1 11925.3 12058.4 11816.2 11828.8 11867.4 11952.3 12099.3 9686.6 10026.2 10045.3 10066.3 10092.9 9664.0 9914.5 9978.6 10079.2 10109.7 7586.0 8328.6 8486.3 8663.0 8730.2 8245.7 8249.5 8381.3 8447.5 8565.3 6543.9 7665.7 7702.7 7711.6 7742.7 5763.5 5809.9 5823.7 5871.3 5961.3 5579.7 5744.9 5957.4 5980.3 6043.2 05:56:05 INFO - PID 1639 | 05:56:05 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5431.00 Median:6031.14 stddev:1664.09 (27.6%) stddev-sans-first:1623.88 05:56:05 INFO - PID 1639 | Values: 317.0 401.7 401.7 409.1 410.4 4732.9 6442.5 6467.1 6481.2 6561.6 4209.7 5297.1 6186.4 6210.8 6348.2 4876.4 4901.1 4903.7 4910.9 4921.3 3993.4 4359.6 4915.0 4926.0 4939.2 4668.4 4818.0 4867.4 4912.5 4915.1 4217.5 4709.8 4830.2 4832.2 4849.2 3418.9 3595.3 4273.7 4327.3 4327.8 5245.5 5938.0 5960.4 6079.8 6184.9 4564.8 5607.7 5660.5 5667.6 5694.3 6364.7 6632.4 6701.2 6716.4 6724.4 4784.5 4786.7 4805.4 4832.2 4844.9 5843.0 6613.3 6911.7 6974.0 7001.8 4644.6 4777.5 4795.7 4963.2 4998.3 4683.5 5065.1 5111.4 5135.3 5137.7 4602.2 4724.4 5180.2 5434.9 5676.7 5647.3 5935.9 6611.8 6818.5 6846.9 1560.2 1575.3 1578.9 1587.7 1594.3 938.6 990.8 1034.6 1040.7 1045.3 5820.8 6008.2 6043.4 6282.8 6302.9 6132.0 6682.5 6962.2 6966.8 6989.6 5299.3 6247.4 6271.2 6282.2 6322.1 3457.7 3629.2 3634.6 3640.3 3642.9 6452.8 6785.3 7041.5 7075.2 7090.6 7096.7 7176.3 7184.8 7189.2 7198.0 6202.4 6447.5 7116.1 7176.7 7186.3 6160.9 6719.5 7026.9 7035.3 7065.4 6543.4 6704.8 6900.8 6918.8 6996.5 5382.8 6018.8 6919.2 7034.4 7077.7 4485.4 4507.7 4516.3 4517.0 4564.2 6623.2 6865.2 6887.1 6931.7 6973.3 5498.0 5559.9 6145.4 6559.5 6710.5 5890.7 6291.7 6582.7 6683.3 6728.2 6506.9 6528.9 6531.7 6534.0 6561.9 5837.7 6608.3 6706.4 6713.4 6748.4 6406.4 6423.5 6431.6 6500.7 6512.3 05:56:05 INFO - PID 1639 | 05:56:05 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:20121.19 Median:35648.37 stddev:29061.89 (81.5%) stddev-sans-first:29106.57 05:56:05 INFO - PID 1639 | Values: 630.2 689.9 696.6 698.6 718.3 14909.6 15774.2 16116.9 16528.3 16784.2 14924.4 15207.2 15356.3 15357.2 15542.8 912.3 928.4 931.6 933.7 934.9 930.3 933.1 940.9 953.7 964.7 894.6 1017.5 1023.9 1024.9 1026.7 997.6 1008.7 1009.0 1010.4 1011.3 743.3 751.4 755.3 755.4 755.4 15779.4 16156.5 16183.3 16850.4 17091.4 14188.8 14378.3 14395.1 14501.9 14545.0 11249.6 11364.3 11504.4 11540.7 11849.0 2818.0 2970.4 3030.3 3132.3 3141.6 116041.0 120079.8 120603.0 121128.4 123188.4 10819.0 10927.6 10990.7 11180.8 11881.1 25129.1 25558.6 25678.5 25740.4 25766.0 7725.3 7777.3 7907.0 7974.9 8010.7 3978.8 4024.2 4035.5 4065.9 4075.8 3797.3 4173.5 4269.5 4269.7 4277.7 791.3 796.4 799.8 819.9 822.0 515.5 557.8 559.9 569.0 588.2 9533.8 9595.0 10086.0 10120.9 10125.2 573.0 591.2 593.9 603.1 614.4 4395.5 4632.8 4670.5 4677.0 4690.0 76334.6 94300.1 95966.6 96289.0 98230.5 69674.7 72332.6 73630.0 89982.0 90112.0 74848.0 78413.2 79867.2 80158.0 81601.0 9473.5 9536.3 9580.8 9604.8 9606.9 28175.0 33774.1 35378.6 35918.1 37884.6 60936.0 66454.0 68770.0 71357.0 71470.3 12265.9 13835.8 13962.4 13970.2 14403.9 14729.9 14733.6 14833.9 15271.4 15648.8 13714.1 13720.8 13971.1 14002.6 14389.4 13497.5 13626.1 13645.9 13651.6 14541.6 13115.8 13329.9 13506.0 13953.9 13988.9 9594.3 9845.9 9898.2 10056.4 10497.7 10374.2 10730.3 11196.4 11234.6 11263.8 05:56:05 INFO - PID 1639 | -------- Summary: end -------- 05:56:05 INFO - PID 1639 | 05:56:05 INFO - TEST-INFO | 1639: exit 0 05:56:05 INFO - TEST-OK | dromaeo_css | took 1118225ms 05:56:05 INFO - TEST-START | kraken 05:56:05 INFO - operating with platform_type : mac_ 05:56:05 INFO - Initialising browser for kraken test... 05:56:05 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpNytvUv/profile http://localhost:49236/getInfo.html) 05:56:08 INFO - PID 1650 | 2017-03-28 05:56:08.494 plugin-container[1651:10736] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 05:56:08 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:08 INFO - PID 1650 | 2017-03-28 05:56:08.497 plugin-container[1651:10736] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 05:56:08 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:08 INFO - PID 1650 | 2017-03-28 05:56:08.497 plugin-container[1651:10736] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:56:08 INFO - PID 1650 | __metrics Screen width/height:1600/1200 05:56:08 INFO - PID 1650 | colorDepth:24 05:56:08 INFO - PID 1650 | Browser inner width/height: 1024/689 05:56:08 INFO - PID 1650 | __metrics 05:56:08 ERROR - PID 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 05:56:08 ERROR - PID 1650 | 1490705768665 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpNytvUv/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpNytvUv/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpNytvUv/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpNytvUv/profile/extensions/talos-powers@TalosPowersService.js:55:9 05:56:09 INFO - TEST-INFO | 1650: exit 0 05:56:09 INFO - Browser initialized. 05:56:09 INFO - Running cycle 1/1 for kraken test... 05:56:09 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpNytvUv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:56:11 INFO - PID 1652 | 2017-03-28 05:56:11.795 plugin-container[1653:10860] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 05:56:11 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:11 INFO - PID 1652 | 2017-03-28 05:56:11.796 plugin-container[1653:10860] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 05:56:11 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:11 INFO - PID 1652 | 2017-03-28 05:56:11.796 plugin-container[1653:10860] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:56:21 INFO - PID 1652 | RSS: Main: 228585472 05:56:21 INFO - PID 1652 | 05:56:24 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 05:56:25 INFO - PID 1652 | RSS: Main: 243482624 05:56:25 INFO - PID 1652 | 05:56:28 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 05:56:29 INFO - PID 1652 | RSS: Main: 251854848 05:56:29 INFO - PID 1652 | 05:56:32 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 05:56:33 INFO - PID 1652 | RSS: Main: 254152704 05:56:33 INFO - PID 1652 | 05:56:35 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 05:56:36 INFO - PID 1652 | RSS: Main: 256233472 05:56:36 INFO - PID 1652 | 05:56:38 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:56:38 INFO - PID 1652 | RSS: Main: 256888832 05:56:38 INFO - PID 1652 | 05:56:42 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 05:56:42 INFO - PID 1652 | RSS: Main: 262156288 05:56:42 INFO - PID 1652 | 05:56:46 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 05:56:46 INFO - PID 1652 | RSS: Main: 262885376 05:56:46 INFO - PID 1652 | 05:56:50 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 05:56:50 INFO - PID 1652 | RSS: Main: 259973120 05:56:50 INFO - PID 1652 | 05:56:52 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:56:53 INFO - PID 1652 | RSS: Main: 261599232 05:56:53 INFO - PID 1652 | 05:56:55 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:56:56 INFO - PID 1652 | RSS: Main: 261738496 05:56:56 INFO - PID 1652 | 05:56:58 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:56:58 INFO - PID 1652 | RSS: Main: 262029312 05:56:58 INFO - PID 1652 | 05:57:01 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:57:02 INFO - PID 1652 | RSS: Main: 262479872 05:57:02 INFO - PID 1652 | 05:57:05 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:57:06 INFO - PID 1652 | RSS: Main: 262254592 05:57:06 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 05:57:08 INFO - PID 1652 | RSS: Main: 265449472 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | __start_tp_report 05:57:08 INFO - PID 1652 | _x_x_mozilla_page_load 05:57:08 INFO - PID 1652 | _x_x_mozilla_page_load_details 05:57:08 INFO - PID 1652 | |i|pagename|runs| 05:57:08 INFO - PID 1652 | |0;ai-astar;110;123;122;122;124;130;126;179;182;108 05:57:08 INFO - PID 1652 | |1;audio-beat-detection;141;134;147;196;123;119;151;117;159;105 05:57:08 INFO - PID 1652 | |2;audio-dft;232;279;263;230;248;265;201;228;209;280 05:57:08 INFO - PID 1652 | |3;audio-fft;81;61;61;88;62;61;63;60;81;85 05:57:08 INFO - PID 1652 | |4;audio-oscillator;84;73;87;75;72;74;86;72;80;102 05:57:08 INFO - PID 1652 | |5;imaging-gaussian-blur;93;105;137;93;120;139;95;85;123;91 05:57:08 INFO - PID 1652 | |6;imaging-darkroom;102;109;96;98;102;146;97;144;134;95 05:57:08 INFO - PID 1652 | |7;imaging-desaturate;87;86;157;166;120;98;155;146;157;137 05:57:08 INFO - PID 1652 | |8;json-parse-financial;69;69;68;68;69;69;71;64;70;79 05:57:08 INFO - PID 1652 | |9;json-stringify-tinderbox;56;65;59;62;93;88;76;84;84;68 05:57:08 INFO - PID 1652 | |10;stanford-crypto-aes;88;88;88;89;89;89;87;92;88;89 05:57:08 INFO - PID 1652 | |11;stanford-crypto-ccm;153;150;151;146;137;165;165;156;206;174 05:57:08 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;197;199;202;229;209;197;197;197;200;194 05:57:08 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;72;72;73;73;73;72;72;70;70;70 05:57:08 INFO - PID 1652 | __end_tp_report 05:57:08 INFO - PID 1652 | __start_cc_report 05:57:08 INFO - PID 1652 | _x_x_mozilla_cycle_collect,1018 05:57:08 INFO - PID 1652 | __end_cc_report 05:57:08 INFO - PID 1652 | __startTimestamp1490705828552__endTimestamp 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | ------- Summary: start ------- 05:57:08 INFO - PID 1652 | Number of tests: 14 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:132.60 Median:125.00 stddev:26.14 (20.9%) stddev-sans-first:26.42 05:57:08 INFO - PID 1652 | Values: 110.0 123.0 122.0 122.0 124.0 130.0 126.0 179.0 182.0 108.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:144.00 stddev:26.21 (18.2%) stddev-sans-first:27.79 05:57:08 INFO - PID 1652 | Values: 141.0 134.0 147.0 196.0 123.0 119.0 151.0 117.0 159.0 105.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:243.50 Median:255.50 stddev:27.90 (10.9%) stddev-sans-first:29.28 05:57:08 INFO - PID 1652 | Values: 232.0 279.0 263.0 230.0 248.0 265.0 201.0 228.0 209.0 280.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:70.30 Median:72.00 stddev:11.77 (16.3%) stddev-sans-first:11.83 05:57:08 INFO - PID 1652 | Values: 81.0 61.0 61.0 88.0 62.0 61.0 63.0 60.0 81.0 85.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:77.50 stddev:9.55 (12.3%) stddev-sans-first:10.04 05:57:08 INFO - PID 1652 | Values: 84.0 73.0 87.0 75.0 72.0 74.0 86.0 72.0 80.0 102.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:108.10 Median:88.00 stddev:20.05 (22.8%) stddev-sans-first:20.50 05:57:08 INFO - PID 1652 | Values: 93.0 105.0 137.0 93.0 120.0 139.0 95.0 85.0 123.0 91.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:112.30 Median:120.50 stddev:20.65 (17.1%) stddev-sans-first:21.56 05:57:08 INFO - PID 1652 | Values: 102.0 109.0 96.0 98.0 102.0 146.0 97.0 144.0 134.0 95.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:130.90 Median:161.50 stddev:30.88 (19.1%) stddev-sans-first:28.38 05:57:08 INFO - PID 1652 | Values: 87.0 86.0 157.0 166.0 120.0 98.0 155.0 146.0 157.0 137.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:69.60 Median:69.00 stddev:3.78 (5.5%) stddev-sans-first:4.00 05:57:08 INFO - PID 1652 | Values: 69.0 69.0 68.0 68.0 69.0 69.0 71.0 64.0 70.0 79.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:73.50 Median:80.00 stddev:13.20 (16.5%) stddev-sans-first:12.39 05:57:08 INFO - PID 1652 | Values: 56.0 65.0 59.0 62.0 93.0 88.0 76.0 84.0 84.0 68.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:89.00 stddev:1.34 (1.5%) stddev-sans-first:1.39 05:57:08 INFO - PID 1652 | Values: 88.0 88.0 88.0 89.0 89.0 89.0 87.0 92.0 88.0 89.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:160.30 Median:160.50 stddev:19.24 (12.0%) stddev-sans-first:20.23 05:57:08 INFO - PID 1652 | Values: 153.0 150.0 151.0 146.0 137.0 165.0 165.0 156.0 206.0 174.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.10 Median:199.50 stddev:10.30 (5.2%) stddev-sans-first:10.76 05:57:08 INFO - PID 1652 | Values: 197.0 199.0 202.0 229.0 209.0 197.0 197.0 197.0 200.0 194.0 05:57:08 INFO - PID 1652 | 05:57:08 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:72.00 stddev:1.25 (1.7%) stddev-sans-first:1.32 05:57:08 INFO - PID 1652 | Values: 72.0 72.0 73.0 73.0 73.0 72.0 72.0 70.0 70.0 70.0 05:57:08 INFO - PID 1652 | -------- Summary: end -------- 05:57:08 INFO - PID 1652 | 05:57:09 INFO - TEST-INFO | 1652: exit 0 05:57:09 INFO - TEST-OK | kraken | took 63703ms 05:57:09 INFO - SUITE-END | took 1181s 05:57:09 INFO - Completed test suite (00:19:42) 05:57:09 INFO - javascript benchmark 05:57:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7735.615253656819, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3441.9311613767727, 3531.081918701137, 3552.964470355299, 3576.839042243099, 3588.443791212363, 5886.205362276091, 6088.969555152224, 6100.054491553817, 6128.387161283875, 6169.876602467948, 6116.092308610896, 6118.418750218733, 6164.013757798753, 6191.597546159496, 6203.596766210193, 5580.079286917654, 5743.913841292384, 5799.942000580004, 5847.122931560258, 5893.292804863401, 5631.099024156136, 5839.328477225131, 5882.205902203191, 5903.970480147594, 5925.5555833312455, 4843.200871856139, 5684.857878553028, 5822.534197264209, 5851.882962340751, 5904.911426328608, 5747.2241247431475, 5806.477417032445, 5812.302523697141, 5832.620879642852, 5874.97062514689, 5641.774329026834, 5655.123455864347, 5686.488216060575, 5707.800226992035, 5758.078707406837, 6049.334573196945, 6118.204633397674, 6125.78559750411, 6133.754649814002, 6188.84527886802, 6505.284418713938, 6624.403803657646, 6656.667166641697, 6667.533272670915, 6691.531592788507, 6769.289224631441, 6778.084958530584, 6799.218089919673, 6810.250872404032, 6811.4550835933005, 6219.098230756515, 6278.591891527128, 6291.559590828644, 6301.897167995582, 6303.054541818672, 6629.171353580802, 6730.697118629673, 6786.219584747817, 6831.6242606656115, 6859.657017149123, 5515.944840551623, 5956.404359564009, 6306.842328941813, 6396.200474940632, 6497.15536980199, 5741.971290143606, 6667.86664266712, 6694.966525167343, 6763.526553141233, 6774.187097548326, 5291.100512912814, 6675.165604299463, 6675.599464032173, 6676.799696009128, 6719.395254427125, 6444.7744328948265, 6492.123563318986, 6589.275179730226, 6629.0, 6647.401733843977, 5389.380221274525, 5393.6224464287125, 5394.595405344537, 5425.104857698515, 5435.537979271806, 4701.8354357598, 4713.222318317507, 4727.527247275245, 4728.905421891612, 4744.051189762062, 6131.754729810757, 6154.846128846814, 6161.907571386433, 6180.47465965389, 6206.162168107338, 5649.4633009864, 6714.462842972551, 6746.561473504305, 6758.256591774901, 6791.660416979132, 6140.907886381708, 6141.96929015361, 6194.4115309045565, 6207.503399728011, 6215.595986260879, 4840.951590484121, 5134.7946082156295, 5843.357230704534, 6016.7292471838, 6019.127226552126, 6629.668516574248, 6733.696983635846, 6760.898586521304, 6793.116894803644, 6818.897716534355, 5626.409227031103, 6725.764598239038, 6752.966235168793, 6767.86464270702, 6769.559978601375, 6692.330766923269, 6765.255821859493, 6803.319668033355, 6826.385625293748, 6833.521653484408, 6385.904211436739, 6454.935450645434, 6578.8355291118105, 6627.0, 6665.266820649822, 6650.201975762746, 6717.395434410926, 6722.596644201363, 6760.830979225362, 6770.52606317553, 5456.809011684572, 6217.875642487034, 6253.3121356651645, 6326.683665816783, 6530.902036469552, 6352.777652782131, 6375.266844312963, 6540.476761858945, 6561.081448597105, 6644.169478815148, 5408.918866217088, 6181.072839074175, 6217.813465596039, 6357.774181501307, 6416.101745755504, 6457.0, 6463.61218326883, 6467.514936379696, 6551.377619126175, 6574.243961944438, 5775.700467394802, 6000.69996500182, 6349.238091429058, 6349.746010159542, 6366.204224471941], "value": 6082.0803097202715, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.3540225291606, 1090.144236774132, 1108.2463924531319, 1203.1758245601768, 1283.7432513497304, 37073.258534829285, 37764.43353349702, 37797.811010944904, 37808.432873506885, 38000.61999380006, 37520.62479375205, 37884.62115378845, 37921.0, 38009.85970420885, 38047.61952380475, 7990.440669153147, 8153.633086511091, 8529.147085291483, 8814.603342849587, 8836.0, 8735.95632021839, 8835.46987180768, 8841.95579022104, 8855.15875991783, 8877.95561022194, 8770.473771573694, 8865.157810008039, 8936.865947010767, 8956.552172391355, 8976.416532925372, 8880.73357799267, 8899.02110767815, 8909.153630405166, 8915.197632213132, 8923.955380223122, 8674.479531228084, 8694.000189978171, 8754.343424243143, 8762.12378762125, 8795.516246606434, 39301.60698393037, 39357.60642393568, 39507.0, 39537.20925581472, 39674.80975570738, 2774.4451109777924, 2975.0777259049764, 3049.259564988221, 3064.4637188491924, 3064.5709600655705, 28567.142985710463, 28709.856450717612, 28717.712822871712, 28846.42307153845, 29688.40623187546, 2702.2028501592076, 2789.246903336108, 2790.441911617664, 2816.0143949617673, 2833.6457942757156, 352399.2380038109, 352953.0, 355432.0, 355680.0, 360874.0, 38400.42399364012, 39690.60309396927, 39777.60222397797, 47730.28404573934, 51457.742711286955, 40234.0, 50538.736531587005, 50589.74705126451, 50721.23918141231, 50925.0, 11685.948264656221, 12849.293288869016, 13494.797578036338, 14396.712065758626, 14472.914531410186, 11440.485178167002, 11620.302781833117, 11628.709282268012, 11630.94184529072, 11770.058395328353, 14095.013349065466, 14118.294085295694, 14140.080894741808, 14176.503822366167, 14273.64315892111, 5341.851501927026, 6164.630122192593, 6195.636959868822, 6196.8760624787255, 6207.193064901624, 865.8095219051798, 983.4885859353096, 991.306085739985, 1003.0, 1020.8476699226272, 24479.0, 24502.0, 24622.261332160066, 24769.62845557318, 24976.37559061038, 1011.9139873110868, 1097.6048622495896, 1115.1524841120645, 1135.9488823002985, 1151.717829131868, 12456.81314780279, 12721.809172862415, 12729.55446559366, 12742.0, 12782.616521504184, 454935.7253213713, 456763.0, 456869.0, 457118.0, 457858.0, 54518.72740636271, 54859.0, 55171.72414137904, 55284.72357638186, 55294.44705552974, 1456.424712238681, 1489.3319482180027, 1494.3873012065, 1530.7091652585973, 1542.8071491063617, 22000.229991950204, 23875.758416941117, 23997.640035399134, 24163.15428959978, 24252.51494970126, 39692.20615587614, 40096.1980760389, 40153.79923100366, 40244.3963340556, 40300.3954940682, 420331.0, 420455.0, 420473.0, 420879.0, 421517.0, 33243.833780830944, 33420.49869251915, 33820.0, 33917.49123763096, 33934.66065339414, 42406.7277981661, 43190.13619727651, 43372.0, 44341.77829110834, 44463.33305000492, 29659.851700741357, 30587.929422470108, 30600.387992240478, 31068.84465577748, 31169.688303117586, 29399.853000735715, 29872.402551948406, 30541.54187687142, 30617.693823061483, 30809.8459507701, 35578.644213557534, 36081.639183607826, 36443.3600487984, 36448.63551364453, 36549.08627284339, 30694.539581905843, 30899.227519312197, 31202.843985779928, 31262.21648994943, 31551.053468395985, 24241.0, 24258.878705607065, 24291.149809756575, 24330.14844480435, 24436.51126977415], "value": 17956.465526788983, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.5091083413231, 415.56158253043026, 421.33849855726504, 425.9063006138649, 442.80516572708007, 19374.19074234235, 21073.57852842942, 21842.563148737016, 22517.54964900701, 22689.773102268973, 17542.64914701705, 21862.781372186273, 22431.102755889744, 22551.887240563774, 22659.886700566516, 6180.690965451732, 6399.968000159993, 6433.710483028275, 6454.419102280794, 6484.027395890607, 6094.908576371358, 6267.623942563438, 6319.936800632004, 6362.395572420613, 6367.554271201017, 6261.655608941506, 6298.905516417258, 6326.65203413812, 6332.651704156269, 6334.271558770732, 6073.574849760518, 6311.526635502356, 6362.840928976766, 6379.298277189506, 6387.169667943161, 5556.638818476786, 5582.9950608890385, 5593.496585307342, 5594.916076258859, 5655.557832752663, 19641.41075767729, 23389.76610233893, 24272.757272427225, 27861.16416507488, 29179.562306565418, 1902.4298361273188, 1917.3960202536157, 1920.4238728381429, 1956.3544030469911, 1984.1666500070005, 15468.690626187527, 16601.50195494137, 16681.749773753403, 16869.24088416024, 17360.39238626642, 1635.4112519492974, 1671.4901954903748, 1715.305301352954, 1725.775649165613, 1765.7174852023616, 119643.0, 131933.0, 158741.0, 160018.19990900086, 165873.17063414727, 11553.653390398302, 11889.989350905096, 12896.548620798414, 12899.742005160031, 12957.0, 15877.285522151531, 16195.757063644054, 16202.837971620369, 16224.432144875109, 16225.51323460298, 8753.0, 9100.0, 9123.17891389765, 9152.496612686366, 9261.67584134552, 8744.125587441333, 8774.956125219333, 8800.603972821238, 8949.552522373855, 8965.327600429864, 7765.495242809199, 7819.139894611588, 7832.921670783333, 7855.607219638995, 7908.604569771604, 2104.515961328903, 2123.7363768557684, 2161.3407910587107, 2215.0696707382617, 2215.080741492263, 879.2789912271876, 918.6922381002305, 923.2337160157053, 945.9952700236593, 962.3023308101571, 19311.137772445567, 20326.491837704176, 20688.58622827535, 20985.265515706877, 21172.420674516125, 953.6376177052675, 977.2572844638127, 978.8042391521724, 979.8236317462926, 981.1022914033722, 8969.641214351353, 9985.700428987142, 10197.235207359477, 10753.892461075446, 10843.783124337628, 681204.0, 684917.0, 691821.0, 707997.0, 708317.0, 130564.0, 134357.0, 147210.7918381225, 157407.0, 159751.0, 1162.4652659776427, 1180.2918249050672, 1200.327816422806, 1202.837616921722, 1209.4617895036624, 17905.0, 18275.45173644793, 18328.0, 18387.632247355246, 18402.539936501693, 52360.21459678184, 62388.0, 63827.680861597255, 65066.0, 65274.0, 87645.56177219073, 88367.55816221135, 89307.55346223227, 89961.0, 90788.5460572693, 8349.290310323691, 8496.745097647081, 8610.311175105851, 8656.26421754158, 8918.598663060047, 13206.0, 13290.667733306746, 13505.32473376347, 13695.520656776966, 14016.229107399191, 10089.394636321844, 10767.946160269148, 11505.906938840797, 11595.8260626089, 11704.414779260833, 8995.0, 10460.424676642857, 10627.946860265649, 10763.730906727395, 11172.720681983015, 10263.692089237335, 10271.794564108826, 11046.0, 11438.656840294805, 11522.711932201426, 7389.556626602206, 8260.463069900437, 8577.35669824778, 8606.483610983361, 8725.345599079967, 10801.513931872927, 11480.0, 11719.707007324885, 11744.17790754639, 11791.115666324888], "value": 10313.896156439996, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.3490841850116, 1426.4365575597637, 1469.3975470057278, 1487.256371814093, 1497.9625509362265, 10233.795324093513, 10671.306365086273, 10724.195685323612, 10887.891121088776, 11062.004419602234, 10744.409057501834, 10856.565737370496, 10905.509252083655, 10931.56273749049, 11023.283486573368, 2452.4236804350962, 2485.291691867818, 2486.3286912533604, 2491.8754062296903, 2498.025769949721, 2405.025964484387, 2422.5760491913934, 2463.8152138589676, 2470.6788117544784, 2476.0838489758817, 2169.5443956769095, 2431.501542183848, 2444.095684596693, 2455.484348286862, 2469.09877894569, 2376.9524609507885, 2442.120836498859, 2442.1452491627965, 2450.546648869965, 2452.8160387970884, 2435.038159926819, 2436.951260974771, 2453.865037422941, 2457.3242358351417, 2465.235776909164, 10649.201309901788, 10848.891511084947, 10850.077743391821, 10851.565937362497, 10865.728356791064, 6246.281677607088, 6455.806325810233, 6458.0, 6461.321561236096, 6468.902966455507, 3822.8470861165797, 3919.2173556774724, 3956.1296514766705, 3998.280309544281, 4032.4354590357375, 1063.622414043019, 1175.383847210086, 1201.9819702704467, 1226.2519862883635, 1228.9078319126102, 13412.597622071353, 13531.120477168952, 14531.709365812625, 14921.850781492263, 15135.621609459742, 5237.711925844115, 5281.815136470205, 5435.0488664483555, 5457.426970168154, 5462.672239665632, 11048.889511104946, 11113.555457781598, 11185.66443006711, 11187.105031597453, 11305.660830175108, 5535.861603459945, 6255.061740738925, 6272.372762723691, 6308.936910630835, 6326.398992095744, 5592.608517403824, 5887.735051922588, 5893.96855550277, 5929.466348028698, 5987.31145918225, 4175.853845115467, 4248.638865696388, 4302.483701955785, 4359.825606975749, 4365.3670217818235, 1203.4945322964377, 1216.1486959128645, 1226.625879106863, 1228.2814553486132, 1229.1150371732338, 750.572173860894, 753.9660715267717, 768.8539177556246, 771.155584634824, 799.7320897499286, 6331.96834015827, 6393.976963685788, 6818.693158807866, 6834.179898412222, 6865.5537390069485, 868.748063061705, 899.2131884600974, 923.4136323434667, 935.1256575102301, 946.97253479975, 3893.4159876018257, 4058.837646494166, 4060.127072679388, 4160.854370097092, 4192.24539582878, 13205.20768753878, 13619.046666733233, 13684.93157534206, 13705.588832335046, 13733.313334333243, 12630.494780208872, 12844.935775321064, 13139.474421023244, 13145.0, 13168.407421666048, 8698.173673501007, 9667.323287369958, 9741.464219467856, 10004.399736015863, 10064.993500649876, 6042.305134909541, 6344.397282258178, 6541.116949211986, 6606.933930660632, 6693.196816382066, 11691.181617286707, 11749.530018799152, 11832.112591555495, 11925.284482931052, 12058.397080146133, 11816.231944923553, 11828.822567661664, 11867.406629668309, 11952.342621155572, 12099.33453660057, 9686.612535498502, 10026.248031397528, 10045.296829221885, 10066.29535932507, 10092.949535252277, 9663.951680241555, 9914.454704991294, 9978.550965206729, 10079.193664506969, 10109.73628296463, 7585.975893254561, 8328.625211865601, 8486.321094312316, 8663.0, 8730.17063378978, 8245.670173193004, 8249.546274954935, 8381.287590554628, 8447.45091569046, 8565.314774818124, 6543.854825405439, 7665.6933722650465, 7702.691892324468, 7711.575863327346, 7742.729004484816, 5763.4812866842185, 5809.883802324014, 5823.70881455934, 5871.324797648154, 5961.318328452259, 5579.744557474536, 5744.885102298014, 5957.404259574181, 5980.312264089685, 6043.184170136975], "value": 5143.77180189968, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.9857356418961, 401.672636801007, 401.7067540695292, 409.1122264685631, 410.4274537020856, 4732.905341893164, 6442.484601231902, 6467.059882035394, 6481.157449531554, 6561.573497722657, 4209.747415155085, 5297.125974214259, 6186.350433204516, 6210.78262260821, 6348.238211414637, 4876.366072410582, 4901.1177987962155, 4903.705777653343, 4910.901781964359, 4921.311016457699, 3993.3643795095245, 4359.629431498326, 4914.950850491503, 4925.950740492603, 4939.160342741725, 4668.393108895839, 4817.9759101204445, 4867.367242258501, 4912.501686985468, 4915.139850526161, 4217.472815898012, 4709.835155769566, 4830.227163653816, 4832.1543729847135, 4849.199882019462, 3418.9487157692656, 3595.334863050344, 4273.722208056466, 4327.2643650579475, 4327.805248763813, 5245.501677340647, 5937.970310148422, 5960.403959604048, 6079.817605471843, 6184.886720390328, 4564.794584243717, 5607.663540187602, 5660.5471562274915, 5667.574931880125, 5694.288213973254, 6364.71358788851, 6632.369924857131, 6701.195856497252, 6716.429103526206, 6724.3611856874, 4784.545468180518, 4786.664933454659, 4805.423349198084, 4832.240243360287, 4844.885676294437, 5843.0066888629035, 6613.3056029117215, 6911.723531058751, 6973.965130174317, 7001.82495437613, 4644.605208557243, 4777.4983626718695, 4795.688280261772, 4963.230699241623, 4998.325226094504, 4683.470925700084, 5065.138926382524, 5111.361079865017, 5135.281060651511, 5137.717425541557, 4602.171609110325, 4724.43306803179, 5180.197069454241, 5434.91847622286, 5676.716164191774, 5647.29408823897, 5935.901858156169, 6611.776821288077, 6818.4886133540185, 6846.863062738717, 1560.1809050248482, 1575.3462313140149, 1578.936842526309, 1587.706274339251, 1594.3303812398588, 938.63428711225, 990.8414653655525, 1034.5861655337774, 1040.6565833275001, 1045.325764881647, 5820.796272130539, 6008.188894499274, 6043.425874541911, 6282.769616786067, 6302.905456418157, 6132.018876979751, 6682.532222744361, 6962.2341542430295, 6966.79099627012, 6989.615571143534, 5299.270875499121, 6247.437730604267, 6271.216097987752, 6282.1519094921605, 6322.114903913456, 3457.7060949819547, 3629.219717760694, 3634.618365071682, 3640.308341415123, 3642.9453558196647, 6452.774152904626, 6785.321467853175, 7041.4718896082995, 7075.186353569303, 7090.574565526085, 7096.680649370791, 7176.318249766368, 7184.820379490555, 7189.173245076779, 7198.0, 6202.379762023852, 6447.484201263887, 7116.146062472537, 7176.725107046317, 7186.347140157688, 6160.872215615459, 6719.4624430044505, 7026.8945965809535, 7035.33164349386, 7065.364117229474, 6543.378379053982, 6704.7988560343265, 6900.7929762107215, 6918.757843475454, 6996.475264355296, 5382.784688612568, 6018.849528761816, 6919.20429150634, 7034.437245020488, 7077.681504332214, 4485.416895803524, 4507.7295362277055, 4516.299973504112, 4516.954830451654, 4564.224081906017, 6623.238327592196, 6865.244823069558, 6887.072993977639, 6931.7227310907, 6973.3375329343635, 5498.037843377313, 5559.916601251065, 6145.354737752472, 6559.4752419807255, 6710.530262881747, 5890.705464726832, 6291.685415729286, 6582.650556635974, 6683.331666833278, 6728.158980127484, 6506.893828049338, 6528.869422611616, 6531.706073226621, 6533.967330163318, 6561.868762624817, 5837.686520532846, 6608.339166083353, 6706.396424321834, 6713.395794378529, 6748.358905903931, 6406.430424546016, 6423.518236132215, 6431.646259455775, 6500.70746816385, 6512.251091124582], "value": 4904.442484546002, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.2122347066166, 689.886168782151, 696.6238231355069, 698.6122701900447, 718.3175982816326, 14909.62725931851, 15774.21128943554, 16116.919415402936, 16528.33886644534, 16784.244708988095, 14924.403023879031, 15207.163606001694, 15356.308966096525, 15357.232138393063, 15542.84457155428, 912.2701838529183, 928.3872644054925, 931.585444477208, 933.743220614333, 934.9205317548017, 930.2604429478581, 933.12752576341, 940.9247260219166, 953.7091187187905, 964.7346979580636, 894.5795476126243, 1017.5421060522795, 1023.9078482936534, 1024.87701475823, 1026.686860507545, 997.5860018092483, 1008.7427705935013, 1009.0464511037073, 1010.408910787183, 1011.2567263061644, 743.2827321634594, 751.4439314906984, 755.2862544895077, 755.3579457461169, 755.4334249313015, 15779.368825246976, 16156.515304540882, 16183.271752771154, 16850.410235641815, 17091.401800936907, 14188.787168192486, 14378.28108594566, 14395.136291822524, 14501.927490362586, 14544.981851270415, 11249.55001799927, 11364.261323013978, 11504.367259800707, 11540.695221407614, 11849.0, 2817.957730634042, 2970.376220993594, 3030.2878823476467, 3132.3265497918055, 3141.575887255214, 116041.0, 120079.79920200861, 120603.0, 121128.39435802853, 123188.38405807913, 10819.0, 10927.562897484011, 10990.670279891616, 11180.776384472265, 11881.108916831272, 25129.120480783084, 25558.616620750705, 25678.48643027129, 25740.356491087874, 25766.0, 7725.304722574995, 7777.261160189773, 7906.972093627906, 7974.920250797418, 8010.679572817138, 3978.840846366113, 4024.215278020816, 4035.5157381114454, 4065.9390109148385, 4075.7962101894786, 3797.2785170817456, 4173.499180098347, 4269.530351661315, 4269.701120921568, 4277.6791740618955, 791.3115589437226, 796.3907610677836, 799.8400319936036, 819.8770184472377, 822.0341099208406, 515.487090345109, 557.8438037349549, 559.8628336057692, 568.9971550142222, 588.2499812738793, 9533.809323813484, 9595.0, 10086.0, 10120.866249657456, 10125.15525936424, 572.9598928074995, 591.2343515147894, 593.8812237552506, 603.1104121420957, 614.3549273263056, 4395.494518130392, 4632.791524381411, 4670.486246512881, 4677.0, 4690.0, 76334.618326908, 94300.11399771966, 95966.60083497969, 96289.0, 98230.5088474553, 69674.65162674256, 72332.63833680903, 73630.0, 89982.0, 90112.0, 74848.0, 78413.21586784173, 79867.20132798713, 80158.0, 81601.0, 9473.47895865734, 9536.33245672824, 9580.76048098803, 9604.807903841744, 9606.903930960601, 28175.013874514294, 33774.14754310896, 35378.64621353753, 35918.10204744902, 37884.62115378811, 60936.0, 66454.00637980868, 68770.0, 71357.0, 71470.28529714636, 12265.877341226831, 13835.792463113263, 13962.394324652794, 13970.161790292208, 14403.855961440671, 14729.914998628754, 14733.631659208177, 14833.851661483679, 15271.389144434543, 15648.765268520754, 13714.062937342349, 13720.794188087386, 13971.09187902783, 14002.57992260234, 14389.424423022961, 13497.527586534425, 13626.1143025703, 13645.931770341085, 13651.639242239506, 14541.636459088608, 13115.803262951255, 13329.933350333185, 13506.0, 13953.860461395256, 13988.930055349658, 9594.328397012143, 9845.901540984498, 9898.158656514277, 10056.446895420822, 10497.737556561147, 10374.17006639452, 10730.302530335815, 11196.38419886914, 11234.606788762354, 11263.774724505627], "value": 7539.987868667987, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1683.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 123.0, 122.0, 122.0, 124.0, 130.0, 126.0, 179.0, 182.0, 108.0], "value": 132.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 134.0, 147.0, 196.0, 123.0, 119.0, 151.0, 117.0, 159.0, 105.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [232.0, 279.0, 263.0, 230.0, 248.0, 265.0, 201.0, 228.0, 209.0, 280.0], "value": 243.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 61.0, 88.0, 62.0, 61.0, 63.0, 60.0, 81.0, 85.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 73.0, 87.0, 75.0, 72.0, 74.0, 86.0, 72.0, 80.0, 102.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 105.0, 137.0, 93.0, 120.0, 139.0, 95.0, 85.0, 123.0, 91.0], "value": 108.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 109.0, 96.0, 98.0, 102.0, 146.0, 97.0, 144.0, 134.0, 95.0], "value": 112.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 157.0, 166.0, 120.0, 98.0, 155.0, 146.0, 157.0, 137.0], "value": 130.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 68.0, 69.0, 69.0, 71.0, 64.0, 70.0, 79.0], "value": 69.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 65.0, 59.0, 62.0, 93.0, 88.0, 76.0, 84.0, 84.0, 68.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 89.0, 89.0, 89.0, 87.0, 92.0, 88.0, 89.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 150.0, 151.0, 146.0, 137.0, 165.0, 165.0, 156.0, 206.0, 174.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 199.0, 202.0, 229.0, 209.0, 197.0, 197.0, 197.0, 200.0, 194.0], "value": 202.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 73.0, 73.0, 73.0, 72.0, 72.0, 70.0, 70.0, 70.0], "value": 71.7, "unit": "score"}]}]} 05:57:09 INFO - Return code: 0 05:57:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:57:09 ERROR - # TBPL SUCCESS # 05:57:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:57:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:57:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:57:09 INFO - 2017-03-28 05:57:09.880 screenresolution[1657:11262] starting screenresolution argv=screenresolution get 05:57:09 INFO - 2017-03-28 05:57:09.882 screenresolution[1657:11262] Display 0: 1600x1200x32@60 05:57:09 INFO - 2017-03-28 05:57:09.888 screenresolution[1658:11265] starting screenresolution argv=screenresolution list 05:57:09 INFO - Available Modes on Display 0 05:57:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:57:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:57:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:57:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:57:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:57:10 INFO - Intel Iris: 05:57:10 INFO - Chipset Model: Intel Iris 05:57:10 INFO - Type: GPU 05:57:10 INFO - Bus: Built-In 05:57:10 INFO - VRAM (Dynamic, Max): 1536 MB 05:57:10 INFO - Vendor: Intel (0x8086) 05:57:10 INFO - Device ID: 0x0a2e 05:57:10 INFO - Revision ID: 0x0009 05:57:10 INFO - Displays: 05:57:10 INFO - Display: 05:57:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:57:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:57:10 INFO - Display Serial Number: 1600x1200 60 05:57:10 INFO - Main Display: Yes 05:57:10 INFO - Mirror: Off 05:57:10 INFO - Online: Yes 05:57:10 INFO - Rotation: Supported 05:57:10 INFO - Return code: 0 05:57:10 INFO - Running post-action listener: _resource_record_post_action 05:57:10 INFO - [mozharness: 2017-03-28 12:57:10.120256Z] Finished run-tests step (success) 05:57:10 INFO - Running post-run listener: _resource_record_post_run 05:57:10 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 39152128; Write bytes: 520507392; Read time: 247; Write time: 1645 05:57:10 INFO - TinderboxPrint: CPU usage
54.3% 05:57:10 INFO - TinderboxPrint: I/O read bytes / time
39,152,128 / 247 05:57:10 INFO - TinderboxPrint: I/O write bytes / time
520,507,392 / 1,645 05:57:10 INFO - TinderboxPrint: CPU idle
2,202.3 (45.7%) 05:57:10 INFO - TinderboxPrint: CPU system
969.1 (20.1%) 05:57:10 INFO - TinderboxPrint: CPU user
1,646.2 (34.2%) 05:57:10 INFO - TinderboxPrint: Swap in / out
250,638,336 / 0 05:57:10 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 209187328; Write bytes: 206186496; Read time: 16638; Write time: 438 05:57:10 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 37046784; Write bytes: 303204352; Read time: 225; Write time: 1170 05:57:10 INFO - Running post-run listener: _upload_blobber_files 05:57:10 INFO - Blob upload gear active. 05:57:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:57:10 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 05:57:10 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'] 05:57:10 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 05:57:11 INFO - (blobuploader) - INFO - Open directory for files ... 05:57:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:57:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:57:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:12 INFO - (blobuploader) - INFO - Done attempting. 05:57:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:57:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:57:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:13 INFO - (blobuploader) - INFO - Done attempting. 05:57:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:57:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:57:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:14 INFO - (blobuploader) - INFO - Done attempting. 05:57:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:57:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:57:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:16 INFO - (blobuploader) - INFO - Done attempting. 05:57:16 INFO - (blobuploader) - INFO - Iteration through files over. 05:57:16 INFO - Return code: 0 05:57:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:57:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:57:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca934ff192d560f614ee5e062a59e2468525a3ac3869ac3d32489e780259d82abfb927a440505265660c1db74b2f80d5adb05933a066b25e799d879302f8cd52", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db250eed02d98cbd08741755c11eda9dc4a33ea879cab2df1f12320de29080279316da6968eb5c20f399d66aae0d349849eccaa8c839866ee01153cea3ee4c2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/374d58ecd6edecc435b08cbac28fda10db0299b2d7b70e6095e36bc6de8b912d02e82f5946e42164757a6872bde7f8af0e9694fe2504fd0752945eb6c45ba6a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1758d575c867e9d3cf22d038e884fd6311338246ce4d067d397e450cb60ea54c840be57e34d075a983f6a4ff877d9e939eceb3de92435fb37fc5fc5756700bcb"} 05:57:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:57:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:57:16 INFO - Contents: 05:57:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca934ff192d560f614ee5e062a59e2468525a3ac3869ac3d32489e780259d82abfb927a440505265660c1db74b2f80d5adb05933a066b25e799d879302f8cd52", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db250eed02d98cbd08741755c11eda9dc4a33ea879cab2df1f12320de29080279316da6968eb5c20f399d66aae0d349849eccaa8c839866ee01153cea3ee4c2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/374d58ecd6edecc435b08cbac28fda10db0299b2d7b70e6095e36bc6de8b912d02e82f5946e42164757a6872bde7f8af0e9694fe2504fd0752945eb6c45ba6a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1758d575c867e9d3cf22d038e884fd6311338246ce4d067d397e450cb60ea54c840be57e34d075a983f6a4ff877d9e939eceb3de92435fb37fc5fc5756700bcb"} 05:57:16 INFO - Running post-run listener: copy_logs_to_upload_dir 05:57:16 INFO - Copying logs to upload dir... 05:57:16 INFO - mkdir: /builds/slave/test/build/upload/logs 05:57:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.985586 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-28 05:57:16.326681) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 05:57:16.330536) ========= 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.G36R2VMfjJ/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.JixhTXwQeE/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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/ca934ff192d560f614ee5e062a59e2468525a3ac3869ac3d32489e780259d82abfb927a440505265660c1db74b2f80d5adb05933a066b25e799d879302f8cd52", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db250eed02d98cbd08741755c11eda9dc4a33ea879cab2df1f12320de29080279316da6968eb5c20f399d66aae0d349849eccaa8c839866ee01153cea3ee4c2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/374d58ecd6edecc435b08cbac28fda10db0299b2d7b70e6095e36bc6de8b912d02e82f5946e42164757a6872bde7f8af0e9694fe2504fd0752945eb6c45ba6a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1758d575c867e9d3cf22d038e884fd6311338246ce4d067d397e450cb60ea54c840be57e34d075a983f6a4ff877d9e939eceb3de92435fb37fc5fc5756700bcb"} build_url:https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/eAR5plulRqiU7_vXIGm2nA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca934ff192d560f614ee5e062a59e2468525a3ac3869ac3d32489e780259d82abfb927a440505265660c1db74b2f80d5adb05933a066b25e799d879302f8cd52", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db250eed02d98cbd08741755c11eda9dc4a33ea879cab2df1f12320de29080279316da6968eb5c20f399d66aae0d349849eccaa8c839866ee01153cea3ee4c2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/374d58ecd6edecc435b08cbac28fda10db0299b2d7b70e6095e36bc6de8b912d02e82f5946e42164757a6872bde7f8af0e9694fe2504fd0752945eb6c45ba6a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1758d575c867e9d3cf22d038e884fd6311338246ce4d067d397e450cb60ea54c840be57e34d075a983f6a4ff877d9e939eceb3de92435fb37fc5fc5756700bcb"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 05:57:16.369071) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:57:16.369865) ========= 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.G36R2VMfjJ/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.JixhTXwQeE/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.005189 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 05:57:16.422762) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 05:57:16.423644) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 05:57:16.424473) ========= ========= Total master_lag: 0.47 =========