builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0360 starttime: 1492099304.65 results: success (0) buildid: 20170413075025 builduid: c5c11c255b5f4f6b9d3eaa88642328a5 revision: d56c36ff0b8bc34183f056866de81a6a61c782ec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.654693) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.662705) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.663188) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.690018) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.690419) ========= 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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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-13 09:01:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-04-13 09:01:44 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123877 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.839160) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.839489) ========= 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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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.027016 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.889253) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-13 09:01:44.889609) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d56c36ff0b8bc34183f056866de81a6a61c782ec --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 d56c36ff0b8bc34183f056866de81a6a61c782ec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4PTSLBhwc8/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.jg6HH3J1bO/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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-13 09:01:44,998 truncating revision to first 12 chars 2017-04-13 09:01:44,998 Setting DEBUG logging. 2017-04-13 09:01:44,998 attempt 1/10 2017-04-13 09:01:44,998 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d56c36ff0b8b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 09:01:46,439 unpacking tar archive at: mozilla-aurora-d56c36ff0b8b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.717104 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-13 09:01:46.630809) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:46.631141) ========= script_repo_revision: d56c36ff0b8bc34183f056866de81a6a61c782ec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:46.631595) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:46.631905) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:46.651832) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-13 09:01:46.652220) ========= /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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 09:01:46 INFO - MultiFileLogger online at 20170413 09:01:46 in /builds/slave/test 09:01:46 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 09:01:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:46 INFO - {'append_to_log': False, 09:01:46 INFO - 'base_work_dir': '/builds/slave/test', 09:01:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:01:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:46 INFO - 'branch': 'Mozilla-Aurora', 09:01:46 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:46 INFO - 'config_files': ('talos/mac_config.py',), 09:01:46 INFO - 'default_actions': ('clobber', 09:01:46 INFO - 'read-buildbot-config', 09:01:46 INFO - 'download-and-extract', 09:01:46 INFO - 'populate-webroot', 09:01:46 INFO - 'create-virtualenv', 09:01:46 INFO - 'install', 09:01:46 INFO - 'run-tests'), 09:01:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:46 INFO - 'download_minidump_stackwalk': True, 09:01:46 INFO - 'download_symbols': 'ondemand', 09:01:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:46 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:46 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:46 INFO - 'gecko_profile': False, 09:01:46 INFO - 'gecko_profile_interval': 0, 09:01:46 INFO - 'installer_path': 'installer.exe', 09:01:46 INFO - 'log_level': 'info', 09:01:46 INFO - 'log_name': 'talos', 09:01:46 INFO - 'log_to_console': True, 09:01:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:01:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:01:46 INFO - 'opt_config_files': (), 09:01:46 INFO - 'pip_index': False, 09:01:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:46 INFO - 'cmd': ('bash', 09:01:46 INFO - '-c', 09:01:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:46 INFO - 'enabled': True, 09:01:46 INFO - 'halt_on_failure': False, 09:01:46 INFO - 'name': 'check_screen_resolution'},), 09:01:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:46 INFO - 'cmd': ('bash', 09:01:46 INFO - '-c', 09:01:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:46 INFO - 'enabled': True, 09:01:46 INFO - 'halt_on_failure': False, 09:01:46 INFO - 'name': 'check_screen_resolution'},), 09:01:46 INFO - 'run_cmd_checks_enabled': True, 09:01:46 INFO - 'suite': 'dromaeojs-e10s', 09:01:46 INFO - 'system_bits': '32', 09:01:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:46 INFO - 'title': 't-yosemite-r7-0360', 09:01:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:46 INFO - 'use_talos_json': True, 09:01:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:46 INFO - 'work_dir': 'build'} 09:01:46 INFO - [mozharness: 2017-04-13 16:01:46.866520Z] Running clobber step. 09:01:46 INFO - Running pre-action listener: _resource_record_pre_action 09:01:46 INFO - Running main action method: clobber 09:01:46 INFO - rmtree: /builds/slave/test/build 09:01:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:49 INFO - Running post-action listener: _resource_record_post_action 09:01:49 INFO - [mozharness: 2017-04-13 16:01:49.327702Z] Finished clobber step (success) 09:01:49 INFO - [mozharness: 2017-04-13 16:01:49.327831Z] Running read-buildbot-config step. 09:01:49 INFO - Running pre-action listener: _resource_record_pre_action 09:01:49 INFO - Running main action method: read_buildbot_config 09:01:49 INFO - Using buildbot properties: 09:01:49 INFO - { 09:01:49 INFO - "project": "", 09:01:49 INFO - "product": "firefox", 09:01:49 INFO - "script_repo_revision": "production", 09:01:49 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:01:49 INFO - "repository": "", 09:01:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:01:49 INFO - "buildid": "20170413075025", 09:01:49 INFO - "pgo_build": "False", 09:01:49 INFO - "basedir": "/builds/slave/test", 09:01:49 INFO - "buildnumber": 15, 09:01:49 INFO - "slavename": "t-yosemite-r7-0360", 09:01:49 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:01:49 INFO - "platform": "yosemite_r7", 09:01:49 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:01:49 INFO - "branch": "mozilla-aurora", 09:01:49 INFO - "repo_path": "releases/mozilla-aurora", 09:01:49 INFO - "revision": "d56c36ff0b8bc34183f056866de81a6a61c782ec", 09:01:49 INFO - "stage_platform": "macosx64", 09:01:49 INFO - "builduid": "c5c11c255b5f4f6b9d3eaa88642328a5", 09:01:49 INFO - "slavebuilddir": "test" 09:01:49 INFO - } 09:01:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 09:01:49 INFO - Running post-action listener: _resource_record_post_action 09:01:49 INFO - [mozharness: 2017-04-13 16:01:49.331224Z] Finished read-buildbot-config step (success) 09:01:49 INFO - [mozharness: 2017-04-13 16:01:49.331359Z] Running download-and-extract step. 09:01:49 INFO - Running pre-action listener: _resource_record_pre_action 09:01:49 INFO - Running main action method: download_and_extract 09:01:49 INFO - mkdir: /builds/slave/test/build/tests 09:01: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')]} 09:01:49 INFO - trying https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 09:01:49 INFO - Downloading https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/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 09:01:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/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 09:01:50 INFO - Downloaded 1199 bytes. 09:01:50 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:01:50 INFO - Using the following test package requirements: 09:01:50 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:01:50 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 09:01:50 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 09:01:50 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:01:50 INFO - u'jsshell-mac.zip'], 09:01:50 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 09:01:50 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:01:50 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 09:01:50 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 09:01:50 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 09:01:50 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 09:01:50 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:01:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:01:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:01:50 INFO - Fetch https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:01:52 INFO - Expected file size: 51790564 09:01:52 INFO - Obtained file size: 51790564 09:02:07 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 09:02:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:02:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:02:07 INFO - Fetch https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:02:09 INFO - Expected file size: 51790564 09:02:09 INFO - Obtained file size: 51790564 09:02:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 09:02:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:02:24 INFO - Fetch https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 09:02:26 INFO - Expected file size: 14404424 09:02:26 INFO - Obtained file size: 14404424 09:02:26 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')]} 09:02:26 INFO - trying https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:02:26 INFO - Downloading https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 09:02:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/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 09:02:28 INFO - Downloaded 74730078 bytes. 09:02:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:02:28 INFO - mkdir: /builds/slave/test/properties 09:02:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:02:28 INFO - Writing to file /builds/slave/test/properties/build_url 09:02:28 INFO - Contents: 09:02:28 INFO - build_url:https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:02:29 INFO - Running post-action listener: _resource_record_post_action 09:02:29 INFO - Running post-action listener: set_extra_try_arguments 09:02:29 INFO - [mozharness: 2017-04-13 16:02:29.464689Z] Finished download-and-extract step (success) 09:02:29 INFO - [mozharness: 2017-04-13 16:02:29.464817Z] Running populate-webroot step. 09:02:29 INFO - Running pre-action listener: _resource_record_pre_action 09:02:29 INFO - Running main action method: populate_webroot 09:02:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:02:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:02:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:02:29 INFO - '--tppagecycles', 09:02:29 INFO - '7'], 09:02:29 INFO - 'tests': ['tsvgm']}}, 09:02:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:02:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:02:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:02:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:02:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['damp', 'tps']}, 09:02:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'tests': ['damp', 'tps']}, 09:02:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['dromaeo_dom']}, 09:02:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:02:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['basic_compositor_video']}, 09:02:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:02:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other-e10s': {'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tsvgx', 09:02:29 INFO - 'tsvgr_opacity', 09:02:29 INFO - 'tart', 09:02:29 INFO - 'tscrollx', 09:02:29 INFO - 'cart', 09:02:29 INFO - 'tsvg_static']}, 09:02:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:02:29 INFO - 'tsvgr_opacity', 09:02:29 INFO - 'tart', 09:02:29 INFO - 'tscrollx', 09:02:29 INFO - 'cart', 09:02:29 INFO - 'tsvg_static']}, 09:02:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tp5o']}, 09:02:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:02:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s', 09:02:29 INFO - '--xperf_path', 09:02:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:29 INFO - 'tests': ['tp5n']}, 09:02:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'talos_options': ['--xperf_path', 09:02:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:29 INFO - 'tests': ['tp5n']}}, 09:02:29 INFO - 'talos.zip': {'path': '', 09:02:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:02:29 INFO - Running post-action listener: _resource_record_post_action 09:02:29 INFO - [mozharness: 2017-04-13 16:02:29.483597Z] Finished populate-webroot step (success) 09:02:29 INFO - [mozharness: 2017-04-13 16:02:29.483729Z] Running create-virtualenv step. 09:02:29 INFO - Running pre-action listener: _resource_record_pre_action 09:02:29 INFO - Running main action method: create_virtualenv 09:02:29 INFO - Creating virtualenv /builds/slave/test/build/venv 09:02:29 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 09:02:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:02:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:02:29 INFO - Using real prefix '/tools/python27' 09:02:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:02:30 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:33 INFO - Installing pip.................done. 09:02:33 INFO - Return code: 0 09:02:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:02:33 INFO - Reading from file tmpfile_stdout 09:02:33 INFO - Output received: 09:02:33 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) 09:02:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02:33 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')]} 09:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 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')]} 09:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 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 0x10fdc5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8101caf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10facadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb98be8>, '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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 09:02:33 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 09:02:33 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 09:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PTSLBhwc8/Render', 09:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:33 INFO - 'HOME': '/Users/cltbld', 09:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:33 INFO - 'LOGNAME': 'cltbld', 09:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:33 INFO - 'MOZ_NO_REMOTE': '1', 09:02:33 INFO - 'NO_EM_RESTART': '1', 09:02:33 INFO - 'PAGER': '/bin/cat', 09:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:33 INFO - 'PWD': '/builds/slave/test', 09:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:33 INFO - 'SHELL': '/bin/bash', 09:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jg6HH3J1bO/Listeners', 09:02:33 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 09:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:33 INFO - 'USER': 'cltbld', 09:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:33 INFO - 'XPC_FLAGS': '0x0', 09:02:33 INFO - 'XPC_SERVICE_NAME': '0', 09:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:33 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 09:02:33 INFO - Cleaning up... 09:02:33 INFO - Return code: 0 09:02:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:02:33 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')]} 09:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 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')]} 09:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 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 0x10fdc5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8101caf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10facadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb98be8>, '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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 09:02:33 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 09:02:33 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 09:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PTSLBhwc8/Render', 09:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:33 INFO - 'HOME': '/Users/cltbld', 09:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:33 INFO - 'LOGNAME': 'cltbld', 09:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:33 INFO - 'MOZ_NO_REMOTE': '1', 09:02:33 INFO - 'NO_EM_RESTART': '1', 09:02:33 INFO - 'PAGER': '/bin/cat', 09:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:33 INFO - 'PWD': '/builds/slave/test', 09:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:33 INFO - 'SHELL': '/bin/bash', 09:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jg6HH3J1bO/Listeners', 09:02:33 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 09:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:33 INFO - 'USER': 'cltbld', 09:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:33 INFO - 'XPC_FLAGS': '0x0', 09:02:33 INFO - 'XPC_SERVICE_NAME': '0', 09:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:34 INFO - Downloading/unpacking psutil>=3.1.1 09:02:34 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 09:02:34 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 09:02:34 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 09:02:34 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 09:02:34 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 09:02:34 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 09:02:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:37 INFO - Installing collected packages: psutil 09:02:37 INFO - Running setup.py install for psutil 09:02:37 INFO - building 'psutil._psutil_osx' extension 09:02:37 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 09:02:38 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 09:02:38 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 09:02:38 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 09:02:38 INFO - building 'psutil._psutil_posix' extension 09:02:38 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 09:02:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:02:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:02:38 INFO - ^ 09:02:38 INFO - 1 warning generated. 09:02:38 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 09:02:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:38 INFO - Successfully installed psutil 09:02:38 INFO - Cleaning up... 09:02:38 INFO - Return code: 0 09:02:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:38 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 0x10fdc5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8101caf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10facadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb98be8>, '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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 09:02:38 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 09:02:38 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 09:02:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PTSLBhwc8/Render', 09:02:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:38 INFO - 'HOME': '/Users/cltbld', 09:02:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:38 INFO - 'LOGNAME': 'cltbld', 09:02:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:38 INFO - 'MOZ_NO_REMOTE': '1', 09:02:38 INFO - 'NO_EM_RESTART': '1', 09:02:38 INFO - 'PAGER': '/bin/cat', 09:02:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:38 INFO - 'PWD': '/builds/slave/test', 09:02:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:38 INFO - 'SHELL': '/bin/bash', 09:02:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jg6HH3J1bO/Listeners', 09:02:38 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 09:02:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:38 INFO - 'USER': 'cltbld', 09:02:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:38 INFO - 'XPC_FLAGS': '0x0', 09:02:38 INFO - 'XPC_SERVICE_NAME': '0', 09:02:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:02:38 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 09:02:38 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 09:02:38 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 09:02:38 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 09:02:38 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 09:02:38 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 09:02:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:02:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:02:41 INFO - Installing collected packages: mozsystemmonitor 09:02:41 INFO - Running setup.py install for mozsystemmonitor 09:02:41 INFO - Successfully installed mozsystemmonitor 09:02:41 INFO - Cleaning up... 09:02:41 INFO - Return code: 0 09:02:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:42 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')]} 09:02:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:42 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 0x10fdc5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8101caf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10facadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb98be8>, '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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 09:02:42 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 09:02:42 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 09:02:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PTSLBhwc8/Render', 09:02:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:42 INFO - 'HOME': '/Users/cltbld', 09:02:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:42 INFO - 'LOGNAME': 'cltbld', 09:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:42 INFO - 'MOZ_NO_REMOTE': '1', 09:02:42 INFO - 'NO_EM_RESTART': '1', 09:02:42 INFO - 'PAGER': '/bin/cat', 09:02:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:42 INFO - 'PWD': '/builds/slave/test', 09:02:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:42 INFO - 'SHELL': '/bin/bash', 09:02:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jg6HH3J1bO/Listeners', 09:02:42 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 09:02:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:42 INFO - 'USER': 'cltbld', 09:02:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:42 INFO - 'XPC_FLAGS': '0x0', 09:02:42 INFO - 'XPC_SERVICE_NAME': '0', 09:02:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:42 INFO - Downloading/unpacking jsonschema==2.5.1 09:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:46 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:46 INFO - warning: no files found matching '*.txt' 09:02:46 INFO - no previously-included directories found matching 'build' 09:02:46 INFO - no previously-included directories found matching 'dist' 09:02:46 INFO - no previously-included directories found matching '.git*' 09:02:46 INFO - Installing collected packages: jsonschema, functools32 09:02:46 INFO - Running setup.py install for functools32 09:02:46 INFO - warning: no files found matching '*.txt' 09:02:46 INFO - no previously-included directories found matching 'build' 09:02:46 INFO - no previously-included directories found matching 'dist' 09:02:46 INFO - no previously-included directories found matching '.git*' 09:02:46 INFO - Successfully installed jsonschema functools32 09:02:46 INFO - Cleaning up... 09:02:46 INFO - Return code: 0 09:02:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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')]} 09:02:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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', '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 0x10fdc5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8101caf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10facadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb98be8>, '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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 09:02: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:02: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 functools32==3.2.3-2 09:02:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PTSLBhwc8/Render', 09:02:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:46 INFO - 'HOME': '/Users/cltbld', 09:02:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:46 INFO - 'LOGNAME': 'cltbld', 09:02:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:46 INFO - 'MOZ_NO_REMOTE': '1', 09:02:46 INFO - 'NO_EM_RESTART': '1', 09:02:46 INFO - 'PAGER': '/bin/cat', 09:02:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:46 INFO - 'PWD': '/builds/slave/test', 09:02:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:46 INFO - 'SHELL': '/bin/bash', 09:02:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jg6HH3J1bO/Listeners', 09:02:46 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 09:02:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:46 INFO - 'USER': 'cltbld', 09:02:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:46 INFO - 'XPC_FLAGS': '0x0', 09:02:46 INFO - 'XPC_SERVICE_NAME': '0', 09:02:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:02:47 INFO - Cleaning up... 09:02:47 INFO - Return code: 0 09:02:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fdc5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8101caf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10facadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb98be8>, '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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 09:02:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:02:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PTSLBhwc8/Render', 09:02:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:47 INFO - 'HOME': '/Users/cltbld', 09:02:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:47 INFO - 'LOGNAME': 'cltbld', 09:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:47 INFO - 'MOZ_NO_REMOTE': '1', 09:02:47 INFO - 'NO_EM_RESTART': '1', 09:02:47 INFO - 'PAGER': '/bin/cat', 09:02:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:47 INFO - 'PWD': '/builds/slave/test', 09:02:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:47 INFO - 'SHELL': '/bin/bash', 09:02:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jg6HH3J1bO/Listeners', 09:02:47 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 09:02:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:47 INFO - 'USER': 'cltbld', 09:02:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:47 INFO - 'XPC_FLAGS': '0x0', 09:02:47 INFO - 'XPC_SERVICE_NAME': '0', 09:02:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:47 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:49 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:51 INFO - Downloading docopt-0.6.1.tar.gz 09:02:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:51 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:51 INFO - Running setup.py install for blobuploader 09:02:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:51 INFO - Running setup.py install for requests 09:02:51 INFO - Running setup.py install for docopt 09:02:52 INFO - Successfully installed blobuploader requests docopt 09:02:52 INFO - Cleaning up... 09:02:52 INFO - Return code: 0 09:02:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:52 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 0x10fdc5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8101caf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10facadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb98be8>, '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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 09:02:52 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 09:02:52 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 09:02:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PTSLBhwc8/Render', 09:02:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:52 INFO - 'HOME': '/Users/cltbld', 09:02:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:52 INFO - 'LOGNAME': 'cltbld', 09:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:52 INFO - 'MOZ_NO_REMOTE': '1', 09:02:52 INFO - 'NO_EM_RESTART': '1', 09:02:52 INFO - 'PAGER': '/bin/cat', 09:02:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:52 INFO - 'PWD': '/builds/slave/test', 09:02:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:52 INFO - 'SHELL': '/bin/bash', 09:02:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jg6HH3J1bO/Listeners', 09:02:52 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 09:02:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:52 INFO - 'USER': 'cltbld', 09:02:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:52 INFO - 'XPC_FLAGS': '0x0', 09:02:52 INFO - 'XPC_SERVICE_NAME': '0', 09:02:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:52 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-JtxBTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:52 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-h7reUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:52 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-dvfZQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:52 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-0gqG03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:52 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-OJ1oSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:52 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-UAKLj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:53 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-OVCJ5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:53 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-2xgA4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:53 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-2sQPhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:53 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-X1pYur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:53 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-uY4RCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:53 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-xirQcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:53 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-IHzxZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:53 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-tqwS1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:53 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-fpZ_d2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:53 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-Eav41L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:54 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-q_mB4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:54 INFO - Running setup.py install for manifestparser 09:02:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:54 INFO - Running setup.py install for mozcrash 09:02:54 INFO - Running setup.py install for mozdebug 09:02:54 INFO - Running setup.py install for mozdevice 09:02:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:02:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:54 INFO - Running setup.py install for mozfile 09:02:54 INFO - Running setup.py install for mozhttpd 09:02:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Running setup.py install for mozinfo 09:02:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Running setup.py install for mozInstall 09:02:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Running setup.py install for mozleak 09:02:55 INFO - Running setup.py install for mozlog 09:02:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Running setup.py install for moznetwork 09:02:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Running setup.py install for mozprocess 09:02:55 INFO - Running setup.py install for mozprofile 09:02:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:56 INFO - Running setup.py install for mozrunner 09:02:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:56 INFO - Running setup.py install for mozscreenshot 09:02:56 INFO - Running setup.py install for moztest 09:02:56 INFO - Running setup.py install for mozversion 09:02:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:56 INFO - Cleaning up... 09:02:56 INFO - Return code: 0 09:02:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:56 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 0x10fdc5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8101caf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10facadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb98be8>, '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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 09:02:56 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 09:02:56 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 09:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PTSLBhwc8/Render', 09:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:56 INFO - 'HOME': '/Users/cltbld', 09:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:56 INFO - 'LOGNAME': 'cltbld', 09:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:56 INFO - 'MOZ_NO_REMOTE': '1', 09:02:56 INFO - 'NO_EM_RESTART': '1', 09:02:56 INFO - 'PAGER': '/bin/cat', 09:02:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:56 INFO - 'PWD': '/builds/slave/test', 09:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:56 INFO - 'SHELL': '/bin/bash', 09:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jg6HH3J1bO/Listeners', 09:02:56 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 09:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:56 INFO - 'USER': 'cltbld', 09:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:56 INFO - 'XPC_FLAGS': '0x0', 09:02:56 INFO - 'XPC_SERVICE_NAME': '0', 09:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:57 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-19fpsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:57 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-kKB8cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:57 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-Kb4i9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:57 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-0O6pMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:57 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-DU7_uN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:57 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-UpQN2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:57 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-dCKRuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:57 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-4d1ry0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:57 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-D2_0Tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:57 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-Q1FqH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:58 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-Y4AUYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:58 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-fqNsCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:58 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-ZK3ze6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:58 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-tikXwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:58 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-7eEHbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:58 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-2pVLKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:58 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-kh6SkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:58 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)) 09:02:58 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)) 09:02:58 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)) 09:02:58 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)) 09:02:58 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)) 09:02:58 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)) 09:02:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:03:01 INFO - Downloading blessings-1.6.tar.gz 09:03:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:03:01 INFO - Installing collected packages: blessings 09:03:01 INFO - Running setup.py install for blessings 09:03:01 INFO - Successfully installed blessings 09:03:01 INFO - Cleaning up... 09:03:01 INFO - Return code: 0 09:03:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:03:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:02 INFO - Reading from file tmpfile_stdout 09:03:02 INFO - Current package versions: 09:03:02 INFO - blessings == 1.6 09:03:02 INFO - blobuploader == 1.2.4 09:03:02 INFO - docopt == 0.6.1 09:03:02 INFO - functools32 == 3.2.3-2 09:03:02 INFO - jsonschema == 2.5.1 09:03:02 INFO - manifestparser == 1.1 09:03:02 INFO - mozInstall == 1.12 09:03:02 INFO - mozcrash == 1.0 09:03:02 INFO - mozdebug == 0.1 09:03:02 INFO - mozdevice == 0.49 09:03:02 INFO - mozfile == 1.2 09:03:02 INFO - mozhttpd == 0.7 09:03:02 INFO - mozinfo == 0.9 09:03:02 INFO - mozleak == 0.1 09:03:02 INFO - mozlog == 3.4 09:03:02 INFO - moznetwork == 0.27 09:03:02 INFO - mozprocess == 0.25 09:03:02 INFO - mozprofile == 0.28 09:03:02 INFO - mozrunner == 6.13 09:03:02 INFO - mozscreenshot == 0.1 09:03:02 INFO - mozsystemmonitor == 0.3 09:03:02 INFO - moztest == 0.8 09:03:02 INFO - mozversion == 1.4 09:03:02 INFO - psutil == 3.1.1 09:03:02 INFO - requests == 1.2.3 09:03:02 INFO - wsgiref == 0.1.2 09:03:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:02 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 0x10fdc5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8101caf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10facadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb98be8>, '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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 09:03:02 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 09:03:02 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 09:03:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PTSLBhwc8/Render', 09:03:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:02 INFO - 'HOME': '/Users/cltbld', 09:03:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:02 INFO - 'LOGNAME': 'cltbld', 09:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:02 INFO - 'MOZ_NO_REMOTE': '1', 09:03:02 INFO - 'NO_EM_RESTART': '1', 09:03:02 INFO - 'PAGER': '/bin/cat', 09:03:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:02 INFO - 'PWD': '/builds/slave/test', 09:03:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:02 INFO - 'SHELL': '/bin/bash', 09:03:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jg6HH3J1bO/Listeners', 09:03:02 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 09:03:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:02 INFO - 'USER': 'cltbld', 09:03:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:02 INFO - 'XPC_FLAGS': '0x0', 09:03:02 INFO - 'XPC_SERVICE_NAME': '0', 09:03:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 INFO - Cleaning up... 09:03:02 INFO - Return code: 0 09:03:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8101caf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba3d30>, '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 0x10facadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb98be8>, '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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 09:03:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:03:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PTSLBhwc8/Render', 09:03:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:02 INFO - 'HOME': '/Users/cltbld', 09:03:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:02 INFO - 'LOGNAME': 'cltbld', 09:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:02 INFO - 'MOZ_NO_REMOTE': '1', 09:03:02 INFO - 'NO_EM_RESTART': '1', 09:03:02 INFO - 'PAGER': '/bin/cat', 09:03:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:02 INFO - 'PWD': '/builds/slave/test', 09:03:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:02 INFO - 'SHELL': '/bin/bash', 09:03:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jg6HH3J1bO/Listeners', 09:03:02 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 09:03:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:02 INFO - 'USER': 'cltbld', 09:03:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:02 INFO - 'XPC_FLAGS': '0x0', 09:03:02 INFO - 'XPC_SERVICE_NAME': '0', 09:03:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:03:02 INFO - Cleaning up... 09:03:02 INFO - Return code: 0 09:03:02 INFO - Running post-action listener: _resource_record_post_action 09:03:02 INFO - Running post-action listener: _start_resource_monitoring 09:03:02 INFO - Starting resource monitoring. 09:03:02 INFO - [mozharness: 2017-04-13 16:03:02.713160Z] Finished create-virtualenv step (success) 09:03:02 INFO - [mozharness: 2017-04-13 16:03:02.714111Z] Running install step. 09:03:02 INFO - Running pre-action listener: _resource_record_pre_action 09:03:02 INFO - Running main action method: install 09:03:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:02 INFO - Reading from file tmpfile_stdout 09:03:02 INFO - Detecting whether we're running mozinstall >=1.0... 09:03:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:03:03 INFO - Reading from file tmpfile_stdout 09:03:03 INFO - Output received: 09:03:03 INFO - Usage: mozinstall [options] installer 09:03:03 INFO - Options: 09:03:03 INFO - -h, --help show this help message and exit 09:03:03 INFO - -d DEST, --destination=DEST 09:03:03 INFO - Directory to install application into. [default: 09:03:03 INFO - "/builds/slave/test"] 09:03:03 INFO - --app=APP Application being installed. [default: firefox] 09:03:03 INFO - mkdir: /builds/slave/test/build/application 09:03:03 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'] 09:03:03 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 09:03:23 INFO - Reading from file tmpfile_stdout 09:03:23 INFO - Output received: 09:03:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:03:23 INFO - Running post-action listener: _resource_record_post_action 09:03:23 INFO - [mozharness: 2017-04-13 16:03:23.392031Z] Finished install step (success) 09:03:23 INFO - [mozharness: 2017-04-13 16:03:23.392169Z] Running run-tests step. 09:03:23 INFO - Running pre-action listener: _resource_record_pre_action 09:03:23 INFO - Running main action method: run_tests 09:03:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:03:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:03:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:03:23 INFO - 2017-04-13 09:03:23.406 screenresolution[1625:7428] starting screenresolution argv=screenresolution get 09:03:23 INFO - 2017-04-13 09:03:23.409 screenresolution[1625:7428] Display 0: 1600x1200x32@60 09:03:23 INFO - 2017-04-13 09:03:23.415 screenresolution[1626:7431] starting screenresolution argv=screenresolution list 09:03:23 INFO - Available Modes on Display 0 09:03:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:03:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:03:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:03:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:03:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:03:23 INFO - Intel Iris: 09:03:23 INFO - Chipset Model: Intel Iris 09:03:23 INFO - Type: GPU 09:03:23 INFO - Bus: Built-In 09:03:23 INFO - VRAM (Dynamic, Max): 1536 MB 09:03:23 INFO - Vendor: Intel (0x8086) 09:03:23 INFO - Device ID: 0x0a2e 09:03:23 INFO - Revision ID: 0x0009 09:03:23 INFO - Displays: 09:03:23 INFO - Display: 09:03:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:03:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:03:23 INFO - Display Serial Number: 1600x1200 60 09:03:23 INFO - Main Display: Yes 09:03:23 INFO - Mirror: Off 09:03:23 INFO - Online: Yes 09:03:23 INFO - Rotation: Supported 09:03:23 INFO - Return code: 0 09:03:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:23 INFO - Python 2.7.3 09:03:23 INFO - Return code: 0 09:03:23 INFO - grabbing minidump binary from tooltool 09:03:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:23 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 0x10fba3d30>, '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 0x10facadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb98be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:23 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 09:03:23 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 09:03:23 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 09:03:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:03:23 INFO - Return code: 0 09:03:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:03:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:03:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:03:23 INFO - ENV: RUST_BACKTRACE is now 1 09:03:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:03:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:03:23 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/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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 09:03:23 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/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0360 --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 09:03:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PTSLBhwc8/Render', 09:03:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:23 INFO - 'HOME': '/Users/cltbld', 09:03:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:23 INFO - 'LOGNAME': 'cltbld', 09:03:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:23 INFO - 'MOZ_NO_REMOTE': '1', 09:03:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:23 INFO - 'NO_EM_RESTART': '1', 09:03:23 INFO - 'PAGER': '/bin/cat', 09:03:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:23 INFO - 'PWD': '/builds/slave/test', 09:03:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:23 INFO - 'RUST_BACKTRACE': '1', 09:03:23 INFO - 'SHELL': '/bin/bash', 09:03:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jg6HH3J1bO/Listeners', 09:03:23 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 09:03:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:23 INFO - 'USER': 'cltbld', 09:03:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:23 INFO - 'XPC_FLAGS': '0x0', 09:03:23 INFO - 'XPC_SERVICE_NAME': '0', 09:03:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:23 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/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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 09:03:24 INFO - mozversion application_buildid: 20170413075025 09:03:24 INFO - mozversion application_changeset: d56c36ff0b8bc34183f056866de81a6a61c782ec 09:03:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:03:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:03:24 INFO - mozversion application_name: Firefox 09:03:24 INFO - mozversion application_remotingname: firefox-dev 09:03:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:03:24 INFO - mozversion application_vendor: Mozilla 09:03:24 INFO - mozversion application_version: 54.0a2 09:03:24 INFO - mozversion platform_buildid: 20170413075025 09:03:24 INFO - mozversion platform_changeset: d56c36ff0b8bc34183f056866de81a6a61c782ec 09:03:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:03:24 INFO - mozversion platform_version: 54.0a2 09:03:24 INFO - using testdate: 1492099404 09:03:24 INFO - actual date: 1492099404 09:03:24 INFO - starting webserver on 'localhost:49240' 09:03:24 INFO - SUITE-START | Running 2 tests 09:03:24 INFO - TEST-START | dromaeo_css 09:03:24 INFO - operating with platform_type : mac_ 09:03:24 INFO - Initialising browser for dromaeo_css test... 09:03:24 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmpR6dd0d/profile http://localhost:49240/getInfo.html) 09:03:26 INFO - PROCESS | 1636 | 2017-04-13 09:03:26.856 plugin-container[1638:7610] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 09:03:26 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:26 INFO - PROCESS | 1636 | 2017-04-13 09:03:26.858 plugin-container[1638:7610] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 09:03:26 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:26 INFO - PROCESS | 1636 | 2017-04-13 09:03:26.858 plugin-container[1638:7610] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:03:26 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 09:03:26 INFO - PROCESS | 1636 | colorDepth:24 09:03:26 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 09:03:26 INFO - PROCESS | 1636 | __metrics 09:03:27 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 09:03:27 INFO - TEST-INFO | 1636: exit 0 09:03:27 INFO - Browser initialized. 09:03:27 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:27 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmpR6dd0d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:30 INFO - PROCESS | 1639 | 2017-04-13 09:03:30.342 plugin-container[1640:7739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8933, name = 'com.apple.tsm.portname' 09:03:30 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:30 INFO - PROCESS | 1639 | 2017-04-13 09:03:30.343 plugin-container[1640:7739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.CFPasteboardClient' 09:03:30 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:30 INFO - PROCESS | 1639 | 2017-04-13 09:03:30.343 plugin-container[1640:7739] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:03:40 INFO - PROCESS | 1639 | RSS: Main: 220712960 09:03:40 INFO - PROCESS | 1639 | 09:06:43 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 09:06:45 INFO - PROCESS | 1639 | RSS: Main: 241475584 09:06:45 INFO - PROCESS | 1639 | 09:08:28 INFO - PROCESS | 1639 | ************************* 09:08:28 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 09:08:28 INFO - PROCESS | 1639 | 09:08:28 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:08:28 INFO - PROCESS | 1639 | Full stack: 09:08:28 INFO - PROCESS | 1639 | ************************* 09:08:28 INFO - PROCESS | 1639 | ************************* 09:08:28 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 09:08:28 INFO - PROCESS | 1639 | 09:08:28 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:08:28 INFO - PROCESS | 1639 | Full stack: 09:08:28 INFO - PROCESS | 1639 | ************************* 09:08:28 INFO - PROCESS | 1639 | ************************* 09:08:28 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 09:08:28 INFO - PROCESS | 1639 | 09:08:28 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:08:28 INFO - PROCESS | 1639 | Full stack: 09:08:28 INFO - PROCESS | 1639 | ************************* 09:08:28 INFO - PROCESS | 1639 | ************************* 09:08:28 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 09:08:28 INFO - PROCESS | 1639 | 09:08:28 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:08:28 INFO - PROCESS | 1639 | Full stack: 09:08:28 INFO - PROCESS | 1639 | ************************* 09:09:47 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 09:09:48 INFO - PROCESS | 1639 | RSS: Main: 250998784 09:09:48 INFO - PROCESS | 1639 | 09:12:50 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 09:12:50 INFO - PROCESS | 1639 | RSS: Main: 258134016 09:12:50 INFO - PROCESS | 1639 | 09:15:52 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 09:15:53 INFO - PROCESS | 1639 | RSS: Main: 254308352 09:15:53 INFO - PROCESS | 1639 | 09:18:56 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 09:18:57 INFO - PROCESS | 1639 | RSS: Main: 259608576 09:18:57 INFO - PROCESS | 1639 | 09:22:00 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 09:22:00 INFO - PROCESS | 1639 | RSS: Main: 255905792 09:22:00 INFO - PROCESS | 1639 | 09:22:00 INFO - PROCESS | 1639 | __start_tp_report 09:22:00 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 09:22:00 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 09:22:00 INFO - PROCESS | 1639 | |i|pagename|runs| 09:22:00 INFO - PROCESS | 1639 | |0;dojo.html;2999.7300242978135;3531.1172206948245;3554.573451185858;3571.6964058055064;3586.193106551026;6178.35127311632;6197.907031394533;6198.969005154968;6218.782342618009;6275.3410891856265;6127.203463549732;6165.722542485588;6171.197744293236;6195.2255968004;6218.284897236819;5560.4161563035905;5593.8601534961745;5597.552195824324;5635.774569017234;5831.037878750001;4112.670986321102;5648.858778530549;5651.547876169917;5653.123765816305;5684.687342196177;5374.167004114349;5398.217258497515;5460.180972854084;5632.802851900185;5642.4357564243455;4582.793774280165;5512.503874651301;5523.696196709179;5529.474699903523;5532.806351777668;5406.675599464044;5541.5289700375515;5598;5641.097424412074;5654.095344744821;6221.844453888643;6252.968735156295;6262.248530176363;6272.717727702218;6274.435300822948;4945.876353091202;6293.685315734195;6496.805095847085;6497.870042599169;6512.251091124534;6369.20384951881;6382.266039405435;6382.6170429774365;6438.903416448757;6452.290248072708;6243.344448832807;6252.5935814171935;6283.277423096311;6291.905621415683;6305.400986906328;6777.593344399352;6781.42357899584;6788.796336109925;6794.11676482064;6795.93204067953;6274.403931626488;6333.303336632966;6361.936380636134;6399.168108146005;6400.71196796136;6468.6442245676;6510.511711621647;6523.7064332105165;6532.902006469907;6557.475401967831;6474.9963755617955;6510.511711621647;6533.705983230766;6547.345265473415;6549.2468366137555;5409.945060713123;6508.251551071593;6537.640429776313;6554.70503827329;6565.080888675589;4971.950280497149;5217.087009773274;5249.947500525022;5304.681719096867;5313.149896016619;3989.20215956804;4677.742724150205;4692;4709.411323584552;4731.503192164773;6291.433770960635;6310.558260921692;6341.587796793194;6354.872902541923;6377.7767778127545;5260.368755749335;5470.91793623096;6314.936850631435;6509.414152726277;6695.096162018162;6265.8120256392385;6348.746050158034;6352.65060421672;6355.555111142268;6360.0777887206095;5811.709414529257;5825.86045771777;5828.825135245949;5839.678817664984;5861.736221870026;5663.773449061991;6492.967535162294;6529.804105876831;6828.7268509259075;6859.382655560924;5396.757145928521;5596.664200148004;5611.270534830446;6535.509836762167;6587.901181482377;6340.397662222082;6803.9319606805275;6808.046873437822;6810.557313774589;6821.727130914906;6319.336469670802;6431.614103153825;6454.419102280817;6458.806235812933;6484.805455836333;6349;6393.680315984089;6451.741930322734;6457.4188323049275;6497.220333560003;6404.391582799627;6443.355664433519;6446.967765161145;6449.580777249464;6468.353164683494;6279.654618995999;6288.5598008138995;6303.086052522267;6334.081558174131;6393.264774550986;5103.744812759422;5308.575313974796;6083.361247069171;6318.652474113968;6370.649614271259;6273.843153921189;6287.2769631492965;6398.168238129014;6440.613563186036;6462.063000864942;6310.242770867341;6311.684415779101;6326.398992095744;6338.683065846597;6419.165508483868 09:22:00 INFO - PROCESS | 1639 | |1;ext.html;709.1561042359592;735.871222536056;759.9620018999052;826.6280173921733;965.377331621104;38567.03582410441;39524.80237598812;40966.180676386495;41148.38277425841;41475.58524414762;39933.80198594047;40142.59857401425;40200.798996005055;40205.19589608206;40492.79753601228;7869.52782833029;8011.238932301422;8645.827083458358;10806.5677372905;10838.72903177419;10519.421431821247;10737.818839927602;10785.46072696366;10792.676219713421;10798.67603971882;10794.46027698616;10798.838017429745;10829.512671929782;10832.295900766465;10844.566217351297;10791.136709063256;10801.891981080207;10808.783824323547;10819.13446924244;10825.566977320897;10527.526261318182;10555.155587552978;10558.577656893714;10614.734631634194;10615.681529554126;34731.13172170691;35115.29769404623;35237.47143792845;35263.1184220394;35425.76009839669;3710.2023065040876;3776.376897811858;3787.7159213058853;3789.2989796887387;3790.0714324990554;25363.746362536323;25454.87272563644;25690.48619027609;26802.5979610306;27342.863285683645;3044.4672182368;3139.481985472394;3164.68353164684;3183.6657150999044;3226.725728313096;352055;357906;362581;365860;375634;43437;43520.56479435165;43786.34320485195;43843.342349864775;43865;45581;45824.54175458203;45858.77070614626;45972.54027459749;46727;13508.054436189568;13588.320583970763;13601.04792664504;13606;13608;11612.535498580131;11814.94092529532;11821;11823.704407389883;11831.881681183078;12012.63962081139;12087.39563021863;12110.697232569257;12156.5137394505;12203.389830508439;4801.135795556764;5007.324011258506;5501.587380946365;5523.502884740393;5695.60130790849;762.3634265388351;977.154761131616;1000.7648202672366;1019.0421004255976;1056.0917610854658;22794.31617051491;27150;28074.43851123007;28210.43579128447;28898;961.6538046303325;1116.6761639124725;1123.253036730565;1138.2658185470325;1155.96532104037;11866.940665296619;14752.778708319383;14809.408914439973;14829.332680029425;14973.850261497246;324153;394106;395003;395604;396655;54639;54870;55018.72490637521;55083;55178;1454.5490897821498;1458.48952866498;1494.4769330734157;1495.9925200374146;1503.9172845493604;20756.377308680763;26027.04797140184;26397.604035939094;26421.867890660425;28243.2939176514;40512.58205962777;41266;45682.31476527921;45938.77030614825;45955.77022114868;300116;345518;350575.49424505426;354373;354963;25650.743492564834;27636.861815691598;27701.722982769916;31876.36247275088;32170.839145804122;38924.80537597294;39360.81917542478;40058;40230;40609.1878162441;28344.291392715346;28385;28477.145685629464;28944.7105528942;28993.565096523147;28226.012089577587;28624.57063144096;28768.136955891358;29556.408871822012;29773;27767.583486247317;32326.83836580802;33391.49912751359;33482.162945926546;33727.66272337245;28131;28553;28628.85685571559;28964.855175723987;29431;22844.428889277235;23226.65160022635;23587.528249435258;23650.526989460457;23739.52520949606 09:22:00 INFO - PROCESS | 1639 | |2;jquery.html;374.50004244333815;404.7634476673762;426.7949638194269;426.8122026308425;442.32766195383016;19505.219791208354;19708.916009619465;20007.699884501744;21000;21108.366748997516;21943.122275108977;22003.78979156146;22330.776692233074;22744;23036.53926921465;6449.516286278536;6629.701663425133;6650.434713049397;6678.732850685967;6685.93314066858;6561.704723287464;6971.372576468142;7289.890651640229;7420.703171873119;7454.403647708197;7189.065421495225;7377.7786666399825;7385.778426647209;7392.55644661322;7407;6313.589616674947;7465;7489.764188908824;7490.3633191178815;7544.170141284454;7187.065681461377;7270.672819723125;7286.198518162998;7305.89041164383;7338;19116.33092841744;22885.88557057204;24537.877310613334;25097.62353564698;25816.354591135183;2409.7831195192343;2797;3020.4865172920654;3227.160938156073;3528.2943411317583;15950.361985520565;16017.279222435018;16799.66400671992;17175.312987480487;17276.22256998438;1902.6384986852454;2021.7068525063783;2024.6456870047682;2025.6657651487487;2032.4817171621141;149028;150279.24860375738;154766;155124;156549;20471.078801453972;21132.89433552853;21314.680279795815;21457.248996285056;21519;32711.018669439956;32938;33007.50488742671;33129;33136;8682.91317086821;10373;10458.477076146162;10501.684949451528;10637.414942178255;8701.869471957925;8707.6516939323;8726.08376120511;8834.381593288408;8871.467711937305;9568.760780980392;9951.601935922483;9995.200383969264;10072.129443108679;10134.847977280348;3918.3534716771983;3958.5447673517915;4207.347861081537;4260.595243451867;4282.464691913511;1072.6031368393744;1082.7509672775147;1085.6742977106994;1088.8257878739364;1132.3771925440974;19525.316613918443;22329.776702232768;22664;22898.427539311317;23030;1034.7826956339102;1077.4074259157433;1086.576235268246;1120.7478317378689;1152.3201311226417;9708;11486.253393529394;11709.648710538697;11719.472623731952;11725.886040826108;655034;656737;657341;739872;753638;161759;162963;163693;164948;165364.17317913333;1186.9109816763778;1199.6281152842646;1229.4959066782576;1239.1573729863599;1241.8385609870659;19186.328478503186;19876.801231988073;19956.90021549883;19988.90005549963;20005.199792008156;56955.86088278294;64840.703185935075;65461;68602;69089.30910690829;89646.10353896377;90102;91587.08412916052;92322;92668.53665731628;11690.298582085103;11893.702657433634;12224;12254.693632659255;12269.57056503018;11867.287962722265;13396.799048014092;13442.327883605976;13690.86309136896;13780.931095344458;11308.886911130783;11314.321140731583;11431.885681143081;11635.127365447795;11859.34773587461;10446.320989135682;10581.259311848406;10627.521761520904;10949.890501094887;10950;9629.518524073908;9655.758606034624;11710.0694875603;11739.23694959825;11955.880441195477;11664.88335116672;11806.881931180578;12044;12157.209781364183;12248.203866748989;11425.485853136463;11503.252288601214;11534.538618455168;11671.941640291745;11862 09:22:00 INFO - PROCESS | 1639 | |3;mootools.html;1361.4282001559345;1372.03957229939;1374.312843578211;1391.1931079973617;1421.3888028147896;8843.425177363475;11156.16328775341;11553.826692599618;11716.882831171697;11726.530938762438;11123.221374503768;11354;11424;11425.371604561746;11492.425378731052;2048.6107639548436;2430.611102223649;2479.0579579759756;2483.292261705414;2488.278399264211;2333.3583264602203;2400.8079353651665;2431.8176136789725;2433.9391515212083;2434.6834911461483;2214.025828635395;2314.5255222679393;2333.0900948630047;2402.4714562796157;2412.601920683085;2349.1777877742825;2418.3470462974988;2421.225207933462;2422.7819496245247;2438.695163104603;2136.9465763355884;2327.6508523721577;2349.330440824374;2430.052279610953;2434.7565243475688;11225.943870280678;11246.49390777417;11318.094552435869;11320.094392448667;11324.03745681618;5960;6128.264608246995;6130.111133885606;6134.631922084644;6151.72317245725;3640.7451478396524;3706.666400023984;3755.962440375589;3766.7363284569815;3839.4816699745456;1187.447836755904;1201.4441298518439;1243.241622610207;1251.255502975728;1252.5553428532921;13747.725045499034;14216.218108004055;15962.600934976603;16068.357265709357;16256.756148657661;4510.188166130128;4699.671023028423;4835.395575553056;4855.8057677692495;4888.65779395446;11712.765744685059;11811.114166437552;11822.290662560274;11844.230125042017;11869.406529673483;4924.261360795909;5828.737706803204;5889.705514724246;5989.341172471025;5990.191324171268;5316.308879845596;5342.332208473941;5399.7300134993875;5423.7830486780085;5466.753996070186;3864.5942176071107;3994.980025099856;4021.8391264349684;4074.1647962167685;4085.428040074392;902.264654306738;1073.63496411219;1076.482160154179;1083.6586475260267;1094.7591529863416;666.2804171494774;807.3904202327323;823.3372135430916;841.3984001439001;860.2429861721645;6133.653812589489;6847.965760171167;6985.091938048021;6987.231404545496;7023.063937750309;903.2322525852973;935.0415823780544;1000.9399436033862;1005.2661557063385;1081.7403823082561;4184.602462766033;4219.767912764827;4269.655058656514;4389.341598760211;4449.354843547668;13007.154534955387;13466.596002119952;13580.524681636096;13603.047786655037;13608.659783505294;13267.469301227842;13391.598252052454;13415.798763018562;13429.798553021712;13549.187048777105;10149.39103653783;10281.12610428122;10296.948515257225;10354.585816567404;10367;6476.255230648536;6653.600783952978;6813.012113243452;6837.027587306547;6863.416609588241;9770.902290977;12666.366681666064;12705.237685738884;12811.87188128144;12853.550125745554;11086.390248536409;11553.537858485566;12007.339596322287;13022.869771302167;13108.344582771015;9230.677195501392;9437.19783818383;9526.52367381642;9533.761655958657;9539.23686105096;10383.94808025955;10393.9480302598;10457.42484163348;10526.263161578616;10541.31481453703;7709.190534993745;8965.327600429864;8970.327225458248;9000.909990900007;9026.548672566476;7383.926160738539;8714.259287960595;8910.643574256957;8916.643334266557;9006.324525660733;7861.1745766693675;8030.959845200737;8035.236652518235;8127.2279133482225;8158.959205203936;5817.5345972321265;5929.584929055098;6017.578769486269;6098.420650038417;6229.875402492015;5846.824595262149;6085;6123.928312545376;6175.320714721467;6191.288001879841 09:22:00 INFO - PROCESS | 1639 | |4;prototype.html;368.1018315310642;372.4729507746539;374.4420812988647;387.6220684832287;394.64679112194585;4611.930821037683;4877.853664390074;4938.25926111083;5253.740891416689;5405.297311349529;4382.408374869399;4991.0017996400775;5202.8699282517955;5314.219736388314;5352.751844225425;3628.9274214515694;4068.8669724312335;4419.867403977886;4425.446819147606;4481.887952801173;3687.7461663034564;3713.4801127842125;4206.3059595166915;4413.558644135593;4460.264056430685;3911;4014.7591144531275;4022.839086436539;4109.116539943911;4610.953890461086;3712.9071773205615;4014.3777714454304;4056.492938382702;4231.280682284019;4341.652667786601;3768.5289338832645;3831.961680383188;3925.5878132796215;4072.6538244249273;4091.1408604193157;4618.953810461886;4911.231956495659;5017.2724954881705;5174.715390653512;5183.844484665466;4529.297958816389;4676.228422310349;5069;5083.440821509631;5092.592592592584;5230.34620672416;5461.399246082928;5605.887882242332;5640.576956728261;5680.31836179657;3755.3052685253315;4269.758376852146;4476;4504.909901801979;4523.344115103291;5507.586930980153;5740.598158128891;5760.724513817061;5902.701405690741;6091.142201628501;3986.382110772835;4167.437395951569;4272.84633149048;4596.126735920165;4639.396878405786;4292.635126014261;4350.499692535399;4670.416197975253;4720.433547974197;4734.28985652148;3942.231264903374;4788.49162513814;4832.420109586872;5041.369828771403;5047.873803154877;4808.35087263222;5277.838875447395;5666.886662266732;5719.828405147769;5726.398728133569;1394.072941493895;1434.168182454174;1498.1535432480614;1580.2888700085007;1581.4227806850417;1056.434807378049;1161.8961986113056;1182.7338848759132;1206.457094307565;1218.7446929662462;4833.975830120898;5336.813211537577;5370.167624018283;5391.487808658171;5417.918731219035;4872.0024789836;5629.915551266734;5926.970365148147;5936.8126374725225;5944.256967879015;4857.514248575185;4878.560824556765;5106.4893510648635;5124.436312005676;5367.624266301399;2670.6795184577586;2707.6714025937677;2722.3194201449637;2842.4560018783486;3072.9539056914164;5426;5568.6658800472105;5617.146341707226;5850.970745146247;6176.536759743038;5360.437154098841;5497.340319161646;5529.944700553024;5785.363610002896;5863;6118.837420890106;6133.26400831903;6165.506759459143;6181.567290289637;6195.079525347157;5164.509371609691;5548.4174161712435;5916.023856063874;5961;5983.940160598512;4809.807607695653;5278.76245568958;5628.973569514889;5770.961226979184;5863.296404431496;5117.718525480986;5441.5374693151525;6014.36849130835;6017.578769486094;6159.538034647329;4546.7953942073345;4566.566176213254;4570.337301091389;4572.268437050056;4579.7252164870215;5527.705599745733;5865.765369385177;5880.470757631833;5923.881522369442;5931.673757943355;5308.2397153479205;5353.143497040455;5459.699716515471;5678.3186017678145;5698.31620205578;4715.834945776881;5143.871403214949;5231.738413079254;5422.5119739223655;5694.117411801178;4830.840598256323;4991.481005890911;5206.323177986837;5548.334199896039;5629.662220266797;5204.687718736888;5319.574434045345;5400.459954004568;5789.1605717171615;5834.416558344132;5244.243178535234;5622.971885140548;5642.363714522907;5651.745671444713;5687.094823234263 09:22:00 INFO - PROCESS | 1639 | |5;yui.html;705.4744215559409;706.4136766483819;755.2900273742683;770.4337312075627;793.7102957420542;15056;15999.120048397333;16049;16216;16349;14701.338439770208;15061.62345941352;15103.773443398359;15222.467213647524;15292.617684557863;930.1582068228264;935.177044201102;953.0421925964396;960.6301573894066;961.697065424389;947.7393716727886;955.4171955107379;955.8852937647492;956.5886668732443;959.5681943125586;909.2816674826879;1003.5283416794096;1015.8780946286456;1018.1956254558927;1022.8874823769379;1014.1430491234895;1014.523174108168;1015.1472762879217;1016.1972042086743;1019.281406608343;756.3230908337063;757.3146302596159;757.38651692129;758.1508710244507;760.7033257029763;13831;16581;16803.91598042002;16952.491425257263;17041.06274154921;11536.48085836131;14123.293835308194;14263.857361426357;14379.78430323546;14429.855701442957;11079.169062320278;11465.885341146564;11504.539818407253;12010.099242556844;12211.93894030533;4224.366345048236;4426.468823741172;4551.40831691878;4678.1813182692895;4689.718616882998;106804;107795.46102269439;108481.99999999921;109339;109446.45276773645;10642.680719578426;10694.037536621587;10707.518161682587;10938.945305273422;11169.553217871195;21376.679349809765;21487.46281342979;21651.67522487164;21694.674579881314;21714;6198.6280823150755;6690.264070952191;6968.268331825187;7201.531900426456;7564.621768911533;4272.252355837778;4393.450818647669;4416.779161041935;4443.622292105208;4447.822087116544;3393.423118069934;3484.8257587120543;3485.0938755923644;3502.404591219499;3510.508528805969;658.8550518885837;662.7481557008402;668.3784080804899;673.1316601583902;676.2865177237962;572.2389222334285;656.2846497317851;677.857649893518;684.6850448793512;688.3632639808258;9917.950410247902;10329.690109296733;10359.119474844572;10485.266031377883;10545.314554554083;553.5820455556029;591.73371982608;597.357840321656;638.8977763557901;696.5633232619454;4336.696431249782;4883.731394773322;4980.12847751642;5004.47453017428;5095.108356037678;89521.55239223762;103756;107387;108360;111236;61844;62770.74458510896;75059.62470187723;75678.24321756711;97275.05449890962;74988.62505687437;76123.23876761162;78482;78940.60529697315;80019;10821.513031913426;10861.45692715377;11412.600558980395;11433.59982400612;11562.710932226762;42479;49586.00827983348;49638.50361496483;49928;50156;62709.86450677539;65420.67289663521;71134;71449.64275178591;73513.16217094401;12655.557055503014;12771.36143192818;12997.935010325265;13160.539381121998;13268.668283292609;14753.409863605764;14948;15382.692346153239;15442.2278886053;15506.689866202387;13512.054156208971;13733.931330343685;14053.789193161907;14262.42950281977;14722.926385368004;13090.280034598189;13526.526571569948;13783.724325513233;13804.424840579903;14078.648033799236;13230;13702.794458083335;13895.930520347334;14021.228832414316;14086.647833804236;9150.267978561833;9519.309205944955;9523.524243150234;9645.886576307052;9800.31397802147;8971.416857904216;10581.017466965717;10942.398168100512;11612.709682258012;11619.360935148648 09:22:00 INFO - PROCESS | 1639 | __end_tp_report 09:22:00 INFO - PROCESS | 1639 | __start_cc_report 09:22:00 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,413 09:22:00 INFO - PROCESS | 1639 | __end_cc_report 09:22:00 INFO - PROCESS | 1639 | __startTimestamp1492100520611__endTimestamp 09:22:00 INFO - PROCESS | 1639 | 09:22:00 INFO - PROCESS | 1639 | ------- Summary: start ------- 09:22:00 INFO - PROCESS | 1639 | Number of tests: 6 09:22:00 INFO - PROCESS | 1639 | 09:22:00 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:6013.93 Median:6293.69 stddev:714.79 (11.4%) stddev-sans-first:676.74 09:22:00 INFO - PROCESS | 1639 | Values: 2999.7 3531.1 3554.6 3571.7 3586.2 6178.4 6197.9 6199.0 6218.8 6275.3 6127.2 6165.7 6171.2 6195.2 6218.3 5560.4 5593.9 5597.6 5635.8 5831.0 4112.7 5648.9 5651.5 5653.1 5684.7 5374.2 5398.2 5460.2 5632.8 5642.4 4582.8 5512.5 5523.7 5529.5 5532.8 5406.7 5541.5 5598.0 5641.1 5654.1 6221.8 6253.0 6262.2 6272.7 6274.4 4945.9 6293.7 6496.8 6497.9 6512.3 6369.2 6382.3 6382.6 6438.9 6452.3 6243.3 6252.6 6283.3 6291.9 6305.4 6777.6 6781.4 6788.8 6794.1 6795.9 6274.4 6333.3 6361.9 6399.2 6400.7 6468.6 6510.5 6523.7 6532.9 6557.5 6475.0 6510.5 6533.7 6547.3 6549.2 5409.9 6508.3 6537.6 6554.7 6565.1 4972.0 5217.1 5249.9 5304.7 5313.1 3989.2 4677.7 4692.0 4709.4 4731.5 6291.4 6310.6 6341.6 6354.9 6377.8 5260.4 5470.9 6314.9 6509.4 6695.1 6265.8 6348.7 6352.7 6355.6 6360.1 5811.7 5825.9 5828.8 5839.7 5861.7 5663.8 6493.0 6529.8 6828.7 6859.4 5396.8 5596.7 5611.3 6535.5 6587.9 6340.4 6803.9 6808.0 6810.6 6821.7 6319.3 6431.6 6454.4 6458.8 6484.8 6349.0 6393.7 6451.7 6457.4 6497.2 6404.4 6443.4 6447.0 6449.6 6468.4 6279.7 6288.6 6303.1 6334.1 6393.3 5103.7 5308.6 6083.4 6318.7 6370.6 6273.8 6287.3 6398.2 6440.6 6462.1 6310.2 6311.7 6326.4 6338.7 6419.2 09:22:00 INFO - PROCESS | 1639 | 09:22:00 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:49976.59 Median:29493.70 stddev:95709.16 (324.5%) stddev-sans-first:95906.17 09:22:00 INFO - PROCESS | 1639 | Values: 709.2 735.9 760.0 826.6 965.4 38567.0 39524.8 40966.2 41148.4 41475.6 39933.8 40142.6 40200.8 40205.2 40492.8 7869.5 8011.2 8645.8 10806.6 10838.7 10519.4 10737.8 10785.5 10792.7 10798.7 10794.5 10798.8 10829.5 10832.3 10844.6 10791.1 10801.9 10808.8 10819.1 10825.6 10527.5 10555.2 10558.6 10614.7 10615.7 34731.1 35115.3 35237.5 35263.1 35425.8 3710.2 3776.4 3787.7 3789.3 3790.1 25363.7 25454.9 25690.5 26802.6 27342.9 3044.5 3139.5 3164.7 3183.7 3226.7 352055.0 357906.0 362581.0 365860.0 375634.0 43437.0 43520.6 43786.3 43843.3 43865.0 45581.0 45824.5 45858.8 45972.5 46727.0 13508.1 13588.3 13601.0 13606.0 13608.0 11612.5 11814.9 11821.0 11823.7 11831.9 12012.6 12087.4 12110.7 12156.5 12203.4 4801.1 5007.3 5501.6 5523.5 5695.6 762.4 977.2 1000.8 1019.0 1056.1 22794.3 27150.0 28074.4 28210.4 28898.0 961.7 1116.7 1123.3 1138.3 1156.0 11866.9 14752.8 14809.4 14829.3 14973.9 324153.0 394106.0 395003.0 395604.0 396655.0 54639.0 54870.0 55018.7 55083.0 55178.0 1454.5 1458.5 1494.5 1496.0 1503.9 20756.4 26027.0 26397.6 26421.9 28243.3 40512.6 41266.0 45682.3 45938.8 45955.8 300116.0 345518.0 350575.5 354373.0 354963.0 25650.7 27636.9 27701.7 31876.4 32170.8 38924.8 39360.8 40058.0 40230.0 40609.2 28344.3 28385.0 28477.1 28944.7 28993.6 28226.0 28624.6 28768.1 29556.4 29773.0 27767.6 32326.8 33391.5 33482.2 33727.7 28131.0 28553.0 28628.9 28964.9 29431.0 22844.4 23226.7 23587.5 23650.5 23739.5 09:22:00 INFO - PROCESS | 1639 | 09:22:00 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:42091.44 Median:21120.63 stddev:116570.02 (551.9%) stddev-sans-first:116854.94 09:22:00 INFO - PROCESS | 1639 | Values: 374.5 404.8 426.8 426.8 442.3 19505.2 19708.9 20007.7 21000.0 21108.4 21943.1 22003.8 22330.8 22744.0 23036.5 6449.5 6629.7 6650.4 6678.7 6685.9 6561.7 6971.4 7289.9 7420.7 7454.4 7189.1 7377.8 7385.8 7392.6 7407.0 6313.6 7465.0 7489.8 7490.4 7544.2 7187.1 7270.7 7286.2 7305.9 7338.0 19116.3 22885.9 24537.9 25097.6 25816.4 2409.8 2797.0 3020.5 3227.2 3528.3 15950.4 16017.3 16799.7 17175.3 17276.2 1902.6 2021.7 2024.6 2025.7 2032.5 149028.0 150279.2 154766.0 155124.0 156549.0 20471.1 21132.9 21314.7 21457.2 21519.0 32711.0 32938.0 33007.5 33129.0 33136.0 8682.9 10373.0 10458.5 10501.7 10637.4 8701.9 8707.7 8726.1 8834.4 8871.5 9568.8 9951.6 9995.2 10072.1 10134.8 3918.4 3958.5 4207.3 4260.6 4282.5 1072.6 1082.8 1085.7 1088.8 1132.4 19525.3 22329.8 22664.0 22898.4 23030.0 1034.8 1077.4 1086.6 1120.7 1152.3 9708.0 11486.3 11709.6 11719.5 11725.9 655034.0 656737.0 657341.0 739872.0 753638.0 161759.0 162963.0 163693.0 164948.0 165364.2 1186.9 1199.6 1229.5 1239.2 1241.8 19186.3 19876.8 19956.9 19988.9 20005.2 56955.9 64840.7 65461.0 68602.0 69089.3 89646.1 90102.0 91587.1 92322.0 92668.5 11690.3 11893.7 12224.0 12254.7 12269.6 11867.3 13396.8 13442.3 13690.9 13780.9 11308.9 11314.3 11431.9 11635.1 11859.3 10446.3 10581.3 10627.5 10949.9 10950.0 9629.5 9655.8 11710.1 11739.2 11955.9 11664.9 11806.9 12044.0 12157.2 12248.2 11425.5 11503.3 11534.5 11671.9 11862.0 09:22:00 INFO - PROCESS | 1639 | 09:22:00 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6789.73 Median:2436.73 stddev:4230.54 (173.6%) stddev-sans-first:4222.75 09:22:00 INFO - PROCESS | 1639 | Values: 1361.4 1372.0 1374.3 1391.2 1421.4 8843.4 11156.2 11553.8 11716.9 11726.5 11123.2 11354.0 11424.0 11425.4 11492.4 2048.6 2430.6 2479.1 2483.3 2488.3 2333.4 2400.8 2431.8 2433.9 2434.7 2214.0 2314.5 2333.1 2402.5 2412.6 2349.2 2418.3 2421.2 2422.8 2438.7 2136.9 2327.7 2349.3 2430.1 2434.8 11225.9 11246.5 11318.1 11320.1 11324.0 5960.0 6128.3 6130.1 6134.6 6151.7 3640.7 3706.7 3756.0 3766.7 3839.5 1187.4 1201.4 1243.2 1251.3 1252.6 13747.7 14216.2 15962.6 16068.4 16256.8 4510.2 4699.7 4835.4 4855.8 4888.7 11712.8 11811.1 11822.3 11844.2 11869.4 4924.3 5828.7 5889.7 5989.3 5990.2 5316.3 5342.3 5399.7 5423.8 5466.8 3864.6 3995.0 4021.8 4074.2 4085.4 902.3 1073.6 1076.5 1083.7 1094.8 666.3 807.4 823.3 841.4 860.2 6133.7 6848.0 6985.1 6987.2 7023.1 903.2 935.0 1000.9 1005.3 1081.7 4184.6 4219.8 4269.7 4389.3 4449.4 13007.2 13466.6 13580.5 13603.0 13608.7 13267.5 13391.6 13415.8 13429.8 13549.2 10149.4 10281.1 10296.9 10354.6 10367.0 6476.3 6653.6 6813.0 6837.0 6863.4 9770.9 12666.4 12705.2 12811.9 12853.6 11086.4 11553.5 12007.3 13022.9 13108.3 9230.7 9437.2 9526.5 9533.8 9539.2 10383.9 10393.9 10457.4 10526.3 10541.3 7709.2 8965.3 8970.3 9000.9 9026.5 7383.9 8714.3 8910.6 8916.6 9006.3 7861.2 8031.0 8035.2 8127.2 8159.0 5817.5 5929.6 6017.6 6098.4 6229.9 5846.8 6085.0 6123.9 6175.3 6191.3 09:22:00 INFO - PROCESS | 1639 | 09:22:00 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4688.80 Median:5088.02 stddev:1340.95 (26.4%) stddev-sans-first:1304.90 09:22:00 INFO - PROCESS | 1639 | Values: 368.1 372.5 374.4 387.6 394.6 4611.9 4877.9 4938.3 5253.7 5405.3 4382.4 4991.0 5202.9 5314.2 5352.8 3628.9 4068.9 4419.9 4425.4 4481.9 3687.7 3713.5 4206.3 4413.6 4460.3 3911.0 4014.8 4022.8 4109.1 4611.0 3712.9 4014.4 4056.5 4231.3 4341.7 3768.5 3832.0 3925.6 4072.7 4091.1 4619.0 4911.2 5017.3 5174.7 5183.8 4529.3 4676.2 5069.0 5083.4 5092.6 5230.3 5461.4 5605.9 5640.6 5680.3 3755.3 4269.8 4476.0 4504.9 4523.3 5507.6 5740.6 5760.7 5902.7 6091.1 3986.4 4167.4 4272.8 4596.1 4639.4 4292.6 4350.5 4670.4 4720.4 4734.3 3942.2 4788.5 4832.4 5041.4 5047.9 4808.4 5277.8 5666.9 5719.8 5726.4 1394.1 1434.2 1498.2 1580.3 1581.4 1056.4 1161.9 1182.7 1206.5 1218.7 4834.0 5336.8 5370.2 5391.5 5417.9 4872.0 5629.9 5927.0 5936.8 5944.3 4857.5 4878.6 5106.5 5124.4 5367.6 2670.7 2707.7 2722.3 2842.5 3073.0 5426.0 5568.7 5617.1 5851.0 6176.5 5360.4 5497.3 5529.9 5785.4 5863.0 6118.8 6133.3 6165.5 6181.6 6195.1 5164.5 5548.4 5916.0 5961.0 5983.9 4809.8 5278.8 5629.0 5771.0 5863.3 5117.7 5441.5 6014.4 6017.6 6159.5 4546.8 4566.6 4570.3 4572.3 4579.7 5527.7 5865.8 5880.5 5923.9 5931.7 5308.2 5353.1 5459.7 5678.3 5698.3 4715.8 5143.9 5231.7 5422.5 5694.1 4830.8 4991.5 5206.3 5548.3 5629.7 5204.7 5319.6 5400.5 5789.2 5834.4 5244.2 5623.0 5642.4 5651.7 5687.1 09:22:00 INFO - PROCESS | 1639 | 09:22:00 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20276.56 Median:12553.71 stddev:28896.77 (230.2%) stddev-sans-first:28940.47 09:22:00 INFO - PROCESS | 1639 | Values: 705.5 706.4 755.3 770.4 793.7 15056.0 15999.1 16049.0 16216.0 16349.0 14701.3 15061.6 15103.8 15222.5 15292.6 930.2 935.2 953.0 960.6 961.7 947.7 955.4 955.9 956.6 959.6 909.3 1003.5 1015.9 1018.2 1022.9 1014.1 1014.5 1015.1 1016.2 1019.3 756.3 757.3 757.4 758.2 760.7 13831.0 16581.0 16803.9 16952.5 17041.1 11536.5 14123.3 14263.9 14379.8 14429.9 11079.2 11465.9 11504.5 12010.1 12211.9 4224.4 4426.5 4551.4 4678.2 4689.7 106804.0 107795.5 108482.0 109339.0 109446.5 10642.7 10694.0 10707.5 10938.9 11169.6 21376.7 21487.5 21651.7 21694.7 21714.0 6198.6 6690.3 6968.3 7201.5 7564.6 4272.3 4393.5 4416.8 4443.6 4447.8 3393.4 3484.8 3485.1 3502.4 3510.5 658.9 662.7 668.4 673.1 676.3 572.2 656.3 677.9 684.7 688.4 9918.0 10329.7 10359.1 10485.3 10545.3 553.6 591.7 597.4 638.9 696.6 4336.7 4883.7 4980.1 5004.5 5095.1 89521.6 103756.0 107387.0 108360.0 111236.0 61844.0 62770.7 75059.6 75678.2 97275.1 74988.6 76123.2 78482.0 78940.6 80019.0 10821.5 10861.5 11412.6 11433.6 11562.7 42479.0 49586.0 49638.5 49928.0 50156.0 62709.9 65420.7 71134.0 71449.6 73513.2 12655.6 12771.4 12997.9 13160.5 13268.7 14753.4 14948.0 15382.7 15442.2 15506.7 13512.1 13733.9 14053.8 14262.4 14722.9 13090.3 13526.5 13783.7 13804.4 14078.6 13230.0 13702.8 13895.9 14021.2 14086.6 9150.3 9519.3 9523.5 9645.9 9800.3 8971.4 10581.0 10942.4 11612.7 11619.4 09:22:00 INFO - PROCESS | 1639 | -------- Summary: end -------- 09:22:00 INFO - PROCESS | 1639 | 09:22:01 INFO - TEST-INFO | 1639: exit 0 09:22:01 INFO - TEST-OK | dromaeo_css | took 1117290ms 09:22:01 INFO - TEST-START | kraken 09:22:01 INFO - operating with platform_type : mac_ 09:22:01 INFO - Initialising browser for kraken test... 09:22:01 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmpWnwZVT/profile http://localhost:49240/getInfo.html) 09:22:04 INFO - PROCESS | 1650 | 2017-04-13 09:22:04.158 plugin-container[1651:10228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 09:22:04 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:04 INFO - PROCESS | 1650 | 2017-04-13 09:22:04.160 plugin-container[1651:10228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 09:22:04 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:04 INFO - PROCESS | 1650 | 2017-04-13 09:22:04.160 plugin-container[1651:10228] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:22:04 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 09:22:04 INFO - PROCESS | 1650 | colorDepth:24 09:22:04 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 09:22:04 INFO - PROCESS | 1650 | __metrics 09:22:04 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 09:22:05 INFO - TEST-INFO | 1650: exit 0 09:22:05 INFO - Browser initialized. 09:22:05 INFO - Running cycle 1/1 for kraken test... 09:22:05 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmpWnwZVT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:07 INFO - PROCESS | 1652 | 2017-04-13 09:22:07.584 plugin-container[1653:10358] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:22:07 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:07 INFO - PROCESS | 1652 | 2017-04-13 09:22:07.585 plugin-container[1653:10358] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:22:07 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:07 INFO - PROCESS | 1652 | 2017-04-13 09:22:07.585 plugin-container[1653:10358] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:22:17 INFO - PROCESS | 1652 | RSS: Main: 220217344 09:22:17 INFO - PROCESS | 1652 | 09:22:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:21 INFO - PROCESS | 1652 | RSS: Main: 234921984 09:22:21 INFO - PROCESS | 1652 | 09:22:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 09:22:24 INFO - PROCESS | 1652 | RSS: Main: 242843648 09:22:24 INFO - PROCESS | 1652 | 09:22:27 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 09:22:28 INFO - PROCESS | 1652 | RSS: Main: 246591488 09:22:28 INFO - PROCESS | 1652 | 09:22:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 09:22:31 INFO - PROCESS | 1652 | RSS: Main: 247971840 09:22:31 INFO - PROCESS | 1652 | 09:22:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:33 INFO - PROCESS | 1652 | RSS: Main: 247967744 09:22:33 INFO - PROCESS | 1652 | 09:22:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 09:22:37 INFO - PROCESS | 1652 | RSS: Main: 252084224 09:22:37 INFO - PROCESS | 1652 | 09:22:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:41 INFO - PROCESS | 1652 | RSS: Main: 251834368 09:22:41 INFO - PROCESS | 1652 | 09:22:44 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 09:22:45 INFO - PROCESS | 1652 | RSS: Main: 252182528 09:22:45 INFO - PROCESS | 1652 | 09:22:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:47 INFO - PROCESS | 1652 | RSS: Main: 251260928 09:22:47 INFO - PROCESS | 1652 | 09:22:49 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:49 INFO - PROCESS | 1652 | RSS: Main: 251265024 09:22:49 INFO - PROCESS | 1652 | 09:22:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:52 INFO - PROCESS | 1652 | RSS: Main: 251330560 09:22:52 INFO - PROCESS | 1652 | 09:22:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:56 INFO - PROCESS | 1652 | RSS: Main: 251699200 09:22:56 INFO - PROCESS | 1652 | 09:22:59 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:23:00 INFO - PROCESS | 1652 | RSS: Main: 251473920 09:23:00 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 09:23:02 INFO - PROCESS | 1652 | RSS: Main: 253485056 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | __start_tp_report 09:23:02 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 09:23:02 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 09:23:02 INFO - PROCESS | 1652 | |i|pagename|runs| 09:23:02 INFO - PROCESS | 1652 | |0;ai-astar;108;120;122;121;121;121;124;126;168;121 09:23:02 INFO - PROCESS | 1652 | |1;audio-beat-detection;142;114;111;145;122;125;110;141;166;145 09:23:02 INFO - PROCESS | 1652 | |2;audio-dft;202;222;195;247;277;203;198;197;195;196 09:23:02 INFO - PROCESS | 1652 | |3;audio-fft;86;64;79;114;55;76;73;85;67;87 09:23:02 INFO - PROCESS | 1652 | |4;audio-oscillator;83;71;85;73;72;70;82;98;97;82 09:23:02 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;89;92;91;105;91;138;86;137;139;89 09:23:02 INFO - PROCESS | 1652 | |6;imaging-darkroom;97;98;96;98;98;96;116;155;104;112 09:23:02 INFO - PROCESS | 1652 | |7;imaging-desaturate;88;93;91;99;94;92;92;90;90;89 09:23:02 INFO - PROCESS | 1652 | |8;json-parse-financial;66;67;65;69;70;69;68;69;69;70 09:23:02 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;67;84;63;74;64;64;70;59;53;62 09:23:02 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;83;85;88;86;87;85;76;107;104;109 09:23:02 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;140;138;144;148;135;170;152;152;198;152 09:23:02 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;225;162;157;224;153;210;216;166;211;258 09:23:02 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;66;68;68;70;67;66;67;70;83;70 09:23:02 INFO - PROCESS | 1652 | __end_tp_report 09:23:02 INFO - PROCESS | 1652 | __start_cc_report 09:23:02 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1013 09:23:02 INFO - PROCESS | 1652 | __end_cc_report 09:23:02 INFO - PROCESS | 1652 | __startTimestamp1492100582370__endTimestamp 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | ------- Summary: start ------- 09:23:02 INFO - PROCESS | 1652 | Number of tests: 14 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:125.20 Median:121.50 stddev:15.77 (13.0%) stddev-sans-first:15.45 09:23:02 INFO - PROCESS | 1652 | Values: 108.0 120.0 122.0 121.0 121.0 121.0 124.0 126.0 168.0 121.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:141.50 stddev:18.48 (13.1%) stddev-sans-first:19.25 09:23:02 INFO - PROCESS | 1652 | Values: 142.0 114.0 111.0 145.0 122.0 125.0 110.0 141.0 166.0 145.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:213.20 Median:202.50 stddev:27.83 (13.7%) stddev-sans-first:29.22 09:23:02 INFO - PROCESS | 1652 | Values: 202.0 222.0 195.0 247.0 277.0 203.0 198.0 197.0 195.0 196.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:78.60 Median:77.50 stddev:16.20 (20.9%) stddev-sans-first:16.96 09:23:02 INFO - PROCESS | 1652 | Values: 86.0 64.0 79.0 114.0 55.0 76.0 73.0 85.0 67.0 87.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:81.30 Median:82.50 stddev:10.18 (12.3%) stddev-sans-first:10.78 09:23:02 INFO - PROCESS | 1652 | Values: 83.0 71.0 85.0 73.0 72.0 70.0 82.0 98.0 97.0 82.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:105.70 Median:89.00 stddev:22.85 (25.7%) stddev-sans-first:23.42 09:23:02 INFO - PROCESS | 1652 | Values: 89.0 92.0 91.0 105.0 91.0 138.0 86.0 137.0 139.0 89.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:107.00 Median:96.50 stddev:18.27 (18.9%) stddev-sans-first:19.02 09:23:02 INFO - PROCESS | 1652 | Values: 97.0 98.0 96.0 98.0 98.0 96.0 116.0 155.0 104.0 112.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:91.80 Median:92.00 stddev:3.12 (3.4%) stddev-sans-first:2.99 09:23:02 INFO - PROCESS | 1652 | Values: 88.0 93.0 91.0 99.0 94.0 92.0 92.0 90.0 90.0 89.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:68.20 Median:69.00 stddev:1.69 (2.4%) stddev-sans-first:1.59 09:23:02 INFO - PROCESS | 1652 | Values: 66.0 67.0 65.0 69.0 70.0 69.0 68.0 69.0 69.0 70.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:65.50 stddev:8.54 (13.0%) stddev-sans-first:9.05 09:23:02 INFO - PROCESS | 1652 | Values: 67.0 84.0 63.0 74.0 64.0 64.0 70.0 59.0 53.0 62.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:85.00 stddev:11.35 (13.4%) stddev-sans-first:11.67 09:23:02 INFO - PROCESS | 1652 | Values: 83.0 85.0 88.0 86.0 87.0 85.0 76.0 107.0 104.0 109.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:152.90 Median:152.00 stddev:18.68 (12.3%) stddev-sans-first:19.22 09:23:02 INFO - PROCESS | 1652 | Values: 140.0 138.0 144.0 148.0 135.0 170.0 152.0 152.0 198.0 152.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.20 Median:213.50 stddev:36.01 (16.9%) stddev-sans-first:36.86 09:23:02 INFO - PROCESS | 1652 | Values: 225.0 162.0 157.0 224.0 153.0 210.0 216.0 166.0 211.0 258.0 09:23:02 INFO - PROCESS | 1652 | 09:23:02 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.00 stddev:4.99 (7.2%) stddev-sans-first:5.13 09:23:02 INFO - PROCESS | 1652 | Values: 66.0 68.0 68.0 70.0 67.0 66.0 67.0 70.0 83.0 70.0 09:23:02 INFO - PROCESS | 1652 | -------- Summary: end -------- 09:23:02 INFO - PROCESS | 1652 | 09:23:03 INFO - TEST-INFO | 1652: exit 0 09:23:03 INFO - TEST-OK | kraken | took 62014ms 09:23:03 INFO - SUITE-END | took 1179s 09:23:03 INFO - Completed test suite (00:19:39) 09:23:03 INFO - javascript benchmark 09:23:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7737.599956647627, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2999.7300242978135, 3531.1172206948245, 3554.573451185858, 3571.6964058055064, 3586.193106551026, 6178.35127311632, 6197.907031394533, 6198.969005154968, 6218.782342618009, 6275.3410891856265, 6127.203463549732, 6165.722542485588, 6171.197744293236, 6195.2255968004, 6218.284897236819, 5560.4161563035905, 5593.8601534961745, 5597.552195824324, 5635.774569017234, 5831.037878750001, 4112.670986321102, 5648.858778530549, 5651.547876169917, 5653.123765816305, 5684.687342196177, 5374.167004114349, 5398.217258497515, 5460.180972854084, 5632.802851900185, 5642.4357564243455, 4582.793774280165, 5512.503874651301, 5523.696196709179, 5529.474699903523, 5532.806351777668, 5406.675599464044, 5541.5289700375515, 5598.0, 5641.097424412074, 5654.095344744821, 6221.844453888643, 6252.968735156295, 6262.248530176363, 6272.717727702218, 6274.435300822948, 4945.876353091202, 6293.685315734195, 6496.805095847085, 6497.870042599169, 6512.251091124534, 6369.20384951881, 6382.266039405435, 6382.6170429774365, 6438.903416448757, 6452.290248072708, 6243.344448832807, 6252.5935814171935, 6283.277423096311, 6291.905621415683, 6305.400986906328, 6777.593344399352, 6781.42357899584, 6788.796336109925, 6794.11676482064, 6795.93204067953, 6274.403931626488, 6333.303336632966, 6361.936380636134, 6399.168108146005, 6400.71196796136, 6468.6442245676, 6510.511711621647, 6523.7064332105165, 6532.902006469907, 6557.475401967831, 6474.9963755617955, 6510.511711621647, 6533.705983230766, 6547.345265473415, 6549.2468366137555, 5409.945060713123, 6508.251551071593, 6537.640429776313, 6554.70503827329, 6565.080888675589, 4971.950280497149, 5217.087009773274, 5249.947500525022, 5304.681719096867, 5313.149896016619, 3989.20215956804, 4677.742724150205, 4692.0, 4709.411323584552, 4731.503192164773, 6291.433770960635, 6310.558260921692, 6341.587796793194, 6354.872902541923, 6377.7767778127545, 5260.368755749335, 5470.91793623096, 6314.936850631435, 6509.414152726277, 6695.096162018162, 6265.8120256392385, 6348.746050158034, 6352.65060421672, 6355.555111142268, 6360.0777887206095, 5811.709414529257, 5825.86045771777, 5828.825135245949, 5839.678817664984, 5861.736221870026, 5663.773449061991, 6492.967535162294, 6529.804105876831, 6828.7268509259075, 6859.382655560924, 5396.757145928521, 5596.664200148004, 5611.270534830446, 6535.509836762167, 6587.901181482377, 6340.397662222082, 6803.9319606805275, 6808.046873437822, 6810.557313774589, 6821.727130914906, 6319.336469670802, 6431.614103153825, 6454.419102280817, 6458.806235812933, 6484.805455836333, 6349.0, 6393.680315984089, 6451.741930322734, 6457.4188323049275, 6497.220333560003, 6404.391582799627, 6443.355664433519, 6446.967765161145, 6449.580777249464, 6468.353164683494, 6279.654618995999, 6288.5598008138995, 6303.086052522267, 6334.081558174131, 6393.264774550986, 5103.744812759422, 5308.575313974796, 6083.361247069171, 6318.652474113968, 6370.649614271259, 6273.843153921189, 6287.2769631492965, 6398.168238129014, 6440.613563186036, 6462.063000864942, 6310.242770867341, 6311.684415779101, 6326.398992095744, 6338.683065846597, 6419.165508483868], "value": 5969.864846947507, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.1561042359592, 735.871222536056, 759.9620018999052, 826.6280173921733, 965.377331621104, 38567.03582410441, 39524.80237598812, 40966.180676386495, 41148.38277425841, 41475.58524414762, 39933.80198594047, 40142.59857401425, 40200.798996005055, 40205.19589608206, 40492.79753601228, 7869.52782833029, 8011.238932301422, 8645.827083458358, 10806.5677372905, 10838.72903177419, 10519.421431821247, 10737.818839927602, 10785.46072696366, 10792.676219713421, 10798.67603971882, 10794.46027698616, 10798.838017429745, 10829.512671929782, 10832.295900766465, 10844.566217351297, 10791.136709063256, 10801.891981080207, 10808.783824323547, 10819.13446924244, 10825.566977320897, 10527.526261318182, 10555.155587552978, 10558.577656893714, 10614.734631634194, 10615.681529554126, 34731.13172170691, 35115.29769404623, 35237.47143792845, 35263.1184220394, 35425.76009839669, 3710.2023065040876, 3776.376897811858, 3787.7159213058853, 3789.2989796887387, 3790.0714324990554, 25363.746362536323, 25454.87272563644, 25690.48619027609, 26802.5979610306, 27342.863285683645, 3044.4672182368, 3139.481985472394, 3164.68353164684, 3183.6657150999044, 3226.725728313096, 352055.0, 357906.0, 362581.0, 365860.0, 375634.0, 43437.0, 43520.56479435165, 43786.34320485195, 43843.342349864775, 43865.0, 45581.0, 45824.54175458203, 45858.77070614626, 45972.54027459749, 46727.0, 13508.054436189568, 13588.320583970763, 13601.04792664504, 13606.0, 13608.0, 11612.535498580131, 11814.94092529532, 11821.0, 11823.704407389883, 11831.881681183078, 12012.63962081139, 12087.39563021863, 12110.697232569257, 12156.5137394505, 12203.389830508439, 4801.135795556764, 5007.324011258506, 5501.587380946365, 5523.502884740393, 5695.60130790849, 762.3634265388351, 977.154761131616, 1000.7648202672366, 1019.0421004255976, 1056.0917610854658, 22794.31617051491, 27150.0, 28074.43851123007, 28210.43579128447, 28898.0, 961.6538046303325, 1116.6761639124725, 1123.253036730565, 1138.2658185470325, 1155.96532104037, 11866.940665296619, 14752.778708319383, 14809.408914439973, 14829.332680029425, 14973.850261497246, 324153.0, 394106.0, 395003.0, 395604.0, 396655.0, 54639.0, 54870.0, 55018.72490637521, 55083.0, 55178.0, 1454.5490897821498, 1458.48952866498, 1494.4769330734157, 1495.9925200374146, 1503.9172845493604, 20756.377308680763, 26027.04797140184, 26397.604035939094, 26421.867890660425, 28243.2939176514, 40512.58205962777, 41266.0, 45682.31476527921, 45938.77030614825, 45955.77022114868, 300116.0, 345518.0, 350575.49424505426, 354373.0, 354963.0, 25650.743492564834, 27636.861815691598, 27701.722982769916, 31876.36247275088, 32170.839145804122, 38924.80537597294, 39360.81917542478, 40058.0, 40230.0, 40609.1878162441, 28344.291392715346, 28385.0, 28477.145685629464, 28944.7105528942, 28993.565096523147, 28226.012089577587, 28624.57063144096, 28768.136955891358, 29556.408871822012, 29773.0, 27767.583486247317, 32326.83836580802, 33391.49912751359, 33482.162945926546, 33727.66272337245, 28131.0, 28553.0, 28628.85685571559, 28964.855175723987, 29431.0, 22844.428889277235, 23226.65160022635, 23587.528249435258, 23650.526989460457, 23739.52520949606], "value": 17916.501155693284, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.50004244333815, 404.7634476673762, 426.7949638194269, 426.8122026308425, 442.32766195383016, 19505.219791208354, 19708.916009619465, 20007.699884501744, 21000.0, 21108.366748997516, 21943.122275108977, 22003.78979156146, 22330.776692233074, 22744.0, 23036.53926921465, 6449.516286278536, 6629.701663425133, 6650.434713049397, 6678.732850685967, 6685.93314066858, 6561.704723287464, 6971.372576468142, 7289.890651640229, 7420.703171873119, 7454.403647708197, 7189.065421495225, 7377.7786666399825, 7385.778426647209, 7392.55644661322, 7407.0, 6313.589616674947, 7465.0, 7489.764188908824, 7490.3633191178815, 7544.170141284454, 7187.065681461377, 7270.672819723125, 7286.198518162998, 7305.89041164383, 7338.0, 19116.33092841744, 22885.88557057204, 24537.877310613334, 25097.62353564698, 25816.354591135183, 2409.7831195192343, 2797.0, 3020.4865172920654, 3227.160938156073, 3528.2943411317583, 15950.361985520565, 16017.279222435018, 16799.66400671992, 17175.312987480487, 17276.22256998438, 1902.6384986852454, 2021.7068525063783, 2024.6456870047682, 2025.6657651487487, 2032.4817171621141, 149028.0, 150279.24860375738, 154766.0, 155124.0, 156549.0, 20471.078801453972, 21132.89433552853, 21314.680279795815, 21457.248996285056, 21519.0, 32711.018669439956, 32938.0, 33007.50488742671, 33129.0, 33136.0, 8682.91317086821, 10373.0, 10458.477076146162, 10501.684949451528, 10637.414942178255, 8701.869471957925, 8707.6516939323, 8726.08376120511, 8834.381593288408, 8871.467711937305, 9568.760780980392, 9951.601935922483, 9995.200383969264, 10072.129443108679, 10134.847977280348, 3918.3534716771983, 3958.5447673517915, 4207.347861081537, 4260.595243451867, 4282.464691913511, 1072.6031368393744, 1082.7509672775147, 1085.6742977106994, 1088.8257878739364, 1132.3771925440974, 19525.316613918443, 22329.776702232768, 22664.0, 22898.427539311317, 23030.0, 1034.7826956339102, 1077.4074259157433, 1086.576235268246, 1120.7478317378689, 1152.3201311226417, 9708.0, 11486.253393529394, 11709.648710538697, 11719.472623731952, 11725.886040826108, 655034.0, 656737.0, 657341.0, 739872.0, 753638.0, 161759.0, 162963.0, 163693.0, 164948.0, 165364.17317913333, 1186.9109816763778, 1199.6281152842646, 1229.4959066782576, 1239.1573729863599, 1241.8385609870659, 19186.328478503186, 19876.801231988073, 19956.90021549883, 19988.90005549963, 20005.199792008156, 56955.86088278294, 64840.703185935075, 65461.0, 68602.0, 69089.30910690829, 89646.10353896377, 90102.0, 91587.08412916052, 92322.0, 92668.53665731628, 11690.298582085103, 11893.702657433634, 12224.0, 12254.693632659255, 12269.57056503018, 11867.287962722265, 13396.799048014092, 13442.327883605976, 13690.86309136896, 13780.931095344458, 11308.886911130783, 11314.321140731583, 11431.885681143081, 11635.127365447795, 11859.34773587461, 10446.320989135682, 10581.259311848406, 10627.521761520904, 10949.890501094887, 10950.0, 9629.518524073908, 9655.758606034624, 11710.0694875603, 11739.23694959825, 11955.880441195477, 11664.88335116672, 11806.881931180578, 12044.0, 12157.209781364183, 12248.203866748989, 11425.485853136463, 11503.252288601214, 11534.538618455168, 11671.941640291745, 11862.0], "value": 11856.840700408738, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.4282001559345, 1372.03957229939, 1374.312843578211, 1391.1931079973617, 1421.3888028147896, 8843.425177363475, 11156.16328775341, 11553.826692599618, 11716.882831171697, 11726.530938762438, 11123.221374503768, 11354.0, 11424.0, 11425.371604561746, 11492.425378731052, 2048.6107639548436, 2430.611102223649, 2479.0579579759756, 2483.292261705414, 2488.278399264211, 2333.3583264602203, 2400.8079353651665, 2431.8176136789725, 2433.9391515212083, 2434.6834911461483, 2214.025828635395, 2314.5255222679393, 2333.0900948630047, 2402.4714562796157, 2412.601920683085, 2349.1777877742825, 2418.3470462974988, 2421.225207933462, 2422.7819496245247, 2438.695163104603, 2136.9465763355884, 2327.6508523721577, 2349.330440824374, 2430.052279610953, 2434.7565243475688, 11225.943870280678, 11246.49390777417, 11318.094552435869, 11320.094392448667, 11324.03745681618, 5960.0, 6128.264608246995, 6130.111133885606, 6134.631922084644, 6151.72317245725, 3640.7451478396524, 3706.666400023984, 3755.962440375589, 3766.7363284569815, 3839.4816699745456, 1187.447836755904, 1201.4441298518439, 1243.241622610207, 1251.255502975728, 1252.5553428532921, 13747.725045499034, 14216.218108004055, 15962.600934976603, 16068.357265709357, 16256.756148657661, 4510.188166130128, 4699.671023028423, 4835.395575553056, 4855.8057677692495, 4888.65779395446, 11712.765744685059, 11811.114166437552, 11822.290662560274, 11844.230125042017, 11869.406529673483, 4924.261360795909, 5828.737706803204, 5889.705514724246, 5989.341172471025, 5990.191324171268, 5316.308879845596, 5342.332208473941, 5399.7300134993875, 5423.7830486780085, 5466.753996070186, 3864.5942176071107, 3994.980025099856, 4021.8391264349684, 4074.1647962167685, 4085.428040074392, 902.264654306738, 1073.63496411219, 1076.482160154179, 1083.6586475260267, 1094.7591529863416, 666.2804171494774, 807.3904202327323, 823.3372135430916, 841.3984001439001, 860.2429861721645, 6133.653812589489, 6847.965760171167, 6985.091938048021, 6987.231404545496, 7023.063937750309, 903.2322525852973, 935.0415823780544, 1000.9399436033862, 1005.2661557063385, 1081.7403823082561, 4184.602462766033, 4219.767912764827, 4269.655058656514, 4389.341598760211, 4449.354843547668, 13007.154534955387, 13466.596002119952, 13580.524681636096, 13603.047786655037, 13608.659783505294, 13267.469301227842, 13391.598252052454, 13415.798763018562, 13429.798553021712, 13549.187048777105, 10149.39103653783, 10281.12610428122, 10296.948515257225, 10354.585816567404, 10367.0, 6476.255230648536, 6653.600783952978, 6813.012113243452, 6837.027587306547, 6863.416609588241, 9770.902290977, 12666.366681666064, 12705.237685738884, 12811.87188128144, 12853.550125745554, 11086.390248536409, 11553.537858485566, 12007.339596322287, 13022.869771302167, 13108.344582771015, 9230.677195501392, 9437.19783818383, 9526.52367381642, 9533.761655958657, 9539.23686105096, 10383.94808025955, 10393.9480302598, 10457.42484163348, 10526.263161578616, 10541.31481453703, 7709.190534993745, 8965.327600429864, 8970.327225458248, 9000.909990900007, 9026.548672566476, 7383.926160738539, 8714.259287960595, 8910.643574256957, 8916.643334266557, 9006.324525660733, 7861.1745766693675, 8030.959845200737, 8035.236652518235, 8127.2279133482225, 8158.959205203936, 5817.5345972321265, 5929.584929055098, 6017.578769486269, 6098.420650038417, 6229.875402492015, 5846.824595262149, 6085.0, 6123.928312545376, 6175.320714721467, 6191.288001879841], "value": 5151.765853654333, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.1018315310642, 372.4729507746539, 374.4420812988647, 387.6220684832287, 394.64679112194585, 4611.930821037683, 4877.853664390074, 4938.25926111083, 5253.740891416689, 5405.297311349529, 4382.408374869399, 4991.0017996400775, 5202.8699282517955, 5314.219736388314, 5352.751844225425, 3628.9274214515694, 4068.8669724312335, 4419.867403977886, 4425.446819147606, 4481.887952801173, 3687.7461663034564, 3713.4801127842125, 4206.3059595166915, 4413.558644135593, 4460.264056430685, 3911.0, 4014.7591144531275, 4022.839086436539, 4109.116539943911, 4610.953890461086, 3712.9071773205615, 4014.3777714454304, 4056.492938382702, 4231.280682284019, 4341.652667786601, 3768.5289338832645, 3831.961680383188, 3925.5878132796215, 4072.6538244249273, 4091.1408604193157, 4618.953810461886, 4911.231956495659, 5017.2724954881705, 5174.715390653512, 5183.844484665466, 4529.297958816389, 4676.228422310349, 5069.0, 5083.440821509631, 5092.592592592584, 5230.34620672416, 5461.399246082928, 5605.887882242332, 5640.576956728261, 5680.31836179657, 3755.3052685253315, 4269.758376852146, 4476.0, 4504.909901801979, 4523.344115103291, 5507.586930980153, 5740.598158128891, 5760.724513817061, 5902.701405690741, 6091.142201628501, 3986.382110772835, 4167.437395951569, 4272.84633149048, 4596.126735920165, 4639.396878405786, 4292.635126014261, 4350.499692535399, 4670.416197975253, 4720.433547974197, 4734.28985652148, 3942.231264903374, 4788.49162513814, 4832.420109586872, 5041.369828771403, 5047.873803154877, 4808.35087263222, 5277.838875447395, 5666.886662266732, 5719.828405147769, 5726.398728133569, 1394.072941493895, 1434.168182454174, 1498.1535432480614, 1580.2888700085007, 1581.4227806850417, 1056.434807378049, 1161.8961986113056, 1182.7338848759132, 1206.457094307565, 1218.7446929662462, 4833.975830120898, 5336.813211537577, 5370.167624018283, 5391.487808658171, 5417.918731219035, 4872.0024789836, 5629.915551266734, 5926.970365148147, 5936.8126374725225, 5944.256967879015, 4857.514248575185, 4878.560824556765, 5106.4893510648635, 5124.436312005676, 5367.624266301399, 2670.6795184577586, 2707.6714025937677, 2722.3194201449637, 2842.4560018783486, 3072.9539056914164, 5426.0, 5568.6658800472105, 5617.146341707226, 5850.970745146247, 6176.536759743038, 5360.437154098841, 5497.340319161646, 5529.944700553024, 5785.363610002896, 5863.0, 6118.837420890106, 6133.26400831903, 6165.506759459143, 6181.567290289637, 6195.079525347157, 5164.509371609691, 5548.4174161712435, 5916.023856063874, 5961.0, 5983.940160598512, 4809.807607695653, 5278.76245568958, 5628.973569514889, 5770.961226979184, 5863.296404431496, 5117.718525480986, 5441.5374693151525, 6014.36849130835, 6017.578769486094, 6159.538034647329, 4546.7953942073345, 4566.566176213254, 4570.337301091389, 4572.268437050056, 4579.7252164870215, 5527.705599745733, 5865.765369385177, 5880.470757631833, 5923.881522369442, 5931.673757943355, 5308.2397153479205, 5353.143497040455, 5459.699716515471, 5678.3186017678145, 5698.31620205578, 4715.834945776881, 5143.871403214949, 5231.738413079254, 5422.5119739223655, 5694.117411801178, 4830.840598256323, 4991.481005890911, 5206.323177986837, 5548.334199896039, 5629.662220266797, 5204.687718736888, 5319.574434045345, 5400.459954004568, 5789.1605717171615, 5834.416558344132, 5244.243178535234, 5622.971885140548, 5642.363714522907, 5651.745671444713, 5687.094823234263], "value": 4294.417918530587, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.4744215559409, 706.4136766483819, 755.2900273742683, 770.4337312075627, 793.7102957420542, 15056.0, 15999.120048397333, 16049.0, 16216.0, 16349.0, 14701.338439770208, 15061.62345941352, 15103.773443398359, 15222.467213647524, 15292.617684557863, 930.1582068228264, 935.177044201102, 953.0421925964396, 960.6301573894066, 961.697065424389, 947.7393716727886, 955.4171955107379, 955.8852937647492, 956.5886668732443, 959.5681943125586, 909.2816674826879, 1003.5283416794096, 1015.8780946286456, 1018.1956254558927, 1022.8874823769379, 1014.1430491234895, 1014.523174108168, 1015.1472762879217, 1016.1972042086743, 1019.281406608343, 756.3230908337063, 757.3146302596159, 757.38651692129, 758.1508710244507, 760.7033257029763, 13831.0, 16581.0, 16803.91598042002, 16952.491425257263, 17041.06274154921, 11536.48085836131, 14123.293835308194, 14263.857361426357, 14379.78430323546, 14429.855701442957, 11079.169062320278, 11465.885341146564, 11504.539818407253, 12010.099242556844, 12211.93894030533, 4224.366345048236, 4426.468823741172, 4551.40831691878, 4678.1813182692895, 4689.718616882998, 106804.0, 107795.46102269439, 108481.99999999921, 109339.0, 109446.45276773645, 10642.680719578426, 10694.037536621587, 10707.518161682587, 10938.945305273422, 11169.553217871195, 21376.679349809765, 21487.46281342979, 21651.67522487164, 21694.674579881314, 21714.0, 6198.6280823150755, 6690.264070952191, 6968.268331825187, 7201.531900426456, 7564.621768911533, 4272.252355837778, 4393.450818647669, 4416.779161041935, 4443.622292105208, 4447.822087116544, 3393.423118069934, 3484.8257587120543, 3485.0938755923644, 3502.404591219499, 3510.508528805969, 658.8550518885837, 662.7481557008402, 668.3784080804899, 673.1316601583902, 676.2865177237962, 572.2389222334285, 656.2846497317851, 677.857649893518, 684.6850448793512, 688.3632639808258, 9917.950410247902, 10329.690109296733, 10359.119474844572, 10485.266031377883, 10545.314554554083, 553.5820455556029, 591.73371982608, 597.357840321656, 638.8977763557901, 696.5633232619454, 4336.696431249782, 4883.731394773322, 4980.12847751642, 5004.47453017428, 5095.108356037678, 89521.55239223762, 103756.0, 107387.0, 108360.0, 111236.0, 61844.0, 62770.74458510896, 75059.62470187723, 75678.24321756711, 97275.05449890962, 74988.62505687437, 76123.23876761162, 78482.0, 78940.60529697315, 80019.0, 10821.513031913426, 10861.45692715377, 11412.600558980395, 11433.59982400612, 11562.710932226762, 42479.0, 49586.00827983348, 49638.50361496483, 49928.0, 50156.0, 62709.86450677539, 65420.67289663521, 71134.0, 71449.64275178591, 73513.16217094401, 12655.557055503014, 12771.36143192818, 12997.935010325265, 13160.539381121998, 13268.668283292609, 14753.409863605764, 14948.0, 15382.692346153239, 15442.2278886053, 15506.689866202387, 13512.054156208971, 13733.931330343685, 14053.789193161907, 14262.42950281977, 14722.926385368004, 13090.280034598189, 13526.526571569948, 13783.724325513233, 13804.424840579903, 14078.648033799236, 13230.0, 13702.794458083335, 13895.930520347334, 14021.228832414316, 14086.647833804236, 9150.267978561833, 9519.309205944955, 9523.524243150234, 9645.886576307052, 9800.31397802147, 8971.416857904216, 10581.017466965717, 10942.398168100512, 11612.709682258012, 11619.360935148648], "value": 7648.063268629434, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 120.0, 122.0, 121.0, 121.0, 121.0, 124.0, 126.0, 168.0, 121.0], "value": 125.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 111.0, 145.0, 122.0, 125.0, 110.0, 141.0, 166.0, 145.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 222.0, 195.0, 247.0, 277.0, 203.0, 198.0, 197.0, 195.0, 196.0], "value": 213.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 64.0, 79.0, 114.0, 55.0, 76.0, 73.0, 85.0, 67.0, 87.0], "value": 78.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 85.0, 73.0, 72.0, 70.0, 82.0, 98.0, 97.0, 82.0], "value": 81.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 91.0, 105.0, 91.0, 138.0, 86.0, 137.0, 139.0, 89.0], "value": 105.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 96.0, 98.0, 98.0, 96.0, 116.0, 155.0, 104.0, 112.0], "value": 107.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 91.0, 99.0, 94.0, 92.0, 92.0, 90.0, 90.0, 89.0], "value": 91.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 69.0, 70.0, 69.0, 68.0, 69.0, 69.0, 70.0], "value": 68.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 84.0, 63.0, 74.0, 64.0, 64.0, 70.0, 59.0, 53.0, 62.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 88.0, 86.0, 87.0, 85.0, 76.0, 107.0, 104.0, 109.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 144.0, 148.0, 135.0, 170.0, 152.0, 152.0, 198.0, 152.0], "value": 152.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [225.0, 162.0, 157.0, 224.0, 153.0, 210.0, 216.0, 166.0, 211.0, 258.0], "value": 198.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 70.0, 67.0, 66.0, 67.0, 70.0, 83.0, 70.0], "value": 69.5, "unit": "score"}]}]} 09:23:03 INFO - Return code: 0 09:23:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:23:03 ERROR - # TBPL SUCCESS # 09:23:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:23:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:23:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:23:03 INFO - 2017-04-13 09:23:03.725 screenresolution[1657:10751] starting screenresolution argv=screenresolution get 09:23:03 INFO - 2017-04-13 09:23:03.728 screenresolution[1657:10751] Display 0: 1600x1200x32@60 09:23:03 INFO - 2017-04-13 09:23:03.734 screenresolution[1658:10754] starting screenresolution argv=screenresolution list 09:23:03 INFO - Available Modes on Display 0 09:23:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:23:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:23:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:23:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:23:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:23:03 INFO - Intel Iris: 09:23:03 INFO - Chipset Model: Intel Iris 09:23:03 INFO - Type: GPU 09:23:03 INFO - Bus: Built-In 09:23:03 INFO - VRAM (Dynamic, Max): 1536 MB 09:23:03 INFO - Vendor: Intel (0x8086) 09:23:03 INFO - Device ID: 0x0a2e 09:23:03 INFO - Revision ID: 0x0009 09:23:03 INFO - Displays: 09:23:03 INFO - Display: 09:23:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:23:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:23:03 INFO - Display Serial Number: 1600x1200 60 09:23:03 INFO - Main Display: Yes 09:23:03 INFO - Mirror: Off 09:23:03 INFO - Online: Yes 09:23:03 INFO - Rotation: Supported 09:23:03 INFO - Return code: 0 09:23:03 INFO - Running post-action listener: _resource_record_post_action 09:23:03 INFO - [mozharness: 2017-04-13 16:23:03.970525Z] Finished run-tests step (success) 09:23:03 INFO - Running post-run listener: _resource_record_post_run 09:23:04 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 37812736; Write bytes: 512692736; Read time: 240; Write time: 1615 09:23:04 INFO - TinderboxPrint: CPU usage
54.7% 09:23:04 INFO - TinderboxPrint: I/O read bytes / time
37,812,736 / 240 09:23:04 INFO - TinderboxPrint: I/O write bytes / time
512,692,736 / 1,615 09:23:04 INFO - TinderboxPrint: CPU idle
2,175.8 (45.3%) 09:23:04 INFO - TinderboxPrint: CPU system
964.4 (20.1%) 09:23:04 INFO - TinderboxPrint: CPU user
1,661.4 (34.6%) 09:23:04 INFO - TinderboxPrint: Swap in / out
246,046,720 / 0 09:23:04 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201812480; Write bytes: 201160704; Read time: 15567; Write time: 424 09:23:04 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 36809216; Write bytes: 301611520; Read time: 226; Write time: 1161 09:23:04 INFO - Running post-run listener: _upload_blobber_files 09:23:04 INFO - Blob upload gear active. 09:23:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:23:04 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 09:23:04 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'] 09:23:04 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 09:23:04 INFO - (blobuploader) - INFO - Open directory for files ... 09:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:23:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:23:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:06 INFO - (blobuploader) - INFO - Done attempting. 09:23:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:23:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:23:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:07 INFO - (blobuploader) - INFO - Done attempting. 09:23:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:23:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:23:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:08 INFO - (blobuploader) - INFO - Done attempting. 09:23:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:10 INFO - (blobuploader) - INFO - Done attempting. 09:23:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:10 INFO - Return code: 0 09:23:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c7c919d4f85ac58ef69548dff68c6f8e24aa705c8a12a246fc43c3aa13ef10f0e7c5b4bbebd11c42be5fe0862bc07903e36aec927a52d753e7cb4f91d26b7f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ec46ef58a2c7c2af122dba9586d00b087af302e98bc7a8a93247e3ce8a1b4b04e6f9fdf7ebb23f2a2a3ed6f507a33c8ec70af17cda17e8524f80f554d0e3917", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8016a42e16e39a45955072b2e21b7e87fee4f7f1af5edff50fed8ccd144bfc138c13d719825d77138265f8fabcaaec086f7a560260025685d737240346589d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9411975b45e62794d89e2c91f710d5852033e047a4e1cc10891d0d5aeaa711374d64090ba660bcfbcc8227649f8a27d398a9d1e2d302b0b84906179c5a57a798"} 09:23:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:10 INFO - Contents: 09:23:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c7c919d4f85ac58ef69548dff68c6f8e24aa705c8a12a246fc43c3aa13ef10f0e7c5b4bbebd11c42be5fe0862bc07903e36aec927a52d753e7cb4f91d26b7f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ec46ef58a2c7c2af122dba9586d00b087af302e98bc7a8a93247e3ce8a1b4b04e6f9fdf7ebb23f2a2a3ed6f507a33c8ec70af17cda17e8524f80f554d0e3917", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8016a42e16e39a45955072b2e21b7e87fee4f7f1af5edff50fed8ccd144bfc138c13d719825d77138265f8fabcaaec086f7a560260025685d737240346589d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9411975b45e62794d89e2c91f710d5852033e047a4e1cc10891d0d5aeaa711374d64090ba660bcfbcc8227649f8a27d398a9d1e2d302b0b84906179c5a57a798"} 09:23:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:10 INFO - Copying logs to upload dir... 09:23:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.487281 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-13 09:23:10.181084) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 09:23:10.184897) ========= 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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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/9c7c919d4f85ac58ef69548dff68c6f8e24aa705c8a12a246fc43c3aa13ef10f0e7c5b4bbebd11c42be5fe0862bc07903e36aec927a52d753e7cb4f91d26b7f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ec46ef58a2c7c2af122dba9586d00b087af302e98bc7a8a93247e3ce8a1b4b04e6f9fdf7ebb23f2a2a3ed6f507a33c8ec70af17cda17e8524f80f554d0e3917", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8016a42e16e39a45955072b2e21b7e87fee4f7f1af5edff50fed8ccd144bfc138c13d719825d77138265f8fabcaaec086f7a560260025685d737240346589d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9411975b45e62794d89e2c91f710d5852033e047a4e1cc10891d0d5aeaa711374d64090ba660bcfbcc8227649f8a27d398a9d1e2d302b0b84906179c5a57a798"} build_url:https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011086 build_url: 'https://queue.taskcluster.net/v1/task/J3NBfqGlSJag2z8c7h5Xdw/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/9c7c919d4f85ac58ef69548dff68c6f8e24aa705c8a12a246fc43c3aa13ef10f0e7c5b4bbebd11c42be5fe0862bc07903e36aec927a52d753e7cb4f91d26b7f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ec46ef58a2c7c2af122dba9586d00b087af302e98bc7a8a93247e3ce8a1b4b04e6f9fdf7ebb23f2a2a3ed6f507a33c8ec70af17cda17e8524f80f554d0e3917", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8016a42e16e39a45955072b2e21b7e87fee4f7f1af5edff50fed8ccd144bfc138c13d719825d77138265f8fabcaaec086f7a560260025685d737240346589d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9411975b45e62794d89e2c91f710d5852033e047a4e1cc10891d0d5aeaa711374d64090ba660bcfbcc8227649f8a27d398a9d1e2d302b0b84906179c5a57a798"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 09:23:10.220124) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:23:10.220460) ========= 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.4PTSLBhwc8/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.jg6HH3J1bO/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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.005194 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:23:10.269358) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 09:23:10.269719) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 09:23:10.270075) ========= ========= Total master_lag: 0.18 =========