builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0274 starttime: 1490802703.25 results: success (0) buildid: 20170329071901 builduid: 09c1e77f1ad74e2a87e3c5275690f682 revision: 6ea713ccc9abea93126423fefb855d0e051c95e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:43.248388) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:43.248845) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:43.249119) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:43.274915) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:43.275512) ========= 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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-29 08:51:43-- 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% 166M=0s 2017-03-29 08:51:43 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130482 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:43.430656) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:43.430988) ========= 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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.021180 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:43.479050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 08:51:43.479411) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6ea713ccc9abea93126423fefb855d0e051c95e2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6ea713ccc9abea93126423fefb855d0e051c95e2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TX82DTzFUt/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.q1ZDsVgeQR/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-29 08:51:43,572 truncating revision to first 12 chars 2017-03-29 08:51:43,572 Setting DEBUG logging. 2017-03-29 08:51:43,572 attempt 1/10 2017-03-29 08:51:43,572 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6ea713ccc9ab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-29 08:51:44,325 unpacking tar archive at: mozilla-central-6ea713ccc9ab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.021881 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 08:51:44.527458) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:44.528097) ========= script_repo_revision: 6ea713ccc9abea93126423fefb855d0e051c95e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:44.528776) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:44.529255) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 08:51:44.550870) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-29 08:51:44.551284) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TX82DTzFUt/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.q1ZDsVgeQR/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:51:44 INFO - MultiFileLogger online at 20170329 08:51:44 in /builds/slave/test 08:51:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:51:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:51:44 INFO - {'append_to_log': False, 08:51:44 INFO - 'base_work_dir': '/builds/slave/test', 08:51:44 INFO - 'blob_upload_branch': 'Firefox', 08:51:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:51:44 INFO - 'branch': 'Firefox', 08:51:44 INFO - 'buildbot_json_path': 'buildprops.json', 08:51:44 INFO - 'config_files': ('talos/mac_config.py',), 08:51:44 INFO - 'default_actions': ('clobber', 08:51:44 INFO - 'read-buildbot-config', 08:51:44 INFO - 'download-and-extract', 08:51:44 INFO - 'populate-webroot', 08:51:44 INFO - 'create-virtualenv', 08:51:44 INFO - 'install', 08:51:44 INFO - 'run-tests'), 08:51:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:51:44 INFO - 'download_minidump_stackwalk': True, 08:51:44 INFO - 'download_symbols': 'ondemand', 08:51:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:51:44 INFO - 'tooltool.py': '/tools/tooltool.py', 08:51:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:51:44 INFO - '/tools/misc-python/virtualenv.py')}, 08:51:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:51:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:51:44 INFO - 'gecko_profile': False, 08:51:44 INFO - 'gecko_profile_interval': 0, 08:51:44 INFO - 'installer_path': 'installer.exe', 08:51:44 INFO - 'log_level': 'info', 08:51:44 INFO - 'log_name': 'talos', 08:51:44 INFO - 'log_to_console': True, 08:51:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:51:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:51:44 INFO - 'opt_config_files': (), 08:51:44 INFO - 'pip_index': False, 08:51:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:51:44 INFO - 'cmd': ('bash', 08:51:44 INFO - '-c', 08:51:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:51:44 INFO - 'enabled': True, 08:51:44 INFO - 'halt_on_failure': False, 08:51:44 INFO - 'name': 'check_screen_resolution'},), 08:51:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:51:44 INFO - 'cmd': ('bash', 08:51:44 INFO - '-c', 08:51:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:51:44 INFO - 'enabled': True, 08:51:44 INFO - 'halt_on_failure': False, 08:51:44 INFO - 'name': 'check_screen_resolution'},), 08:51:44 INFO - 'run_cmd_checks_enabled': True, 08:51:44 INFO - 'suite': 'dromaeojs-e10s', 08:51:44 INFO - 'system_bits': '32', 08:51:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:51:44 INFO - 'title': 't-yosemite-r7-0274', 08:51:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:51:44 INFO - 'use_talos_json': True, 08:51:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:51:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:51:44 INFO - 'work_dir': 'build'} 08:51:44 INFO - [mozharness: 2017-03-29 15:51:44.760207Z] Running clobber step. 08:51:44 INFO - Running pre-action listener: _resource_record_pre_action 08:51:44 INFO - Running main action method: clobber 08:51:44 INFO - rmtree: /builds/slave/test/build 08:51:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:51:46 INFO - Running post-action listener: _resource_record_post_action 08:51:46 INFO - [mozharness: 2017-03-29 15:51:46.472954Z] Finished clobber step (success) 08:51:46 INFO - [mozharness: 2017-03-29 15:51:46.473087Z] Running read-buildbot-config step. 08:51:46 INFO - Running pre-action listener: _resource_record_pre_action 08:51:46 INFO - Running main action method: read_buildbot_config 08:51:46 INFO - Using buildbot properties: 08:51:46 INFO - { 08:51:46 INFO - "project": "", 08:51:46 INFO - "product": "firefox", 08:51:46 INFO - "script_repo_revision": "production", 08:51:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:51:46 INFO - "repository": "", 08:51:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:51:46 INFO - "buildid": "20170329071901", 08:51:46 INFO - "stage_platform": "macosx64", 08:51:46 INFO - "basedir": "/builds/slave/test", 08:51:46 INFO - "nightly_build": "True", 08:51:46 INFO - "buildnumber": 3, 08:51:46 INFO - "slavename": "t-yosemite-r7-0274", 08:51:46 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:51:46 INFO - "platform": "yosemite_r7", 08:51:46 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:51:46 INFO - "branch": "mozilla-central", 08:51:46 INFO - "repo_path": "mozilla-central", 08:51:46 INFO - "revision": "6ea713ccc9abea93126423fefb855d0e051c95e2", 08:51:46 INFO - "pgo_build": "False", 08:51:46 INFO - "builduid": "09c1e77f1ad74e2a87e3c5275690f682", 08:51:46 INFO - "slavebuilddir": "test" 08:51:46 INFO - } 08:51:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 08:51:46 INFO - Running post-action listener: _resource_record_post_action 08:51:46 INFO - [mozharness: 2017-03-29 15:51:46.477507Z] Finished read-buildbot-config step (success) 08:51:46 INFO - [mozharness: 2017-03-29 15:51:46.477669Z] Running download-and-extract step. 08:51:46 INFO - Running pre-action listener: _resource_record_pre_action 08:51:46 INFO - Running main action method: download_and_extract 08:51:46 INFO - mkdir: /builds/slave/test/build/tests 08:51: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')]} 08:51:46 INFO - trying https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:51:46 INFO - Downloading https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:51:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 08:51:47 INFO - Downloaded 1325 bytes. 08:51:47 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:51:47 INFO - Using the following test package requirements: 08:51:47 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:51:47 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:51:47 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:51:47 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:51:47 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:51:47 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:51:47 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:51:47 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:51:47 INFO - u'jsshell-mac.zip'], 08:51:47 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:51:47 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:51:47 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:51:47 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:51:47 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:51:47 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:51:47 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:51:47 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:51:47 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:51:47 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:51:47 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:51:47 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:51:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:51:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:51:47 INFO - Fetch https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:51:48 INFO - Expected file size: 52507316 08:51:48 INFO - Obtained file size: 52507316 08:52:05 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:52:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:52:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:52:05 INFO - Fetch https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:52:06 INFO - Expected file size: 52507316 08:52:06 INFO - Obtained file size: 52507316 08:52:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:52:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:52:22 INFO - Fetch https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:52:23 INFO - Expected file size: 14404171 08:52:23 INFO - Obtained file size: 14404171 08:52:24 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')]} 08:52:24 INFO - trying https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:52:24 INFO - Downloading https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:52:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 08:52:26 INFO - Downloaded 75653334 bytes. 08:52:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:52:26 INFO - mkdir: /builds/slave/test/properties 08:52:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:52:26 INFO - Writing to file /builds/slave/test/properties/build_url 08:52:26 INFO - Contents: 08:52:26 INFO - build_url:https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:52:27 INFO - Running post-action listener: _resource_record_post_action 08:52:27 INFO - Running post-action listener: set_extra_try_arguments 08:52:27 INFO - [mozharness: 2017-03-29 15:52:27.001729Z] Finished download-and-extract step (success) 08:52:27 INFO - [mozharness: 2017-03-29 15:52:27.001859Z] Running populate-webroot step. 08:52:27 INFO - Running pre-action listener: _resource_record_pre_action 08:52:27 INFO - Running main action method: populate_webroot 08:52:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:52:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:52:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:52:27 INFO - '--tppagecycles', 08:52:27 INFO - '7'], 08:52:27 INFO - 'tests': ['tsvgm']}}, 08:52:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:52:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:52:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:52:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:52:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:52:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:52:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:52:27 INFO - 'talos_options': ['--disable-e10s'], 08:52:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:52:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:52:27 INFO - 'talos_options': ['--disable-e10s'], 08:52:27 INFO - 'tests': ['damp', 'tps']}, 08:52:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:52:27 INFO - 'tests': ['damp', 'tps']}, 08:52:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:52:27 INFO - 'tests': ['dromaeo_dom']}, 08:52:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:52:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:52:27 INFO - 'tests': ['basic_compositor_video']}, 08:52:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:52:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:52:27 INFO - 'tests': ['a11yr', 08:52:27 INFO - 'ts_paint', 08:52:27 INFO - 'tpaint', 08:52:27 INFO - 'sessionrestore', 08:52:27 INFO - 'sessionrestore_no_auto_restore', 08:52:27 INFO - 'tabpaint']}, 08:52:27 INFO - 'other-e10s': {'tests': ['a11yr', 08:52:27 INFO - 'ts_paint', 08:52:27 INFO - 'tpaint', 08:52:27 INFO - 'sessionrestore', 08:52:27 INFO - 'sessionrestore_no_auto_restore', 08:52:27 INFO - 'tabpaint']}, 08:52:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:52:27 INFO - 'ts_paint', 08:52:27 INFO - 'tpaint', 08:52:27 INFO - 'sessionrestore', 08:52:27 INFO - 'sessionrestore_no_auto_restore', 08:52:27 INFO - 'tabpaint']}, 08:52:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:52:27 INFO - 'ts_paint', 08:52:27 INFO - 'tpaint', 08:52:27 INFO - 'sessionrestore', 08:52:27 INFO - 'sessionrestore_no_auto_restore', 08:52:27 INFO - 'tabpaint']}, 08:52:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:52:27 INFO - 'tests': ['a11yr', 08:52:27 INFO - 'ts_paint', 08:52:27 INFO - 'tpaint', 08:52:27 INFO - 'sessionrestore', 08:52:27 INFO - 'sessionrestore_no_auto_restore', 08:52:27 INFO - 'tabpaint']}, 08:52:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:52:27 INFO - 'tests': ['a11yr', 08:52:27 INFO - 'ts_paint', 08:52:27 INFO - 'tpaint', 08:52:27 INFO - 'sessionrestore', 08:52:27 INFO - 'sessionrestore_no_auto_restore', 08:52:27 INFO - 'tabpaint']}, 08:52:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:52:27 INFO - 'tests': ['tsvgx', 08:52:27 INFO - 'tsvgr_opacity', 08:52:27 INFO - 'tart', 08:52:27 INFO - 'tscrollx', 08:52:27 INFO - 'cart', 08:52:27 INFO - 'tsvg_static']}, 08:52:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:52:27 INFO - 'tsvgr_opacity', 08:52:27 INFO - 'tart', 08:52:27 INFO - 'tscrollx', 08:52:27 INFO - 'cart', 08:52:27 INFO - 'tsvg_static']}, 08:52:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:52:27 INFO - 'talos_options': ['--disable-e10s'], 08:52:27 INFO - 'tests': ['tp5o']}, 08:52:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:52:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:52:27 INFO - 'talos_options': ['--disable-e10s', 08:52:27 INFO - '--xperf_path', 08:52:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:27 INFO - 'tests': ['tp5n']}, 08:52:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:52:27 INFO - 'talos_options': ['--xperf_path', 08:52:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:27 INFO - 'tests': ['tp5n']}}, 08:52:27 INFO - 'talos.zip': {'path': '', 08:52:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:52:27 INFO - Running post-action listener: _resource_record_post_action 08:52:27 INFO - [mozharness: 2017-03-29 15:52:27.021048Z] Finished populate-webroot step (success) 08:52:27 INFO - [mozharness: 2017-03-29 15:52:27.021180Z] Running create-virtualenv step. 08:52:27 INFO - Running pre-action listener: _resource_record_pre_action 08:52:27 INFO - Running main action method: create_virtualenv 08:52:27 INFO - Creating virtualenv /builds/slave/test/build/venv 08:52:27 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 08:52:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:52:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:52:27 INFO - Using real prefix '/tools/python27' 08:52:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:52:27 INFO - Installing distribute.............................................................................................................................................................................................done. 08:52:30 INFO - Installing pip.................done. 08:52:30 INFO - Return code: 0 08:52:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:52:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:52:31 INFO - Reading from file tmpfile_stdout 08:52:31 INFO - Output received: 08:52:31 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) 08:52:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:31 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 0x10d4a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda4bd785d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d27ed30>, '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 0x10d1a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d273be8>, '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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:52:31 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 08:52:31 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 08:52:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TX82DTzFUt/Render', 08:52:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:31 INFO - 'HOME': '/Users/cltbld', 08:52:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:31 INFO - 'LOGNAME': 'cltbld', 08:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:31 INFO - 'MOZ_NO_REMOTE': '1', 08:52:31 INFO - 'NO_EM_RESTART': '1', 08:52:31 INFO - 'PAGER': '/bin/cat', 08:52:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:31 INFO - 'PWD': '/builds/slave/test', 08:52:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:31 INFO - 'SHELL': '/bin/bash', 08:52:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1ZDsVgeQR/Listeners', 08:52:31 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:52:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:31 INFO - 'USER': 'cltbld', 08:52:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:31 INFO - 'XPC_FLAGS': '0x0', 08:52:31 INFO - 'XPC_SERVICE_NAME': '0', 08:52:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:31 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 08:52:31 INFO - Cleaning up... 08:52:31 INFO - Return code: 0 08:52:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:31 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 0x10d4a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda4bd785d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d27ed30>, '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 0x10d1a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d273be8>, '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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:52:31 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 08:52:31 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 08:52:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TX82DTzFUt/Render', 08:52:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:31 INFO - 'HOME': '/Users/cltbld', 08:52:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:31 INFO - 'LOGNAME': 'cltbld', 08:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:31 INFO - 'MOZ_NO_REMOTE': '1', 08:52:31 INFO - 'NO_EM_RESTART': '1', 08:52:31 INFO - 'PAGER': '/bin/cat', 08:52:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:31 INFO - 'PWD': '/builds/slave/test', 08:52:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:31 INFO - 'SHELL': '/bin/bash', 08:52:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1ZDsVgeQR/Listeners', 08:52:31 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:52:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:31 INFO - 'USER': 'cltbld', 08:52:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:31 INFO - 'XPC_FLAGS': '0x0', 08:52:31 INFO - 'XPC_SERVICE_NAME': '0', 08:52:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:31 INFO - Downloading/unpacking psutil>=3.1.1 08:52:31 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 08:52:31 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 08:52:31 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 08:52:31 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 08:52:31 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 08:52:31 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 08:52:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:52:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:35 INFO - Installing collected packages: psutil 08:52:35 INFO - Running setup.py install for psutil 08:52:35 INFO - building 'psutil._psutil_osx' extension 08:52:35 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 08:52:35 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 08:52:35 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 08:52:35 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 08:52:35 INFO - building 'psutil._psutil_posix' extension 08:52:35 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 08:52:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:52:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:52:35 INFO - ^ 08:52:35 INFO - 1 warning generated. 08:52:35 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 08:52:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:35 INFO - Successfully installed psutil 08:52:35 INFO - Cleaning up... 08:52:36 INFO - Return code: 0 08:52:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:52:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda4bd785d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d27ed30>, '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 0x10d1a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d273be8>, '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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:52:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:52:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:52:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TX82DTzFUt/Render', 08:52:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:36 INFO - 'HOME': '/Users/cltbld', 08:52:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:36 INFO - 'LOGNAME': 'cltbld', 08:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:36 INFO - 'MOZ_NO_REMOTE': '1', 08:52:36 INFO - 'NO_EM_RESTART': '1', 08:52:36 INFO - 'PAGER': '/bin/cat', 08:52:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:36 INFO - 'PWD': '/builds/slave/test', 08:52:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:36 INFO - 'SHELL': '/bin/bash', 08:52:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1ZDsVgeQR/Listeners', 08:52:36 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:52:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:36 INFO - 'USER': 'cltbld', 08:52:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:36 INFO - 'XPC_FLAGS': '0x0', 08:52:36 INFO - 'XPC_SERVICE_NAME': '0', 08:52:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:52:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:52:38 INFO - Installing collected packages: mozsystemmonitor 08:52:38 INFO - Running setup.py install for mozsystemmonitor 08:52:39 INFO - Successfully installed mozsystemmonitor 08:52:39 INFO - Cleaning up... 08:52:39 INFO - Return code: 0 08:52:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:52:39 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')]} 08:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:39 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')]} 08:52:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:40 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 0x10d4a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda4bd785d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d27ed30>, '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 0x10d1a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d273be8>, '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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:52:40 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 08:52:40 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 08:52:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TX82DTzFUt/Render', 08:52:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:40 INFO - 'HOME': '/Users/cltbld', 08:52:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:40 INFO - 'LOGNAME': 'cltbld', 08:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:40 INFO - 'MOZ_NO_REMOTE': '1', 08:52:40 INFO - 'NO_EM_RESTART': '1', 08:52:40 INFO - 'PAGER': '/bin/cat', 08:52:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:40 INFO - 'PWD': '/builds/slave/test', 08:52:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:40 INFO - 'SHELL': '/bin/bash', 08:52:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1ZDsVgeQR/Listeners', 08:52:40 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:52:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:40 INFO - 'USER': 'cltbld', 08:52:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:40 INFO - 'XPC_FLAGS': '0x0', 08:52:40 INFO - 'XPC_SERVICE_NAME': '0', 08:52:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:40 INFO - Downloading/unpacking jsonschema==2.5.1 08:52:40 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 08:52:40 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 08:52:40 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 08:52:40 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 08:52:40 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 08:52:40 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 08:52:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:52:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:52:43 INFO - Downloading functools32-3.2.3-2.tar.gz 08:52:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:52:43 INFO - warning: no files found matching '*.txt' 08:52:43 INFO - no previously-included directories found matching 'build' 08:52:43 INFO - no previously-included directories found matching 'dist' 08:52:43 INFO - no previously-included directories found matching '.git*' 08:52:43 INFO - Installing collected packages: jsonschema, functools32 08:52:43 INFO - Running setup.py install for functools32 08:52:43 INFO - warning: no files found matching '*.txt' 08:52:43 INFO - no previously-included directories found matching 'build' 08:52:43 INFO - no previously-included directories found matching 'dist' 08:52:43 INFO - no previously-included directories found matching '.git*' 08:52:43 INFO - Successfully installed jsonschema functools32 08:52:43 INFO - Cleaning up... 08:52:43 INFO - Return code: 0 08:52:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:52:43 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')]} 08:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:43 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')]} 08:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:43 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 0x10d4a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda4bd785d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d27ed30>, '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 0x10d1a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d273be8>, '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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:52:43 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 08:52:43 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 08:52:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TX82DTzFUt/Render', 08:52:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:43 INFO - 'HOME': '/Users/cltbld', 08:52:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:43 INFO - 'LOGNAME': 'cltbld', 08:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:43 INFO - 'MOZ_NO_REMOTE': '1', 08:52:43 INFO - 'NO_EM_RESTART': '1', 08:52:43 INFO - 'PAGER': '/bin/cat', 08:52:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:43 INFO - 'PWD': '/builds/slave/test', 08:52:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:43 INFO - 'SHELL': '/bin/bash', 08:52:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1ZDsVgeQR/Listeners', 08:52:43 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:52:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:43 INFO - 'USER': 'cltbld', 08:52:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:43 INFO - 'XPC_FLAGS': '0x0', 08:52:43 INFO - 'XPC_SERVICE_NAME': '0', 08:52:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:52:44 INFO - Cleaning up... 08:52:44 INFO - Return code: 0 08:52:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:52:44 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')]} 08:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:44 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')]} 08:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:44 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 0x10d4a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda4bd785d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d27ed30>, '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 0x10d1a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d273be8>, '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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:52:44 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 08:52:44 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 08:52:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TX82DTzFUt/Render', 08:52:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:44 INFO - 'HOME': '/Users/cltbld', 08:52:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:44 INFO - 'LOGNAME': 'cltbld', 08:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:44 INFO - 'MOZ_NO_REMOTE': '1', 08:52:44 INFO - 'NO_EM_RESTART': '1', 08:52:44 INFO - 'PAGER': '/bin/cat', 08:52:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:44 INFO - 'PWD': '/builds/slave/test', 08:52:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:44 INFO - 'SHELL': '/bin/bash', 08:52:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1ZDsVgeQR/Listeners', 08:52:44 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:52:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:44 INFO - 'USER': 'cltbld', 08:52:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:44 INFO - 'XPC_FLAGS': '0x0', 08:52:44 INFO - 'XPC_SERVICE_NAME': '0', 08:52:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:44 INFO - Downloading/unpacking blobuploader==1.2.4 08:52:44 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 08:52:44 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 08:52:44 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 08:52:44 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 08:52:44 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 08:52:44 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 08:52:46 INFO - Downloading blobuploader-1.2.4.tar.gz 08:52:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:52:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:52:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:52:48 INFO - Downloading docopt-0.6.1.tar.gz 08:52:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:52:48 INFO - Installing collected packages: blobuploader, requests, docopt 08:52:48 INFO - Running setup.py install for blobuploader 08:52:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:52:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:52:48 INFO - Running setup.py install for requests 08:52:48 INFO - Running setup.py install for docopt 08:52:48 INFO - Successfully installed blobuploader requests docopt 08:52:48 INFO - Cleaning up... 08:52:49 INFO - Return code: 0 08:52:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52: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')]} 08:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52: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')]} 08:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:49 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 0x10d4a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda4bd785d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d27ed30>, '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 0x10d1a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d273be8>, '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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:52:49 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 08:52:49 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 08:52:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TX82DTzFUt/Render', 08:52:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:49 INFO - 'HOME': '/Users/cltbld', 08:52:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:49 INFO - 'LOGNAME': 'cltbld', 08:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:49 INFO - 'MOZ_NO_REMOTE': '1', 08:52:49 INFO - 'NO_EM_RESTART': '1', 08:52:49 INFO - 'PAGER': '/bin/cat', 08:52:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:49 INFO - 'PWD': '/builds/slave/test', 08:52:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:49 INFO - 'SHELL': '/bin/bash', 08:52:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1ZDsVgeQR/Listeners', 08:52:49 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:52:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:49 INFO - 'USER': 'cltbld', 08:52:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:49 INFO - 'XPC_FLAGS': '0x0', 08:52:49 INFO - 'XPC_SERVICE_NAME': '0', 08:52:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:52:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Bf124a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:52:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-iXwHtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:52:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-JKxAzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:52:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-1OWlWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:52:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ww8vf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:52:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-5jPhbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:52:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-b51jmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:52:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-smP8Oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:52:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ecaps0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:52:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-L7Bulj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:52:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-7hrF5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:52:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-EEFecC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:52:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Xgf1OV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:52:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-6xx71n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-B_rsiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:52:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-hKIjtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:52:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-7KBEyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:52:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:52:51 INFO - Running setup.py install for manifestparser 08:52:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:52:51 INFO - Running setup.py install for mozcrash 08:52:51 INFO - Running setup.py install for mozdebug 08:52:51 INFO - Running setup.py install for mozdevice 08:52:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:52:51 INFO - Running setup.py install for mozfile 08:52:51 INFO - Running setup.py install for mozhttpd 08:52:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:52:51 INFO - Running setup.py install for mozinfo 08:52:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:52:52 INFO - Running setup.py install for mozInstall 08:52:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:52:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:52:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:52:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:52:52 INFO - Running setup.py install for mozleak 08:52:52 INFO - Running setup.py install for mozlog 08:52:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:52:52 INFO - Running setup.py install for moznetwork 08:52:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:52:52 INFO - Running setup.py install for mozprocess 08:52:52 INFO - Running setup.py install for mozprofile 08:52:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:52:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:52:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:52:53 INFO - Running setup.py install for mozrunner 08:52:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:52:53 INFO - Running setup.py install for mozscreenshot 08:52:53 INFO - Running setup.py install for moztest 08:52:53 INFO - Running setup.py install for mozversion 08:52:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:52:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:52:53 INFO - Cleaning up... 08:52:53 INFO - Return code: 0 08:52:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52:53 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')]} 08:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:53 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')]} 08:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:53 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 0x10d4a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda4bd785d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d27ed30>, '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 0x10d1a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d273be8>, '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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:52:53 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 08:52:53 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 08:52:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TX82DTzFUt/Render', 08:52:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:53 INFO - 'HOME': '/Users/cltbld', 08:52:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:53 INFO - 'LOGNAME': 'cltbld', 08:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:53 INFO - 'MOZ_NO_REMOTE': '1', 08:52:53 INFO - 'NO_EM_RESTART': '1', 08:52:53 INFO - 'PAGER': '/bin/cat', 08:52:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:53 INFO - 'PWD': '/builds/slave/test', 08:52:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:53 INFO - 'SHELL': '/bin/bash', 08:52:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1ZDsVgeQR/Listeners', 08:52:53 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:52:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:53 INFO - 'USER': 'cltbld', 08:52:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:53 INFO - 'XPC_FLAGS': '0x0', 08:52:53 INFO - 'XPC_SERVICE_NAME': '0', 08:52:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:52:53 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-rROtoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:52:54 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)) 08:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:52:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-jC9amX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:52:54 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)) 08:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:52:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-29RwO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:52:54 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)) 08:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:52:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-5EtFJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:52:54 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)) 08:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:52:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-zpIQ1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:52:54 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)) 08:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:52:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-txp86f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:52:54 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)) 08:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:52:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-DGuA89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:52:54 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)) 08:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:52:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-H_z3bL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:52:54 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)) 08:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:52:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Y6SLfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:52:54 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)) 08:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:52:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-dC_7ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:52:54 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)) 08:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:52:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ySnKuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:52:55 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)) 08:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:52:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-S_ayLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:52:55 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)) 08:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:52:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Fn7zAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:52:55 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)) 08:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:52:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-UR6reA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:52:55 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)) 08:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-G9GCeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:55 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)) 08:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:52:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-D0IMDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:52:55 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)) 08:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:52:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-dBrCzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:52:55 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)) 08:52:55 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)) 08:52:55 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)) 08:52:55 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)) 08:52:55 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)) 08:52:55 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)) 08:52:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:52:55 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 08:52:55 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 08:52:55 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 08:52:55 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 08:52:55 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 08:52:55 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 08:52:58 INFO - Downloading blessings-1.6.tar.gz 08:52:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:52:58 INFO - Installing collected packages: blessings 08:52:58 INFO - Running setup.py install for blessings 08:52:58 INFO - Successfully installed blessings 08:52:58 INFO - Cleaning up... 08:52:58 INFO - Return code: 0 08:52:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:52:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:52:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:52:59 INFO - Reading from file tmpfile_stdout 08:52:59 INFO - Current package versions: 08:52:59 INFO - blessings == 1.6 08:52:59 INFO - blobuploader == 1.2.4 08:52:59 INFO - docopt == 0.6.1 08:52:59 INFO - functools32 == 3.2.3-2 08:52:59 INFO - jsonschema == 2.5.1 08:52:59 INFO - manifestparser == 1.1 08:52:59 INFO - mozInstall == 1.12 08:52:59 INFO - mozcrash == 1.0 08:52:59 INFO - mozdebug == 0.1 08:52:59 INFO - mozdevice == 0.49 08:52:59 INFO - mozfile == 1.2 08:52:59 INFO - mozhttpd == 0.7 08:52:59 INFO - mozinfo == 0.9 08:52:59 INFO - mozleak == 0.1 08:52:59 INFO - mozlog == 3.4 08:52:59 INFO - moznetwork == 0.27 08:52:59 INFO - mozprocess == 0.25 08:52:59 INFO - mozprofile == 0.28 08:52:59 INFO - mozrunner == 6.13 08:52:59 INFO - mozscreenshot == 0.1 08:52:59 INFO - mozsystemmonitor == 0.3 08:52:59 INFO - moztest == 0.8 08:52:59 INFO - mozversion == 1.4 08:52:59 INFO - psutil == 3.1.1 08:52:59 INFO - requests == 1.2.3 08:52:59 INFO - wsgiref == 0.1.2 08:52:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:59 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 0x10d4a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda4bd785d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d27ed30>, '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 0x10d1a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d273be8>, '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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:52:59 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 08:52:59 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 08:52:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TX82DTzFUt/Render', 08:52:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:59 INFO - 'HOME': '/Users/cltbld', 08:52:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:59 INFO - 'LOGNAME': 'cltbld', 08:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:59 INFO - 'MOZ_NO_REMOTE': '1', 08:52:59 INFO - 'NO_EM_RESTART': '1', 08:52:59 INFO - 'PAGER': '/bin/cat', 08:52:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:59 INFO - 'PWD': '/builds/slave/test', 08:52:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:59 INFO - 'SHELL': '/bin/bash', 08:52:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1ZDsVgeQR/Listeners', 08:52:59 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:52:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:59 INFO - 'USER': 'cltbld', 08:52:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:59 INFO - 'XPC_FLAGS': '0x0', 08:52:59 INFO - 'XPC_SERVICE_NAME': '0', 08:52:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:59 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)) 08:52:59 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)) 08:52:59 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)) 08:52:59 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)) 08:52:59 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)) 08:52:59 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)) 08:52:59 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)) 08:52:59 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)) 08:52:59 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)) 08:52:59 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)) 08:52:59 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)) 08:52:59 INFO - Cleaning up... 08:52:59 INFO - Return code: 0 08:52:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:59 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 0x10d4a0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda4bd785d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d27ed30>, '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 0x10d1a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d273be8>, '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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:52:59 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 08:52:59 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 08:52:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TX82DTzFUt/Render', 08:52:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:59 INFO - 'HOME': '/Users/cltbld', 08:52:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:59 INFO - 'LOGNAME': 'cltbld', 08:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:59 INFO - 'MOZ_NO_REMOTE': '1', 08:52:59 INFO - 'NO_EM_RESTART': '1', 08:52:59 INFO - 'PAGER': '/bin/cat', 08:52:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:59 INFO - 'PWD': '/builds/slave/test', 08:52:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:59 INFO - 'SHELL': '/bin/bash', 08:52:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1ZDsVgeQR/Listeners', 08:52:59 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:52:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:59 INFO - 'USER': 'cltbld', 08:52:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:59 INFO - 'XPC_FLAGS': '0x0', 08:52:59 INFO - 'XPC_SERVICE_NAME': '0', 08:52:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:52:59 INFO - Cleaning up... 08:52:59 INFO - Return code: 0 08:52:59 INFO - Running post-action listener: _resource_record_post_action 08:52:59 INFO - Running post-action listener: _start_resource_monitoring 08:52:59 INFO - Starting resource monitoring. 08:52:59 INFO - [mozharness: 2017-03-29 15:52:59.605438Z] Finished create-virtualenv step (success) 08:52:59 INFO - [mozharness: 2017-03-29 15:52:59.606400Z] Running install step. 08:52:59 INFO - Running pre-action listener: _resource_record_pre_action 08:52:59 INFO - Running main action method: install 08:52:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:52:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:52:59 INFO - Reading from file tmpfile_stdout 08:52:59 INFO - Detecting whether we're running mozinstall >=1.0... 08:52:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:52:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:52:59 INFO - Reading from file tmpfile_stdout 08:52:59 INFO - Output received: 08:52:59 INFO - Usage: mozinstall [options] installer 08:52:59 INFO - Options: 08:52:59 INFO - -h, --help show this help message and exit 08:52:59 INFO - -d DEST, --destination=DEST 08:52:59 INFO - Directory to install application into. [default: 08:52:59 INFO - "/builds/slave/test"] 08:52:59 INFO - --app=APP Application being installed. [default: firefox] 08:52:59 INFO - mkdir: /builds/slave/test/build/application 08:52:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:52:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:53:20 INFO - Reading from file tmpfile_stdout 08:53:20 INFO - Output received: 08:53:20 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 08:53:20 INFO - Running post-action listener: _resource_record_post_action 08:53:20 INFO - [mozharness: 2017-03-29 15:53:20.843072Z] Finished install step (success) 08:53:20 INFO - [mozharness: 2017-03-29 15:53:20.843210Z] Running run-tests step. 08:53:20 INFO - Running pre-action listener: _resource_record_pre_action 08:53:20 INFO - Running main action method: run_tests 08:53:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:53:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:53:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:53:20 INFO - 2017-03-29 08:53:20.858 screenresolution[1899:18102] starting screenresolution argv=screenresolution get 08:53:20 INFO - 2017-03-29 08:53:20.860 screenresolution[1899:18102] Display 0: 1600x1200x32@60 08:53:20 INFO - 2017-03-29 08:53:20.866 screenresolution[1900:18104] starting screenresolution argv=screenresolution list 08:53:20 INFO - Available Modes on Display 0 08:53:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:53:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:53:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:53:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:53:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:53:21 INFO - Intel Iris: 08:53:21 INFO - Chipset Model: Intel Iris 08:53:21 INFO - Type: GPU 08:53:21 INFO - Bus: Built-In 08:53:21 INFO - VRAM (Dynamic, Max): 1536 MB 08:53:21 INFO - Vendor: Intel (0x8086) 08:53:21 INFO - Device ID: 0x0a2e 08:53:21 INFO - Revision ID: 0x0009 08:53:21 INFO - Displays: 08:53:21 INFO - Display: 08:53:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:53:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:53:21 INFO - Display Serial Number: 1600x1200 60 08:53:21 INFO - Main Display: Yes 08:53:21 INFO - Mirror: Off 08:53:21 INFO - Online: Yes 08:53:21 INFO - Rotation: Supported 08:53:21 INFO - Return code: 0 08:53:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:53:21 INFO - Python 2.7.3 08:53:21 INFO - Return code: 0 08:53:21 INFO - grabbing minidump binary from tooltool 08:53:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:21 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 0x10d27ed30>, '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 0x10d1a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d273be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:53:21 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 08:53:21 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 08:53:21 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 08:53:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:53:21 INFO - Return code: 0 08:53:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:53:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:53:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:53:21 INFO - ENV: RUST_BACKTRACE is now 1 08:53:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:53:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:53:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 08:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0274 --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 08:53:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TX82DTzFUt/Render', 08:53:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:21 INFO - 'HOME': '/Users/cltbld', 08:53:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:21 INFO - 'LOGNAME': 'cltbld', 08:53:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:53:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:53:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:21 INFO - 'MOZ_NO_REMOTE': '1', 08:53:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:53:21 INFO - 'NO_EM_RESTART': '1', 08:53:21 INFO - 'PAGER': '/bin/cat', 08:53:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:21 INFO - 'PWD': '/builds/slave/test', 08:53:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:53:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:21 INFO - 'RUST_BACKTRACE': '1', 08:53:21 INFO - 'SHELL': '/bin/bash', 08:53:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1ZDsVgeQR/Listeners', 08:53:21 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:53:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:21 INFO - 'USER': 'cltbld', 08:53:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:21 INFO - 'XPC_FLAGS': '0x0', 08:53:21 INFO - 'XPC_SERVICE_NAME': '0', 08:53:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 08:53:21 INFO - mozversion application_buildid: 20170329071901 08:53:21 INFO - mozversion application_changeset: 6ea713ccc9abea93126423fefb855d0e051c95e2 08:53:21 INFO - mozversion application_display_name: Nightly 08:53:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:53:21 INFO - mozversion application_name: Firefox 08:53:21 INFO - mozversion application_remotingname: firefox 08:53:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:53:21 INFO - mozversion application_vendor: Mozilla 08:53:21 INFO - mozversion application_version: 55.0a1 08:53:21 INFO - mozversion platform_buildid: 20170329071901 08:53:21 INFO - mozversion platform_changeset: 6ea713ccc9abea93126423fefb855d0e051c95e2 08:53:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:53:21 INFO - mozversion platform_version: 55.0a1 08:53:21 INFO - using testdate: 1490802801 08:53:21 INFO - actual date: 1490802801 08:53:21 INFO - starting webserver on 'localhost:54221' 08:53:21 INFO - SUITE-START | Running 2 tests 08:53:21 INFO - TEST-START | dromaeo_css 08:53:21 INFO - operating with platform_type : mac_ 08:53:21 INFO - Initialising browser for dromaeo_css test... 08:53:21 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpQ7f2HL/profile http://localhost:54221/getInfo.html) 08:53:24 INFO - PID 1910 | 2017-03-29 08:53:24.407 plugin-container[1913:18279] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b53, name = 'com.apple.tsm.portname' 08:53:24 INFO - PID 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:24 INFO - PID 1910 | 2017-03-29 08:53:24.408 plugin-container[1913:18279] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9803, name = 'com.apple.CFPasteboardClient' 08:53:24 INFO - PID 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:24 INFO - PID 1910 | 2017-03-29 08:53:24.408 plugin-container[1913:18279] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:53:24 INFO - PID 1910 | __metrics Screen width/height:1600/1200 08:53:24 INFO - PID 1910 | colorDepth:24 08:53:24 INFO - PID 1910 | Browser inner width/height: 1024/689 08:53:24 INFO - PID 1910 | __metrics 08:53:24 ERROR - PID 1910 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 08:53:24 ERROR - PID 1910 | 1490802804595 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpQ7f2HL/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpQ7f2HL/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpQ7f2HL/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpQ7f2HL/profile/extensions/talos-powers@TalosPowersService.js:55:9 08:53:25 INFO - TEST-INFO | 1910: exit 0 08:53:25 INFO - Browser initialized. 08:53:25 INFO - Running cycle 1/1 for dromaeo_css test... 08:53:25 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpQ7f2HL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:53:27 INFO - PID 1914 | 2017-03-29 08:53:27.901 plugin-container[1915:18411] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 08:53:27 INFO - PID 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:27 INFO - PID 1914 | 2017-03-29 08:53:27.901 plugin-container[1915:18411] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 08:53:27 INFO - PID 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:27 INFO - PID 1914 | 2017-03-29 08:53:27.901 plugin-container[1915:18411] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:53:37 INFO - PID 1914 | RSS: Main: 230174720 08:53:37 INFO - PID 1914 | 08:56:42 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:54221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54221/tests/dromaeo/cssquery-ext.html) 08:56:43 INFO - PID 1914 | RSS: Main: 252456960 08:56:43 INFO - PID 1914 | 08:58:25 INFO - PID 1914 | ************************* 08:58:25 INFO - PID 1914 | A coding exception was thrown and uncaught in a Task. 08:58:25 INFO - PID 1914 | 08:58:25 ERROR - PID 1914 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:25 INFO - PID 1914 | Full stack: 08:58:25 INFO - PID 1914 | ************************* 08:58:25 INFO - PID 1914 | ************************* 08:58:25 INFO - PID 1914 | A coding exception was thrown and uncaught in a Task. 08:58:25 INFO - PID 1914 | 08:58:25 ERROR - PID 1914 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:25 INFO - PID 1914 | Full stack: 08:58:25 INFO - PID 1914 | ************************* 08:58:25 INFO - PID 1914 | ************************* 08:58:25 INFO - PID 1914 | A coding exception was thrown and uncaught in a Task. 08:58:25 INFO - PID 1914 | 08:58:25 ERROR - PID 1914 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:25 INFO - PID 1914 | Full stack: 08:58:25 INFO - PID 1914 | ************************* 08:58:25 INFO - PID 1914 | ************************* 08:58:25 INFO - PID 1914 | A coding exception was thrown and uncaught in a Task. 08:58:25 INFO - PID 1914 | 08:58:25 ERROR - PID 1914 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:25 INFO - PID 1914 | Full stack: 08:58:25 INFO - PID 1914 | ************************* 08:59:46 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:54221/tests/dromaeo/cssquery-ext.html (next: http://localhost:54221/tests/dromaeo/cssquery-jquery.html) 08:59:46 INFO - PID 1914 | RSS: Main: 260931584 08:59:46 INFO - PID 1914 | 09:02:48 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:54221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54221/tests/dromaeo/cssquery-mootools.html) 09:02:49 INFO - PID 1914 | RSS: Main: 271880192 09:02:49 INFO - PID 1914 | 09:05:51 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:54221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54221/tests/dromaeo/cssquery-prototype.html) 09:05:51 INFO - PID 1914 | RSS: Main: 265351168 09:05:51 INFO - PID 1914 | 09:08:54 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:54221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54221/tests/dromaeo/cssquery-yui.html) 09:08:55 INFO - PID 1914 | RSS: Main: 275558400 09:08:55 INFO - PID 1914 | 09:11:57 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:54221/tests/dromaeo/cssquery-yui.html (next: http://localhost:54221/tests/dromaeo/cssquery-dojo.html) 09:11:58 INFO - PID 1914 | RSS: Main: 269328384 09:11:58 INFO - PID 1914 | 09:11:58 INFO - PID 1914 | __start_tp_report 09:11:58 INFO - PID 1914 | _x_x_mozilla_page_load 09:11:58 INFO - PID 1914 | _x_x_mozilla_page_load_details 09:11:58 INFO - PID 1914 | |i|pagename|runs| 09:11:58 INFO - PID 1914 | |0;dojo.html;3565.857365705372;3568.4290513517844;3601.963980360199;3606.2787442511503;3627.564692236932;6163.938360616393;6282.183316168892;6296.685165741712;6320.3363646817115;6329.082283068952;6237.656928868911;6285.6857157142185;6290.874182516347;6340.968295158529;6406.487481001521;5758.280214973129;5773.480386765201;5883.7058147092475;5974.97012514939;6020.6688632125215;4594.977025114869;5833.591648584601;6004.489618382443;6019.247594050744;6062.302835173945;4217.8312867485265;5574.024545704526;5632.324121105453;5638.746256418471;5775.393583673716;5670.858228544278;5699.544036477072;5732.197492351032;5739.823336216107;5743.827685169452;5564.970480461127;5647.43525647436;5648.63283886546;5652.63257888236;5679.176519404705;6082.330943596201;6204.5967012144065;6223.657698826562;6248.843778905518;6302.212223472066;6728.293529179415;6771.085903403026;6787.932120678779;6802.251752307242;6813.216480104752;6895.689693963784;6902.413294869942;6903.068085808452;6909.723611055551;6922.930770692329;6260.655663938527;6296.212973378328;6349.873002539969;6360.491160707133;6361.681915904186;6834.419074378634;6984.3364880336285;7000.964995174992;7047.013418121467;7057.8235544110785;6403.263624683128;6459.354064593597;6474.1907261592305;6681.265060843303;6712.127423434922;6715;6727.899081513781;6861.245263021064;6862.759803406957;6886.621235831977;6798.694058767367;6799.558028728117;6807.489438292049;6825.215100263434;6830.316968303229;6523.347665233438;6621.238557565942;6675.966620166868;6738.225104112992;6789.762358317435;5019.7490125494305;5022.171341728647;5396.83809485716;5424.294841670637;5432.049391356497;4076.2458945095254;4805.687630304019;4826.034793041335;4826.131296366688;4826.782794774173;5788.652680839163;6054.66699331541;6278.623282603058;6338.8415289617205;6416.00551914454;6866.862662746817;6875.140607424072;6918.342757438036;6935.202451718016;6939.826504337331;6359.777407790798;6389.744410223632;6406.423421892052;6410.839729006719;6413.134226879405;5885.735141918624;5945.078512830518;5967.6419414835245;6065;6087.086936959491;5860.941390586039;6828.829279268058;6956.826079348057;7009.824754381181;7032.331928466788;6895.379415852597;6904.13698287714;6917.342852429011;6922.203946546211;6931.89602155958;6939.6877140529805;6973.721051157897;7046;7076.00935868989;7079.787606371817;6498.512611554058;6616.040674102324;6699.296573859675;6723.327667233238;6760.425363844129;5645.294338207724;6623.470122390101;6715.462762978852;6761.121054262914;6784.491163162881;6339.049142628458;6455.741770329134;6537.673116344259;6576.868462630816;6687.465002799861;6503.187101612299;6650.434713049445;6704.156357001669;6709.228438729413;6718.160229971254;6291.716872740646;6374.840628984312;6385.744570217139;6386.169797926425;6537.248216454978;5833.737481813413;6442.387973142544;6465.062565928009;6601.471882249504;6603.933960660332;6408.942524483408;6412.35876412355;6412.487001039999;6414.326495717883;6434.131392261985 09:11:58 INFO - PID 1914 | |1;ext.html;808.4421748993194;1258.6790368456047;1263.721981164144;1270.4029106320027;1293.5925183567176;26370.604440933377;39472.80263598685;39504.80247598761;39537.8023109884;39549.2090158197;39703.205935881335;39974.40038399426;39986.40020399689;40105.99735006626;40160.59839401605;8602.311815054796;8865.867011994826;8884.466931984069;8909.331800115016;8953.641854325819;8790.769292299052;8887;8892.466452012868;8895.021547629756;8903.198712115909;8871.467711937305;8873.20141187293;8874.068222836606;8880.8667869982;8883.733487995338;8803.867941980876;8805.779855503599;8831.735047948572;8834.955825220897;8846.115388461167;7710;8639.438436501607;8715.651373945035;8726.738197854074;8758.693445729368;36755.264894701955;38232.044198894975;38338.61661383379;38383.42424863629;38434.231315373814;2996.8951086712077;3019.4866872631706;3020.3808219315206;3039.711227433412;3048.9695103048907;27707.04601243781;27721.58417623737;28030.159095227176;28048.018319358725;28696.569551456745;2803.1310293808983;2826.279298778819;2891;2914.096630044693;2970.643522777259;250550;306735;308025;308651;309843;40544;47919.28121078187;48571.75714121477;48610.27084593734;48727;50377.74811125921;50749.49250507448;50755.74622126939;50899.74550127226;51010.469685909484;11829.763404731857;14211.43154273838;14221.004529682823;14253.50112746049;14254.216018119103;9095;10353.654024976704;11534.480948357344;11619.53521859133;11648;9880;13696.726065478635;13764.793528097087;13849.515266965607;13931.791023134661;5896;5914.54546363263;5929.14027466015;5936.168936348915;5941.821745347646;965.1699538396973;989.5497548615363;990.6037584966072;996.7807082441851;1033.7570670892335;24409.389765255655;24547;24675.506489870102;24730.876345618155;24741.5051698965;974.2985050763439;997.486294558303;1101.4547798839626;1126.4705588373452;1137.1641843245209;11883.940580297043;12522.373881305899;12533.56132535375;12547.874521254853;12791;445239;447683;448038.7598061989;448942;450302;55872.44127558754;56199.71900140473;56277;56380.71809641008;56424.43575564274;1461.291273732239;1482.5700546841294;1525.9008164469274;1526.8320484746669;1533.9079655220723;20262.797372026092;24111.75888241095;24133.034678612657;24301.27096187117;24371.878140609184;39561.01097472586;39571.80214098911;39685;39860.60139398685;39886.80339589817;415585;417727;420039;420367;421225;29970.700292997662;33522.832385837915;34066.82966585151;34682.13294667654;34708.13229669177;36563.90308290756;44031.779841101874;44073.338899917166;44420.55579444164;44442;29156.27109322284;29547.704522954496;29935.55096673508;30568.388632227674;30815.691843081284;23269.3019209424;29407.26481837971;29502.409951801274;30335;30519.250088715562;30091;30685.846570767895;36171.45742813807;36705.44941825928;36852.81573592115;29819.552706708982;30153.849230753705;30217.942372016874;30858.69141308558;30888;24065.639015415134;24533.754662453146;24706.309316071955;24712.13507527228;25891.705414729564 09:11:58 INFO - PID 1914 | |2;jquery.html;399.81808277233864;419.7796157017567;421.4652338751491;428.096715929389;437.58210908582305;19411.611767764636;21016.369508914737;21818.89090554545;21851;21923.451913702167;20511.97440127995;21758.564828703416;22206;23679;23704.17035403761;6129.877402451948;6401.1678481797535;6420.871582568346;6429.646369449679;6435.839104022413;5170.560502357304;5301.681899086047;6176.073588961647;6354.650494222816;6366.331535188808;6098.542609304298;6279.686015699197;6320.525960552954;6332.018537126753;6341.302456729756;6175.197224360827;6354.364563543655;6358.713857876407;6360.586561873487;6366.522510811707;5418.9458105418835;5597.916031259534;5638.633488823213;5647.435256474319;5659.207710920475;25961;26755.19734407971;26959.595606065926;26999.46001079987;27144.45711085787;2009.8995050247574;2016.5261163626535;2029.8173164415125;2038.4598081508411;2040.5306779440812;15910.363585456567;16106.677866442722;16463.34146634133;16678.749818752727;17057.658846822993;1672.3059930129104;1683.166832417961;1706.7866516685415;1708.841917237278;1711.2042900051536;145847;153996;156658;157091.51617240647;158605.82788344283;10075.546600402999;12284.385780710929;12468;12475.376231188586;12633.178843375335;16130.435434759962;16314.592135196715;16315.673686526441;16346.83653163477;16377;8754.168354006359;9072.773180670536;9073.41022833527;9215.17063464278;9220.815583688423;8652.702094685721;8662.30701543875;8664.566771661392;8838.425502342327;8852.424592401474;6524.314946930599;7765.505522514891;7871.33093687043;7882.935803666546;7973.681052757941;1980.722698822166;2133.232036466871;2139.2030694216933;2140.379290005912;2327.534493101353;930.8929473110544;943.8820147481565;950.7147855643279;987.6543209876486;996.1084829077935;19375.8062419374;20974.2659006934;21133.837638929697;22649.773502265096;23304.067837286657;803.325067234822;892.0187793427308;915.9862602060974;973.7292832853194;990.321629683669;9694.854577181348;10267.94866025665;10376;10601;10637.042666160083;537796;722060;724644;745033;749291;121793;139794.99999999796;153237;155261;159927.20036399743;1029.8609687692215;1159.4550561236206;1192.2548407245472;1236.116176933497;1245.6325384011739;18737.812621873607;21261.6810747842;21502.684968662426;21510.56978860383;21570.6764398531;38766.6123338763;63217.68391158015;63690.04464933123;64040.35959640344;64588;86836;86959.56520217359;88130;89372.6594101102;89645.55177224072;12560.937195313965;12573.622791316275;12752.617421477371;12889.548865789653;13021.609351719464;13623.523176688768;13682.042257041912;13884.86115138876;13963.30183490801;14018.859811401755;11582.305061696325;11691.707707307385;12051.698707532381;12080.45637946277;12148.757024859276;9006.684766033157;10578;10578.100861426865;10821.837672435076;11067.225294229327;10433.686989390331;11590.478428470571;11814.763704726029;11942.283462992249;11965.16243862956;12296.50813967467;12343.259404435763;12592.811107833573;12626.979683251004;12750.979921606435;10254.615626890274;11605.245659032136;11805.291682499077;11955.402229888297;12028.699282518008 09:11:58 INFO - PID 1914 | |3;mootools.html;1189.38746545529;1249.4814651919453;1382.1016339379405;1422.9644258893527;1452.8329242137152;8679.783005424859;10860.6741797746;10892.346459212453;11032.779344413117;11096.223264371498;10866.185036122302;10949.83575246372;11074.778504429927;11096.500657470413;11133.944330278337;2429.501952099824;2486.0677246032737;2486.7388924162974;2487.577111890974;2494.528228345276;2413.5293617744546;2417.782399584037;2459.7048354197523;2466.0135945621714;2468.7654672806057;2401.807855371566;2423;2437.2322718343667;2450.779429851313;2458.618914068313;2369.5260947810507;2412.107520217522;2431.9392015199583;2438.85366877987;2454.460018795862;2360.3509035015336;2374.299581623425;2394.353524548362;2399.6160614301803;2461.618449140386;11049.28179668319;11132.276402033842;11170.609028683957;11170.994610485095;11172.385518796464;6189.6286222826775;6431.3247109053345;6431.935680643181;6434.131392262078;6444.130042444257;3988.840446382141;3999.7200195986293;4110.486189226347;4118.567550407224;4157.646600039;1169.935653539055;1185.3954483213622;1198.3648666206925;1221.926684398939;1229.1703100407278;13659.658508537266;14105.788413173914;15011.924940375227;15165;15172.468963586221;4133.607307305801;5241.816536421207;5257.211418287211;5414.7021913795115;5436.945630543723;9388.483633400092;10468.162546996222;11398.544058237578;11437.485313160927;11447.713807154722;5746.913796293059;6284.057391391237;6284.245890493079;6287.717052732638;6322.178116844781;5703.230063941398;5813.331466881278;5913.822585322448;5964.314103878023;6023.096535519707;3729.291434627412;4112;4236.915261694749;4269.295566231537;4274.358846173099;1252.2861968677767;1255.6358655989843;1259.6913756129807;1264.646827894163;1276.5723482633236;735.7498450526847;747.7644541969262;765.6975494679571;766.0654002117408;797.6889013284823;5757.740901659436;5847.912281315784;5966.850828729316;6019.578629495893;6058.757649693963;553.5073784331947;568.0967262053355;581.9010768169421;583.4282403244844;585.912113183026;3666.7983260920373;4112.362583799516;4358.912821743547;4434.290513517886;4603.608693261043;13628.65928351799;14071.648208794657;14075.155490670388;14075.71848563023;14080.647983800487;13771.862281377258;13817.240051797247;13945.860541394659;13980.300984950914;14113.929430352782;9204.815903682023;10494.370337779757;10504.107150892107;10518.526666299882;10524.31591946521;5113.8721531962;5723.570732195185;6583.407493325623;6775.1531058617675;6796.558223715443;12327.568535101229;12752.553660621834;12758;12765.93617031909;12767.425465853874;10297.9485102574;12574.497020119094;12771;12875.678108047374;12946.546870859474;9150.40522366044;9171.037041110587;9328.113829186217;9339.439633622005;9376.624935002523;10133.847992279974;10307.278490505592;10309.22680798928;10364.119049880845;10387.740306492398;8440.15598440151;8777.517236551796;8796.208341249318;8829.779255518664;8858.64565417376;8527.957360213159;8559.486430814171;8597.18326758957;8599.82800343977;8723.214910657818;6935.202451718117;7628.961855190689;7699.846003080019;7781.144074151952;7817.179196184319;5381.838544843661;5752.884942301214;5866.120081987736;5949.434803693643;6012.458878700938;5499.120140777616;5801.1878337032;5965.9105113422465;6067.120267561268;6122.6938653068055 09:11:58 INFO - PID 1914 | |4;prototype.html;358.8941262327613;372.18862878923954;372.21648430054734;382.44163521258963;394.82824971137575;4842.975785121074;6129.203203583539;6336;6353.968230158848;6386.393292637198;6025.397460253972;6107.633541987473;6168.75324987;6181.196444462214;6337.429631333179;4303.5696430356875;4407.140607581523;4641.535846415365;4644.907101857961;4668.852327929157;4167.747082876231;4561.677113637042;4620;4621.4223222097235;4638.489766125724;4553.635709143261;4565.634749220055;4578.725276483405;4585.977070114644;4588.073009336087;4218.282891908352;4581.427321584802;4594.632429405656;4595.241785105453;4621.306803979396;3195.568598239231;3274.7052765251005;3450.7756995795194;3854.7879866607327;3970.344893092636;5995.61028533144;6013.857367100237;6033.1553582498045;6065.4541091302;6079.756809727605;4887.413510378778;5334.599905007101;5406.324209473816;5410.134378499461;5422.8373148805595;6302.432781049682;6370.458511026568;6574.671266436707;6643.5017373697165;6668.399844014062;4379.781010949439;4394.8461803837035;4399.142167277382;4419.977900110511;4432.290833466662;6215.937840621581;6656.7684978278685;6763.289854565298;6938.965305173492;6943.548669336477;3617.1318883468302;4288.52826189119;4468.620167285817;4471.932921006188;4495.550444955478;4195.538490766014;4209.97895010523;4514.413126293626;4572.108438854391;4587.082583483317;5080.49195080489;5273.499017593323;5382.192671099288;5415.806598327684;5451.727413629303;5624.943750562524;6177.826213019512;6610.983649986704;6681.832954176088;6683.7660681875905;1349.2916218984915;1550.4573399310152;1558.4233833481458;1559.9844001560066;1564;990.3958585262984;1012.7771890184148;1013.7566983923805;1019.8011387779312;1026.2918586175515;5291.947080529222;5713.457366511018;6015.232863029092;6229.626222426669;6314.147590075281;5831.979403604352;6648.401643852077;6931.826704332432;6949.756758513528;6972.442204623618;5228.712420816892;6007.609505382136;6149.877002459926;6290.591111577733;6310.652914089677;2820.4218135282217;3234.498652708834;3596.9280614387567;3635.182084031072;3660.1215708230366;6818.28408017151;7042.837931741307;7119.750808721774;7139.464540159405;7290.4167666586545;5802.709864506758;6206.968965155235;7042.330978557029;7171.641417929082;7223.89164162538;6425.421712045938;6734.292899245705;6765.391114799593;7035;7105.431565474646;6920.1938933587335;6926.549774264657;6938.895916561357;6952.165740111219;6987;5569.777208911761;6508.60948343101;6598.472122230306;6732.528722989344;6904.585724856524;5590.524805391425;6965.547239429421;6967.581945083109;7138.607376594337;7269.709211631687;4481.596656300949;4495.516479561802;4500.144972455354;4509.481409637933;4511.323301504801;5773.018586840163;6724.957631567115;6858.519903606899;6950.895736563854;6958.756443524452;6118.938810612015;6698.39714425704;6712.026756120237;6723.697433615597;6736.8652626946205;5518.889622207614;5836.037053886231;6009.939900600938;6390.169277993831;6696.966515167393;5430.375506816767;6556.377144171296;6556.934430655632;6563.803085907431;6566.343365663395;6275.466585340298;6701.396874281338;6707.698153583198;6726.192856857208;6731.158605174353;6404.199475065617;6493.707783149675;6496.297110646886;6506.804795856132;6507.707153178213 09:11:58 INFO - PID 1914 | |5;yui.html;698.3435570563673;731.6561216228371;736.7458226911717;737.494816051005;746.8020974441774;15184.999999999985;16132.516024519284;16175.43385981491;16198.190090495473;16255.187240637964;14600.123992560453;14926.253687315619;15063.62340941477;15227.39090436384;15277.312520936584;965.4255717847886;983.4099540275813;989.1789814453986;990.5195979949755;991.5984026469256;921.5254144115786;960.0735290444719;978.403174063824;979.7060881735487;980.1081016275191;1047.2041248651042;1057.259918057359;1061.872575290966;1064.3454275620477;1064.7710742190427;1044.9059584637382;1055.973600659982;1057.8783439904432;1059.5179193466993;1060.8939106089406;772.9033870766154;780.0444455541973;786.4101923557332;787.1852632525308;788.1330536409961;16135.515934521984;16473.176341182894;16596.50210493687;16616.25226864794;16936.322547098225;14583.781243281359;14794.038387504777;14838.406463741438;14880.032797868103;15385.692286154326;11338.319700817978;11360.602378916697;11795;11807.055435565135;12131;2944.926376840575;2960.9111726648234;3006.353633968707;3031.696830316973;3099.7830151889375;81695;95660;108630.3705444411;109278.45360773224;110854.31941345273;10222.846657300146;11096.500657470433;11316.830247546293;11453.484593193327;11758;24739.87630061874;24794.256172314497;25048;25102;25151.496970060864;7713.730019449293;7778.338841198562;7854.410919181084;7879.33025692811;8010.038795344596;4069.7558146511305;4161.771102589387;4167.458230430086;4167.91664166715;4176.248275310473;4043.353063509883;4071.1654110907193;4113.8971525711495;4156.750594964312;4174.352975288835;734.2400615363074;734.5115498193749;786.9055713314439;789.6841263494648;789.940754443419;565.7934853778341;566.8809549994547;578.2020811280406;586.8591538030927;590.7164561010738;8270.214329638675;8387.52303786908;9772.804543909224;9919.851202231972;10173.694789156338;511.47852979659984;536.8979893820161;570.3042288408135;583.2126629050832;593.8426317025992;4379.934300985487;4597;4639.141758774637;4641.2109941310055;4705.717656940595;85871.14128858631;95722.52138739261;99412;101177.49411252896;103530;88356;88640;90393.54803226073;90618.54690726504;91674.54162729277;65792.6710366445;71506.64246678734;72543;72663;73382.53234935271;9062.048484909254;9194.632214711337;9251.537423128682;9313.662093733501;9356.953215234153;38532.614673852906;38922.22155556816;39646.80176599098;39654.81035568938;39950;52666.73666631642;54816.72591637016;67197;67919;68412.31587684258;10775.838362424412;13678.863211368158;13904.930475347559;13913.373898174808;14083.647908802363;12403.441845116811;14723.558293251219;14821.555353339418;15179;15310.387584496495;13236;13388.330583470593;13824.93087534596;14350.928245358706;14374.496892608708;13550.32248387557;13893.919663927483;13981.860181398462;14242.359093841009;14435.3504092314;13610.455581777014;13740.10689305192;13870.514531991332;14178.432862685377;14188.290585470892;8764.255038321558;9795.43685903617;9803.803923921625;9941.105300522988;10281;9022;9216.294985427718;9531.857022144535;11281.115315752982;11311.038561722016 09:11:58 INFO - PID 1914 | __end_tp_report 09:11:58 INFO - PID 1914 | __start_cc_report 09:11:58 INFO - PID 1914 | _x_x_mozilla_cycle_collect,435 09:11:58 INFO - PID 1914 | __end_cc_report 09:11:58 INFO - PID 1914 | __startTimestamp1490803918153__endTimestamp 09:11:58 INFO - PID 1914 | 09:11:58 INFO - PID 1914 | ------- Summary: start ------- 09:11:58 INFO - PID 1914 | Number of tests: 6 09:11:58 INFO - PID 1914 | 09:11:58 INFO - PID 1914 | [#0] dojo.html Cycles:165 Average:6247.27 Median:6410.84 stddev:746.99 (11.7%) stddev-sans-first:719.05 09:11:58 INFO - PID 1914 | Values: 3565.9 3568.4 3602.0 3606.3 3627.6 6163.9 6282.2 6296.7 6320.3 6329.1 6237.7 6285.7 6290.9 6341.0 6406.5 5758.3 5773.5 5883.7 5975.0 6020.7 4595.0 5833.6 6004.5 6019.2 6062.3 4217.8 5574.0 5632.3 5638.7 5775.4 5670.9 5699.5 5732.2 5739.8 5743.8 5565.0 5647.4 5648.6 5652.6 5679.2 6082.3 6204.6 6223.7 6248.8 6302.2 6728.3 6771.1 6787.9 6802.3 6813.2 6895.7 6902.4 6903.1 6909.7 6922.9 6260.7 6296.2 6349.9 6360.5 6361.7 6834.4 6984.3 7001.0 7047.0 7057.8 6403.3 6459.4 6474.2 6681.3 6712.1 6715.0 6727.9 6861.2 6862.8 6886.6 6798.7 6799.6 6807.5 6825.2 6830.3 6523.3 6621.2 6676.0 6738.2 6789.8 5019.7 5022.2 5396.8 5424.3 5432.0 4076.2 4805.7 4826.0 4826.1 4826.8 5788.7 6054.7 6278.6 6338.8 6416.0 6866.9 6875.1 6918.3 6935.2 6939.8 6359.8 6389.7 6406.4 6410.8 6413.1 5885.7 5945.1 5967.6 6065.0 6087.1 5860.9 6828.8 6956.8 7009.8 7032.3 6895.4 6904.1 6917.3 6922.2 6931.9 6939.7 6973.7 7046.0 7076.0 7079.8 6498.5 6616.0 6699.3 6723.3 6760.4 5645.3 6623.5 6715.5 6761.1 6784.5 6339.0 6455.7 6537.7 6576.9 6687.5 6503.2 6650.4 6704.2 6709.2 6718.2 6291.7 6374.8 6385.7 6386.2 6537.2 5833.7 6442.4 6465.1 6601.5 6603.9 6408.9 6412.4 6412.5 6414.3 6434.1 09:11:58 INFO - PID 1914 | 09:11:58 INFO - PID 1914 | [#1] ext.html Cycles:180 Average:52303.76 Median:36634.68 stddev:104478.77 (285.2%) stddev-sans-first:104700.33 09:11:58 INFO - PID 1914 | Values: 808.4 1258.7 1263.7 1270.4 1293.6 26370.6 39472.8 39504.8 39537.8 39549.2 39703.2 39974.4 39986.4 40106.0 40160.6 8602.3 8865.9 8884.5 8909.3 8953.6 8790.8 8887.0 8892.5 8895.0 8903.2 8871.5 8873.2 8874.1 8880.9 8883.7 8803.9 8805.8 8831.7 8835.0 8846.1 7710.0 8639.4 8715.7 8726.7 8758.7 36755.3 38232.0 38338.6 38383.4 38434.2 2996.9 3019.5 3020.4 3039.7 3049.0 27707.0 27721.6 28030.2 28048.0 28696.6 2803.1 2826.3 2891.0 2914.1 2970.6 250550.0 306735.0 308025.0 308651.0 309843.0 40544.0 47919.3 48571.8 48610.3 48727.0 50377.7 50749.5 50755.7 50899.7 51010.5 11829.8 14211.4 14221.0 14253.5 14254.2 9095.0 10353.7 11534.5 11619.5 11648.0 9880.0 13696.7 13764.8 13849.5 13931.8 5896.0 5914.5 5929.1 5936.2 5941.8 965.2 989.5 990.6 996.8 1033.8 24409.4 24547.0 24675.5 24730.9 24741.5 974.3 997.5 1101.5 1126.5 1137.2 11883.9 12522.4 12533.6 12547.9 12791.0 445239.0 447683.0 448038.8 448942.0 450302.0 55872.4 56199.7 56277.0 56380.7 56424.4 1461.3 1482.6 1525.9 1526.8 1533.9 20262.8 24111.8 24133.0 24301.3 24371.9 39561.0 39571.8 39685.0 39860.6 39886.8 415585.0 417727.0 420039.0 420367.0 421225.0 29970.7 33522.8 34066.8 34682.1 34708.1 36563.9 44031.8 44073.3 44420.6 44442.0 29156.3 29547.7 29935.6 30568.4 30815.7 23269.3 29407.3 29502.4 30335.0 30519.3 30091.0 30685.8 36171.5 36705.4 36852.8 29819.6 30153.8 30217.9 30858.7 30888.0 24065.6 24533.8 24706.3 24712.1 25891.7 09:11:58 INFO - PID 1914 | 09:11:58 INFO - PID 1914 | [#2] jquery.html Cycles:180 Average:40575.87 Median:21506.63 stddev:117314.27 (545.5%) stddev-sans-first:117604.58 09:11:58 INFO - PID 1914 | Values: 399.8 419.8 421.5 428.1 437.6 19411.6 21016.4 21818.9 21851.0 21923.5 20512.0 21758.6 22206.0 23679.0 23704.2 6129.9 6401.2 6420.9 6429.6 6435.8 5170.6 5301.7 6176.1 6354.7 6366.3 6098.5 6279.7 6320.5 6332.0 6341.3 6175.2 6354.4 6358.7 6360.6 6366.5 5418.9 5597.9 5638.6 5647.4 5659.2 25961.0 26755.2 26959.6 26999.5 27144.5 2009.9 2016.5 2029.8 2038.5 2040.5 15910.4 16106.7 16463.3 16678.7 17057.7 1672.3 1683.2 1706.8 1708.8 1711.2 145847.0 153996.0 156658.0 157091.5 158605.8 10075.5 12284.4 12468.0 12475.4 12633.2 16130.4 16314.6 16315.7 16346.8 16377.0 8754.2 9072.8 9073.4 9215.2 9220.8 8652.7 8662.3 8664.6 8838.4 8852.4 6524.3 7765.5 7871.3 7882.9 7973.7 1980.7 2133.2 2139.2 2140.4 2327.5 930.9 943.9 950.7 987.7 996.1 19375.8 20974.3 21133.8 22649.8 23304.1 803.3 892.0 916.0 973.7 990.3 9694.9 10267.9 10376.0 10601.0 10637.0 537796.0 722060.0 724644.0 745033.0 749291.0 121793.0 139795.0 153237.0 155261.0 159927.2 1029.9 1159.5 1192.3 1236.1 1245.6 18737.8 21261.7 21502.7 21510.6 21570.7 38766.6 63217.7 63690.0 64040.4 64588.0 86836.0 86959.6 88130.0 89372.7 89645.6 12560.9 12573.6 12752.6 12889.5 13021.6 13623.5 13682.0 13884.9 13963.3 14018.9 11582.3 11691.7 12051.7 12080.5 12148.8 9006.7 10578.0 10578.1 10821.8 11067.2 10433.7 11590.5 11814.8 11942.3 11965.2 12296.5 12343.3 12592.8 12627.0 12751.0 10254.6 11605.2 11805.3 11955.4 12028.7 09:11:58 INFO - PID 1914 | 09:11:58 INFO - PID 1914 | [#3] mootools.html Cycles:180 Average:6746.04 Median:2491.05 stddev:4188.05 (168.1%) stddev-sans-first:4178.98 09:11:58 INFO - PID 1914 | Values: 1189.4 1249.5 1382.1 1423.0 1452.8 8679.8 10860.7 10892.3 11032.8 11096.2 10866.2 10949.8 11074.8 11096.5 11133.9 2429.5 2486.1 2486.7 2487.6 2494.5 2413.5 2417.8 2459.7 2466.0 2468.8 2401.8 2423.0 2437.2 2450.8 2458.6 2369.5 2412.1 2431.9 2438.9 2454.5 2360.4 2374.3 2394.4 2399.6 2461.6 11049.3 11132.3 11170.6 11171.0 11172.4 6189.6 6431.3 6431.9 6434.1 6444.1 3988.8 3999.7 4110.5 4118.6 4157.6 1169.9 1185.4 1198.4 1221.9 1229.2 13659.7 14105.8 15011.9 15165.0 15172.5 4133.6 5241.8 5257.2 5414.7 5436.9 9388.5 10468.2 11398.5 11437.5 11447.7 5746.9 6284.1 6284.2 6287.7 6322.2 5703.2 5813.3 5913.8 5964.3 6023.1 3729.3 4112.0 4236.9 4269.3 4274.4 1252.3 1255.6 1259.7 1264.6 1276.6 735.7 747.8 765.7 766.1 797.7 5757.7 5847.9 5966.9 6019.6 6058.8 553.5 568.1 581.9 583.4 585.9 3666.8 4112.4 4358.9 4434.3 4603.6 13628.7 14071.6 14075.2 14075.7 14080.6 13771.9 13817.2 13945.9 13980.3 14113.9 9204.8 10494.4 10504.1 10518.5 10524.3 5113.9 5723.6 6583.4 6775.2 6796.6 12327.6 12752.6 12758.0 12765.9 12767.4 10297.9 12574.5 12771.0 12875.7 12946.5 9150.4 9171.0 9328.1 9339.4 9376.6 10133.8 10307.3 10309.2 10364.1 10387.7 8440.2 8777.5 8796.2 8829.8 8858.6 8528.0 8559.5 8597.2 8599.8 8723.2 6935.2 7629.0 7699.8 7781.1 7817.2 5381.8 5752.9 5866.1 5949.4 6012.5 5499.1 5801.2 5965.9 6067.1 6122.7 09:11:58 INFO - PID 1914 | 09:11:58 INFO - PID 1914 | [#4] prototype.html Cycles:180 Average:5336.75 Median:6014.55 stddev:1701.17 (28.3%) stddev-sans-first:1664.41 09:11:58 INFO - PID 1914 | Values: 358.9 372.2 372.2 382.4 394.8 4843.0 6129.2 6336.0 6354.0 6386.4 6025.4 6107.6 6168.8 6181.2 6337.4 4303.6 4407.1 4641.5 4644.9 4668.9 4167.7 4561.7 4620.0 4621.4 4638.5 4553.6 4565.6 4578.7 4586.0 4588.1 4218.3 4581.4 4594.6 4595.2 4621.3 3195.6 3274.7 3450.8 3854.8 3970.3 5995.6 6013.9 6033.2 6065.5 6079.8 4887.4 5334.6 5406.3 5410.1 5422.8 6302.4 6370.5 6574.7 6643.5 6668.4 4379.8 4394.8 4399.1 4420.0 4432.3 6215.9 6656.8 6763.3 6939.0 6943.5 3617.1 4288.5 4468.6 4471.9 4495.6 4195.5 4210.0 4514.4 4572.1 4587.1 5080.5 5273.5 5382.2 5415.8 5451.7 5624.9 6177.8 6611.0 6681.8 6683.8 1349.3 1550.5 1558.4 1560.0 1564.0 990.4 1012.8 1013.8 1019.8 1026.3 5291.9 5713.5 6015.2 6229.6 6314.1 5832.0 6648.4 6931.8 6949.8 6972.4 5228.7 6007.6 6149.9 6290.6 6310.7 2820.4 3234.5 3596.9 3635.2 3660.1 6818.3 7042.8 7119.8 7139.5 7290.4 5802.7 6207.0 7042.3 7171.6 7223.9 6425.4 6734.3 6765.4 7035.0 7105.4 6920.2 6926.5 6938.9 6952.2 6987.0 5569.8 6508.6 6598.5 6732.5 6904.6 5590.5 6965.5 6967.6 7138.6 7269.7 4481.6 4495.5 4500.1 4509.5 4511.3 5773.0 6725.0 6858.5 6950.9 6958.8 6118.9 6698.4 6712.0 6723.7 6736.9 5518.9 5836.0 6009.9 6390.2 6697.0 5430.4 6556.4 6556.9 6563.8 6566.3 6275.5 6701.4 6707.7 6726.2 6731.2 6404.2 6493.7 6496.3 6506.8 6507.7 09:11:58 INFO - PID 1914 | 09:11:58 INFO - PID 1914 | [#5] yui.html Cycles:180 Average:19732.07 Median:4070.46 stddev:27863.52 (684.5%) stddev-sans-first:27905.03 09:11:58 INFO - PID 1914 | Values: 698.3 731.7 736.7 737.5 746.8 15185.0 16132.5 16175.4 16198.2 16255.2 14600.1 14926.3 15063.6 15227.4 15277.3 965.4 983.4 989.2 990.5 991.6 921.5 960.1 978.4 979.7 980.1 1047.2 1057.3 1061.9 1064.3 1064.8 1044.9 1056.0 1057.9 1059.5 1060.9 772.9 780.0 786.4 787.2 788.1 16135.5 16473.2 16596.5 16616.3 16936.3 14583.8 14794.0 14838.4 14880.0 15385.7 11338.3 11360.6 11795.0 11807.1 12131.0 2944.9 2960.9 3006.4 3031.7 3099.8 81695.0 95660.0 108630.4 109278.5 110854.3 10222.8 11096.5 11316.8 11453.5 11758.0 24739.9 24794.3 25048.0 25102.0 25151.5 7713.7 7778.3 7854.4 7879.3 8010.0 4069.8 4161.8 4167.5 4167.9 4176.2 4043.4 4071.2 4113.9 4156.8 4174.4 734.2 734.5 786.9 789.7 789.9 565.8 566.9 578.2 586.9 590.7 8270.2 8387.5 9772.8 9919.9 10173.7 511.5 536.9 570.3 583.2 593.8 4379.9 4597.0 4639.1 4641.2 4705.7 85871.1 95722.5 99412.0 101177.5 103530.0 88356.0 88640.0 90393.5 90618.5 91674.5 65792.7 71506.6 72543.0 72663.0 73382.5 9062.0 9194.6 9251.5 9313.7 9357.0 38532.6 38922.2 39646.8 39654.8 39950.0 52666.7 54816.7 67197.0 67919.0 68412.3 10775.8 13678.9 13904.9 13913.4 14083.6 12403.4 14723.6 14821.6 15179.0 15310.4 13236.0 13388.3 13824.9 14350.9 14374.5 13550.3 13893.9 13981.9 14242.4 14435.4 13610.5 13740.1 13870.5 14178.4 14188.3 8764.3 9795.4 9803.8 9941.1 10281.0 9022.0 9216.3 9531.9 11281.1 11311.0 09:11:58 INFO - PID 1914 | -------- Summary: end -------- 09:11:58 INFO - PID 1914 | 09:11:58 INFO - TEST-INFO | 1914: exit 0 09:11:58 INFO - TEST-OK | dromaeo_css | took 1117411ms 09:11:58 INFO - TEST-START | kraken 09:11:58 INFO - operating with platform_type : mac_ 09:11:58 INFO - Initialising browser for kraken test... 09:11:58 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpCk79sh/profile http://localhost:54221/getInfo.html) 09:12:01 INFO - PID 1920 | 2017-03-29 09:12:01.740 plugin-container[1922:20622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 09:12:01 INFO - PID 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:01 INFO - PID 1920 | 2017-03-29 09:12:01.741 plugin-container[1922:20622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:12:01 INFO - PID 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:01 INFO - PID 1920 | 2017-03-29 09:12:01.742 plugin-container[1922:20622] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:12:01 INFO - PID 1920 | __metrics Screen width/height:1600/1200 09:12:01 INFO - PID 1920 | colorDepth:24 09:12:01 INFO - PID 1920 | Browser inner width/height: 1024/689 09:12:01 INFO - PID 1920 | __metrics 09:12:01 ERROR - PID 1920 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 09:12:01 ERROR - PID 1920 | 1490803921929 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpCk79sh/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpCk79sh/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpCk79sh/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpCk79sh/profile/extensions/talos-powers@TalosPowersService.js:55:9 09:12:02 INFO - TEST-INFO | 1920: exit 0 09:12:02 INFO - Browser initialized. 09:12:02 INFO - Running cycle 1/1 for kraken test... 09:12:02 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpCk79sh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:05 INFO - PID 1923 | 2017-03-29 09:12:05.301 plugin-container[1924:20752] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 09:12:05 INFO - PID 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:05 INFO - PID 1923 | 2017-03-29 09:12:05.302 plugin-container[1924:20752] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 09:12:05 INFO - PID 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:05 INFO - PID 1923 | 2017-03-29 09:12:05.302 plugin-container[1924:20752] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:12:15 INFO - PID 1923 | RSS: Main: 229289984 09:12:15 INFO - PID 1923 | 09:12:17 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54221/tests/kraken/driver.html?testName=audio-beat-detection) 09:12:18 INFO - PID 1923 | RSS: Main: 244428800 09:12:18 INFO - PID 1923 | 09:12:21 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54221/tests/kraken/driver.html?testName=audio-dft) 09:12:22 INFO - PID 1923 | RSS: Main: 253091840 09:12:22 INFO - PID 1923 | 09:12:25 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54221/tests/kraken/driver.html?testName=audio-fft) 09:12:26 INFO - PID 1923 | RSS: Main: 254078976 09:12:26 INFO - PID 1923 | 09:12:28 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54221/tests/kraken/driver.html?testName=audio-oscillator) 09:12:29 INFO - PID 1923 | RSS: Main: 255221760 09:12:29 INFO - PID 1923 | 09:12:31 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:12:31 INFO - PID 1923 | RSS: Main: 255569920 09:12:31 INFO - PID 1923 | 09:12:34 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54221/tests/kraken/driver.html?testName=imaging-darkroom) 09:12:35 INFO - PID 1923 | RSS: Main: 261492736 09:12:35 INFO - PID 1923 | 09:12:38 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54221/tests/kraken/driver.html?testName=imaging-desaturate) 09:12:39 INFO - PID 1923 | RSS: Main: 263122944 09:12:39 INFO - PID 1923 | 09:12:42 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54221/tests/kraken/driver.html?testName=json-parse-financial) 09:12:43 INFO - PID 1923 | RSS: Main: 262410240 09:12:43 INFO - PID 1923 | 09:12:45 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:12:45 INFO - PID 1923 | RSS: Main: 262553600 09:12:45 INFO - PID 1923 | 09:12:47 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54221/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:12:48 INFO - PID 1923 | RSS: Main: 262406144 09:12:48 INFO - PID 1923 | 09:12:50 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:12:51 INFO - PID 1923 | RSS: Main: 262545408 09:12:51 INFO - PID 1923 | 09:12:54 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:12:55 INFO - PID 1923 | RSS: Main: 262938624 09:12:55 INFO - PID 1923 | 09:12:58 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:12:59 INFO - PID 1923 | RSS: Main: 260354048 09:12:59 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:54221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54221/tests/kraken/driver.html?testName=ai-astar) 09:13:01 INFO - PID 1923 | RSS: Main: 263577600 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | __start_tp_report 09:13:01 INFO - PID 1923 | _x_x_mozilla_page_load 09:13:01 INFO - PID 1923 | _x_x_mozilla_page_load_details 09:13:01 INFO - PID 1923 | |i|pagename|runs| 09:13:01 INFO - PID 1923 | |0;ai-astar;116;169;123;123;125;125;124;129;126;127 09:13:01 INFO - PID 1923 | |1;audio-beat-detection;152;116;117;182;143;119;120;152;138;115 09:13:01 INFO - PID 1923 | |2;audio-dft;220;236;288;220;222;182;278;300;212;211 09:13:01 INFO - PID 1923 | |3;audio-fft;86;57;58;90;59;60;63;69;76;58 09:13:01 INFO - PID 1923 | |4;audio-oscillator;88;73;90;74;72;74;86;75;74;118 09:13:01 INFO - PID 1923 | |5;imaging-gaussian-blur;95;93;98;121;95;93;111;106;97;94 09:13:01 INFO - PID 1923 | |6;imaging-darkroom;104;102;103;132;102;102;110;138;104;102 09:13:01 INFO - PID 1923 | |7;imaging-desaturate;103;101;136;110;84;83;143;165;165;140 09:13:01 INFO - PID 1923 | |8;json-parse-financial;68;67;67;68;70;70;69;69;68;69 09:13:01 INFO - PID 1923 | |9;json-stringify-tinderbox;59;64;62;67;58;64;89;91;89;92 09:13:01 INFO - PID 1923 | |10;stanford-crypto-aes;87;88;87;86;85;99;110;112;116;99 09:13:01 INFO - PID 1923 | |11;stanford-crypto-ccm;150;180;181;172;152;151;150;161;231;188 09:13:01 INFO - PID 1923 | |12;stanford-crypto-pbkdf2;201;203;250;252;196;202;179;222;193;233 09:13:01 INFO - PID 1923 | |13;stanford-crypto-sha256-iterative;73;74;71;73;88;87;100;72;72;73 09:13:01 INFO - PID 1923 | __end_tp_report 09:13:01 INFO - PID 1923 | __start_cc_report 09:13:01 INFO - PID 1923 | _x_x_mozilla_cycle_collect,1032 09:13:01 INFO - PID 1923 | __end_cc_report 09:13:01 INFO - PID 1923 | __startTimestamp1490803981546__endTimestamp 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | ------- Summary: start ------- 09:13:01 INFO - PID 1923 | Number of tests: 14 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#0] ai-astar Cycles:10 Average:128.70 Median:125.50 stddev:14.57 (11.6%) stddev-sans-first:14.71 09:13:01 INFO - PID 1923 | Values: 116.0 169.0 123.0 123.0 125.0 125.0 124.0 129.0 126.0 127.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#1] audio-beat-detection Cycles:10 Average:135.40 Median:140.50 stddev:22.17 (15.8%) stddev-sans-first:22.69 09:13:01 INFO - PID 1923 | Values: 152.0 116.0 117.0 182.0 143.0 119.0 120.0 152.0 138.0 115.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#2] audio-dft Cycles:10 Average:236.90 Median:229.00 stddev:38.56 (16.8%) stddev-sans-first:40.41 09:13:01 INFO - PID 1923 | Values: 220.0 236.0 288.0 220.0 222.0 182.0 278.0 300.0 212.0 211.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#3] audio-fft Cycles:10 Average:67.60 Median:66.00 stddev:12.30 (18.6%) stddev-sans-first:11.10 09:13:01 INFO - PID 1923 | Values: 86.0 57.0 58.0 90.0 59.0 60.0 63.0 69.0 76.0 58.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:74.50 stddev:14.27 (19.2%) stddev-sans-first:14.99 09:13:01 INFO - PID 1923 | Values: 88.0 73.0 90.0 74.0 72.0 74.0 86.0 75.0 74.0 118.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#5] imaging-gaussian-blur Cycles:10 Average:100.30 Median:94.50 stddev:9.39 (9.9%) stddev-sans-first:9.77 09:13:01 INFO - PID 1923 | Values: 95.0 93.0 98.0 121.0 95.0 93.0 111.0 106.0 97.0 94.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#6] imaging-darkroom Cycles:10 Average:109.90 Median:104.00 stddev:13.52 (13.0%) stddev-sans-first:14.17 09:13:01 INFO - PID 1923 | Values: 104.0 102.0 103.0 132.0 102.0 102.0 110.0 138.0 104.0 102.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#7] imaging-desaturate Cycles:10 Average:123.00 Median:154.00 stddev:30.84 (20.0%) stddev-sans-first:31.85 09:13:01 INFO - PID 1923 | Values: 103.0 101.0 136.0 110.0 84.0 83.0 143.0 165.0 165.0 140.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#8] json-parse-financial Cycles:10 Average:68.50 Median:69.00 stddev:1.08 (1.6%) stddev-sans-first:1.13 09:13:01 INFO - PID 1923 | Values: 68.0 67.0 67.0 68.0 70.0 70.0 69.0 69.0 68.0 69.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#9] json-stringify-tinderbox Cycles:10 Average:73.50 Median:78.00 stddev:14.66 (18.8%) stddev-sans-first:14.58 09:13:01 INFO - PID 1923 | Values: 59.0 64.0 62.0 67.0 58.0 64.0 89.0 91.0 89.0 92.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#10] stanford-crypto-aes Cycles:10 Average:96.90 Median:87.00 stddev:12.06 (13.9%) stddev-sans-first:12.25 09:13:01 INFO - PID 1923 | Values: 87.0 88.0 87.0 86.0 85.0 99.0 110.0 112.0 116.0 99.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#11] stanford-crypto-ccm Cycles:10 Average:171.60 Median:176.00 stddev:25.41 (14.4%) stddev-sans-first:25.72 09:13:01 INFO - PID 1923 | Values: 150.0 180.0 181.0 172.0 152.0 151.0 150.0 161.0 231.0 188.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:213.10 Median:212.50 stddev:24.90 (11.7%) stddev-sans-first:26.02 09:13:01 INFO - PID 1923 | Values: 201.0 203.0 250.0 252.0 196.0 202.0 179.0 222.0 193.0 233.0 09:13:01 INFO - PID 1923 | 09:13:01 INFO - PID 1923 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.30 Median:73.00 stddev:9.87 (13.5%) stddev-sans-first:10.28 09:13:01 INFO - PID 1923 | Values: 73.0 74.0 71.0 73.0 88.0 87.0 100.0 72.0 72.0 73.0 09:13:01 INFO - PID 1923 | -------- Summary: end -------- 09:13:01 INFO - PID 1923 | 09:13:02 INFO - TEST-INFO | 1923: exit 0 09:13:02 INFO - TEST-OK | kraken | took 63599ms 09:13:02 INFO - SUITE-END | took 1181s 09:13:02 INFO - Completed test suite (00:19:41) 09:13:02 INFO - javascript benchmark 09:13:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7744.213511876859, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3565.857365705372, 3568.4290513517844, 3601.963980360199, 3606.2787442511503, 3627.564692236932, 6163.938360616393, 6282.183316168892, 6296.685165741712, 6320.3363646817115, 6329.082283068952, 6237.656928868911, 6285.6857157142185, 6290.874182516347, 6340.968295158529, 6406.487481001521, 5758.280214973129, 5773.480386765201, 5883.7058147092475, 5974.97012514939, 6020.6688632125215, 4594.977025114869, 5833.591648584601, 6004.489618382443, 6019.247594050744, 6062.302835173945, 4217.8312867485265, 5574.024545704526, 5632.324121105453, 5638.746256418471, 5775.393583673716, 5670.858228544278, 5699.544036477072, 5732.197492351032, 5739.823336216107, 5743.827685169452, 5564.970480461127, 5647.43525647436, 5648.63283886546, 5652.63257888236, 5679.176519404705, 6082.330943596201, 6204.5967012144065, 6223.657698826562, 6248.843778905518, 6302.212223472066, 6728.293529179415, 6771.085903403026, 6787.932120678779, 6802.251752307242, 6813.216480104752, 6895.689693963784, 6902.413294869942, 6903.068085808452, 6909.723611055551, 6922.930770692329, 6260.655663938527, 6296.212973378328, 6349.873002539969, 6360.491160707133, 6361.681915904186, 6834.419074378634, 6984.3364880336285, 7000.964995174992, 7047.013418121467, 7057.8235544110785, 6403.263624683128, 6459.354064593597, 6474.1907261592305, 6681.265060843303, 6712.127423434922, 6715.0, 6727.899081513781, 6861.245263021064, 6862.759803406957, 6886.621235831977, 6798.694058767367, 6799.558028728117, 6807.489438292049, 6825.215100263434, 6830.316968303229, 6523.347665233438, 6621.238557565942, 6675.966620166868, 6738.225104112992, 6789.762358317435, 5019.7490125494305, 5022.171341728647, 5396.83809485716, 5424.294841670637, 5432.049391356497, 4076.2458945095254, 4805.687630304019, 4826.034793041335, 4826.131296366688, 4826.782794774173, 5788.652680839163, 6054.66699331541, 6278.623282603058, 6338.8415289617205, 6416.00551914454, 6866.862662746817, 6875.140607424072, 6918.342757438036, 6935.202451718016, 6939.826504337331, 6359.777407790798, 6389.744410223632, 6406.423421892052, 6410.839729006719, 6413.134226879405, 5885.735141918624, 5945.078512830518, 5967.6419414835245, 6065.0, 6087.086936959491, 5860.941390586039, 6828.829279268058, 6956.826079348057, 7009.824754381181, 7032.331928466788, 6895.379415852597, 6904.13698287714, 6917.342852429011, 6922.203946546211, 6931.89602155958, 6939.6877140529805, 6973.721051157897, 7046.0, 7076.00935868989, 7079.787606371817, 6498.512611554058, 6616.040674102324, 6699.296573859675, 6723.327667233238, 6760.425363844129, 5645.294338207724, 6623.470122390101, 6715.462762978852, 6761.121054262914, 6784.491163162881, 6339.049142628458, 6455.741770329134, 6537.673116344259, 6576.868462630816, 6687.465002799861, 6503.187101612299, 6650.434713049445, 6704.156357001669, 6709.228438729413, 6718.160229971254, 6291.716872740646, 6374.840628984312, 6385.744570217139, 6386.169797926425, 6537.248216454978, 5833.737481813413, 6442.387973142544, 6465.062565928009, 6601.471882249504, 6603.933960660332, 6408.942524483408, 6412.35876412355, 6412.487001039999, 6414.326495717883, 6434.131392261985], "value": 6198.045716342932, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [808.4421748993194, 1258.6790368456047, 1263.721981164144, 1270.4029106320027, 1293.5925183567176, 26370.604440933377, 39472.80263598685, 39504.80247598761, 39537.8023109884, 39549.2090158197, 39703.205935881335, 39974.40038399426, 39986.40020399689, 40105.99735006626, 40160.59839401605, 8602.311815054796, 8865.867011994826, 8884.466931984069, 8909.331800115016, 8953.641854325819, 8790.769292299052, 8887.0, 8892.466452012868, 8895.021547629756, 8903.198712115909, 8871.467711937305, 8873.20141187293, 8874.068222836606, 8880.8667869982, 8883.733487995338, 8803.867941980876, 8805.779855503599, 8831.735047948572, 8834.955825220897, 8846.115388461167, 7710.0, 8639.438436501607, 8715.651373945035, 8726.738197854074, 8758.693445729368, 36755.264894701955, 38232.044198894975, 38338.61661383379, 38383.42424863629, 38434.231315373814, 2996.8951086712077, 3019.4866872631706, 3020.3808219315206, 3039.711227433412, 3048.9695103048907, 27707.04601243781, 27721.58417623737, 28030.159095227176, 28048.018319358725, 28696.569551456745, 2803.1310293808983, 2826.279298778819, 2891.0, 2914.096630044693, 2970.643522777259, 250550.0, 306735.0, 308025.0, 308651.0, 309843.0, 40544.0, 47919.28121078187, 48571.75714121477, 48610.27084593734, 48727.0, 50377.74811125921, 50749.49250507448, 50755.74622126939, 50899.74550127226, 51010.469685909484, 11829.763404731857, 14211.43154273838, 14221.004529682823, 14253.50112746049, 14254.216018119103, 9095.0, 10353.654024976704, 11534.480948357344, 11619.53521859133, 11648.0, 9880.0, 13696.726065478635, 13764.793528097087, 13849.515266965607, 13931.791023134661, 5896.0, 5914.54546363263, 5929.14027466015, 5936.168936348915, 5941.821745347646, 965.1699538396973, 989.5497548615363, 990.6037584966072, 996.7807082441851, 1033.7570670892335, 24409.389765255655, 24547.0, 24675.506489870102, 24730.876345618155, 24741.5051698965, 974.2985050763439, 997.486294558303, 1101.4547798839626, 1126.4705588373452, 1137.1641843245209, 11883.940580297043, 12522.373881305899, 12533.56132535375, 12547.874521254853, 12791.0, 445239.0, 447683.0, 448038.7598061989, 448942.0, 450302.0, 55872.44127558754, 56199.71900140473, 56277.0, 56380.71809641008, 56424.43575564274, 1461.291273732239, 1482.5700546841294, 1525.9008164469274, 1526.8320484746669, 1533.9079655220723, 20262.797372026092, 24111.75888241095, 24133.034678612657, 24301.27096187117, 24371.878140609184, 39561.01097472586, 39571.80214098911, 39685.0, 39860.60139398685, 39886.80339589817, 415585.0, 417727.0, 420039.0, 420367.0, 421225.0, 29970.700292997662, 33522.832385837915, 34066.82966585151, 34682.13294667654, 34708.13229669177, 36563.90308290756, 44031.779841101874, 44073.338899917166, 44420.55579444164, 44442.0, 29156.27109322284, 29547.704522954496, 29935.55096673508, 30568.388632227674, 30815.691843081284, 23269.3019209424, 29407.26481837971, 29502.409951801274, 30335.0, 30519.250088715562, 30091.0, 30685.846570767895, 36171.45742813807, 36705.44941825928, 36852.81573592115, 29819.552706708982, 30153.849230753705, 30217.942372016874, 30858.69141308558, 30888.0, 24065.639015415134, 24533.754662453146, 24706.309316071955, 24712.13507527228, 25891.705414729564], "value": 17804.577722126156, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.81808277233864, 419.7796157017567, 421.4652338751491, 428.096715929389, 437.58210908582305, 19411.611767764636, 21016.369508914737, 21818.89090554545, 21851.0, 21923.451913702167, 20511.97440127995, 21758.564828703416, 22206.0, 23679.0, 23704.17035403761, 6129.877402451948, 6401.1678481797535, 6420.871582568346, 6429.646369449679, 6435.839104022413, 5170.560502357304, 5301.681899086047, 6176.073588961647, 6354.650494222816, 6366.331535188808, 6098.542609304298, 6279.686015699197, 6320.525960552954, 6332.018537126753, 6341.302456729756, 6175.197224360827, 6354.364563543655, 6358.713857876407, 6360.586561873487, 6366.522510811707, 5418.9458105418835, 5597.916031259534, 5638.633488823213, 5647.435256474319, 5659.207710920475, 25961.0, 26755.19734407971, 26959.595606065926, 26999.46001079987, 27144.45711085787, 2009.8995050247574, 2016.5261163626535, 2029.8173164415125, 2038.4598081508411, 2040.5306779440812, 15910.363585456567, 16106.677866442722, 16463.34146634133, 16678.749818752727, 17057.658846822993, 1672.3059930129104, 1683.166832417961, 1706.7866516685415, 1708.841917237278, 1711.2042900051536, 145847.0, 153996.0, 156658.0, 157091.51617240647, 158605.82788344283, 10075.546600402999, 12284.385780710929, 12468.0, 12475.376231188586, 12633.178843375335, 16130.435434759962, 16314.592135196715, 16315.673686526441, 16346.83653163477, 16377.0, 8754.168354006359, 9072.773180670536, 9073.41022833527, 9215.17063464278, 9220.815583688423, 8652.702094685721, 8662.30701543875, 8664.566771661392, 8838.425502342327, 8852.424592401474, 6524.314946930599, 7765.505522514891, 7871.33093687043, 7882.935803666546, 7973.681052757941, 1980.722698822166, 2133.232036466871, 2139.2030694216933, 2140.379290005912, 2327.534493101353, 930.8929473110544, 943.8820147481565, 950.7147855643279, 987.6543209876486, 996.1084829077935, 19375.8062419374, 20974.2659006934, 21133.837638929697, 22649.773502265096, 23304.067837286657, 803.325067234822, 892.0187793427308, 915.9862602060974, 973.7292832853194, 990.321629683669, 9694.854577181348, 10267.94866025665, 10376.0, 10601.0, 10637.042666160083, 537796.0, 722060.0, 724644.0, 745033.0, 749291.0, 121793.0, 139794.99999999796, 153237.0, 155261.0, 159927.20036399743, 1029.8609687692215, 1159.4550561236206, 1192.2548407245472, 1236.116176933497, 1245.6325384011739, 18737.812621873607, 21261.6810747842, 21502.684968662426, 21510.56978860383, 21570.6764398531, 38766.6123338763, 63217.68391158015, 63690.04464933123, 64040.35959640344, 64588.0, 86836.0, 86959.56520217359, 88130.0, 89372.6594101102, 89645.55177224072, 12560.937195313965, 12573.622791316275, 12752.617421477371, 12889.548865789653, 13021.609351719464, 13623.523176688768, 13682.042257041912, 13884.86115138876, 13963.30183490801, 14018.859811401755, 11582.305061696325, 11691.707707307385, 12051.698707532381, 12080.45637946277, 12148.757024859276, 9006.684766033157, 10578.0, 10578.100861426865, 10821.837672435076, 11067.225294229327, 10433.686989390331, 11590.478428470571, 11814.763704726029, 11942.283462992249, 11965.16243862956, 12296.50813967467, 12343.259404435763, 12592.811107833573, 12626.979683251004, 12750.979921606435, 10254.615626890274, 11605.245659032136, 11805.291682499077, 11955.402229888297, 12028.699282518008], "value": 10631.074616197971, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1189.38746545529, 1249.4814651919453, 1382.1016339379405, 1422.9644258893527, 1452.8329242137152, 8679.783005424859, 10860.6741797746, 10892.346459212453, 11032.779344413117, 11096.223264371498, 10866.185036122302, 10949.83575246372, 11074.778504429927, 11096.500657470413, 11133.944330278337, 2429.501952099824, 2486.0677246032737, 2486.7388924162974, 2487.577111890974, 2494.528228345276, 2413.5293617744546, 2417.782399584037, 2459.7048354197523, 2466.0135945621714, 2468.7654672806057, 2401.807855371566, 2423.0, 2437.2322718343667, 2450.779429851313, 2458.618914068313, 2369.5260947810507, 2412.107520217522, 2431.9392015199583, 2438.85366877987, 2454.460018795862, 2360.3509035015336, 2374.299581623425, 2394.353524548362, 2399.6160614301803, 2461.618449140386, 11049.28179668319, 11132.276402033842, 11170.609028683957, 11170.994610485095, 11172.385518796464, 6189.6286222826775, 6431.3247109053345, 6431.935680643181, 6434.131392262078, 6444.130042444257, 3988.840446382141, 3999.7200195986293, 4110.486189226347, 4118.567550407224, 4157.646600039, 1169.935653539055, 1185.3954483213622, 1198.3648666206925, 1221.926684398939, 1229.1703100407278, 13659.658508537266, 14105.788413173914, 15011.924940375227, 15165.0, 15172.468963586221, 4133.607307305801, 5241.816536421207, 5257.211418287211, 5414.7021913795115, 5436.945630543723, 9388.483633400092, 10468.162546996222, 11398.544058237578, 11437.485313160927, 11447.713807154722, 5746.913796293059, 6284.057391391237, 6284.245890493079, 6287.717052732638, 6322.178116844781, 5703.230063941398, 5813.331466881278, 5913.822585322448, 5964.314103878023, 6023.096535519707, 3729.291434627412, 4112.0, 4236.915261694749, 4269.295566231537, 4274.358846173099, 1252.2861968677767, 1255.6358655989843, 1259.6913756129807, 1264.646827894163, 1276.5723482633236, 735.7498450526847, 747.7644541969262, 765.6975494679571, 766.0654002117408, 797.6889013284823, 5757.740901659436, 5847.912281315784, 5966.850828729316, 6019.578629495893, 6058.757649693963, 553.5073784331947, 568.0967262053355, 581.9010768169421, 583.4282403244844, 585.912113183026, 3666.7983260920373, 4112.362583799516, 4358.912821743547, 4434.290513517886, 4603.608693261043, 13628.65928351799, 14071.648208794657, 14075.155490670388, 14075.71848563023, 14080.647983800487, 13771.862281377258, 13817.240051797247, 13945.860541394659, 13980.300984950914, 14113.929430352782, 9204.815903682023, 10494.370337779757, 10504.107150892107, 10518.526666299882, 10524.31591946521, 5113.8721531962, 5723.570732195185, 6583.407493325623, 6775.1531058617675, 6796.558223715443, 12327.568535101229, 12752.553660621834, 12758.0, 12765.93617031909, 12767.425465853874, 10297.9485102574, 12574.497020119094, 12771.0, 12875.678108047374, 12946.546870859474, 9150.40522366044, 9171.037041110587, 9328.113829186217, 9339.439633622005, 9376.624935002523, 10133.847992279974, 10307.278490505592, 10309.22680798928, 10364.119049880845, 10387.740306492398, 8440.15598440151, 8777.517236551796, 8796.208341249318, 8829.779255518664, 8858.64565417376, 8527.957360213159, 8559.486430814171, 8597.18326758957, 8599.82800343977, 8723.214910657818, 6935.202451718117, 7628.961855190689, 7699.846003080019, 7781.144074151952, 7817.179196184319, 5381.838544843661, 5752.884942301214, 5866.120081987736, 5949.434803693643, 6012.458878700938, 5499.120140777616, 5801.1878337032, 5965.9105113422465, 6067.120267561268, 6122.6938653068055], "value": 5091.44316554179, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.8941262327613, 372.18862878923954, 372.21648430054734, 382.44163521258963, 394.82824971137575, 4842.975785121074, 6129.203203583539, 6336.0, 6353.968230158848, 6386.393292637198, 6025.397460253972, 6107.633541987473, 6168.75324987, 6181.196444462214, 6337.429631333179, 4303.5696430356875, 4407.140607581523, 4641.535846415365, 4644.907101857961, 4668.852327929157, 4167.747082876231, 4561.677113637042, 4620.0, 4621.4223222097235, 4638.489766125724, 4553.635709143261, 4565.634749220055, 4578.725276483405, 4585.977070114644, 4588.073009336087, 4218.282891908352, 4581.427321584802, 4594.632429405656, 4595.241785105453, 4621.306803979396, 3195.568598239231, 3274.7052765251005, 3450.7756995795194, 3854.7879866607327, 3970.344893092636, 5995.61028533144, 6013.857367100237, 6033.1553582498045, 6065.4541091302, 6079.756809727605, 4887.413510378778, 5334.599905007101, 5406.324209473816, 5410.134378499461, 5422.8373148805595, 6302.432781049682, 6370.458511026568, 6574.671266436707, 6643.5017373697165, 6668.399844014062, 4379.781010949439, 4394.8461803837035, 4399.142167277382, 4419.977900110511, 4432.290833466662, 6215.937840621581, 6656.7684978278685, 6763.289854565298, 6938.965305173492, 6943.548669336477, 3617.1318883468302, 4288.52826189119, 4468.620167285817, 4471.932921006188, 4495.550444955478, 4195.538490766014, 4209.97895010523, 4514.413126293626, 4572.108438854391, 4587.082583483317, 5080.49195080489, 5273.499017593323, 5382.192671099288, 5415.806598327684, 5451.727413629303, 5624.943750562524, 6177.826213019512, 6610.983649986704, 6681.832954176088, 6683.7660681875905, 1349.2916218984915, 1550.4573399310152, 1558.4233833481458, 1559.9844001560066, 1564.0, 990.3958585262984, 1012.7771890184148, 1013.7566983923805, 1019.8011387779312, 1026.2918586175515, 5291.947080529222, 5713.457366511018, 6015.232863029092, 6229.626222426669, 6314.147590075281, 5831.979403604352, 6648.401643852077, 6931.826704332432, 6949.756758513528, 6972.442204623618, 5228.712420816892, 6007.609505382136, 6149.877002459926, 6290.591111577733, 6310.652914089677, 2820.4218135282217, 3234.498652708834, 3596.9280614387567, 3635.182084031072, 3660.1215708230366, 6818.28408017151, 7042.837931741307, 7119.750808721774, 7139.464540159405, 7290.4167666586545, 5802.709864506758, 6206.968965155235, 7042.330978557029, 7171.641417929082, 7223.89164162538, 6425.421712045938, 6734.292899245705, 6765.391114799593, 7035.0, 7105.431565474646, 6920.1938933587335, 6926.549774264657, 6938.895916561357, 6952.165740111219, 6987.0, 5569.777208911761, 6508.60948343101, 6598.472122230306, 6732.528722989344, 6904.585724856524, 5590.524805391425, 6965.547239429421, 6967.581945083109, 7138.607376594337, 7269.709211631687, 4481.596656300949, 4495.516479561802, 4500.144972455354, 4509.481409637933, 4511.323301504801, 5773.018586840163, 6724.957631567115, 6858.519903606899, 6950.895736563854, 6958.756443524452, 6118.938810612015, 6698.39714425704, 6712.026756120237, 6723.697433615597, 6736.8652626946205, 5518.889622207614, 5836.037053886231, 6009.939900600938, 6390.169277993831, 6696.966515167393, 5430.375506816767, 6556.377144171296, 6556.934430655632, 6563.803085907431, 6566.343365663395, 6275.466585340298, 6701.396874281338, 6707.698153583198, 6726.192856857208, 6731.158605174353, 6404.199475065617, 6493.707783149675, 6496.297110646886, 6506.804795856132, 6507.707153178213], "value": 4796.572221736358, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.3435570563673, 731.6561216228371, 736.7458226911717, 737.494816051005, 746.8020974441774, 15184.999999999985, 16132.516024519284, 16175.43385981491, 16198.190090495473, 16255.187240637964, 14600.123992560453, 14926.253687315619, 15063.62340941477, 15227.39090436384, 15277.312520936584, 965.4255717847886, 983.4099540275813, 989.1789814453986, 990.5195979949755, 991.5984026469256, 921.5254144115786, 960.0735290444719, 978.403174063824, 979.7060881735487, 980.1081016275191, 1047.2041248651042, 1057.259918057359, 1061.872575290966, 1064.3454275620477, 1064.7710742190427, 1044.9059584637382, 1055.973600659982, 1057.8783439904432, 1059.5179193466993, 1060.8939106089406, 772.9033870766154, 780.0444455541973, 786.4101923557332, 787.1852632525308, 788.1330536409961, 16135.515934521984, 16473.176341182894, 16596.50210493687, 16616.25226864794, 16936.322547098225, 14583.781243281359, 14794.038387504777, 14838.406463741438, 14880.032797868103, 15385.692286154326, 11338.319700817978, 11360.602378916697, 11795.0, 11807.055435565135, 12131.0, 2944.926376840575, 2960.9111726648234, 3006.353633968707, 3031.696830316973, 3099.7830151889375, 81695.0, 95660.0, 108630.3705444411, 109278.45360773224, 110854.31941345273, 10222.846657300146, 11096.500657470433, 11316.830247546293, 11453.484593193327, 11758.0, 24739.87630061874, 24794.256172314497, 25048.0, 25102.0, 25151.496970060864, 7713.730019449293, 7778.338841198562, 7854.410919181084, 7879.33025692811, 8010.038795344596, 4069.7558146511305, 4161.771102589387, 4167.458230430086, 4167.91664166715, 4176.248275310473, 4043.353063509883, 4071.1654110907193, 4113.8971525711495, 4156.750594964312, 4174.352975288835, 734.2400615363074, 734.5115498193749, 786.9055713314439, 789.6841263494648, 789.940754443419, 565.7934853778341, 566.8809549994547, 578.2020811280406, 586.8591538030927, 590.7164561010738, 8270.214329638675, 8387.52303786908, 9772.804543909224, 9919.851202231972, 10173.694789156338, 511.47852979659984, 536.8979893820161, 570.3042288408135, 583.2126629050832, 593.8426317025992, 4379.934300985487, 4597.0, 4639.141758774637, 4641.2109941310055, 4705.717656940595, 85871.14128858631, 95722.52138739261, 99412.0, 101177.49411252896, 103530.0, 88356.0, 88640.0, 90393.54803226073, 90618.54690726504, 91674.54162729277, 65792.6710366445, 71506.64246678734, 72543.0, 72663.0, 73382.53234935271, 9062.048484909254, 9194.632214711337, 9251.537423128682, 9313.662093733501, 9356.953215234153, 38532.614673852906, 38922.22155556816, 39646.80176599098, 39654.81035568938, 39950.0, 52666.73666631642, 54816.72591637016, 67197.0, 67919.0, 68412.31587684258, 10775.838362424412, 13678.863211368158, 13904.930475347559, 13913.373898174808, 14083.647908802363, 12403.441845116811, 14723.558293251219, 14821.555353339418, 15179.0, 15310.387584496495, 13236.0, 13388.330583470593, 13824.93087534596, 14350.928245358706, 14374.496892608708, 13550.32248387557, 13893.919663927483, 13981.860181398462, 14242.359093841009, 14435.3504092314, 13610.455581777014, 13740.10689305192, 13870.514531991332, 14178.432862685377, 14188.290585470892, 8764.255038321558, 9795.43685903617, 9803.803923921625, 9941.105300522988, 10281.0, 9022.0, 9216.294985427718, 9531.857022144535, 11281.115315752982, 11311.038561722016], "value": 7528.284891485919, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1686.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 169.0, 123.0, 123.0, 125.0, 125.0, 124.0, 129.0, 126.0, 127.0], "value": 128.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 116.0, 117.0, 182.0, 143.0, 119.0, 120.0, 152.0, 138.0, 115.0], "value": 135.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 236.0, 288.0, 220.0, 222.0, 182.0, 278.0, 300.0, 212.0, 211.0], "value": 236.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 57.0, 58.0, 90.0, 59.0, 60.0, 63.0, 69.0, 76.0, 58.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 73.0, 90.0, 74.0, 72.0, 74.0, 86.0, 75.0, 74.0, 118.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 98.0, 121.0, 95.0, 93.0, 111.0, 106.0, 97.0, 94.0], "value": 100.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 102.0, 103.0, 132.0, 102.0, 102.0, 110.0, 138.0, 104.0, 102.0], "value": 109.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 101.0, 136.0, 110.0, 84.0, 83.0, 143.0, 165.0, 165.0, 140.0], "value": 123.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 70.0, 70.0, 69.0, 69.0, 68.0, 69.0], "value": 68.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 62.0, 67.0, 58.0, 64.0, 89.0, 91.0, 89.0, 92.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 87.0, 86.0, 85.0, 99.0, 110.0, 112.0, 116.0, 99.0], "value": 96.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 180.0, 181.0, 172.0, 152.0, 151.0, 150.0, 161.0, 231.0, 188.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 203.0, 250.0, 252.0, 196.0, 202.0, 179.0, 222.0, 193.0, 233.0], "value": 213.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 71.0, 73.0, 88.0, 87.0, 100.0, 72.0, 72.0, 73.0], "value": 78.3, "unit": "score"}]}]} 09:13:02 INFO - Return code: 0 09:13:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:13:02 ERROR - # TBPL SUCCESS # 09:13:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:13:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:13:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:13:02 INFO - 2017-03-29 09:13:02.861 screenresolution[1928:21146] starting screenresolution argv=screenresolution get 09:13:02 INFO - 2017-03-29 09:13:02.863 screenresolution[1928:21146] Display 0: 1600x1200x32@60 09:13:02 INFO - 2017-03-29 09:13:02.870 screenresolution[1929:21149] starting screenresolution argv=screenresolution list 09:13:02 INFO - Available Modes on Display 0 09:13:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:13:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:13:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:13:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:13:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:13:03 INFO - Intel Iris: 09:13:03 INFO - Chipset Model: Intel Iris 09:13:03 INFO - Type: GPU 09:13:03 INFO - Bus: Built-In 09:13:03 INFO - VRAM (Dynamic, Max): 1536 MB 09:13:03 INFO - Vendor: Intel (0x8086) 09:13:03 INFO - Device ID: 0x0a2e 09:13:03 INFO - Revision ID: 0x0009 09:13:03 INFO - Displays: 09:13:03 INFO - Display: 09:13:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:13:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:13:03 INFO - Display Serial Number: 1600x1200 60 09:13:03 INFO - Main Display: Yes 09:13:03 INFO - Mirror: Off 09:13:03 INFO - Online: Yes 09:13:03 INFO - Rotation: Supported 09:13:03 INFO - Return code: 0 09:13:03 INFO - Running post-action listener: _resource_record_post_action 09:13:03 INFO - [mozharness: 2017-03-29 16:13:03.107887Z] Finished run-tests step (success) 09:13:03 INFO - Running post-run listener: _resource_record_post_run 09:13:03 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 1228800; Write bytes: 518234624; Read time: 12; Write time: 1639 09:13:03 INFO - TinderboxPrint: CPU usage
54.3% 09:13:03 INFO - TinderboxPrint: I/O read bytes / time
1,228,800 / 12 09:13:03 INFO - TinderboxPrint: I/O write bytes / time
518,234,624 / 1,639 09:13:03 INFO - TinderboxPrint: CPU idle
2,200.2 (45.7%) 09:13:03 INFO - TinderboxPrint: CPU system
971.4 (20.2%) 09:13:03 INFO - TinderboxPrint: CPU user
1,642.1 (34.1%) 09:13:03 INFO - TinderboxPrint: Swap in / out
213,221,376 / 0 09:13:03 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 208992768; Write bytes: 205674496; Read time: 16728; Write time: 452 09:13:03 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 1179648; Write bytes: 301447680; Read time: 10; Write time: 1148 09:13:03 INFO - Running post-run listener: _upload_blobber_files 09:13:03 INFO - Blob upload gear active. 09:13:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:13:03 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:13:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:13:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:13:04 INFO - (blobuploader) - INFO - Open directory for files ... 09:13:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:13:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:13:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:04 INFO - (blobuploader) - INFO - Done attempting. 09:13:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:13:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:13:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:05 INFO - (blobuploader) - INFO - Done attempting. 09:13:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:13:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:13:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:06 INFO - (blobuploader) - INFO - Done attempting. 09:13:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:13:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:13:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:08 INFO - (blobuploader) - INFO - Done attempting. 09:13:08 INFO - (blobuploader) - INFO - Iteration through files over. 09:13:08 INFO - Return code: 0 09:13:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:13:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:13:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1261ab7b9d40137f266be2ba2178fbcbed9f2d030fef97b0ef5704493082717d8d6ec542b84ef2162521b52aa0d04709f27f8b91345cfbb072b7aceaf693aa59", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25b18e06fdd525855eeb9452c7126400b9811283242b06c59bd7de994c04bc003afcc41363be278bd3fed2271d3c5138cef89c2e443d11f3af93e493a2846fcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/357f87816304c21f7d3b9f78c1d89fabe2fe78b48416d2f636e62a50bc5a029179fed41d346f866c6a2b46110d4b5d75fa4241f1a2d46566d56bb15fe837a132", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/413e02d5312d3bf5bf6718a362f3e0f4c6959b47cdab2de79c2a4077084c3ebde33c4b824e1601e5728f89e902ca159a504b173a69572185fc48060f074bb48e"} 09:13:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:13:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:13:08 INFO - Contents: 09:13:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1261ab7b9d40137f266be2ba2178fbcbed9f2d030fef97b0ef5704493082717d8d6ec542b84ef2162521b52aa0d04709f27f8b91345cfbb072b7aceaf693aa59", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25b18e06fdd525855eeb9452c7126400b9811283242b06c59bd7de994c04bc003afcc41363be278bd3fed2271d3c5138cef89c2e443d11f3af93e493a2846fcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/357f87816304c21f7d3b9f78c1d89fabe2fe78b48416d2f636e62a50bc5a029179fed41d346f866c6a2b46110d4b5d75fa4241f1a2d46566d56bb15fe837a132", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/413e02d5312d3bf5bf6718a362f3e0f4c6959b47cdab2de79c2a4077084c3ebde33c4b824e1601e5728f89e902ca159a504b173a69572185fc48060f074bb48e"} 09:13:08 INFO - Running post-run listener: copy_logs_to_upload_dir 09:13:08 INFO - Copying logs to upload dir... 09:13:08 INFO - mkdir: /builds/slave/test/build/upload/logs 09:13:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.236124 ========= master_lag: 1.63 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-29 09:13:10.418841) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 09:13:10.422628) ========= 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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1261ab7b9d40137f266be2ba2178fbcbed9f2d030fef97b0ef5704493082717d8d6ec542b84ef2162521b52aa0d04709f27f8b91345cfbb072b7aceaf693aa59", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25b18e06fdd525855eeb9452c7126400b9811283242b06c59bd7de994c04bc003afcc41363be278bd3fed2271d3c5138cef89c2e443d11f3af93e493a2846fcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/357f87816304c21f7d3b9f78c1d89fabe2fe78b48416d2f636e62a50bc5a029179fed41d346f866c6a2b46110d4b5d75fa4241f1a2d46566d56bb15fe837a132", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/413e02d5312d3bf5bf6718a362f3e0f4c6959b47cdab2de79c2a4077084c3ebde33c4b824e1601e5728f89e902ca159a504b173a69572185fc48060f074bb48e"} build_url:https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011188 build_url: 'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1261ab7b9d40137f266be2ba2178fbcbed9f2d030fef97b0ef5704493082717d8d6ec542b84ef2162521b52aa0d04709f27f8b91345cfbb072b7aceaf693aa59", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25b18e06fdd525855eeb9452c7126400b9811283242b06c59bd7de994c04bc003afcc41363be278bd3fed2271d3c5138cef89c2e443d11f3af93e493a2846fcc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/357f87816304c21f7d3b9f78c1d89fabe2fe78b48416d2f636e62a50bc5a029179fed41d346f866c6a2b46110d4b5d75fa4241f1a2d46566d56bb15fe837a132", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/413e02d5312d3bf5bf6718a362f3e0f4c6959b47cdab2de79c2a4077084c3ebde33c4b824e1601e5728f89e902ca159a504b173a69572185fc48060f074bb48e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 09:13:10.466777) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 09:13:10.468279) ========= 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.TX82DTzFUt/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.q1ZDsVgeQR/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.005487 ========= master_lag: 0.91 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 09:13:11.384442) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 09:13:11.384763) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 09:13:11.385879) ========= ========= Total master_lag: 2.65 =========