builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0390 starttime: 1491931733.07 results: success (0) buildid: 20170411085621 builduid: 86378fcf94b94a6588ae6b5d77e3f17f revision: c876f8b62d63bc7ba2dd15870add8e461da05427 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:53.065682) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:53.066116) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:53.066403) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:53.093319) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:53.093714) ========= 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.VJqHdlar6S/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.NTfabdeOnM/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-04-11 10:28:53-- 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% 18.3M=0.001s 2017-04-11 10:28:53 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113951 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:53.233406) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:53.233876) ========= 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.VJqHdlar6S/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.NTfabdeOnM/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.027327 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:53.283586) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 10:28:53.283931) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c876f8b62d63bc7ba2dd15870add8e461da05427 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c876f8b62d63bc7ba2dd15870add8e461da05427 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VJqHdlar6S/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.NTfabdeOnM/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-04-11 10:28:53,370 truncating revision to first 12 chars 2017-04-11 10:28:53,371 Setting DEBUG logging. 2017-04-11 10:28:53,371 attempt 1/10 2017-04-11 10:28:53,371 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c876f8b62d63?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 10:28:54,561 unpacking tar archive at: mozilla-aurora-c876f8b62d63/testing/mozharness/ program finished with exit code 0 elapsedTime=1.468081 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 10:28:54.778659) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:54.778996) ========= script_repo_revision: c876f8b62d63bc7ba2dd15870add8e461da05427 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:54.779393) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:54.779668) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 10:28:54.799749) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-11 10:28:54.800128) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VJqHdlar6S/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.NTfabdeOnM/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 10:28:54 INFO - MultiFileLogger online at 20170411 10:28:54 in /builds/slave/test 10:28:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:28:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:28:54 INFO - {'append_to_log': False, 10:28:54 INFO - 'base_work_dir': '/builds/slave/test', 10:28:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:28:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:28:54 INFO - 'branch': 'Mozilla-Aurora', 10:28:54 INFO - 'buildbot_json_path': 'buildprops.json', 10:28:54 INFO - 'config_files': ('talos/mac_config.py',), 10:28:54 INFO - 'default_actions': ('clobber', 10:28:54 INFO - 'read-buildbot-config', 10:28:54 INFO - 'download-and-extract', 10:28:54 INFO - 'populate-webroot', 10:28:54 INFO - 'create-virtualenv', 10:28:54 INFO - 'install', 10:28:54 INFO - 'run-tests'), 10:28:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:28:54 INFO - 'download_minidump_stackwalk': True, 10:28:54 INFO - 'download_symbols': 'ondemand', 10:28:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:28:54 INFO - 'tooltool.py': '/tools/tooltool.py', 10:28:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:28:54 INFO - '/tools/misc-python/virtualenv.py')}, 10:28:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:28:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:28:54 INFO - 'gecko_profile': False, 10:28:54 INFO - 'gecko_profile_interval': 0, 10:28:54 INFO - 'installer_path': 'installer.exe', 10:28:54 INFO - 'log_level': 'info', 10:28:54 INFO - 'log_name': 'talos', 10:28:54 INFO - 'log_to_console': True, 10:28:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:28:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:28:54 INFO - 'opt_config_files': (), 10:28:54 INFO - 'pip_index': False, 10:28:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:28:54 INFO - 'cmd': ('bash', 10:28:54 INFO - '-c', 10:28:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:28:54 INFO - 'enabled': True, 10:28:54 INFO - 'halt_on_failure': False, 10:28:54 INFO - 'name': 'check_screen_resolution'},), 10:28:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:28:54 INFO - 'cmd': ('bash', 10:28:54 INFO - '-c', 10:28:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:28:54 INFO - 'enabled': True, 10:28:54 INFO - 'halt_on_failure': False, 10:28:54 INFO - 'name': 'check_screen_resolution'},), 10:28:54 INFO - 'run_cmd_checks_enabled': True, 10:28:54 INFO - 'suite': 'dromaeojs-e10s', 10:28:54 INFO - 'system_bits': '32', 10:28:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:28:54 INFO - 'title': 't-yosemite-r7-0390', 10:28:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:28:54 INFO - 'use_talos_json': True, 10:28:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:28:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:28:54 INFO - 'work_dir': 'build'} 10:28:54 INFO - [mozharness: 2017-04-11 17:28:54.996074Z] Running clobber step. 10:28:54 INFO - Running pre-action listener: _resource_record_pre_action 10:28:54 INFO - Running main action method: clobber 10:28:54 INFO - rmtree: /builds/slave/test/build 10:28:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:28:57 INFO - Running post-action listener: _resource_record_post_action 10:28:57 INFO - [mozharness: 2017-04-11 17:28:57.480025Z] Finished clobber step (success) 10:28:57 INFO - [mozharness: 2017-04-11 17:28:57.480162Z] Running read-buildbot-config step. 10:28:57 INFO - Running pre-action listener: _resource_record_pre_action 10:28:57 INFO - Running main action method: read_buildbot_config 10:28:57 INFO - Using buildbot properties: 10:28:57 INFO - { 10:28:57 INFO - "project": "", 10:28:57 INFO - "product": "firefox", 10:28:57 INFO - "script_repo_revision": "production", 10:28:57 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 10:28:57 INFO - "repository": "", 10:28:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 10:28:57 INFO - "buildid": "20170411085621", 10:28:57 INFO - "pgo_build": "False", 10:28:57 INFO - "basedir": "/builds/slave/test", 10:28:57 INFO - "buildnumber": 39, 10:28:57 INFO - "slavename": "t-yosemite-r7-0390", 10:28:57 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 10:28:57 INFO - "platform": "yosemite_r7", 10:28:57 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:28:57 INFO - "branch": "mozilla-aurora", 10:28:57 INFO - "repo_path": "releases/mozilla-aurora", 10:28:57 INFO - "revision": "c876f8b62d63bc7ba2dd15870add8e461da05427", 10:28:57 INFO - "stage_platform": "macosx64", 10:28:57 INFO - "builduid": "86378fcf94b94a6588ae6b5d77e3f17f", 10:28:57 INFO - "slavebuilddir": "test" 10:28:57 INFO - } 10:28:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 10:28:57 INFO - Running post-action listener: _resource_record_post_action 10:28:57 INFO - [mozharness: 2017-04-11 17:28:57.483248Z] Finished read-buildbot-config step (success) 10:28:57 INFO - [mozharness: 2017-04-11 17:28:57.483360Z] Running download-and-extract step. 10:28:57 INFO - Running pre-action listener: _resource_record_pre_action 10:28:57 INFO - Running main action method: download_and_extract 10:28:57 INFO - mkdir: /builds/slave/test/build/tests 10:28:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:57 INFO - trying https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 10:28:57 INFO - Downloading https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 10:28:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 10:28:58 INFO - Downloaded 1199 bytes. 10:28:58 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 10:28:58 INFO - Using the following test package requirements: 10:28:58 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 10:28:58 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:28:58 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 10:28:58 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:28:58 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 10:28:58 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:28:58 INFO - u'jsshell-mac.zip'], 10:28:58 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:28:58 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 10:28:58 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 10:28:58 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:28:58 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 10:28:58 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:28:58 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 10:28:58 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:28:58 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 10:28:58 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:28:58 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 10:28:58 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:28:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 10:28:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:28:58 INFO - Fetch https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 10:28:59 INFO - Expected file size: 51790623 10:28:59 INFO - Obtained file size: 51790623 10:29:15 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 10:29:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 10:29:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:29:15 INFO - Fetch https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 10:29:17 INFO - Expected file size: 51790623 10:29:17 INFO - Obtained file size: 51790623 10:29:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 10:29:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:29:32 INFO - Fetch https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 10:29:34 INFO - Expected file size: 14404424 10:29:34 INFO - Obtained file size: 14404424 10:29:34 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')]} 10:29:34 INFO - trying https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 10:29:34 INFO - Downloading https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 10:29:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 10:29:36 INFO - Downloaded 74688692 bytes. 10:29:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 10:29:36 INFO - mkdir: /builds/slave/test/properties 10:29:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:29:36 INFO - Writing to file /builds/slave/test/properties/build_url 10:29:36 INFO - Contents: 10:29:36 INFO - build_url:https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 10:29:37 INFO - Running post-action listener: _resource_record_post_action 10:29:37 INFO - Running post-action listener: set_extra_try_arguments 10:29:37 INFO - [mozharness: 2017-04-11 17:29:37.244552Z] Finished download-and-extract step (success) 10:29:37 INFO - [mozharness: 2017-04-11 17:29:37.244700Z] Running populate-webroot step. 10:29:37 INFO - Running pre-action listener: _resource_record_pre_action 10:29:37 INFO - Running main action method: populate_webroot 10:29:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:29:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:29:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:29:37 INFO - '--tppagecycles', 10:29:37 INFO - '7'], 10:29:37 INFO - 'tests': ['tsvgm']}}, 10:29:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:29:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:29:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:29:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:29:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:29:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:29:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:29:37 INFO - 'talos_options': ['--disable-e10s'], 10:29:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:29:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:29:37 INFO - 'talos_options': ['--disable-e10s'], 10:29:37 INFO - 'tests': ['damp', 'tps']}, 10:29:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:29:37 INFO - 'tests': ['damp', 'tps']}, 10:29:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:29:37 INFO - 'tests': ['dromaeo_dom']}, 10:29:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:29:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:29:37 INFO - 'tests': ['basic_compositor_video']}, 10:29:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:29:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:29:37 INFO - 'tests': ['a11yr', 10:29:37 INFO - 'ts_paint', 10:29:37 INFO - 'tpaint', 10:29:37 INFO - 'sessionrestore', 10:29:37 INFO - 'sessionrestore_no_auto_restore', 10:29:37 INFO - 'tabpaint']}, 10:29:37 INFO - 'other-e10s': {'tests': ['a11yr', 10:29:37 INFO - 'ts_paint', 10:29:37 INFO - 'tpaint', 10:29:37 INFO - 'sessionrestore', 10:29:37 INFO - 'sessionrestore_no_auto_restore', 10:29:37 INFO - 'tabpaint']}, 10:29:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:29:37 INFO - 'ts_paint', 10:29:37 INFO - 'tpaint', 10:29:37 INFO - 'sessionrestore', 10:29:37 INFO - 'sessionrestore_no_auto_restore', 10:29:37 INFO - 'tabpaint']}, 10:29:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:29:37 INFO - 'ts_paint', 10:29:37 INFO - 'tpaint', 10:29:37 INFO - 'sessionrestore', 10:29:37 INFO - 'sessionrestore_no_auto_restore', 10:29:37 INFO - 'tabpaint']}, 10:29:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:29:37 INFO - 'tests': ['a11yr', 10:29:37 INFO - 'ts_paint', 10:29:37 INFO - 'tpaint', 10:29:37 INFO - 'sessionrestore', 10:29:37 INFO - 'sessionrestore_no_auto_restore', 10:29:37 INFO - 'tabpaint']}, 10:29:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:29:37 INFO - 'tests': ['a11yr', 10:29:37 INFO - 'ts_paint', 10:29:37 INFO - 'tpaint', 10:29:37 INFO - 'sessionrestore', 10:29:37 INFO - 'sessionrestore_no_auto_restore', 10:29:37 INFO - 'tabpaint']}, 10:29:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:29:37 INFO - 'tests': ['tsvgx', 10:29:37 INFO - 'tsvgr_opacity', 10:29:37 INFO - 'tart', 10:29:37 INFO - 'tscrollx', 10:29:37 INFO - 'cart', 10:29:37 INFO - 'tsvg_static']}, 10:29:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:29:37 INFO - 'tsvgr_opacity', 10:29:37 INFO - 'tart', 10:29:37 INFO - 'tscrollx', 10:29:37 INFO - 'cart', 10:29:37 INFO - 'tsvg_static']}, 10:29:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:29:37 INFO - 'talos_options': ['--disable-e10s'], 10:29:37 INFO - 'tests': ['tp5o']}, 10:29:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:29:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:29:37 INFO - 'talos_options': ['--disable-e10s', 10:29:37 INFO - '--xperf_path', 10:29:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:37 INFO - 'tests': ['tp5n']}, 10:29:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:29:37 INFO - 'talos_options': ['--xperf_path', 10:29:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:37 INFO - 'tests': ['tp5n']}}, 10:29:37 INFO - 'talos.zip': {'path': '', 10:29:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:29:37 INFO - Running post-action listener: _resource_record_post_action 10:29:37 INFO - [mozharness: 2017-04-11 17:29:37.262925Z] Finished populate-webroot step (success) 10:29:37 INFO - [mozharness: 2017-04-11 17:29:37.263042Z] Running create-virtualenv step. 10:29:37 INFO - Running pre-action listener: _resource_record_pre_action 10:29:37 INFO - Running main action method: create_virtualenv 10:29:37 INFO - Creating virtualenv /builds/slave/test/build/venv 10:29:37 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 10:29:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:29:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:29:37 INFO - Using real prefix '/tools/python27' 10:29:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:29:38 INFO - Installing distribute.............................................................................................................................................................................................done. 10:29:40 INFO - Installing pip.................done. 10:29:40 INFO - Return code: 0 10:29:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:29:41 INFO - Reading from file tmpfile_stdout 10:29:41 INFO - Output received: 10:29:41 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) 10:29:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073ddb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd011d66590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071bbd30>, '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 0x1070e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b0be8>, '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.VJqHdlar6S/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.NTfabdeOnM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 10:29: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', 'pip>=1.5'] in /builds/slave/test/build 10:29: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 pip>=1.5 10:29:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqHdlar6S/Render', 10:29:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:41 INFO - 'HOME': '/Users/cltbld', 10:29:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:41 INFO - 'LOGNAME': 'cltbld', 10:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:41 INFO - 'MOZ_NO_REMOTE': '1', 10:29:41 INFO - 'NO_EM_RESTART': '1', 10:29:41 INFO - 'PAGER': '/bin/cat', 10:29:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:41 INFO - 'PWD': '/builds/slave/test', 10:29:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:41 INFO - 'SHELL': '/bin/bash', 10:29:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NTfabdeOnM/Listeners', 10:29:41 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 10:29:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:41 INFO - 'USER': 'cltbld', 10:29:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:41 INFO - 'XPC_FLAGS': '0x0', 10:29:41 INFO - 'XPC_SERVICE_NAME': '0', 10:29:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:41 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 10:29:41 INFO - Cleaning up... 10:29:41 INFO - Return code: 0 10:29:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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', '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 0x1073ddb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd011d66590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071bbd30>, '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 0x1070e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b0be8>, '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.VJqHdlar6S/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.NTfabdeOnM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 10:29: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:29: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 psutil>=3.1.1 10:29:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqHdlar6S/Render', 10:29:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:41 INFO - 'HOME': '/Users/cltbld', 10:29:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:41 INFO - 'LOGNAME': 'cltbld', 10:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:41 INFO - 'MOZ_NO_REMOTE': '1', 10:29:41 INFO - 'NO_EM_RESTART': '1', 10:29:41 INFO - 'PAGER': '/bin/cat', 10:29:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:41 INFO - 'PWD': '/builds/slave/test', 10:29:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:41 INFO - 'SHELL': '/bin/bash', 10:29:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NTfabdeOnM/Listeners', 10:29:41 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 10:29:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:41 INFO - 'USER': 'cltbld', 10:29:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:41 INFO - 'XPC_FLAGS': '0x0', 10:29:41 INFO - 'XPC_SERVICE_NAME': '0', 10:29:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:41 INFO - Downloading/unpacking psutil>=3.1.1 10:29:41 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 10:29:41 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 10:29:41 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 10:29:41 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 10:29:41 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 10:29:41 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 10:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:29:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:45 INFO - Installing collected packages: psutil 10:29:45 INFO - Running setup.py install for psutil 10:29:45 INFO - building 'psutil._psutil_osx' extension 10:29:45 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 10:29:45 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 10:29:45 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 10:29:46 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 10:29:46 INFO - building 'psutil._psutil_posix' extension 10:29:46 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 10:29:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:29:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:29:46 INFO - ^ 10:29:46 INFO - 1 warning generated. 10:29:46 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 10:29:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:46 INFO - Successfully installed psutil 10:29:46 INFO - Cleaning up... 10:29:46 INFO - Return code: 0 10:29:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073ddb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd011d66590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071bbd30>, '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 0x1070e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b0be8>, '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.VJqHdlar6S/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.NTfabdeOnM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 10:29:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:29:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:29:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqHdlar6S/Render', 10:29:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:46 INFO - 'HOME': '/Users/cltbld', 10:29:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:46 INFO - 'LOGNAME': 'cltbld', 10:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:46 INFO - 'MOZ_NO_REMOTE': '1', 10:29:46 INFO - 'NO_EM_RESTART': '1', 10:29:46 INFO - 'PAGER': '/bin/cat', 10:29:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:46 INFO - 'PWD': '/builds/slave/test', 10:29:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:46 INFO - 'SHELL': '/bin/bash', 10:29:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NTfabdeOnM/Listeners', 10:29:46 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 10:29:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:46 INFO - 'USER': 'cltbld', 10:29:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:46 INFO - 'XPC_FLAGS': '0x0', 10:29:46 INFO - 'XPC_SERVICE_NAME': '0', 10:29:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:29:46 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 10:29:46 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 10:29:46 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 10:29:46 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 10:29:46 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 10:29:46 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 10:29:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:29:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:29:49 INFO - Installing collected packages: mozsystemmonitor 10:29:49 INFO - Running setup.py install for mozsystemmonitor 10:29:49 INFO - Successfully installed mozsystemmonitor 10:29:49 INFO - Cleaning up... 10:29:49 INFO - Return code: 0 10:29:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:50 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 0x1073ddb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd011d66590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071bbd30>, '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 0x1070e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b0be8>, '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.VJqHdlar6S/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.NTfabdeOnM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 10:29:50 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 10:29:50 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 10:29:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqHdlar6S/Render', 10:29:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:50 INFO - 'HOME': '/Users/cltbld', 10:29:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:50 INFO - 'LOGNAME': 'cltbld', 10:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:50 INFO - 'MOZ_NO_REMOTE': '1', 10:29:50 INFO - 'NO_EM_RESTART': '1', 10:29:50 INFO - 'PAGER': '/bin/cat', 10:29:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:50 INFO - 'PWD': '/builds/slave/test', 10:29:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:50 INFO - 'SHELL': '/bin/bash', 10:29:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NTfabdeOnM/Listeners', 10:29:50 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 10:29:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:50 INFO - 'USER': 'cltbld', 10:29:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:50 INFO - 'XPC_FLAGS': '0x0', 10:29:50 INFO - 'XPC_SERVICE_NAME': '0', 10:29:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:50 INFO - Downloading/unpacking jsonschema==2.5.1 10:29:50 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 10:29:50 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 10:29:50 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 10:29:50 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 10:29:50 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 10:29:50 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 10:29:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:29:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29:54 INFO - Downloading functools32-3.2.3-2.tar.gz 10:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:29:54 INFO - warning: no files found matching '*.txt' 10:29:54 INFO - no previously-included directories found matching 'build' 10:29:54 INFO - no previously-included directories found matching 'dist' 10:29:54 INFO - no previously-included directories found matching '.git*' 10:29:54 INFO - Installing collected packages: jsonschema, functools32 10:29:54 INFO - Running setup.py install for functools32 10:29:54 INFO - warning: no files found matching '*.txt' 10:29:54 INFO - no previously-included directories found matching 'build' 10:29:54 INFO - no previously-included directories found matching 'dist' 10:29:54 INFO - no previously-included directories found matching '.git*' 10:29:54 INFO - Successfully installed jsonschema functools32 10:29:54 INFO - Cleaning up... 10:29:54 INFO - Return code: 0 10:29:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1073ddb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd011d66590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071bbd30>, '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 0x1070e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b0be8>, '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.VJqHdlar6S/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.NTfabdeOnM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 10:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqHdlar6S/Render', 10:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:54 INFO - 'HOME': '/Users/cltbld', 10:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:54 INFO - 'LOGNAME': 'cltbld', 10:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:54 INFO - 'MOZ_NO_REMOTE': '1', 10:29:54 INFO - 'NO_EM_RESTART': '1', 10:29:54 INFO - 'PAGER': '/bin/cat', 10:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:54 INFO - 'PWD': '/builds/slave/test', 10:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:54 INFO - 'SHELL': '/bin/bash', 10:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NTfabdeOnM/Listeners', 10:29:54 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 10:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:54 INFO - 'USER': 'cltbld', 10:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:54 INFO - 'XPC_FLAGS': '0x0', 10:29:54 INFO - 'XPC_SERVICE_NAME': '0', 10:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:29:54 INFO - Cleaning up... 10:29:54 INFO - Return code: 0 10:29:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073ddb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd011d66590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071bbd30>, '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 0x1070e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b0be8>, '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.VJqHdlar6S/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.NTfabdeOnM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 10:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqHdlar6S/Render', 10:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:54 INFO - 'HOME': '/Users/cltbld', 10:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:54 INFO - 'LOGNAME': 'cltbld', 10:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:54 INFO - 'MOZ_NO_REMOTE': '1', 10:29:54 INFO - 'NO_EM_RESTART': '1', 10:29:54 INFO - 'PAGER': '/bin/cat', 10:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:54 INFO - 'PWD': '/builds/slave/test', 10:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:54 INFO - 'SHELL': '/bin/bash', 10:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NTfabdeOnM/Listeners', 10:29:54 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 10:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:54 INFO - 'USER': 'cltbld', 10:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:54 INFO - 'XPC_FLAGS': '0x0', 10:29:54 INFO - 'XPC_SERVICE_NAME': '0', 10:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:54 INFO - Downloading/unpacking blobuploader==1.2.4 10:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:57 INFO - Downloading blobuploader-1.2.4.tar.gz 10:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:29:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:29:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:59 INFO - Downloading docopt-0.6.1.tar.gz 10:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:29:59 INFO - Installing collected packages: blobuploader, requests, docopt 10:29:59 INFO - Running setup.py install for blobuploader 10:29:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:29:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:29:59 INFO - Running setup.py install for requests 10:29:59 INFO - Running setup.py install for docopt 10:29:59 INFO - Successfully installed blobuploader requests docopt 10:29:59 INFO - Cleaning up... 10:30:00 INFO - Return code: 0 10:30:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:00 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 0x1073ddb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd011d66590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071bbd30>, '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 0x1070e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b0be8>, '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.VJqHdlar6S/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.NTfabdeOnM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 10:30:00 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 10:30:00 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 10:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqHdlar6S/Render', 10:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:00 INFO - 'HOME': '/Users/cltbld', 10:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:00 INFO - 'LOGNAME': 'cltbld', 10:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:00 INFO - 'MOZ_NO_REMOTE': '1', 10:30:00 INFO - 'NO_EM_RESTART': '1', 10:30:00 INFO - 'PAGER': '/bin/cat', 10:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:00 INFO - 'PWD': '/builds/slave/test', 10:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:00 INFO - 'SHELL': '/bin/bash', 10:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NTfabdeOnM/Listeners', 10:30:00 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 10:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:00 INFO - 'USER': 'cltbld', 10:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:00 INFO - 'XPC_FLAGS': '0x0', 10:30:00 INFO - 'XPC_SERVICE_NAME': '0', 10:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:30:00 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Srm2uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:30:00 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-p0Lq3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:30:00 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-48eQbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:30:00 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-4BfgQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:30:00 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-xZEE4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:30:00 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-3VfY2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:30:00 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-r0rOxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:30:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-CnqA6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:30:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-i43iLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:30:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-DRWNE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:30:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ZzxiT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:30:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-eUVaqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:30:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-_NS9vJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:30:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-556MRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Wp7rZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:30:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-9xUUEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:30:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ly0ubA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:30:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:30:02 INFO - Running setup.py install for manifestparser 10:30:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Running setup.py install for mozcrash 10:30:02 INFO - Running setup.py install for mozdebug 10:30:02 INFO - Running setup.py install for mozdevice 10:30:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Running setup.py install for mozfile 10:30:02 INFO - Running setup.py install for mozhttpd 10:30:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Running setup.py install for mozinfo 10:30:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Running setup.py install for mozInstall 10:30:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Running setup.py install for mozleak 10:30:03 INFO - Running setup.py install for mozlog 10:30:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Running setup.py install for moznetwork 10:30:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Running setup.py install for mozprocess 10:30:03 INFO - Running setup.py install for mozprofile 10:30:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:30:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:30:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:30:04 INFO - Running setup.py install for mozrunner 10:30:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:30:04 INFO - Running setup.py install for mozscreenshot 10:30:04 INFO - Running setup.py install for moztest 10:30:04 INFO - Running setup.py install for mozversion 10:30:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:30:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:30:04 INFO - Cleaning up... 10:30:04 INFO - Return code: 0 10:30:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30: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')]} 10:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30: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')]} 10:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:04 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 0x1073ddb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd011d66590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071bbd30>, '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 0x1070e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b0be8>, '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.VJqHdlar6S/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.NTfabdeOnM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 10:30:04 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 10:30:04 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 10:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqHdlar6S/Render', 10:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:04 INFO - 'HOME': '/Users/cltbld', 10:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:04 INFO - 'LOGNAME': 'cltbld', 10:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:04 INFO - 'MOZ_NO_REMOTE': '1', 10:30:04 INFO - 'NO_EM_RESTART': '1', 10:30:04 INFO - 'PAGER': '/bin/cat', 10:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:04 INFO - 'PWD': '/builds/slave/test', 10:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:04 INFO - 'SHELL': '/bin/bash', 10:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NTfabdeOnM/Listeners', 10:30:04 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 10:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:04 INFO - 'USER': 'cltbld', 10:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:04 INFO - 'XPC_FLAGS': '0x0', 10:30:04 INFO - 'XPC_SERVICE_NAME': '0', 10:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:30:04 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-A5eqIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:30:05 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)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:30:05 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-rS4YCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:30:05 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)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:30:05 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-bjTHal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:30:05 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)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:30:05 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-XXxNJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:30:05 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)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:30:05 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-fPc6Fa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:30:05 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)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:30:05 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ntVKMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:30:05 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)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:30:05 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-mATELM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:30:05 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)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:30:05 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-3Eb0xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:30:05 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)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:30:05 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-_pr_uO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:30:05 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-8I9Fgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:30:06 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)) 10:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:30:06 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-xs4MG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:30:06 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)) 10:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:30:06 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ZS_Vdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:30:06 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)) 10:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:30:06 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-AIEp4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:30:06 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)) 10:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:30:06 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-EGn2vI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:30:06 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)) 10:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:06 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-5rUutc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:06 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)) 10:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:30:06 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-bkZ6FN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:30:06 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)) 10:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:30:06 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-KMSjmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:30:06 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)) 10:30:06 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)) 10:30:06 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)) 10:30:06 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)) 10:30:06 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)) 10:30:06 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)) 10:30:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:30:06 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 10:30:06 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 10:30:06 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 10:30:06 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 10:30:06 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 10:30:06 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 10:30:09 INFO - Downloading blessings-1.6.tar.gz 10:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:30:09 INFO - Installing collected packages: blessings 10:30:09 INFO - Running setup.py install for blessings 10:30:09 INFO - Successfully installed blessings 10:30:09 INFO - Cleaning up... 10:30:09 INFO - Return code: 0 10:30:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:30:09 INFO - Reading from file tmpfile_stdout 10:30:10 INFO - Current package versions: 10:30:10 INFO - blessings == 1.6 10:30:10 INFO - blobuploader == 1.2.4 10:30:10 INFO - docopt == 0.6.1 10:30:10 INFO - functools32 == 3.2.3-2 10:30:10 INFO - jsonschema == 2.5.1 10:30:10 INFO - manifestparser == 1.1 10:30:10 INFO - mozInstall == 1.12 10:30:10 INFO - mozcrash == 1.0 10:30:10 INFO - mozdebug == 0.1 10:30:10 INFO - mozdevice == 0.49 10:30:10 INFO - mozfile == 1.2 10:30:10 INFO - mozhttpd == 0.7 10:30:10 INFO - mozinfo == 0.9 10:30:10 INFO - mozleak == 0.1 10:30:10 INFO - mozlog == 3.4 10:30:10 INFO - moznetwork == 0.27 10:30:10 INFO - mozprocess == 0.25 10:30:10 INFO - mozprofile == 0.28 10:30:10 INFO - mozrunner == 6.13 10:30:10 INFO - mozscreenshot == 0.1 10:30:10 INFO - mozsystemmonitor == 0.3 10:30:10 INFO - moztest == 0.8 10:30:10 INFO - mozversion == 1.4 10:30:10 INFO - psutil == 3.1.1 10:30:10 INFO - requests == 1.2.3 10:30:10 INFO - wsgiref == 0.1.2 10:30:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:10 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 0x1073ddb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd011d66590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071bbd30>, '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 0x1070e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b0be8>, '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.VJqHdlar6S/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.NTfabdeOnM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 10:30:10 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 10:30:10 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 10:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqHdlar6S/Render', 10:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:10 INFO - 'HOME': '/Users/cltbld', 10:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:10 INFO - 'LOGNAME': 'cltbld', 10:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:10 INFO - 'MOZ_NO_REMOTE': '1', 10:30:10 INFO - 'NO_EM_RESTART': '1', 10:30:10 INFO - 'PAGER': '/bin/cat', 10:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:10 INFO - 'PWD': '/builds/slave/test', 10:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:10 INFO - 'SHELL': '/bin/bash', 10:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NTfabdeOnM/Listeners', 10:30:10 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 10:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:10 INFO - 'USER': 'cltbld', 10:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:10 INFO - 'XPC_FLAGS': '0x0', 10:30:10 INFO - 'XPC_SERVICE_NAME': '0', 10:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:10 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)) 10:30:10 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)) 10:30:10 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)) 10:30:10 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)) 10:30:10 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)) 10:30:10 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)) 10:30:10 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)) 10:30:10 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)) 10:30:10 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)) 10:30:10 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)) 10:30:10 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)) 10:30:10 INFO - Cleaning up... 10:30:10 INFO - Return code: 0 10:30:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:10 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 0x1073ddb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd011d66590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071bbd30>, '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 0x1070e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b0be8>, '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.VJqHdlar6S/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.NTfabdeOnM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 10:30:10 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 10:30:10 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 10:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqHdlar6S/Render', 10:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:10 INFO - 'HOME': '/Users/cltbld', 10:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:10 INFO - 'LOGNAME': 'cltbld', 10:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:10 INFO - 'MOZ_NO_REMOTE': '1', 10:30:10 INFO - 'NO_EM_RESTART': '1', 10:30:10 INFO - 'PAGER': '/bin/cat', 10:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:10 INFO - 'PWD': '/builds/slave/test', 10:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:10 INFO - 'SHELL': '/bin/bash', 10:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NTfabdeOnM/Listeners', 10:30:10 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 10:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:10 INFO - 'USER': 'cltbld', 10:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:10 INFO - 'XPC_FLAGS': '0x0', 10:30:10 INFO - 'XPC_SERVICE_NAME': '0', 10:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:30:10 INFO - Cleaning up... 10:30:10 INFO - Return code: 0 10:30:10 INFO - Running post-action listener: _resource_record_post_action 10:30:10 INFO - Running post-action listener: _start_resource_monitoring 10:30:10 INFO - Starting resource monitoring. 10:30:10 INFO - [mozharness: 2017-04-11 17:30:10.566380Z] Finished create-virtualenv step (success) 10:30:10 INFO - [mozharness: 2017-04-11 17:30:10.567384Z] Running install step. 10:30:10 INFO - Running pre-action listener: _resource_record_pre_action 10:30:10 INFO - Running main action method: install 10:30:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:30:10 INFO - Reading from file tmpfile_stdout 10:30:10 INFO - Detecting whether we're running mozinstall >=1.0... 10:30:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:30:10 INFO - Reading from file tmpfile_stdout 10:30:10 INFO - Output received: 10:30:10 INFO - Usage: mozinstall [options] installer 10:30:10 INFO - Options: 10:30:10 INFO - -h, --help show this help message and exit 10:30:10 INFO - -d DEST, --destination=DEST 10:30:10 INFO - Directory to install application into. [default: 10:30:10 INFO - "/builds/slave/test"] 10:30:10 INFO - --app=APP Application being installed. [default: firefox] 10:30:10 INFO - mkdir: /builds/slave/test/build/application 10:30:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:30:31 INFO - Reading from file tmpfile_stdout 10:30:31 INFO - Output received: 10:30:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:30:31 INFO - Running post-action listener: _resource_record_post_action 10:30:31 INFO - [mozharness: 2017-04-11 17:30:31.164263Z] Finished install step (success) 10:30:31 INFO - [mozharness: 2017-04-11 17:30:31.164393Z] Running run-tests step. 10:30:31 INFO - Running pre-action listener: _resource_record_pre_action 10:30:31 INFO - Running main action method: run_tests 10:30:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:30:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:30:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:30:31 INFO - 2017-04-11 10:30:31.179 screenresolution[1641:7612] starting screenresolution argv=screenresolution get 10:30:31 INFO - 2017-04-11 10:30:31.181 screenresolution[1641:7612] Display 0: 1600x1200x32@60 10:30:31 INFO - 2017-04-11 10:30:31.187 screenresolution[1642:7614] starting screenresolution argv=screenresolution list 10:30:31 INFO - Available Modes on Display 0 10:30:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:30:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:30:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:30:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:30:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:30:31 INFO - Intel Iris: 10:30:31 INFO - Chipset Model: Intel Iris 10:30:31 INFO - Type: GPU 10:30:31 INFO - Bus: Built-In 10:30:31 INFO - VRAM (Dynamic, Max): 1536 MB 10:30:31 INFO - Vendor: Intel (0x8086) 10:30:31 INFO - Device ID: 0x0a2e 10:30:31 INFO - Revision ID: 0x0009 10:30:31 INFO - Displays: 10:30:31 INFO - Display: 10:30:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:30:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:30:31 INFO - Display Serial Number: 1600x1200 60 10:30:31 INFO - Main Display: Yes 10:30:31 INFO - Mirror: Off 10:30:31 INFO - Online: Yes 10:30:31 INFO - Rotation: Supported 10:30:31 INFO - Return code: 0 10:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:30:31 INFO - Python 2.7.3 10:30:31 INFO - Return code: 0 10:30:31 INFO - grabbing minidump binary from tooltool 10:30:31 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')]} 10:30:31 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 0x1071bbd30>, '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 0x1070e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:30:31 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 10:30:31 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 10:30:31 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 10:30:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:30:31 INFO - Return code: 0 10:30:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:30:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:30:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:30:31 INFO - ENV: RUST_BACKTRACE is now 1 10:30:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:30:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 10:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0390 --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 10:30:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VJqHdlar6S/Render', 10:30:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:31 INFO - 'HOME': '/Users/cltbld', 10:30:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:31 INFO - 'LOGNAME': 'cltbld', 10:30:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:30:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:31 INFO - 'MOZ_NO_REMOTE': '1', 10:30:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:30:31 INFO - 'NO_EM_RESTART': '1', 10:30:31 INFO - 'PAGER': '/bin/cat', 10:30:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:31 INFO - 'PWD': '/builds/slave/test', 10:30:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:30:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:31 INFO - 'RUST_BACKTRACE': '1', 10:30:31 INFO - 'SHELL': '/bin/bash', 10:30:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NTfabdeOnM/Listeners', 10:30:31 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 10:30:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:31 INFO - 'USER': 'cltbld', 10:30:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:31 INFO - 'XPC_FLAGS': '0x0', 10:30:31 INFO - 'XPC_SERVICE_NAME': '0', 10:30:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 10:30:31 INFO - mozversion application_buildid: 20170411085621 10:30:31 INFO - mozversion application_changeset: c876f8b62d63bc7ba2dd15870add8e461da05427 10:30:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:30:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:30:31 INFO - mozversion application_name: Firefox 10:30:31 INFO - mozversion application_remotingname: firefox-dev 10:30:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:30:31 INFO - mozversion application_vendor: Mozilla 10:30:31 INFO - mozversion application_version: 54.0a2 10:30:31 INFO - mozversion platform_buildid: 20170411085621 10:30:31 INFO - mozversion platform_changeset: c876f8b62d63bc7ba2dd15870add8e461da05427 10:30:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:30:31 INFO - mozversion platform_version: 54.0a2 10:30:31 INFO - using testdate: 1491931831 10:30:31 INFO - actual date: 1491931831 10:30:31 INFO - starting webserver on 'localhost:49242' 10:30:31 INFO - SUITE-START | Running 2 tests 10:30:31 INFO - TEST-START | dromaeo_css 10:30:31 INFO - operating with platform_type : mac_ 10:30:31 INFO - Initialising browser for dromaeo_css test... 10:30:31 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmp9wLEe7/profile http://localhost:49242/getInfo.html) 10:30:34 INFO - PROCESS | 1652 | 2017-04-11 10:30:34.644 plugin-container[1654:7796] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 10:30:34 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:34 INFO - PROCESS | 1652 | 2017-04-11 10:30:34.646 plugin-container[1654:7796] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 10:30:34 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:34 INFO - PROCESS | 1652 | 2017-04-11 10:30:34.646 plugin-container[1654:7796] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:30:34 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 10:30:34 INFO - PROCESS | 1652 | colorDepth:24 10:30:34 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 10:30:34 INFO - PROCESS | 1652 | __metrics 10:30:34 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 10:30:35 INFO - TEST-INFO | 1652: exit 0 10:30:35 INFO - Browser initialized. 10:30:35 INFO - Running cycle 1/1 for dromaeo_css test... 10:30:35 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmp9wLEe7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:30:38 INFO - PROCESS | 1655 | 2017-04-11 10:30:38.197 plugin-container[1656:7924] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 10:30:38 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:38 INFO - PROCESS | 1655 | 2017-04-11 10:30:38.197 plugin-container[1656:7924] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 10:30:38 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:38 INFO - PROCESS | 1655 | 2017-04-11 10:30:38.198 plugin-container[1656:7924] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:30:47 INFO - PROCESS | 1655 | RSS: Main: 220524544 10:30:47 INFO - PROCESS | 1655 | 10:33:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 10:33:53 INFO - PROCESS | 1655 | RSS: Main: 243941376 10:33:53 INFO - PROCESS | 1655 | 10:35:36 INFO - PROCESS | 1655 | ************************* 10:35:36 INFO - PROCESS | 1655 | A coding exception was thrown and uncaught in a Task. 10:35:36 INFO - PROCESS | 1655 | 10:35:36 ERROR - PROCESS | 1655 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:35:36 INFO - PROCESS | 1655 | Full stack: 10:35:36 INFO - PROCESS | 1655 | ************************* 10:35:36 INFO - PROCESS | 1655 | ************************* 10:35:36 INFO - PROCESS | 1655 | A coding exception was thrown and uncaught in a Task. 10:35:36 INFO - PROCESS | 1655 | 10:35:36 ERROR - PROCESS | 1655 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:35:36 INFO - PROCESS | 1655 | Full stack: 10:35:36 INFO - PROCESS | 1655 | ************************* 10:35:36 INFO - PROCESS | 1655 | ************************* 10:35:36 INFO - PROCESS | 1655 | A coding exception was thrown and uncaught in a Task. 10:35:36 INFO - PROCESS | 1655 | 10:35:36 ERROR - PROCESS | 1655 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:35:36 INFO - PROCESS | 1655 | Full stack: 10:35:36 INFO - PROCESS | 1655 | ************************* 10:35:36 INFO - PROCESS | 1655 | ************************* 10:35:36 INFO - PROCESS | 1655 | A coding exception was thrown and uncaught in a Task. 10:35:36 INFO - PROCESS | 1655 | 10:35:36 ERROR - PROCESS | 1655 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:35:36 INFO - PROCESS | 1655 | Full stack: 10:35:36 INFO - PROCESS | 1655 | ************************* 10:36:55 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 10:36:56 INFO - PROCESS | 1655 | RSS: Main: 254898176 10:36:56 INFO - PROCESS | 1655 | 10:39:58 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 10:39:58 INFO - PROCESS | 1655 | RSS: Main: 261165056 10:39:58 INFO - PROCESS | 1655 | 10:43:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 10:43:01 INFO - PROCESS | 1655 | RSS: Main: 257359872 10:43:01 INFO - PROCESS | 1655 | 10:46:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 10:46:04 INFO - PROCESS | 1655 | RSS: Main: 262270976 10:46:04 INFO - PROCESS | 1655 | 10:49:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 10:49:08 INFO - PROCESS | 1655 | RSS: Main: 258633728 10:49:08 INFO - PROCESS | 1655 | 10:49:08 INFO - PROCESS | 1655 | __start_tp_report 10:49:08 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 10:49:08 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 10:49:08 INFO - PROCESS | 1655 | |i|pagename|runs| 10:49:08 INFO - PROCESS | 1655 | |0;dojo.html;2751.931201719957;3409.437442821934;3425.520427140201;3430.708389786868;3452.9813704957037;5107.361579802525;5267.23625074364;5911.408859114092;5945.672987985659;5996.2804463464445;5628.831135065944;5917.822465326047;5981.431763982426;5988.88022239556;6012.038073908176;5386.057439948013;5405.162199859008;5406.297181366417;5414;5452.536534394581;5438.945610543903;5465.890682186354;5467.9610873934025;5476.17857321401;5495.6153069284965;5243.056249875021;5253.0544501989625;5462.344518657746;5465.098258787315;5498.340199176085;4452.621527170194;5392.622516423852;5411.377691565481;5443.618946673695;5519.420460851633;4378;5251.86870328241;5374.731263436852;5393.73031348435;5408.5402740766685;5588.608797384185;5883.441073098049;5886.087656413263;5971.313298970631;5978.790742323998;6264.21697287839;6268.49852011838;6272.529560282951;6273.090401891745;6292.748290068392;6341.365863413668;6363.459105975998;6384.233891932951;6405.775797847053;6407.199100112486;6054.878902421927;6074.757009719606;6079.118527813486;6114.57197996136;6118.510519158456;5031.874203144877;6164.260288765377;6267.9373206267355;6278;6367.777127800598;6253.343398943137;6342.238931328178;6351.936480635227;6370.713317900706;6372.74509019634;6356.555041147075;6369.394907483782;6375.3624637536805;6377.585456945284;6389;5114.258432527263;5285.392179899284;6184.628922264678;6287.559870808999;6395.072714456378;4941.234108713155;6288.968555157195;6295.748170073238;6299.307076221612;6350.364963503705;4931.679440836334;5059.19052951526;5121.180611102206;5142.948570514247;5154.690718556823;4512.120136573402;4538.546145385435;4569.977150114295;4575;4580.404547408815;5061;5197.948020519822;5797.130430435384;5969.283685957713;5997.220361352996;6250.74997000124;6301.275353334425;6367.936320636827;6399.264084630234;6399.488040956712;6050.839424252234;6052.243469566304;6053.243344581927;6066.848328791727;6101.664408457577;5588.552915766729;5639.971800140973;5641.351244606841;5642.322921249476;5654.293213348332;6276.686165691788;6312.116303717391;6433.839154021187;6464.127342808661;6521.217453905561;6334.461570766536;6364.586301890362;6375.776847810304;6387.93612063892;6405.8718825624155;6485.44873685742;6530.346965303432;6535.3137920517665;6562.934370656232;6599.241087275025;6179.443850053517;6245.531585131042;6267.529935254783;6291.622502649856;6334.113224148531;6177.536684748572;6325.746970121328;6329.050642403677;6335.556511044183;6347.777827776005;6303.9684801575695;6310.716017779119;6333.524985626004;6338.8732225354315;6340.873182536231;5096.413912399973;5680.261565996394;6089.33017368098;6136.2636483622255;6151.169592105188;5965.940340596539;5982.222311099703;6116.244470650166;6125.449118208347;6133.601315914451;6232.8130156095385;6237.095621135;6240.28236752761;6246.750129994749;6288.591241569284;5979.5814292999075;6047.5464340173785;6122.969385153046;6124.594542075475;6153.169322141453 10:49:08 INFO - PROCESS | 1655 | |1;ext.html;680.9693563789629;730.5580124024965;748.6069813347991;777.7394572818106;958.2429880394484;38021.80989095054;38476.807615961916;39868.80065599668;39875.80062099685;39968.80015599922;34215.65784342162;35223.2955340893;36603.90188294355;36619.633803661956;36728.44907326386;8867.290616750644;8975.057618950013;8990.865137022916;8995.64017439305;9010.774730631721;8760.8685869712;8839.46963182208;8859.73420797374;8864.8670269946;8866.157715017096;8851.734447966572;8871.068537803534;8879.422837515542;8889.466632002068;8899.77750556238;7612.619369031554;8879.600417981206;8887.955560222223;8902.910970890305;8916.554172291391;8692.261157801595;8734.51960142192;8753.3872628916;8758.737237882873;8760.649574017032;33845.49231761526;33853.83073084619;33859.8149064784;33866.83066584676;33881.32237355239;3210.598675165604;3577.5170352002406;3635.1820840310984;3670.687991520724;3696.5009723687226;21418;24694.629580556128;24698.506029879303;25871.48257034849;26060.478790424277;3028.1369809604375;3036.6052413186367;3169.6830316968117;3223.339215460825;3242.837858107085;273948;304006;347120;348192;362747.9999999974;37445.62554374476;41632.791836040626;41918;41941;42173;43797.78101109474;43828.3425748614;43959.78020109879;43992.3401148983;44041.77979110148;12737.936310318575;12778.488860445665;12824.230546167073;12843.908267797342;12854.871451285555;10899.83650245247;10917.126629869592;10925.726856828644;10927.289726167774;10943;9868.963758805367;9869;9874.506274686237;9881.851772223423;9929.156021738088;4937.185364414831;4961.826336078292;4964.478729733398;4974;4988.775505102279;1049.4363398536127;1079.6935707793657;1085.9945700271448;1133.3143448213877;1149.7010777197988;25295.24114276538;26097.739022609912;26146.86926565355;26281.474370512482;26404.20787376382;1012.6101450941441;1206.0833766337646;1207.974025067201;1254.1689133864477;1260.6155122687485;13432.19406835593;13591.524296649768;13600.31998400076;13611.591652250449;13634.590962271148;411992;413124;413420;414552;416361;52898;52914.735426323394;53000.73499632477;53012;53019.734901325246;1374.3540535948089;1424.8788852947616;1427.6145440730945;1436.2029073863896;1441.4594527052354;24190.63714044326;24719;25421.11026114151;25451.36371590725;25806;44643.776781115885;44704.32943505785;44787.55212447834;44824.32763508485;44872.32691509695;367544;368113;368169;368194;368236;22942.885285573466;27459.725402745717;28086.43827123487;30125.397492050473;30171.09486715402;36757.44863827098;37252.25495490021;38144.61855381522;38158.42762358623;38277.425838613;26800.59799102976;27033.59449608297;27136.321591959568;27230.72769272282;27845;26985.325366865985;27265.454690906467;27402.725972740016;27612.171634850987;27615.72384276212;31500.89746858849;32715.509267361485;32760.83619581887;32789.0163295092;32912.67087329096;27727.861360693067;27904.860475697493;28384.148475545768;28746.99385521497;28768.856155719088;22159.44601384978;22267.443313917283;22777.77222227823;23345.883270583538;23413.53172936566 10:49:08 INFO - PROCESS | 1655 | |2;jquery.html;340.9744269179812;405.6997821612008;418.93715942608617;421.2817146764766;442.8007396671497;19136.904315478405;20136.69794953075;20790.48023799406;21400.143994240232;21477.140914363426;21087.2619458319;21216;21526.892365538188;21574.568508629818;21703.565928681415;6194.7522099116095;6513.055606936991;6536.379043990837;6547.3125321841235;6561.442277406426;6205.193324867783;6271.247450305969;6385.914394552914;6387.776427825027;6464.3212462691445;6441.3880681335195;6471.870562588745;6484.448821850148;6488.513361497883;6494.31809659986;5457.808976685797;6395.20059992501;6407.391297826723;6459.5478316517865;6486.643234622094;6020.217371741689;6021.939780602182;6335.8416039598915;6341.556091073627;6341.904871426932;25998.87000565004;26288;26292;26328.47343053128;26490.47019059608;2833.9291517712018;2844.6728626207837;2850.1307101334087;2852.443773464175;2862.069827306113;12075.335856527887;15713.685726285525;16076.356945722275;16179;16513.6697266054;1784.8929064256185;1994.3717728915344;2044.2845004248543;2054.95890082199;2057.3210840422626;129770.40459190763;131774.6822531772;136131;137390;138009;21902.45243868884;22159.113635454723;22186.44533886666;22187.77812221857;22474.438139046328;34314.65685343165;34436.79471218495;35209.64790352115;35291.647083528835;35329.82335088308;8447.662093516314;10303.536340864679;10458.68623941283;10484;10572.471376431147;8422.494650321001;8444.451110677786;8598.183172598594;8606.225439710455;8638.654453821902;8721.65113395457;9034.954825225963;10213.234007449471;10380.32527885685;10799.40603266828;4374.671899607542;4449.154660614473;4456.927528699171;4458.866234012985;4473.3960915276675;909.5043201455222;917.4082716647724;922.8661844032578;947.4173383369179;951.1724799424406;21014.2645007424;21015.579688406146;22455.88772056162;22544.549109017727;22581.983810728558;935.5135329628691;958.7219706285239;965.3483898368713;971.4802580619335;973.2116985241977;11083.614548181476;11346.602868899548;11604.129690273263;11835.644930652094;11853.525858965717;622972;627708;759234;786510;787168.0641596755;154912;155179;157398;161433;164612;1158.3571118029574;1222.370479203211;1229.624964385871;1238.2508582307755;1252.8997680185746;19323.937183454484;19876.60246795085;19978.60042799165;20052.69920951158;20132.798672013094;51318.43619923915;64413;66570;67369.66315168393;68153.3184668147;93843.53078234839;94061;94229.5288523553;94968;95487.52256238674;11374.20380573352;11738.765224695288;11756.470958806703;11783.587574434854;11788.29270243788;11994.160408771302;12478.18891772032;12741.17182383142;12823.166494177849;13177.407016684081;11002.669919902415;11085.057770089632;11106.222564420414;11457.484413201593;11626.128040396836;10398.636047738293;10402.531886065297;10483.52824122901;10578.89421105779;10688.984546468073;9656.179224765974;11321.603743868925;11480.483378248166;11731.35477548743;11832.763344732884;9636.325457217927;11597.608287005613;11749.706257343634;11863.644090677293;11864.76270474603;10881.347119172875;10973.012428881439;11116.388598627153;11122.833157502806;11190.160737944523 10:49:08 INFO - PROCESS | 1655 | |3;mootools.html;1539.1919242397746;1563.3199558192186;1579.4708772561191;1583.3349993002937;1615.6607112506374;10619;11278.548858045679;11281.717957051078;11375.488103035363;11726.706832329175;11420.771584568325;11572.247803892762;11597.65207043788;11604.419779011057;11635.650930472098;2418.201993342193;2444.926652200437;2450.0812195426715;2463.359526523099;2466.4450498637843;1930.6428310762483;2379.0245999140357;2392.4138586046442;2393.832431729771;2403.8437501562344;2335.381124002141;2392.940176495593;2409.542186984467;2410.6625072489865;2424.9151279705216;2318.9652155217686;2377.3105799318178;2380.642903564462;2388.8208384371155;2394.1979436888664;2364.243442098529;2386.97613023871;2396.3290278721984;2398.4243781492314;2406.831521793475;10968.396738179397;11140.944295278472;11161.66515004543;11175.497102630401;11181.83227251592;5660.971695141498;5727.856803579903;5807.680577568232;5807.680577568232;5811.099279611667;3392.86428542858;3490.7905525668543;3537.1157210697324;3575.6424357564297;3596.5684117905757;1175.341808587194;1178.0987544528443;1206.897413719835;1231.4212320209488;1231.6859200903803;13595.116317439433;13928.233947132903;14760.188189649565;15342.463013794575;15511.379544818194;4424.889377765581;4578.381918440968;4751.218293140072;4768.642351823558;4775.761211939389;10892.292001019909;10991.230613857113;10991.450427478594;11003.284786488852;11010.39442830652;5503.724813759296;5508;5516.475934786189;5541.196526503634;5546.057170281062;4997.700137991659;5160.716160611128;5216.165413533892;5240.423553409122;5242.501962313574;3601.1897323102617;3871.670907972854;3949.1311911379453;4005.6995725320717;4017.5982401760175;979.8138353712772;1007.8359494783537;1045.1847558904067;1045.7908418316367;1049.197364016528;833.6748668019477;927.8422668146432;930.3347008854075;941.0354386753633;986.4574484033752;6582.736690532325;6609.008648702733;6623.304553021863;6715.496337774686;6814.727410903508;921.2906062331967;1005.5022763731853;1006.2302338710892;1027.429776474049;1029.9279050466546;3630.2557975614936;4157.48031496063;4207.537170911197;4388.648908087409;4415.183191109655;13680.93159534196;14288.285585720672;14297.285135743172;14302.427902883976;14335.283235838166;14121.576352709435;14136.363863626162;14238.074525155831;14261.073030253001;14267;8810.603522841615;10264.230182736324;10577.100946419487;10594.417307048187;10619;6071.787487437919;6771.898421523582;6782.796516104525;7019.894701579378;7043.894341584778;11920.701982450508;12207.75584488323;12220;12224.511019559119;12258.570950016707;12132.150749447454;12190.2076365036;12295.754084918073;12385.19496232742;12436.253824770543;8608.956955215184;8806.471611703319;9071.637134514545;9493.28800339991;9527.237820974444;10704.197185210984;10733.677989660322;10822.458877056273;10825.729356766144;10827.296225745302;8884.466931984101;8929.419587726778;8971.102889710977;8991.325650576102;9013.954930225307;7182.507311832227;8749.16882896124;8806.64773409082;8814.955925220333;8880.289576833968;7923.326517246099;7927.207279272026;8013.4791238569305;8023.037235531773;8033.236842499953;5915.053591425351;5931.822045338647;5935.347111817783;5943.346231914573;6022.2171117754415;5929.407059294036;6012.368701286302;6027.849303767441;6104.786332478342;6113.419225173601 10:49:08 INFO - PROCESS | 1655 | |4;prototype.html;317.6950127877236;326.9673032696729;363.4312301248547;380.91810260793926;387.4865802811276;4643.489216186217;4970.701757894524;5030.717167122388;5165.354330708661;5325.440828712978;4801.639877009221;5092.955944031464;5155.473817829265;5179.928028788487;5223.451537588556;3926.489556357684;4079.8776036718946;4232.137859341879;4335.458067741532;4394.5385734497895;4329.194594000657;4338.459846754404;4347.369631403445;4379.5182529921685;4396.1867054595;3718.2856514744117;3751.8311675974646;3875.302445559798;4323.3947247385395;4375.846845360413;3580.820958952068;3785.413260944558;3788.086741696415;4097.885754126877;4272.380504826799;3263.494158405451;3981.7013723970817;4003.97998010011;4005.399190121476;4015.4779878615886;3999.7600143991162;4600.930986035212;5111.923321150186;5153.56194723449;5169.733415313272;4585.977070114662;4748.667593268473;4927.950720492785;5079.517445842639;5120.92602495986;4869.659123861331;5336.67979921206;5568.3596386415675;5597.496225339739;5598.608097433182;4211.947013246689;4237.4279472271155;4453;4472.932906006413;4475.619572336355;5064.442911279756;5163.328767260232;5363.597730170253;5423.593230507728;5470.56235501159;3754.755940863835;3842.4044273137706;4493.730376177375;4513.300438432048;4523.810713447012;4041.6558396130763;4612.95387046132;4678.789454474557;4702.777277907769;4747.7626118693925;4142.502899652061;4308.418363520947;4475.843345482893;4997.051150051483;5032.79868805251;5109.795608175706;5136.885413701975;5553.8333849984565;5587;5637.7181140942785;1457.4243173946231;1467.3565606520765;1588.6584384357418;1595.8803089768314;1613.515945216454;976.1361195342173;1062.3466568060587;1083.4668943445095;1116.5087361560886;1173.4660729368118;5001.974990125026;5013.197888337849;5101.464346243591;5236.502532259429;5297.080160681068;4752.477227504972;5132.358455193101;5514.6139770216505;5628.071368224197;5874.472637114295;4708.810403162477;4916.582090522345;4939.950600493949;5048.974755126201;5151.4333423323405;2600.180943002948;2754.9173524794287;2986.2385091801452;3012.7439167670555;3020.320427903748;5102.744862756922;5244.5279924806955;5380.865478363072;5481.369642491165;5856.159762515177;5379.606075296456;5657.19968615538;5682.346530149086;5844.649321040666;5939.495142912814;5188.429272779991;5249.658772179796;5352.732363381893;5587.972060139673;5862.736176871966;4920.950790492049;5437.293151890229;5727.56578454147;5795.82612521625;5797.333306669787;5141.511556402136;5186.1622399424905;5227.503387178212;5499.945000549943;5608.663480191201;5413.0214292495775;5569.972150139223;5966.970165149147;5971.910421343597;6001.939980600138;3958.782266975344;4156.875293741193;4241.87274381769;4367.6724245682335;4368.60682538573;4762.808918025001;5210.218467229945;5300.840974770763;5336.519713225828;5986.281646202484;4842.837718947498;5314.757997621525;5373.731313434391;5628.720368250865;5654.491095801398;4677.438707355003;5114.437411884615;5372.4419918223275;5374.758135883816;5634.337870328345;4706.482286948364;4900.99529596439;5135.204043373283;5159.530897248928;5521.116621340566;5651.547876169979;5703.714814259188;5703.973284808774;5754.913676294943;5758.992176369203;4543.727376357429;5300.6024548158275;5388.3925458910835;5513.2310551373575;5554.250880655461 10:49:08 INFO - PROCESS | 1655 | |5;yui.html;726.6802606852981;728.9161746399166;745.3143108340327;817.0195765081901;835.1231207232408;14825.703485930275;15642;15896;16033;16064.759028614551;15142.848571514282;15294.235288235599;15361.30874110665;15380.154091524962;15382.384704611803;773.5783997721242;844.2907957315854;863.2532859076885;923.1875949164726;955.5938726041439;920.8618707193941;927.1053433436732;963.7012526116927;967.4823969176492;970.9514524273793;995.5072239241549;1004.2116938203511;1006.2252065909246;1009.1523120578712;1009.6415772400802;1003.76411543288;1005.0452070532987;1005.3063386263491;1006.6174853555675;1007.8790545134558;739.7780665800238;746.4737360161128;747.0997448075111;747.3162056718096;757.4962649837851;13347.799783003262;15900.204989750582;16291.348346066143;16822.83177168225;17062.829371706248;13860.099093558887;14159.079659822182;14202.715945681028;14259.429622815074;14286;11496.482658280314;11785.88214117865;11823.763524729544;11835.230710003822;11963.641090767203;3571.375009373375;3621.746477746559;3655.597884232732;3667.3215455140617;3788.981055094707;87681.56159219227;93911;114024.57926262225;118430;119979.4001029998;10506.317089389166;10593.947030264953;10854.131669466424;11260.605878794202;11264.38045907483;20699.4825129373;21918.780812191675;22454.560879936933;22967.54064918692;23027.309180724606;7625.6568454419685;7660.2722741339485;7708.306252437307;7729.149793522708;7814;4244.469441319835;4294.162638285567;4360.258756011485;4367.62875155609;4440.777961101997;3371.8145501997624;3450.344434557426;3454.1882657575447;3459.3773120837986;3476.791392516457;596;614.9385061493815;620.6183197333607;631.6368088349218;635.4916067146264;653.6372313366128;665;684.7637565040053;707.504746677328;720.8089856188114;10034.397936123856;10248.282620216662;10306.587736490457;10342.276040677081;10362.963703629728;536.1048692870684;553.069786445311;572.6649265063512;576.4091805898987;584.0975692554928;4503;4897.436794768647;4899.216125419915;5009.223570346602;5045;77748;99917;104486.47756761167;105783.47108264563;106719;60302.79394412087;72825;78028;91096;91297;74609.5078098435;76098.4780304402;79498;79614.70806611521;81493;9059.501727405055;11034.337939723642;11046.56394668688;11284.774304514029;11468.369239691898;41430.585694142675;51229.7438512805;51643.74178129085;52025.479745202065;52031.47968520266;47214.76392618015;48438.81604194412;52602.21096683629;62428.375716242255;64520.677396612715;12362.814557781461;12390.256584604953;12461.376931153225;12799.552015679406;12880.227186368473;14361.994660373673;14425.278736062945;14704.55886323412;14720.116792992454;14789.92605037011;13425.86574134246;13715.794263085862;13838.308084595528;13861.237631929936;13953.3720982554;11016.559337626726;13502.392392342172;13678.521251756141;13866.791998119834;14259.857401426269;12978.610641680765;13518.12535963001;13554.186748795104;13599.388027538587;13777.931110344383;8899.199072083544;8908.331875109523;9120.954395227982;9295.907040929775;9428.936979454378;9165.725028249164;11252.21234513609;11489.827652585385;11512.906273903965;11529.481173347052 10:49:08 INFO - PROCESS | 1655 | __end_tp_report 10:49:08 INFO - PROCESS | 1655 | __start_cc_report 10:49:08 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,411 10:49:08 INFO - PROCESS | 1655 | __end_cc_report 10:49:08 INFO - PROCESS | 1655 | __startTimestamp1491932948210__endTimestamp 10:49:08 INFO - PROCESS | 1655 | 10:49:08 INFO - PROCESS | 1655 | ------- Summary: start ------- 10:49:08 INFO - PROCESS | 1655 | Number of tests: 6 10:49:08 INFO - PROCESS | 1655 | 10:49:08 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5832.36 Median:6089.33 stddev:685.95 (11.3%) stddev-sans-first:644.09 10:49:08 INFO - PROCESS | 1655 | Values: 2751.9 3409.4 3425.5 3430.7 3453.0 5107.4 5267.2 5911.4 5945.7 5996.3 5628.8 5917.8 5981.4 5988.9 6012.0 5386.1 5405.2 5406.3 5414.0 5452.5 5438.9 5465.9 5468.0 5476.2 5495.6 5243.1 5253.1 5462.3 5465.1 5498.3 4452.6 5392.6 5411.4 5443.6 5519.4 4378.0 5251.9 5374.7 5393.7 5408.5 5588.6 5883.4 5886.1 5971.3 5978.8 6264.2 6268.5 6272.5 6273.1 6292.7 6341.4 6363.5 6384.2 6405.8 6407.2 6054.9 6074.8 6079.1 6114.6 6118.5 5031.9 6164.3 6267.9 6278.0 6367.8 6253.3 6342.2 6351.9 6370.7 6372.7 6356.6 6369.4 6375.4 6377.6 6389.0 5114.3 5285.4 6184.6 6287.6 6395.1 4941.2 6289.0 6295.7 6299.3 6350.4 4931.7 5059.2 5121.2 5142.9 5154.7 4512.1 4538.5 4570.0 4575.0 4580.4 5061.0 5197.9 5797.1 5969.3 5997.2 6250.7 6301.3 6367.9 6399.3 6399.5 6050.8 6052.2 6053.2 6066.8 6101.7 5588.6 5640.0 5641.4 5642.3 5654.3 6276.7 6312.1 6433.8 6464.1 6521.2 6334.5 6364.6 6375.8 6387.9 6405.9 6485.4 6530.3 6535.3 6562.9 6599.2 6179.4 6245.5 6267.5 6291.6 6334.1 6177.5 6325.7 6329.1 6335.6 6347.8 6304.0 6310.7 6333.5 6338.9 6340.9 5096.4 5680.3 6089.3 6136.3 6151.2 5965.9 5982.2 6116.2 6125.4 6133.6 6232.8 6237.1 6240.3 6246.8 6288.6 5979.6 6047.5 6123.0 6124.6 6153.2 10:49:08 INFO - PROCESS | 1655 | 10:49:08 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:49656.10 Median:191707.65 stddev:98529.96 (51.4%) stddev-sans-first:98737.74 10:49:08 INFO - PROCESS | 1655 | Values: 681.0 730.6 748.6 777.7 958.2 38021.8 38476.8 39868.8 39875.8 39968.8 34215.7 35223.3 36603.9 36619.6 36728.4 8867.3 8975.1 8990.9 8995.6 9010.8 8760.9 8839.5 8859.7 8864.9 8866.2 8851.7 8871.1 8879.4 8889.5 8899.8 7612.6 8879.6 8888.0 8902.9 8916.6 8692.3 8734.5 8753.4 8758.7 8760.6 33845.5 33853.8 33859.8 33866.8 33881.3 3210.6 3577.5 3635.2 3670.7 3696.5 21418.0 24694.6 24698.5 25871.5 26060.5 3028.1 3036.6 3169.7 3223.3 3242.8 273948.0 304006.0 347120.0 348192.0 362748.0 37445.6 41632.8 41918.0 41941.0 42173.0 43797.8 43828.3 43959.8 43992.3 44041.8 12737.9 12778.5 12824.2 12843.9 12854.9 10899.8 10917.1 10925.7 10927.3 10943.0 9869.0 9869.0 9874.5 9881.9 9929.2 4937.2 4961.8 4964.5 4974.0 4988.8 1049.4 1079.7 1086.0 1133.3 1149.7 25295.2 26097.7 26146.9 26281.5 26404.2 1012.6 1206.1 1208.0 1254.2 1260.6 13432.2 13591.5 13600.3 13611.6 13634.6 411992.0 413124.0 413420.0 414552.0 416361.0 52898.0 52914.7 53000.7 53012.0 53019.7 1374.4 1424.9 1427.6 1436.2 1441.5 24190.6 24719.0 25421.1 25451.4 25806.0 44643.8 44704.3 44787.6 44824.3 44872.3 367544.0 368113.0 368169.0 368194.0 368236.0 22942.9 27459.7 28086.4 30125.4 30171.1 36757.4 37252.3 38144.6 38158.4 38277.4 26800.6 27033.6 27136.3 27230.7 27845.0 26985.3 27265.5 27402.7 27612.2 27615.7 31500.9 32715.5 32760.8 32789.0 32912.7 27727.9 27904.9 28384.1 28747.0 28768.9 22159.4 22267.4 22777.8 23345.9 23413.5 10:49:08 INFO - PROCESS | 1655 | 10:49:08 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:42078.53 Median:22321.83 stddev:120278.37 (538.8%) stddev-sans-first:120574.96 10:49:08 INFO - PROCESS | 1655 | Values: 341.0 405.7 418.9 421.3 442.8 19136.9 20136.7 20790.5 21400.1 21477.1 21087.3 21216.0 21526.9 21574.6 21703.6 6194.8 6513.1 6536.4 6547.3 6561.4 6205.2 6271.2 6385.9 6387.8 6464.3 6441.4 6471.9 6484.4 6488.5 6494.3 5457.8 6395.2 6407.4 6459.5 6486.6 6020.2 6021.9 6335.8 6341.6 6341.9 25998.9 26288.0 26292.0 26328.5 26490.5 2833.9 2844.7 2850.1 2852.4 2862.1 12075.3 15713.7 16076.4 16179.0 16513.7 1784.9 1994.4 2044.3 2055.0 2057.3 129770.4 131774.7 136131.0 137390.0 138009.0 21902.5 22159.1 22186.4 22187.8 22474.4 34314.7 34436.8 35209.6 35291.6 35329.8 8447.7 10303.5 10458.7 10484.0 10572.5 8422.5 8444.5 8598.2 8606.2 8638.7 8721.7 9035.0 10213.2 10380.3 10799.4 4374.7 4449.2 4456.9 4458.9 4473.4 909.5 917.4 922.9 947.4 951.2 21014.3 21015.6 22455.9 22544.5 22582.0 935.5 958.7 965.3 971.5 973.2 11083.6 11346.6 11604.1 11835.6 11853.5 622972.0 627708.0 759234.0 786510.0 787168.1 154912.0 155179.0 157398.0 161433.0 164612.0 1158.4 1222.4 1229.6 1238.3 1252.9 19323.9 19876.6 19978.6 20052.7 20132.8 51318.4 64413.0 66570.0 67369.7 68153.3 93843.5 94061.0 94229.5 94968.0 95487.5 11374.2 11738.8 11756.5 11783.6 11788.3 11994.2 12478.2 12741.2 12823.2 13177.4 11002.7 11085.1 11106.2 11457.5 11626.1 10398.6 10402.5 10483.5 10578.9 10689.0 9656.2 11321.6 11480.5 11731.4 11832.8 9636.3 11597.6 11749.7 11863.6 11864.8 10881.3 10973.0 11116.4 11122.8 11190.2 10:49:08 INFO - PROCESS | 1655 | 10:49:08 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:6766.46 Median:2414.43 stddev:4251.38 (176.1%) stddev-sans-first:4245.17 10:49:08 INFO - PROCESS | 1655 | Values: 1539.2 1563.3 1579.5 1583.3 1615.7 10619.0 11278.5 11281.7 11375.5 11726.7 11420.8 11572.2 11597.7 11604.4 11635.7 2418.2 2444.9 2450.1 2463.4 2466.4 1930.6 2379.0 2392.4 2393.8 2403.8 2335.4 2392.9 2409.5 2410.7 2424.9 2319.0 2377.3 2380.6 2388.8 2394.2 2364.2 2387.0 2396.3 2398.4 2406.8 10968.4 11140.9 11161.7 11175.5 11181.8 5661.0 5727.9 5807.7 5807.7 5811.1 3392.9 3490.8 3537.1 3575.6 3596.6 1175.3 1178.1 1206.9 1231.4 1231.7 13595.1 13928.2 14760.2 15342.5 15511.4 4424.9 4578.4 4751.2 4768.6 4775.8 10892.3 10991.2 10991.5 11003.3 11010.4 5503.7 5508.0 5516.5 5541.2 5546.1 4997.7 5160.7 5216.2 5240.4 5242.5 3601.2 3871.7 3949.1 4005.7 4017.6 979.8 1007.8 1045.2 1045.8 1049.2 833.7 927.8 930.3 941.0 986.5 6582.7 6609.0 6623.3 6715.5 6814.7 921.3 1005.5 1006.2 1027.4 1029.9 3630.3 4157.5 4207.5 4388.6 4415.2 13680.9 14288.3 14297.3 14302.4 14335.3 14121.6 14136.4 14238.1 14261.1 14267.0 8810.6 10264.2 10577.1 10594.4 10619.0 6071.8 6771.9 6782.8 7019.9 7043.9 11920.7 12207.8 12220.0 12224.5 12258.6 12132.2 12190.2 12295.8 12385.2 12436.3 8609.0 8806.5 9071.6 9493.3 9527.2 10704.2 10733.7 10822.5 10825.7 10827.3 8884.5 8929.4 8971.1 8991.3 9014.0 7182.5 8749.2 8806.6 8815.0 8880.3 7923.3 7927.2 8013.5 8023.0 8033.2 5915.1 5931.8 5935.3 5943.3 6022.2 5929.4 6012.4 6027.8 6104.8 6113.4 10:49:08 INFO - PROCESS | 1655 | 10:49:08 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4575.24 Median:5031.76 stddev:1284.22 (25.5%) stddev-sans-first:1247.43 10:49:08 INFO - PROCESS | 1655 | Values: 317.7 327.0 363.4 380.9 387.5 4643.5 4970.7 5030.7 5165.4 5325.4 4801.6 5093.0 5155.5 5179.9 5223.5 3926.5 4079.9 4232.1 4335.5 4394.5 4329.2 4338.5 4347.4 4379.5 4396.2 3718.3 3751.8 3875.3 4323.4 4375.8 3580.8 3785.4 3788.1 4097.9 4272.4 3263.5 3981.7 4004.0 4005.4 4015.5 3999.8 4600.9 5111.9 5153.6 5169.7 4586.0 4748.7 4928.0 5079.5 5120.9 4869.7 5336.7 5568.4 5597.5 5598.6 4211.9 4237.4 4453.0 4472.9 4475.6 5064.4 5163.3 5363.6 5423.6 5470.6 3754.8 3842.4 4493.7 4513.3 4523.8 4041.7 4613.0 4678.8 4702.8 4747.8 4142.5 4308.4 4475.8 4997.1 5032.8 5109.8 5136.9 5553.8 5587.0 5637.7 1457.4 1467.4 1588.7 1595.9 1613.5 976.1 1062.3 1083.5 1116.5 1173.5 5002.0 5013.2 5101.5 5236.5 5297.1 4752.5 5132.4 5514.6 5628.1 5874.5 4708.8 4916.6 4940.0 5049.0 5151.4 2600.2 2754.9 2986.2 3012.7 3020.3 5102.7 5244.5 5380.9 5481.4 5856.2 5379.6 5657.2 5682.3 5844.6 5939.5 5188.4 5249.7 5352.7 5588.0 5862.7 4921.0 5437.3 5727.6 5795.8 5797.3 5141.5 5186.2 5227.5 5499.9 5608.7 5413.0 5570.0 5967.0 5971.9 6001.9 3958.8 4156.9 4241.9 4367.7 4368.6 4762.8 5210.2 5300.8 5336.5 5986.3 4842.8 5314.8 5373.7 5628.7 5654.5 4677.4 5114.4 5372.4 5374.8 5634.3 4706.5 4901.0 5135.2 5159.5 5521.1 5651.5 5703.7 5704.0 5754.9 5759.0 4543.7 5300.6 5388.4 5513.2 5554.3 10:49:08 INFO - PROCESS | 1655 | 10:49:08 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:19817.96 Median:3456.78 stddev:28238.97 (816.9%) stddev-sans-first:28281.80 10:49:08 INFO - PROCESS | 1655 | Values: 726.7 728.9 745.3 817.0 835.1 14825.7 15642.0 15896.0 16033.0 16064.8 15142.8 15294.2 15361.3 15380.2 15382.4 773.6 844.3 863.3 923.2 955.6 920.9 927.1 963.7 967.5 971.0 995.5 1004.2 1006.2 1009.2 1009.6 1003.8 1005.0 1005.3 1006.6 1007.9 739.8 746.5 747.1 747.3 757.5 13347.8 15900.2 16291.3 16822.8 17062.8 13860.1 14159.1 14202.7 14259.4 14286.0 11496.5 11785.9 11823.8 11835.2 11963.6 3571.4 3621.7 3655.6 3667.3 3789.0 87681.6 93911.0 114024.6 118430.0 119979.4 10506.3 10593.9 10854.1 11260.6 11264.4 20699.5 21918.8 22454.6 22967.5 23027.3 7625.7 7660.3 7708.3 7729.1 7814.0 4244.5 4294.2 4360.3 4367.6 4440.8 3371.8 3450.3 3454.2 3459.4 3476.8 596.0 614.9 620.6 631.6 635.5 653.6 665.0 684.8 707.5 720.8 10034.4 10248.3 10306.6 10342.3 10363.0 536.1 553.1 572.7 576.4 584.1 4503.0 4897.4 4899.2 5009.2 5045.0 77748.0 99917.0 104486.5 105783.5 106719.0 60302.8 72825.0 78028.0 91096.0 91297.0 74609.5 76098.5 79498.0 79614.7 81493.0 9059.5 11034.3 11046.6 11284.8 11468.4 41430.6 51229.7 51643.7 52025.5 52031.5 47214.8 48438.8 52602.2 62428.4 64520.7 12362.8 12390.3 12461.4 12799.6 12880.2 14362.0 14425.3 14704.6 14720.1 14789.9 13425.9 13715.8 13838.3 13861.2 13953.4 11016.6 13502.4 13678.5 13866.8 14259.9 12978.6 13518.1 13554.2 13599.4 13777.9 8899.2 8908.3 9121.0 9295.9 9428.9 9165.7 11252.2 11489.8 11512.9 11529.5 10:49:08 INFO - PROCESS | 1655 | -------- Summary: end -------- 10:49:08 INFO - PROCESS | 1655 | 10:49:09 INFO - TEST-INFO | 1655: exit 0 10:49:09 INFO - TEST-OK | dromaeo_css | took 1117250ms 10:49:09 INFO - TEST-START | kraken 10:49:09 INFO - operating with platform_type : mac_ 10:49:09 INFO - Initialising browser for kraken test... 10:49:09 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpriUlFj/profile http://localhost:49242/getInfo.html) 10:49:11 INFO - PROCESS | 1666 | 2017-04-11 10:49:11.853 plugin-container[1667:10399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 10:49:11 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:49:11 INFO - PROCESS | 1666 | 2017-04-11 10:49:11.857 plugin-container[1667:10399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d2b, name = 'com.apple.CFPasteboardClient' 10:49:11 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:49:11 INFO - PROCESS | 1666 | 2017-04-11 10:49:11.857 plugin-container[1667:10399] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:49:11 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 10:49:11 INFO - PROCESS | 1666 | colorDepth:24 10:49:11 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 10:49:11 INFO - PROCESS | 1666 | __metrics 10:49:12 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 10:49:12 INFO - TEST-INFO | 1666: exit 0 10:49:12 INFO - Browser initialized. 10:49:12 INFO - Running cycle 1/1 for kraken test... 10:49:12 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpriUlFj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:49:15 INFO - PROCESS | 1668 | 2017-04-11 10:49:15.240 plugin-container[1669:10524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 10:49:15 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:49:15 INFO - PROCESS | 1668 | 2017-04-11 10:49:15.240 plugin-container[1669:10524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 10:49:15 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:49:15 INFO - PROCESS | 1668 | 2017-04-11 10:49:15.240 plugin-container[1669:10524] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:49:25 INFO - PROCESS | 1668 | RSS: Main: 220725248 10:49:25 INFO - PROCESS | 1668 | 10:49:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 10:49:28 INFO - PROCESS | 1668 | RSS: Main: 235220992 10:49:28 INFO - PROCESS | 1668 | 10:49:31 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 10:49:32 INFO - PROCESS | 1668 | RSS: Main: 243154944 10:49:32 INFO - PROCESS | 1668 | 10:49:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 10:49:36 INFO - PROCESS | 1668 | RSS: Main: 246824960 10:49:36 INFO - PROCESS | 1668 | 10:49:37 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 10:49:38 INFO - PROCESS | 1668 | RSS: Main: 247300096 10:49:38 INFO - PROCESS | 1668 | 10:49:40 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:49:41 INFO - PROCESS | 1668 | RSS: Main: 248696832 10:49:41 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 10:49:44 INFO - PROCESS | 1668 | RSS: Main: 252579840 10:49:44 INFO - PROCESS | 1668 | 10:49:47 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 10:49:48 INFO - PROCESS | 1668 | RSS: Main: 252141568 10:49:48 INFO - PROCESS | 1668 | 10:49:51 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 10:49:52 INFO - PROCESS | 1668 | RSS: Main: 252260352 10:49:52 INFO - PROCESS | 1668 | 10:49:53 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:49:54 INFO - PROCESS | 1668 | RSS: Main: 251691008 10:49:54 INFO - PROCESS | 1668 | 10:49:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:49:56 INFO - PROCESS | 1668 | RSS: Main: 251650048 10:49:56 INFO - PROCESS | 1668 | 10:49:59 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:49:59 INFO - PROCESS | 1668 | RSS: Main: 251760640 10:49:59 INFO - PROCESS | 1668 | 10:50:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:50:03 INFO - PROCESS | 1668 | RSS: Main: 252153856 10:50:03 INFO - PROCESS | 1668 | 10:50:06 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:50:07 INFO - PROCESS | 1668 | RSS: Main: 252207104 10:50:07 INFO - PROCESS | 1668 | 10:50:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 10:50:09 INFO - PROCESS | 1668 | RSS: Main: 253890560 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | __start_tp_report 10:50:09 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 10:50:09 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 10:50:09 INFO - PROCESS | 1668 | |i|pagename|runs| 10:50:09 INFO - PROCESS | 1668 | |0;ai-astar;115;101;141;182;181;121;121;121;120;119 10:50:09 INFO - PROCESS | 1668 | |1;audio-beat-detection;133;115;125;142;145;144;139;112;123;118 10:50:09 INFO - PROCESS | 1668 | |2;audio-dft;202;200;194;202;198;199;196;196;196;196 10:50:09 INFO - PROCESS | 1668 | |3;audio-fft;78;59;57;86;62;61;61;63;75;62 10:50:09 INFO - PROCESS | 1668 | |4;audio-oscillator;86;70;89;76;103;88;82;74;72;84 10:50:09 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;90;89;91;91;91;90;90;91;89;93 10:50:09 INFO - PROCESS | 1668 | |6;imaging-darkroom;97;98;98;97;124;156;137;97;97;106 10:50:09 INFO - PROCESS | 1668 | |7;imaging-desaturate;91;93;91;92;93;90;93;102;91;93 10:50:09 INFO - PROCESS | 1668 | |8;json-parse-financial;67;65;66;85;73;70;69;69;68;70 10:50:09 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;56;66;67;65;60;59;58;59;59;62 10:50:09 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;86;94;110;103;106;90;83;83;87;84 10:50:09 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;144;152;150;141;143;139;140;142;175;164 10:50:09 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;171;162;168;161;178;147;172;155;200;224 10:50:09 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;66;68;66;64;67;74;63;77;83;85 10:50:09 INFO - PROCESS | 1668 | __end_tp_report 10:50:09 INFO - PROCESS | 1668 | __start_cc_report 10:50:09 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1008 10:50:09 INFO - PROCESS | 1668 | __end_cc_report 10:50:09 INFO - PROCESS | 1668 | __startTimestamp1491933009158__endTimestamp 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | ------- Summary: start ------- 10:50:09 INFO - PROCESS | 1668 | Number of tests: 14 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:132.20 Median:121.00 stddev:27.70 (22.9%) stddev-sans-first:28.68 10:50:09 INFO - PROCESS | 1668 | Values: 115.0 101.0 141.0 182.0 181.0 121.0 121.0 121.0 120.0 119.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:136.00 stddev:12.56 (9.2%) stddev-sans-first:13.26 10:50:09 INFO - PROCESS | 1668 | Values: 133.0 115.0 125.0 142.0 145.0 144.0 139.0 112.0 123.0 118.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:197.90 Median:198.50 stddev:2.77 (1.4%) stddev-sans-first:2.51 10:50:09 INFO - PROCESS | 1668 | Values: 202.0 200.0 194.0 202.0 198.0 199.0 196.0 196.0 196.0 196.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:66.40 Median:62.50 stddev:9.69 (15.5%) stddev-sans-first:9.32 10:50:09 INFO - PROCESS | 1668 | Values: 78.0 59.0 57.0 86.0 62.0 61.0 61.0 63.0 75.0 62.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:83.00 stddev:9.94 (12.0%) stddev-sans-first:10.45 10:50:09 INFO - PROCESS | 1668 | Values: 86.0 70.0 89.0 76.0 103.0 88.0 82.0 74.0 72.0 84.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:91.00 stddev:1.18 (1.3%) stddev-sans-first:1.24 10:50:09 INFO - PROCESS | 1668 | Values: 90.0 89.0 91.0 91.0 91.0 90.0 90.0 91.0 89.0 93.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:110.70 Median:97.00 stddev:21.12 (21.8%) stddev-sans-first:21.82 10:50:09 INFO - PROCESS | 1668 | Values: 97.0 98.0 98.0 97.0 124.0 156.0 137.0 97.0 97.0 106.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:92.90 Median:92.50 stddev:3.38 (3.7%) stddev-sans-first:3.52 10:50:09 INFO - PROCESS | 1668 | Values: 91.0 93.0 91.0 92.0 93.0 90.0 93.0 102.0 91.0 93.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:70.20 Median:69.50 stddev:5.67 (8.2%) stddev-sans-first:5.90 10:50:09 INFO - PROCESS | 1668 | Values: 67.0 65.0 66.0 85.0 73.0 70.0 69.0 69.0 68.0 70.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:3.73 (6.1%) stddev-sans-first:3.46 10:50:09 INFO - PROCESS | 1668 | Values: 56.0 66.0 67.0 65.0 60.0 59.0 58.0 59.0 59.0 62.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:85.00 stddev:10.18 (12.0%) stddev-sans-first:10.51 10:50:09 INFO - PROCESS | 1668 | Values: 86.0 94.0 110.0 103.0 106.0 90.0 83.0 83.0 87.0 84.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:147.00 stddev:11.86 (8.1%) stddev-sans-first:12.44 10:50:09 INFO - PROCESS | 1668 | Values: 144.0 152.0 150.0 141.0 143.0 139.0 140.0 142.0 175.0 164.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:171.50 stddev:22.71 (13.2%) stddev-sans-first:24.07 10:50:09 INFO - PROCESS | 1668 | Values: 171.0 162.0 168.0 161.0 178.0 147.0 172.0 155.0 200.0 224.0 10:50:09 INFO - PROCESS | 1668 | 10:50:09 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:71.00 stddev:7.97 (11.2%) stddev-sans-first:8.22 10:50:09 INFO - PROCESS | 1668 | Values: 66.0 68.0 66.0 64.0 67.0 74.0 63.0 77.0 83.0 85.0 10:50:09 INFO - PROCESS | 1668 | -------- Summary: end -------- 10:50:09 INFO - PROCESS | 1668 | 10:50:10 INFO - TEST-INFO | 1668: exit 0 10:50:10 INFO - TEST-OK | kraken | took 61027ms 10:50:10 INFO - SUITE-END | took 1178s 10:50:10 INFO - Completed test suite (00:19:38) 10:50:10 INFO - javascript benchmark 10:50:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7539.946181173483, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2751.931201719957, 3409.437442821934, 3425.520427140201, 3430.708389786868, 3452.9813704957037, 5107.361579802525, 5267.23625074364, 5911.408859114092, 5945.672987985659, 5996.2804463464445, 5628.831135065944, 5917.822465326047, 5981.431763982426, 5988.88022239556, 6012.038073908176, 5386.057439948013, 5405.162199859008, 5406.297181366417, 5414.0, 5452.536534394581, 5438.945610543903, 5465.890682186354, 5467.9610873934025, 5476.17857321401, 5495.6153069284965, 5243.056249875021, 5253.0544501989625, 5462.344518657746, 5465.098258787315, 5498.340199176085, 4452.621527170194, 5392.622516423852, 5411.377691565481, 5443.618946673695, 5519.420460851633, 4378.0, 5251.86870328241, 5374.731263436852, 5393.73031348435, 5408.5402740766685, 5588.608797384185, 5883.441073098049, 5886.087656413263, 5971.313298970631, 5978.790742323998, 6264.21697287839, 6268.49852011838, 6272.529560282951, 6273.090401891745, 6292.748290068392, 6341.365863413668, 6363.459105975998, 6384.233891932951, 6405.775797847053, 6407.199100112486, 6054.878902421927, 6074.757009719606, 6079.118527813486, 6114.57197996136, 6118.510519158456, 5031.874203144877, 6164.260288765377, 6267.9373206267355, 6278.0, 6367.777127800598, 6253.343398943137, 6342.238931328178, 6351.936480635227, 6370.713317900706, 6372.74509019634, 6356.555041147075, 6369.394907483782, 6375.3624637536805, 6377.585456945284, 6389.0, 5114.258432527263, 5285.392179899284, 6184.628922264678, 6287.559870808999, 6395.072714456378, 4941.234108713155, 6288.968555157195, 6295.748170073238, 6299.307076221612, 6350.364963503705, 4931.679440836334, 5059.19052951526, 5121.180611102206, 5142.948570514247, 5154.690718556823, 4512.120136573402, 4538.546145385435, 4569.977150114295, 4575.0, 4580.404547408815, 5061.0, 5197.948020519822, 5797.130430435384, 5969.283685957713, 5997.220361352996, 6250.74997000124, 6301.275353334425, 6367.936320636827, 6399.264084630234, 6399.488040956712, 6050.839424252234, 6052.243469566304, 6053.243344581927, 6066.848328791727, 6101.664408457577, 5588.552915766729, 5639.971800140973, 5641.351244606841, 5642.322921249476, 5654.293213348332, 6276.686165691788, 6312.116303717391, 6433.839154021187, 6464.127342808661, 6521.217453905561, 6334.461570766536, 6364.586301890362, 6375.776847810304, 6387.93612063892, 6405.8718825624155, 6485.44873685742, 6530.346965303432, 6535.3137920517665, 6562.934370656232, 6599.241087275025, 6179.443850053517, 6245.531585131042, 6267.529935254783, 6291.622502649856, 6334.113224148531, 6177.536684748572, 6325.746970121328, 6329.050642403677, 6335.556511044183, 6347.777827776005, 6303.9684801575695, 6310.716017779119, 6333.524985626004, 6338.8732225354315, 6340.873182536231, 5096.413912399973, 5680.261565996394, 6089.33017368098, 6136.2636483622255, 6151.169592105188, 5965.940340596539, 5982.222311099703, 6116.244470650166, 6125.449118208347, 6133.601315914451, 6232.8130156095385, 6237.095621135, 6240.28236752761, 6246.750129994749, 6288.591241569284, 5979.5814292999075, 6047.5464340173785, 6122.969385153046, 6124.594542075475, 6153.169322141453], "value": 5789.164097423517, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.9693563789629, 730.5580124024965, 748.6069813347991, 777.7394572818106, 958.2429880394484, 38021.80989095054, 38476.807615961916, 39868.80065599668, 39875.80062099685, 39968.80015599922, 34215.65784342162, 35223.2955340893, 36603.90188294355, 36619.633803661956, 36728.44907326386, 8867.290616750644, 8975.057618950013, 8990.865137022916, 8995.64017439305, 9010.774730631721, 8760.8685869712, 8839.46963182208, 8859.73420797374, 8864.8670269946, 8866.157715017096, 8851.734447966572, 8871.068537803534, 8879.422837515542, 8889.466632002068, 8899.77750556238, 7612.619369031554, 8879.600417981206, 8887.955560222223, 8902.910970890305, 8916.554172291391, 8692.261157801595, 8734.51960142192, 8753.3872628916, 8758.737237882873, 8760.649574017032, 33845.49231761526, 33853.83073084619, 33859.8149064784, 33866.83066584676, 33881.32237355239, 3210.598675165604, 3577.5170352002406, 3635.1820840310984, 3670.687991520724, 3696.5009723687226, 21418.0, 24694.629580556128, 24698.506029879303, 25871.48257034849, 26060.478790424277, 3028.1369809604375, 3036.6052413186367, 3169.6830316968117, 3223.339215460825, 3242.837858107085, 273948.0, 304006.0, 347120.0, 348192.0, 362747.9999999974, 37445.62554374476, 41632.791836040626, 41918.0, 41941.0, 42173.0, 43797.78101109474, 43828.3425748614, 43959.78020109879, 43992.3401148983, 44041.77979110148, 12737.936310318575, 12778.488860445665, 12824.230546167073, 12843.908267797342, 12854.871451285555, 10899.83650245247, 10917.126629869592, 10925.726856828644, 10927.289726167774, 10943.0, 9868.963758805367, 9869.0, 9874.506274686237, 9881.851772223423, 9929.156021738088, 4937.185364414831, 4961.826336078292, 4964.478729733398, 4974.0, 4988.775505102279, 1049.4363398536127, 1079.6935707793657, 1085.9945700271448, 1133.3143448213877, 1149.7010777197988, 25295.24114276538, 26097.739022609912, 26146.86926565355, 26281.474370512482, 26404.20787376382, 1012.6101450941441, 1206.0833766337646, 1207.974025067201, 1254.1689133864477, 1260.6155122687485, 13432.19406835593, 13591.524296649768, 13600.31998400076, 13611.591652250449, 13634.590962271148, 411992.0, 413124.0, 413420.0, 414552.0, 416361.0, 52898.0, 52914.735426323394, 53000.73499632477, 53012.0, 53019.734901325246, 1374.3540535948089, 1424.8788852947616, 1427.6145440730945, 1436.2029073863896, 1441.4594527052354, 24190.63714044326, 24719.0, 25421.11026114151, 25451.36371590725, 25806.0, 44643.776781115885, 44704.32943505785, 44787.55212447834, 44824.32763508485, 44872.32691509695, 367544.0, 368113.0, 368169.0, 368194.0, 368236.0, 22942.885285573466, 27459.725402745717, 28086.43827123487, 30125.397492050473, 30171.09486715402, 36757.44863827098, 37252.25495490021, 38144.61855381522, 38158.42762358623, 38277.425838613, 26800.59799102976, 27033.59449608297, 27136.321591959568, 27230.72769272282, 27845.0, 26985.325366865985, 27265.454690906467, 27402.725972740016, 27612.171634850987, 27615.72384276212, 31500.89746858849, 32715.509267361485, 32760.83619581887, 32789.0163295092, 32912.67087329096, 27727.861360693067, 27904.860475697493, 28384.148475545768, 28746.99385521497, 28768.856155719088, 22159.44601384978, 22267.443313917283, 22777.77222227823, 23345.883270583538, 23413.53172936566], "value": 17038.417125474407, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.9744269179812, 405.6997821612008, 418.93715942608617, 421.2817146764766, 442.8007396671497, 19136.904315478405, 20136.69794953075, 20790.48023799406, 21400.143994240232, 21477.140914363426, 21087.2619458319, 21216.0, 21526.892365538188, 21574.568508629818, 21703.565928681415, 6194.7522099116095, 6513.055606936991, 6536.379043990837, 6547.3125321841235, 6561.442277406426, 6205.193324867783, 6271.247450305969, 6385.914394552914, 6387.776427825027, 6464.3212462691445, 6441.3880681335195, 6471.870562588745, 6484.448821850148, 6488.513361497883, 6494.31809659986, 5457.808976685797, 6395.20059992501, 6407.391297826723, 6459.5478316517865, 6486.643234622094, 6020.217371741689, 6021.939780602182, 6335.8416039598915, 6341.556091073627, 6341.904871426932, 25998.87000565004, 26288.0, 26292.0, 26328.47343053128, 26490.47019059608, 2833.9291517712018, 2844.6728626207837, 2850.1307101334087, 2852.443773464175, 2862.069827306113, 12075.335856527887, 15713.685726285525, 16076.356945722275, 16179.0, 16513.6697266054, 1784.8929064256185, 1994.3717728915344, 2044.2845004248543, 2054.95890082199, 2057.3210840422626, 129770.40459190763, 131774.6822531772, 136131.0, 137390.0, 138009.0, 21902.45243868884, 22159.113635454723, 22186.44533886666, 22187.77812221857, 22474.438139046328, 34314.65685343165, 34436.79471218495, 35209.64790352115, 35291.647083528835, 35329.82335088308, 8447.662093516314, 10303.536340864679, 10458.68623941283, 10484.0, 10572.471376431147, 8422.494650321001, 8444.451110677786, 8598.183172598594, 8606.225439710455, 8638.654453821902, 8721.65113395457, 9034.954825225963, 10213.234007449471, 10380.32527885685, 10799.40603266828, 4374.671899607542, 4449.154660614473, 4456.927528699171, 4458.866234012985, 4473.3960915276675, 909.5043201455222, 917.4082716647724, 922.8661844032578, 947.4173383369179, 951.1724799424406, 21014.2645007424, 21015.579688406146, 22455.88772056162, 22544.549109017727, 22581.983810728558, 935.5135329628691, 958.7219706285239, 965.3483898368713, 971.4802580619335, 973.2116985241977, 11083.614548181476, 11346.602868899548, 11604.129690273263, 11835.644930652094, 11853.525858965717, 622972.0, 627708.0, 759234.0, 786510.0, 787168.0641596755, 154912.0, 155179.0, 157398.0, 161433.0, 164612.0, 1158.3571118029574, 1222.370479203211, 1229.624964385871, 1238.2508582307755, 1252.8997680185746, 19323.937183454484, 19876.60246795085, 19978.60042799165, 20052.69920951158, 20132.798672013094, 51318.43619923915, 64413.0, 66570.0, 67369.66315168393, 68153.3184668147, 93843.53078234839, 94061.0, 94229.5288523553, 94968.0, 95487.52256238674, 11374.20380573352, 11738.765224695288, 11756.470958806703, 11783.587574434854, 11788.29270243788, 11994.160408771302, 12478.18891772032, 12741.17182383142, 12823.166494177849, 13177.407016684081, 11002.669919902415, 11085.057770089632, 11106.222564420414, 11457.484413201593, 11626.128040396836, 10398.636047738293, 10402.531886065297, 10483.52824122901, 10578.89421105779, 10688.984546468073, 9656.179224765974, 11321.603743868925, 11480.483378248166, 11731.35477548743, 11832.763344732884, 9636.325457217927, 11597.608287005613, 11749.706257343634, 11863.644090677293, 11864.76270474603, 10881.347119172875, 10973.012428881439, 11116.388598627153, 11122.833157502806, 11190.160737944523], "value": 11497.767468719201, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1539.1919242397746, 1563.3199558192186, 1579.4708772561191, 1583.3349993002937, 1615.6607112506374, 10619.0, 11278.548858045679, 11281.717957051078, 11375.488103035363, 11726.706832329175, 11420.771584568325, 11572.247803892762, 11597.65207043788, 11604.419779011057, 11635.650930472098, 2418.201993342193, 2444.926652200437, 2450.0812195426715, 2463.359526523099, 2466.4450498637843, 1930.6428310762483, 2379.0245999140357, 2392.4138586046442, 2393.832431729771, 2403.8437501562344, 2335.381124002141, 2392.940176495593, 2409.542186984467, 2410.6625072489865, 2424.9151279705216, 2318.9652155217686, 2377.3105799318178, 2380.642903564462, 2388.8208384371155, 2394.1979436888664, 2364.243442098529, 2386.97613023871, 2396.3290278721984, 2398.4243781492314, 2406.831521793475, 10968.396738179397, 11140.944295278472, 11161.66515004543, 11175.497102630401, 11181.83227251592, 5660.971695141498, 5727.856803579903, 5807.680577568232, 5807.680577568232, 5811.099279611667, 3392.86428542858, 3490.7905525668543, 3537.1157210697324, 3575.6424357564297, 3596.5684117905757, 1175.341808587194, 1178.0987544528443, 1206.897413719835, 1231.4212320209488, 1231.6859200903803, 13595.116317439433, 13928.233947132903, 14760.188189649565, 15342.463013794575, 15511.379544818194, 4424.889377765581, 4578.381918440968, 4751.218293140072, 4768.642351823558, 4775.761211939389, 10892.292001019909, 10991.230613857113, 10991.450427478594, 11003.284786488852, 11010.39442830652, 5503.724813759296, 5508.0, 5516.475934786189, 5541.196526503634, 5546.057170281062, 4997.700137991659, 5160.716160611128, 5216.165413533892, 5240.423553409122, 5242.501962313574, 3601.1897323102617, 3871.670907972854, 3949.1311911379453, 4005.6995725320717, 4017.5982401760175, 979.8138353712772, 1007.8359494783537, 1045.1847558904067, 1045.7908418316367, 1049.197364016528, 833.6748668019477, 927.8422668146432, 930.3347008854075, 941.0354386753633, 986.4574484033752, 6582.736690532325, 6609.008648702733, 6623.304553021863, 6715.496337774686, 6814.727410903508, 921.2906062331967, 1005.5022763731853, 1006.2302338710892, 1027.429776474049, 1029.9279050466546, 3630.2557975614936, 4157.48031496063, 4207.537170911197, 4388.648908087409, 4415.183191109655, 13680.93159534196, 14288.285585720672, 14297.285135743172, 14302.427902883976, 14335.283235838166, 14121.576352709435, 14136.363863626162, 14238.074525155831, 14261.073030253001, 14267.0, 8810.603522841615, 10264.230182736324, 10577.100946419487, 10594.417307048187, 10619.0, 6071.787487437919, 6771.898421523582, 6782.796516104525, 7019.894701579378, 7043.894341584778, 11920.701982450508, 12207.75584488323, 12220.0, 12224.511019559119, 12258.570950016707, 12132.150749447454, 12190.2076365036, 12295.754084918073, 12385.19496232742, 12436.253824770543, 8608.956955215184, 8806.471611703319, 9071.637134514545, 9493.28800339991, 9527.237820974444, 10704.197185210984, 10733.677989660322, 10822.458877056273, 10825.729356766144, 10827.296225745302, 8884.466931984101, 8929.419587726778, 8971.102889710977, 8991.325650576102, 9013.954930225307, 7182.507311832227, 8749.16882896124, 8806.64773409082, 8814.955925220333, 8880.289576833968, 7923.326517246099, 7927.207279272026, 8013.4791238569305, 8023.037235531773, 8033.236842499953, 5915.053591425351, 5931.822045338647, 5935.347111817783, 5943.346231914573, 6022.2171117754415, 5929.407059294036, 6012.368701286302, 6027.849303767441, 6104.786332478342, 6113.419225173601], "value": 5146.684640735728, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.6950127877236, 326.9673032696729, 363.4312301248547, 380.91810260793926, 387.4865802811276, 4643.489216186217, 4970.701757894524, 5030.717167122388, 5165.354330708661, 5325.440828712978, 4801.639877009221, 5092.955944031464, 5155.473817829265, 5179.928028788487, 5223.451537588556, 3926.489556357684, 4079.8776036718946, 4232.137859341879, 4335.458067741532, 4394.5385734497895, 4329.194594000657, 4338.459846754404, 4347.369631403445, 4379.5182529921685, 4396.1867054595, 3718.2856514744117, 3751.8311675974646, 3875.302445559798, 4323.3947247385395, 4375.846845360413, 3580.820958952068, 3785.413260944558, 3788.086741696415, 4097.885754126877, 4272.380504826799, 3263.494158405451, 3981.7013723970817, 4003.97998010011, 4005.399190121476, 4015.4779878615886, 3999.7600143991162, 4600.930986035212, 5111.923321150186, 5153.56194723449, 5169.733415313272, 4585.977070114662, 4748.667593268473, 4927.950720492785, 5079.517445842639, 5120.92602495986, 4869.659123861331, 5336.67979921206, 5568.3596386415675, 5597.496225339739, 5598.608097433182, 4211.947013246689, 4237.4279472271155, 4453.0, 4472.932906006413, 4475.619572336355, 5064.442911279756, 5163.328767260232, 5363.597730170253, 5423.593230507728, 5470.56235501159, 3754.755940863835, 3842.4044273137706, 4493.730376177375, 4513.300438432048, 4523.810713447012, 4041.6558396130763, 4612.95387046132, 4678.789454474557, 4702.777277907769, 4747.7626118693925, 4142.502899652061, 4308.418363520947, 4475.843345482893, 4997.051150051483, 5032.79868805251, 5109.795608175706, 5136.885413701975, 5553.8333849984565, 5587.0, 5637.7181140942785, 1457.4243173946231, 1467.3565606520765, 1588.6584384357418, 1595.8803089768314, 1613.515945216454, 976.1361195342173, 1062.3466568060587, 1083.4668943445095, 1116.5087361560886, 1173.4660729368118, 5001.974990125026, 5013.197888337849, 5101.464346243591, 5236.502532259429, 5297.080160681068, 4752.477227504972, 5132.358455193101, 5514.6139770216505, 5628.071368224197, 5874.472637114295, 4708.810403162477, 4916.582090522345, 4939.950600493949, 5048.974755126201, 5151.4333423323405, 2600.180943002948, 2754.9173524794287, 2986.2385091801452, 3012.7439167670555, 3020.320427903748, 5102.744862756922, 5244.5279924806955, 5380.865478363072, 5481.369642491165, 5856.159762515177, 5379.606075296456, 5657.19968615538, 5682.346530149086, 5844.649321040666, 5939.495142912814, 5188.429272779991, 5249.658772179796, 5352.732363381893, 5587.972060139673, 5862.736176871966, 4920.950790492049, 5437.293151890229, 5727.56578454147, 5795.82612521625, 5797.333306669787, 5141.511556402136, 5186.1622399424905, 5227.503387178212, 5499.945000549943, 5608.663480191201, 5413.0214292495775, 5569.972150139223, 5966.970165149147, 5971.910421343597, 6001.939980600138, 3958.782266975344, 4156.875293741193, 4241.87274381769, 4367.6724245682335, 4368.60682538573, 4762.808918025001, 5210.218467229945, 5300.840974770763, 5336.519713225828, 5986.281646202484, 4842.837718947498, 5314.757997621525, 5373.731313434391, 5628.720368250865, 5654.491095801398, 4677.438707355003, 5114.437411884615, 5372.4419918223275, 5374.758135883816, 5634.337870328345, 4706.482286948364, 4900.99529596439, 5135.204043373283, 5159.530897248928, 5521.116621340566, 5651.547876169979, 5703.714814259188, 5703.973284808774, 5754.913676294943, 5758.992176369203, 4543.727376357429, 5300.6024548158275, 5388.3925458910835, 5513.2310551373575, 5554.250880655461], "value": 4192.420937532126, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.6802606852981, 728.9161746399166, 745.3143108340327, 817.0195765081901, 835.1231207232408, 14825.703485930275, 15642.0, 15896.0, 16033.0, 16064.759028614551, 15142.848571514282, 15294.235288235599, 15361.30874110665, 15380.154091524962, 15382.384704611803, 773.5783997721242, 844.2907957315854, 863.2532859076885, 923.1875949164726, 955.5938726041439, 920.8618707193941, 927.1053433436732, 963.7012526116927, 967.4823969176492, 970.9514524273793, 995.5072239241549, 1004.2116938203511, 1006.2252065909246, 1009.1523120578712, 1009.6415772400802, 1003.76411543288, 1005.0452070532987, 1005.3063386263491, 1006.6174853555675, 1007.8790545134558, 739.7780665800238, 746.4737360161128, 747.0997448075111, 747.3162056718096, 757.4962649837851, 13347.799783003262, 15900.204989750582, 16291.348346066143, 16822.83177168225, 17062.829371706248, 13860.099093558887, 14159.079659822182, 14202.715945681028, 14259.429622815074, 14286.0, 11496.482658280314, 11785.88214117865, 11823.763524729544, 11835.230710003822, 11963.641090767203, 3571.375009373375, 3621.746477746559, 3655.597884232732, 3667.3215455140617, 3788.981055094707, 87681.56159219227, 93911.0, 114024.57926262225, 118430.0, 119979.4001029998, 10506.317089389166, 10593.947030264953, 10854.131669466424, 11260.605878794202, 11264.38045907483, 20699.4825129373, 21918.780812191675, 22454.560879936933, 22967.54064918692, 23027.309180724606, 7625.6568454419685, 7660.2722741339485, 7708.306252437307, 7729.149793522708, 7814.0, 4244.469441319835, 4294.162638285567, 4360.258756011485, 4367.62875155609, 4440.777961101997, 3371.8145501997624, 3450.344434557426, 3454.1882657575447, 3459.3773120837986, 3476.791392516457, 596.0, 614.9385061493815, 620.6183197333607, 631.6368088349218, 635.4916067146264, 653.6372313366128, 665.0, 684.7637565040053, 707.504746677328, 720.8089856188114, 10034.397936123856, 10248.282620216662, 10306.587736490457, 10342.276040677081, 10362.963703629728, 536.1048692870684, 553.069786445311, 572.6649265063512, 576.4091805898987, 584.0975692554928, 4503.0, 4897.436794768647, 4899.216125419915, 5009.223570346602, 5045.0, 77748.0, 99917.0, 104486.47756761167, 105783.47108264563, 106719.0, 60302.79394412087, 72825.0, 78028.0, 91096.0, 91297.0, 74609.5078098435, 76098.4780304402, 79498.0, 79614.70806611521, 81493.0, 9059.501727405055, 11034.337939723642, 11046.56394668688, 11284.774304514029, 11468.369239691898, 41430.585694142675, 51229.7438512805, 51643.74178129085, 52025.479745202065, 52031.47968520266, 47214.76392618015, 48438.81604194412, 52602.21096683629, 62428.375716242255, 64520.677396612715, 12362.814557781461, 12390.256584604953, 12461.376931153225, 12799.552015679406, 12880.227186368473, 14361.994660373673, 14425.278736062945, 14704.55886323412, 14720.116792992454, 14789.92605037011, 13425.86574134246, 13715.794263085862, 13838.308084595528, 13861.237631929936, 13953.3720982554, 11016.559337626726, 13502.392392342172, 13678.521251756141, 13866.791998119834, 14259.857401426269, 12978.610641680765, 13518.12535963001, 13554.186748795104, 13599.388027538587, 13777.931110344383, 8899.199072083544, 8908.331875109523, 9120.954395227982, 9295.907040929775, 9428.936979454378, 9165.725028249164, 11252.21234513609, 11489.827652585385, 11512.906273903965, 11529.481173347052], "value": 7507.924834285278, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 101.0, 141.0, 182.0, 181.0, 121.0, 121.0, 121.0, 120.0, 119.0], "value": 132.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 115.0, 125.0, 142.0, 145.0, 144.0, 139.0, 112.0, 123.0, 118.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 200.0, 194.0, 202.0, 198.0, 199.0, 196.0, 196.0, 196.0, 196.0], "value": 197.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 57.0, 86.0, 62.0, 61.0, 61.0, 63.0, 75.0, 62.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 89.0, 76.0, 103.0, 88.0, 82.0, 74.0, 72.0, 84.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 91.0, 91.0, 91.0, 90.0, 90.0, 91.0, 89.0, 93.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 98.0, 97.0, 124.0, 156.0, 137.0, 97.0, 97.0, 106.0], "value": 110.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 91.0, 92.0, 93.0, 90.0, 93.0, 102.0, 91.0, 93.0], "value": 92.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 85.0, 73.0, 70.0, 69.0, 69.0, 68.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 66.0, 67.0, 65.0, 60.0, 59.0, 58.0, 59.0, 59.0, 62.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 94.0, 110.0, 103.0, 106.0, 90.0, 83.0, 83.0, 87.0, 84.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 152.0, 150.0, 141.0, 143.0, 139.0, 140.0, 142.0, 175.0, 164.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 162.0, 168.0, 161.0, 178.0, 147.0, 172.0, 155.0, 200.0, 224.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 64.0, 67.0, 74.0, 63.0, 77.0, 83.0, 85.0], "value": 71.3, "unit": "score"}]}]} 10:50:10 INFO - Return code: 0 10:50:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:50:10 ERROR - # TBPL SUCCESS # 10:50:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:50:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:50:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:50:10 INFO - 2017-04-11 10:50:10.479 screenresolution[1673:10908] starting screenresolution argv=screenresolution get 10:50:10 INFO - 2017-04-11 10:50:10.483 screenresolution[1673:10908] Display 0: 1600x1200x32@60 10:50:10 INFO - 2017-04-11 10:50:10.489 screenresolution[1674:10911] starting screenresolution argv=screenresolution list 10:50:10 INFO - Available Modes on Display 0 10:50:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:50:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:50:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:50:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:50:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:50:10 INFO - Intel Iris: 10:50:10 INFO - Chipset Model: Intel Iris 10:50:10 INFO - Type: GPU 10:50:10 INFO - Bus: Built-In 10:50:10 INFO - VRAM (Dynamic, Max): 1536 MB 10:50:10 INFO - Vendor: Intel (0x8086) 10:50:10 INFO - Device ID: 0x0a2e 10:50:10 INFO - Revision ID: 0x0009 10:50:10 INFO - Displays: 10:50:10 INFO - Display: 10:50:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:50:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:50:10 INFO - Display Serial Number: 1600x1200 60 10:50:10 INFO - Main Display: Yes 10:50:10 INFO - Mirror: Off 10:50:10 INFO - Online: Yes 10:50:10 INFO - Rotation: Supported 10:50:10 INFO - Return code: 0 10:50:10 INFO - Running post-action listener: _resource_record_post_action 10:50:10 INFO - [mozharness: 2017-04-11 17:50:10.738118Z] Finished run-tests step (success) 10:50:10 INFO - Running post-run listener: _resource_record_post_run 10:50:10 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 39414272; Write bytes: 513147904; Read time: 255; Write time: 1625 10:50:10 INFO - TinderboxPrint: CPU usage
54.6% 10:50:10 INFO - TinderboxPrint: I/O read bytes / time
39,414,272 / 255 10:50:10 INFO - TinderboxPrint: I/O write bytes / time
513,147,904 / 1,625 10:50:10 INFO - TinderboxPrint: CPU idle
2,177.2 (45.4%) 10:50:10 INFO - TinderboxPrint: CPU system
970.2 (20.2%) 10:50:10 INFO - TinderboxPrint: CPU user
1,650.2 (34.4%) 10:50:10 INFO - TinderboxPrint: Swap in / out
246,865,920 / 0 10:50:10 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201806336; Write bytes: 201533440; Read time: 15659; Write time: 447 10:50:10 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 37530112; Write bytes: 300829696; Read time: 238; Write time: 1147 10:50:11 INFO - Running post-run listener: _upload_blobber_files 10:50:11 INFO - Blob upload gear active. 10:50:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:50:11 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 10:50:11 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:50:11 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:50:11 INFO - (blobuploader) - INFO - Open directory for files ... 10:50:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:50:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:50:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:12 INFO - (blobuploader) - INFO - Done attempting. 10:50:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:50:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:50:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:13 INFO - (blobuploader) - INFO - Done attempting. 10:50:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:50:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:50:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:14 INFO - (blobuploader) - INFO - Done attempting. 10:50:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:50:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:50:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:17 INFO - (blobuploader) - INFO - Done attempting. 10:50:17 INFO - (blobuploader) - INFO - Iteration through files over. 10:50:17 INFO - Return code: 0 10:50:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:50:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:50:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad904f0ca2d745ed0cb16bb7e9a1a369694f0a57e9cad0c411116d33279557705644fda437afe2eacfec2e846c6a7555e6706cc26b9404db30e956b6d69d8159", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83b1612bf229869adda52db570895706afa4005c6f9fc73910938e33aa636e0cd86ab8aecf67fbcbe3478c3fef8a4670418c04bf724b0f3e9ab492ab232eaad5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5fab524793bfdd8641230be06156ed73be812dfed843c6d9f393f48fb51862b36e4c13517627cd9d3350bfd2dee919483ca167e243fcd683b95770a2f17b323", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4844b4dc82fc83f4adb05e4f86392a0c54934c8e44ca92d9f109f0d06d3cb7031c04a321543ed8d0f7b3d40dc6932e434b199917da68f4ae8f6baa87446f84e1"} 10:50:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:50:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:50:17 INFO - Contents: 10:50:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad904f0ca2d745ed0cb16bb7e9a1a369694f0a57e9cad0c411116d33279557705644fda437afe2eacfec2e846c6a7555e6706cc26b9404db30e956b6d69d8159", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83b1612bf229869adda52db570895706afa4005c6f9fc73910938e33aa636e0cd86ab8aecf67fbcbe3478c3fef8a4670418c04bf724b0f3e9ab492ab232eaad5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5fab524793bfdd8641230be06156ed73be812dfed843c6d9f393f48fb51862b36e4c13517627cd9d3350bfd2dee919483ca167e243fcd683b95770a2f17b323", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4844b4dc82fc83f4adb05e4f86392a0c54934c8e44ca92d9f109f0d06d3cb7031c04a321543ed8d0f7b3d40dc6932e434b199917da68f4ae8f6baa87446f84e1"} 10:50:17 INFO - Running post-run listener: copy_logs_to_upload_dir 10:50:17 INFO - Copying logs to upload dir... 10:50:17 INFO - mkdir: /builds/slave/test/build/upload/logs 10:50:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.546983 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-11 10:50:17.381189) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 10:50:17.384803) ========= 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.VJqHdlar6S/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.NTfabdeOnM/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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/Mozilla-Aurora/sha512/ad904f0ca2d745ed0cb16bb7e9a1a369694f0a57e9cad0c411116d33279557705644fda437afe2eacfec2e846c6a7555e6706cc26b9404db30e956b6d69d8159", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83b1612bf229869adda52db570895706afa4005c6f9fc73910938e33aa636e0cd86ab8aecf67fbcbe3478c3fef8a4670418c04bf724b0f3e9ab492ab232eaad5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5fab524793bfdd8641230be06156ed73be812dfed843c6d9f393f48fb51862b36e4c13517627cd9d3350bfd2dee919483ca167e243fcd683b95770a2f17b323", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4844b4dc82fc83f4adb05e4f86392a0c54934c8e44ca92d9f109f0d06d3cb7031c04a321543ed8d0f7b3d40dc6932e434b199917da68f4ae8f6baa87446f84e1"} build_url:https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011110 build_url: 'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad904f0ca2d745ed0cb16bb7e9a1a369694f0a57e9cad0c411116d33279557705644fda437afe2eacfec2e846c6a7555e6706cc26b9404db30e956b6d69d8159", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83b1612bf229869adda52db570895706afa4005c6f9fc73910938e33aa636e0cd86ab8aecf67fbcbe3478c3fef8a4670418c04bf724b0f3e9ab492ab232eaad5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5fab524793bfdd8641230be06156ed73be812dfed843c6d9f393f48fb51862b36e4c13517627cd9d3350bfd2dee919483ca167e243fcd683b95770a2f17b323", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4844b4dc82fc83f4adb05e4f86392a0c54934c8e44ca92d9f109f0d06d3cb7031c04a321543ed8d0f7b3d40dc6932e434b199917da68f4ae8f6baa87446f84e1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 10:50:17.419823) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:50:17.420148) ========= 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.VJqHdlar6S/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.NTfabdeOnM/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.005324 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:50:17.468217) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 10:50:17.468540) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 10:50:17.468864) ========= ========= Total master_lag: 0.18 =========