builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0269 starttime: 1487004723.76 results: success (0) buildid: 20170213070640 builduid: 4647ad45d9ef4ad5b2bd45e9152236e9 revision: e1a4314f8e6eae8bbc06394c14132a9c5011371b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:03.755839) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:03.756276) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:03.756547) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:03.790055) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:03.790429) ========= 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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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-02-13 08:52:03-- 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% 168M=0s 2017-02-13 08:52:03 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123757 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:03.940319) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:03.940638) ========= 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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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.019562 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:03.989173) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-13 08:52:03.992605) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e1a4314f8e6eae8bbc06394c14132a9c5011371b --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 e1a4314f8e6eae8bbc06394c14132a9c5011371b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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-02-13 08:52:04,142 truncating revision to first 12 chars 2017-02-13 08:52:04,142 Setting DEBUG logging. 2017-02-13 08:52:04,142 attempt 1/10 2017-02-13 08:52:04,142 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e1a4314f8e6e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-13 08:52:06,060 unpacking tar archive at: mozilla-central-e1a4314f8e6e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.186579 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-13 08:52:06.202648) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:06.202975) ========= script_repo_revision: e1a4314f8e6eae8bbc06394c14132a9c5011371b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:06.204403) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:06.204688) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-13 08:52:06.225837) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-02-13 08:52:06.226213) ========= /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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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:52:06 INFO - MultiFileLogger online at 20170213 08:52:06 in /builds/slave/test 08:52:06 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:52:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:52:06 INFO - {'append_to_log': False, 08:52:06 INFO - 'base_work_dir': '/builds/slave/test', 08:52:06 INFO - 'blob_upload_branch': 'Firefox', 08:52:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:52:06 INFO - 'branch': 'Firefox', 08:52:06 INFO - 'buildbot_json_path': 'buildprops.json', 08:52:06 INFO - 'config_files': ('talos/mac_config.py',), 08:52:06 INFO - 'default_actions': ('clobber', 08:52:06 INFO - 'read-buildbot-config', 08:52:06 INFO - 'download-and-extract', 08:52:06 INFO - 'populate-webroot', 08:52:06 INFO - 'create-virtualenv', 08:52:06 INFO - 'install', 08:52:06 INFO - 'run-tests'), 08:52:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:52:06 INFO - 'download_minidump_stackwalk': True, 08:52:06 INFO - 'download_symbols': 'ondemand', 08:52:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:52:06 INFO - 'tooltool.py': '/tools/tooltool.py', 08:52:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:52:06 INFO - '/tools/misc-python/virtualenv.py')}, 08:52:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:52:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:52:06 INFO - 'installer_path': 'installer.exe', 08:52:06 INFO - 'log_level': 'info', 08:52:06 INFO - 'log_name': 'talos', 08:52:06 INFO - 'log_to_console': True, 08:52:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:52:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:52:06 INFO - 'opt_config_files': (), 08:52:06 INFO - 'pip_index': False, 08:52:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:06 INFO - 'cmd': ('bash', 08:52:06 INFO - '-c', 08:52:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:06 INFO - 'enabled': True, 08:52:06 INFO - 'halt_on_failure': False, 08:52:06 INFO - 'name': 'check_screen_resolution'},), 08:52:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:06 INFO - 'cmd': ('bash', 08:52:06 INFO - '-c', 08:52:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:06 INFO - 'enabled': True, 08:52:06 INFO - 'halt_on_failure': False, 08:52:06 INFO - 'name': 'check_screen_resolution'},), 08:52:06 INFO - 'run_cmd_checks_enabled': True, 08:52:06 INFO - 'sps_profile': False, 08:52:06 INFO - 'sps_profile_interval': 0, 08:52:06 INFO - 'suite': 'dromaeojs-e10s', 08:52:06 INFO - 'system_bits': '32', 08:52:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:52:06 INFO - 'title': 't-yosemite-r7-0269', 08:52:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:52:06 INFO - 'use_talos_json': True, 08:52:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:52:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:52:06 INFO - 'work_dir': 'build'} 08:52:06 INFO - [mozharness: 2017-02-13 16:52:06.495416Z] Running clobber step. 08:52:06 INFO - Running pre-action listener: _resource_record_pre_action 08:52:06 INFO - Running main action method: clobber 08:52:06 INFO - rmtree: /builds/slave/test/build 08:52:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:52:08 INFO - Running post-action listener: _resource_record_post_action 08:52:08 INFO - [mozharness: 2017-02-13 16:52:08.121573Z] Finished clobber step (success) 08:52:08 INFO - [mozharness: 2017-02-13 16:52:08.121742Z] Running read-buildbot-config step. 08:52:08 INFO - Running pre-action listener: _resource_record_pre_action 08:52:08 INFO - Running main action method: read_buildbot_config 08:52:08 INFO - Using buildbot properties: 08:52:08 INFO - { 08:52:08 INFO - "project": "", 08:52:08 INFO - "product": "firefox", 08:52:08 INFO - "script_repo_revision": "production", 08:52:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:52:08 INFO - "repository": "", 08:52:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:52:08 INFO - "buildid": "20170213070640", 08:52:08 INFO - "pgo_build": "False", 08:52:08 INFO - "basedir": "/builds/slave/test", 08:52:08 INFO - "buildnumber": 89, 08:52:08 INFO - "slavename": "t-yosemite-r7-0269", 08:52:08 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:52:08 INFO - "platform": "yosemite_r7", 08:52:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:52:08 INFO - "branch": "mozilla-central", 08:52:08 INFO - "repo_path": "mozilla-central", 08:52:08 INFO - "revision": "e1a4314f8e6eae8bbc06394c14132a9c5011371b", 08:52:08 INFO - "stage_platform": "macosx64", 08:52:08 INFO - "builduid": "4647ad45d9ef4ad5b2bd45e9152236e9", 08:52:08 INFO - "slavebuilddir": "test" 08:52:08 INFO - } 08:52:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 08:52:08 INFO - Running post-action listener: _resource_record_post_action 08:52:08 INFO - [mozharness: 2017-02-13 16:52:08.125149Z] Finished read-buildbot-config step (success) 08:52:08 INFO - [mozharness: 2017-02-13 16:52:08.125268Z] Running download-and-extract step. 08:52:08 INFO - Running pre-action listener: _resource_record_pre_action 08:52:08 INFO - Running main action method: download_and_extract 08:52:08 INFO - mkdir: /builds/slave/test/build/tests 08:52:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:08 INFO - trying https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 08:52:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:52:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 08:52:09 INFO - Downloaded 1199 bytes. 08:52:09 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:52:09 INFO - Using the following test package requirements: 08:52:09 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:52:09 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:52:09 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 08:52:09 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:52:09 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 08:52:09 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:52:09 INFO - u'jsshell-mac.zip'], 08:52:09 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:52:09 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 08:52:09 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:52:09 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:52:09 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 08:52:09 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:52:09 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 08:52:09 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:52:09 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 08:52:09 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:52:09 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 08:52:09 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:52:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:52:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:52:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:52:11 INFO - Expected file size: 51508540 08:52:11 INFO - Obtained file size: 51508540 08:52:27 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:52:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:52:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:52:27 INFO - Fetch https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:52:29 INFO - Expected file size: 51508540 08:52:29 INFO - Obtained file size: 51508540 08:52:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 08:52:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:52:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 08:52:45 INFO - Expected file size: 14403647 08:52:45 INFO - Obtained file size: 14403647 08:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:45 INFO - trying https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:52:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 08:52:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 08:52:47 INFO - Downloaded 70384748 bytes. 08:52:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:52:47 INFO - mkdir: /builds/slave/test/properties 08:52:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:52:47 INFO - Writing to file /builds/slave/test/properties/build_url 08:52:47 INFO - Contents: 08:52:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:52:48 INFO - Running post-action listener: _resource_record_post_action 08:52:48 INFO - Running post-action listener: set_extra_try_arguments 08:52:48 INFO - [mozharness: 2017-02-13 16:52:48.212874Z] Finished download-and-extract step (success) 08:52:48 INFO - [mozharness: 2017-02-13 16:52:48.212989Z] Running populate-webroot step. 08:52:48 INFO - Running pre-action listener: _resource_record_pre_action 08:52:48 INFO - Running main action method: populate_webroot 08:52:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:52:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:52:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:52:48 INFO - '--tppagecycles', 08:52:48 INFO - '7'], 08:52:48 INFO - 'tests': ['tsvgm']}}, 08:52:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:52:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:52:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:52:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:52:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:52:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:52:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:52:48 INFO - 'talos_options': ['--disable-e10s'], 08:52:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:52:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:52:48 INFO - 'talos_options': ['--disable-e10s'], 08:52:48 INFO - 'tests': ['damp', 'tps']}, 08:52:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:52:48 INFO - 'tests': ['damp', 'tps']}, 08:52:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:52:48 INFO - 'tests': ['dromaeo_dom']}, 08:52:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:52:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:52:48 INFO - 'tests': ['basic_compositor_video']}, 08:52:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:52:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:52:48 INFO - 'tests': ['a11yr', 08:52:48 INFO - 'ts_paint', 08:52:48 INFO - 'tpaint', 08:52:48 INFO - 'sessionrestore', 08:52:48 INFO - 'sessionrestore_no_auto_restore', 08:52:48 INFO - 'tabpaint']}, 08:52:48 INFO - 'other-e10s': {'tests': ['a11yr', 08:52:48 INFO - 'ts_paint', 08:52:48 INFO - 'tpaint', 08:52:48 INFO - 'sessionrestore', 08:52:48 INFO - 'sessionrestore_no_auto_restore', 08:52:48 INFO - 'tabpaint']}, 08:52:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:52:48 INFO - 'ts_paint', 08:52:48 INFO - 'tpaint', 08:52:48 INFO - 'sessionrestore', 08:52:48 INFO - 'sessionrestore_no_auto_restore', 08:52:48 INFO - 'tabpaint']}, 08:52:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:52:48 INFO - 'ts_paint', 08:52:48 INFO - 'tpaint', 08:52:48 INFO - 'sessionrestore', 08:52:48 INFO - 'sessionrestore_no_auto_restore', 08:52:48 INFO - 'tabpaint']}, 08:52:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:52:48 INFO - 'tests': ['a11yr', 08:52:48 INFO - 'ts_paint', 08:52:48 INFO - 'tpaint', 08:52:48 INFO - 'sessionrestore', 08:52:48 INFO - 'sessionrestore_no_auto_restore', 08:52:48 INFO - 'tabpaint']}, 08:52:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:52:48 INFO - 'tests': ['a11yr', 08:52:48 INFO - 'ts_paint', 08:52:48 INFO - 'tpaint', 08:52:48 INFO - 'sessionrestore', 08:52:48 INFO - 'sessionrestore_no_auto_restore', 08:52:48 INFO - 'tabpaint']}, 08:52:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:52:48 INFO - 'tests': ['tsvgx', 08:52:48 INFO - 'tsvgr_opacity', 08:52:48 INFO - 'tart', 08:52:48 INFO - 'tscrollx', 08:52:48 INFO - 'cart', 08:52:48 INFO - 'tsvg_static']}, 08:52:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:52:48 INFO - 'tsvgr_opacity', 08:52:48 INFO - 'tart', 08:52:48 INFO - 'tscrollx', 08:52:48 INFO - 'cart', 08:52:48 INFO - 'tsvg_static']}, 08:52:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:52:48 INFO - 'talos_options': ['--disable-e10s'], 08:52:48 INFO - 'tests': ['tp5o']}, 08:52:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:52:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:52:48 INFO - 'talos_options': ['--disable-e10s', 08:52:48 INFO - '--xperf_path', 08:52:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:48 INFO - 'tests': ['tp5n']}, 08:52:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:52:48 INFO - 'talos_options': ['--xperf_path', 08:52:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:48 INFO - 'tests': ['tp5n']}}, 08:52:48 INFO - 'talos.zip': {'path': '', 08:52:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:52:48 INFO - Running post-action listener: _resource_record_post_action 08:52:48 INFO - [mozharness: 2017-02-13 16:52:48.231321Z] Finished populate-webroot step (success) 08:52:48 INFO - [mozharness: 2017-02-13 16:52:48.231452Z] Running create-virtualenv step. 08:52:48 INFO - Running pre-action listener: _resource_record_pre_action 08:52:48 INFO - Running main action method: create_virtualenv 08:52:48 INFO - Creating virtualenv /builds/slave/test/build/venv 08:52:48 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:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:52:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:52:48 INFO - Using real prefix '/tools/python27' 08:52:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:52:49 INFO - Installing distribute.............................................................................................................................................................................................done. 08:52:51 INFO - Installing pip.................done. 08:52:51 INFO - Return code: 0 08:52:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:52:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:52:52 INFO - Reading from file tmpfile_stdout 08:52:52 INFO - Output received: 08:52:52 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:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:52 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 0x110372b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110375768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82dac59f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110150d30>, '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 0x110077db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110145be8>, '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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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:52 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:52 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:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f8tQTRwf2o/Render', 08:52:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:52 INFO - 'HOME': '/Users/cltbld', 08:52:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:52 INFO - 'LOGNAME': 'cltbld', 08:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:52 INFO - 'MOZ_NO_REMOTE': '1', 08:52:52 INFO - 'NO_EM_RESTART': '1', 08:52:52 INFO - 'PAGER': '/bin/cat', 08:52:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:52 INFO - 'PWD': '/builds/slave/test', 08:52:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:52 INFO - 'SHELL': '/bin/bash', 08:52:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYbw2ZBuDL/Listeners', 08:52:52 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 08:52:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:52 INFO - 'USER': 'cltbld', 08:52:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:52 INFO - 'XPC_FLAGS': '0x0', 08:52:52 INFO - 'XPC_SERVICE_NAME': '0', 08:52:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:52 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:52 INFO - Cleaning up... 08:52:52 INFO - Return code: 0 08:52:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:52 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 0x110372b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110375768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82dac59f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110150d30>, '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 0x110077db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110145be8>, '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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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:52 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:52 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:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f8tQTRwf2o/Render', 08:52:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:52 INFO - 'HOME': '/Users/cltbld', 08:52:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:52 INFO - 'LOGNAME': 'cltbld', 08:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:52 INFO - 'MOZ_NO_REMOTE': '1', 08:52:52 INFO - 'NO_EM_RESTART': '1', 08:52:52 INFO - 'PAGER': '/bin/cat', 08:52:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:52 INFO - 'PWD': '/builds/slave/test', 08:52:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:52 INFO - 'SHELL': '/bin/bash', 08:52:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYbw2ZBuDL/Listeners', 08:52:52 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 08:52:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:52 INFO - 'USER': 'cltbld', 08:52:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:52 INFO - 'XPC_FLAGS': '0x0', 08:52:52 INFO - 'XPC_SERVICE_NAME': '0', 08:52:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:52 INFO - Downloading/unpacking psutil>=3.1.1 08:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:52:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:56 INFO - Installing collected packages: psutil 08:52:56 INFO - Running setup.py install for psutil 08:52:56 INFO - building 'psutil._psutil_osx' extension 08:52:56 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:56 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:56 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:56 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:56 INFO - building 'psutil._psutil_posix' extension 08:52:56 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:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:52:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:52:56 INFO - ^ 08:52:56 INFO - 1 warning generated. 08:52:56 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:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:57 INFO - Successfully installed psutil 08:52:57 INFO - Cleaning up... 08:52:57 INFO - Return code: 0 08:52:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:57 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 0x110372b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110375768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82dac59f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110150d30>, '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 0x110077db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110145be8>, '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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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:57 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:57 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:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f8tQTRwf2o/Render', 08:52:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:57 INFO - 'HOME': '/Users/cltbld', 08:52:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:57 INFO - 'LOGNAME': 'cltbld', 08:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:57 INFO - 'MOZ_NO_REMOTE': '1', 08:52:57 INFO - 'NO_EM_RESTART': '1', 08:52:57 INFO - 'PAGER': '/bin/cat', 08:52:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:57 INFO - 'PWD': '/builds/slave/test', 08:52:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:57 INFO - 'SHELL': '/bin/bash', 08:52:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYbw2ZBuDL/Listeners', 08:52:57 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 08:52:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:57 INFO - 'USER': 'cltbld', 08:52:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:57 INFO - 'XPC_FLAGS': '0x0', 08:52:57 INFO - 'XPC_SERVICE_NAME': '0', 08:52:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:52:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:53:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:53:00 INFO - Installing collected packages: mozsystemmonitor 08:53:00 INFO - Running setup.py install for mozsystemmonitor 08:53:00 INFO - Successfully installed mozsystemmonitor 08:53:00 INFO - Cleaning up... 08:53:00 INFO - Return code: 0 08:53:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:01 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 0x110372b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110375768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82dac59f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110150d30>, '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 0x110077db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110145be8>, '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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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:53:01 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:53:01 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:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f8tQTRwf2o/Render', 08:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:01 INFO - 'HOME': '/Users/cltbld', 08:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:01 INFO - 'LOGNAME': 'cltbld', 08:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:01 INFO - 'MOZ_NO_REMOTE': '1', 08:53:01 INFO - 'NO_EM_RESTART': '1', 08:53:01 INFO - 'PAGER': '/bin/cat', 08:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:01 INFO - 'PWD': '/builds/slave/test', 08:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:01 INFO - 'SHELL': '/bin/bash', 08:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYbw2ZBuDL/Listeners', 08:53:01 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 08:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:01 INFO - 'USER': 'cltbld', 08:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:01 INFO - 'XPC_FLAGS': '0x0', 08:53:01 INFO - 'XPC_SERVICE_NAME': '0', 08:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:01 INFO - Downloading/unpacking jsonschema==2.5.1 08:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:53:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:53:04 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:53:04 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:53:04 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:53:04 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:53:04 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:53:04 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:53:04 INFO - Downloading functools32-3.2.3-2.tar.gz 08:53:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:53:04 INFO - warning: no files found matching '*.txt' 08:53:04 INFO - no previously-included directories found matching 'build' 08:53:04 INFO - no previously-included directories found matching 'dist' 08:53:04 INFO - no previously-included directories found matching '.git*' 08:53:04 INFO - Installing collected packages: jsonschema, functools32 08:53:04 INFO - Running setup.py install for functools32 08:53:04 INFO - warning: no files found matching '*.txt' 08:53:04 INFO - no previously-included directories found matching 'build' 08:53:04 INFO - no previously-included directories found matching 'dist' 08:53:04 INFO - no previously-included directories found matching '.git*' 08:53:04 INFO - Successfully installed jsonschema functools32 08:53:04 INFO - Cleaning up... 08:53:04 INFO - Return code: 0 08:53:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:04 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 0x110372b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110375768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82dac59f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110150d30>, '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 0x110077db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110145be8>, '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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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:53:04 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:53:04 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:53:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f8tQTRwf2o/Render', 08:53:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:04 INFO - 'HOME': '/Users/cltbld', 08:53:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:04 INFO - 'LOGNAME': 'cltbld', 08:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:04 INFO - 'MOZ_NO_REMOTE': '1', 08:53:04 INFO - 'NO_EM_RESTART': '1', 08:53:04 INFO - 'PAGER': '/bin/cat', 08:53:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:04 INFO - 'PWD': '/builds/slave/test', 08:53:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:04 INFO - 'SHELL': '/bin/bash', 08:53:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYbw2ZBuDL/Listeners', 08:53:04 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 08:53:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:04 INFO - 'USER': 'cltbld', 08:53:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:04 INFO - 'XPC_FLAGS': '0x0', 08:53:04 INFO - 'XPC_SERVICE_NAME': '0', 08:53:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:53:05 INFO - Cleaning up... 08:53:05 INFO - Return code: 0 08:53:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110372b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110375768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82dac59f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110150d30>, '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 0x110077db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110145be8>, '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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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:53:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f8tQTRwf2o/Render', 08:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:05 INFO - 'HOME': '/Users/cltbld', 08:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:05 INFO - 'LOGNAME': 'cltbld', 08:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:05 INFO - 'MOZ_NO_REMOTE': '1', 08:53:05 INFO - 'NO_EM_RESTART': '1', 08:53:05 INFO - 'PAGER': '/bin/cat', 08:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:05 INFO - 'PWD': '/builds/slave/test', 08:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:05 INFO - 'SHELL': '/bin/bash', 08:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYbw2ZBuDL/Listeners', 08:53:05 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 08:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:05 INFO - 'USER': 'cltbld', 08:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:05 INFO - 'XPC_FLAGS': '0x0', 08:53:05 INFO - 'XPC_SERVICE_NAME': '0', 08:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:05 INFO - Downloading/unpacking blobuploader==1.2.4 08:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:08 INFO - Downloading blobuploader-1.2.4.tar.gz 08:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:53:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:53:08 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:53:08 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:53:08 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:53:08 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:53:08 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:53:08 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:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:53:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:53:08 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:53:08 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:53:08 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:53:08 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:53:08 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:53:08 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:53:09 INFO - Downloading docopt-0.6.1.tar.gz 08:53:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:53:09 INFO - Installing collected packages: blobuploader, requests, docopt 08:53:09 INFO - Running setup.py install for blobuploader 08:53:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:53:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:53:09 INFO - Running setup.py install for requests 08:53:10 INFO - Running setup.py install for docopt 08:53:10 INFO - Successfully installed blobuploader requests docopt 08:53:10 INFO - Cleaning up... 08:53:10 INFO - Return code: 0 08:53:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:10 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 0x110372b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110375768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82dac59f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110150d30>, '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 0x110077db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110145be8>, '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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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:53:10 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:53:10 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:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f8tQTRwf2o/Render', 08:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:10 INFO - 'HOME': '/Users/cltbld', 08:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:10 INFO - 'LOGNAME': 'cltbld', 08:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:10 INFO - 'MOZ_NO_REMOTE': '1', 08:53:10 INFO - 'NO_EM_RESTART': '1', 08:53:10 INFO - 'PAGER': '/bin/cat', 08:53:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:10 INFO - 'PWD': '/builds/slave/test', 08:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:10 INFO - 'SHELL': '/bin/bash', 08:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYbw2ZBuDL/Listeners', 08:53:10 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 08:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:10 INFO - 'USER': 'cltbld', 08:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:10 INFO - 'XPC_FLAGS': '0x0', 08:53:10 INFO - 'XPC_SERVICE_NAME': '0', 08:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:10 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-OIHZti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:10 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-iG3p4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:10 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-LUpaSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:10 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-7eFkR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:11 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-aAkxdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:11 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-m78dhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:11 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-u5Nxq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:11 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-a3ms1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:11 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-hmSbMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:11 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-94VO_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:11 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-AkSlx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:11 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-NQ5HZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:11 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-PBRhUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:12 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-6xACsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:12 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-_L06YZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:12 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-SJ4bYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:12 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-aan_pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:53:12 INFO - Running setup.py install for manifestparser 08:53:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:53:12 INFO - Running setup.py install for mozcrash 08:53:12 INFO - Running setup.py install for mozdebug 08:53:12 INFO - Running setup.py install for mozdevice 08:53:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Running setup.py install for mozfile 08:53:13 INFO - Running setup.py install for mozhttpd 08:53:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Running setup.py install for mozinfo 08:53:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Running setup.py install for mozInstall 08:53:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Running setup.py install for mozleak 08:53:13 INFO - Running setup.py install for mozlog 08:53:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:53:13 INFO - Running setup.py install for moznetwork 08:53:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:53:14 INFO - Running setup.py install for mozprocess 08:53:14 INFO - Running setup.py install for mozprofile 08:53:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:53:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:53:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:53:14 INFO - Running setup.py install for mozrunner 08:53:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:53:14 INFO - Running setup.py install for mozscreenshot 08:53:14 INFO - Running setup.py install for moztest 08:53:14 INFO - Running setup.py install for mozversion 08:53:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:53:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:53:14 INFO - Cleaning up... 08:53:14 INFO - Return code: 0 08:53:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:15 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:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:15 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 0x110372b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110375768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82dac59f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110150d30>, '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 0x110077db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110145be8>, '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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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:53:15 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:53:15 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:53:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f8tQTRwf2o/Render', 08:53:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:15 INFO - 'HOME': '/Users/cltbld', 08:53:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:15 INFO - 'LOGNAME': 'cltbld', 08:53:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:15 INFO - 'MOZ_NO_REMOTE': '1', 08:53:15 INFO - 'NO_EM_RESTART': '1', 08:53:15 INFO - 'PAGER': '/bin/cat', 08:53:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:15 INFO - 'PWD': '/builds/slave/test', 08:53:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:15 INFO - 'SHELL': '/bin/bash', 08:53:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYbw2ZBuDL/Listeners', 08:53:15 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 08:53:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:15 INFO - 'USER': 'cltbld', 08:53:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:15 INFO - 'XPC_FLAGS': '0x0', 08:53:15 INFO - 'XPC_SERVICE_NAME': '0', 08:53:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:15 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-r3JuxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:15 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:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:15 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-Ixa_Nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:15 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:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:15 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-USEOH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:15 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:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:15 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-rNbUoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:15 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:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:15 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-sm5oTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:15 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:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:15 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-yWiGUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:15 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:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:15 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-coLsqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:16 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:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:16 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-hmwCCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:16 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:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:16 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-DDbwMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:16 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:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:16 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-A4h3dO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:16 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:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:16 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-iZphq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:16 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:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:16 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-UzVjaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:16 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-EFtb0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:16 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:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:16 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-NRQpY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:16 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:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:16 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-bpKKgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:16 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:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:16 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-CZVFWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:16 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:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:16 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-oxXxGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:17 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:53:17 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:53:17 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:53:17 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:53:17 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:53:17 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:53:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:19 INFO - Downloading blessings-1.6.tar.gz 08:53:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:53:19 INFO - Installing collected packages: blessings 08:53:19 INFO - Running setup.py install for blessings 08:53:19 INFO - Successfully installed blessings 08:53:19 INFO - Cleaning up... 08:53:20 INFO - Return code: 0 08:53:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:53:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:20 INFO - Reading from file tmpfile_stdout 08:53:20 INFO - Current package versions: 08:53:20 INFO - blessings == 1.6 08:53:20 INFO - blobuploader == 1.2.4 08:53:20 INFO - docopt == 0.6.1 08:53:20 INFO - functools32 == 3.2.3-2 08:53:20 INFO - jsonschema == 2.5.1 08:53:20 INFO - manifestparser == 1.1 08:53:20 INFO - mozInstall == 1.12 08:53:20 INFO - mozcrash == 1.0 08:53:20 INFO - mozdebug == 0.1 08:53:20 INFO - mozdevice == 0.49 08:53:20 INFO - mozfile == 1.2 08:53:20 INFO - mozhttpd == 0.7 08:53:20 INFO - mozinfo == 0.9 08:53:20 INFO - mozleak == 0.1 08:53:20 INFO - mozlog == 3.4 08:53:20 INFO - moznetwork == 0.27 08:53:20 INFO - mozprocess == 0.24 08:53:20 INFO - mozprofile == 0.28 08:53:20 INFO - mozrunner == 6.13 08:53:20 INFO - mozscreenshot == 0.1 08:53:20 INFO - mozsystemmonitor == 0.3 08:53:20 INFO - moztest == 0.8 08:53:20 INFO - mozversion == 1.4 08:53:20 INFO - psutil == 3.1.1 08:53:20 INFO - requests == 1.2.3 08:53:20 INFO - wsgiref == 0.1.2 08:53:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:20 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:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:20 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:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:20 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 0x110372b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110375768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82dac59f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110150d30>, '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 0x110077db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110145be8>, '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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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:53:20 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:53:20 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:53:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f8tQTRwf2o/Render', 08:53:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:20 INFO - 'HOME': '/Users/cltbld', 08:53:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:20 INFO - 'LOGNAME': 'cltbld', 08:53:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:20 INFO - 'MOZ_NO_REMOTE': '1', 08:53:20 INFO - 'NO_EM_RESTART': '1', 08:53:20 INFO - 'PAGER': '/bin/cat', 08:53:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:20 INFO - 'PWD': '/builds/slave/test', 08:53:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:20 INFO - 'SHELL': '/bin/bash', 08:53:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYbw2ZBuDL/Listeners', 08:53:20 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 08:53:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:20 INFO - 'USER': 'cltbld', 08:53:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:20 INFO - 'XPC_FLAGS': '0x0', 08:53:20 INFO - 'XPC_SERVICE_NAME': '0', 08:53:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:20 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:53:20 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:53:20 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:53:20 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:53:20 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:53:20 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:53:20 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:53:20 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:53:20 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:53:20 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:53:20 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:53:20 INFO - Cleaning up... 08:53:20 INFO - Return code: 0 08:53:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:53:20 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:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:20 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:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:20 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 0x110372b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110375768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82dac59f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110150d30>, '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 0x110077db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110145be8>, '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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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:53:20 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:53:20 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:53:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f8tQTRwf2o/Render', 08:53:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:20 INFO - 'HOME': '/Users/cltbld', 08:53:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:20 INFO - 'LOGNAME': 'cltbld', 08:53:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:20 INFO - 'MOZ_NO_REMOTE': '1', 08:53:20 INFO - 'NO_EM_RESTART': '1', 08:53:20 INFO - 'PAGER': '/bin/cat', 08:53:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:20 INFO - 'PWD': '/builds/slave/test', 08:53:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:20 INFO - 'SHELL': '/bin/bash', 08:53:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYbw2ZBuDL/Listeners', 08:53:20 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 08:53:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:20 INFO - 'USER': 'cltbld', 08:53:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:20 INFO - 'XPC_FLAGS': '0x0', 08:53:20 INFO - 'XPC_SERVICE_NAME': '0', 08:53:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:53:20 INFO - Cleaning up... 08:53:20 INFO - Return code: 0 08:53:20 INFO - Running post-action listener: _resource_record_post_action 08:53:20 INFO - Running post-action listener: _start_resource_monitoring 08:53:20 INFO - Starting resource monitoring. 08:53:20 INFO - [mozharness: 2017-02-13 16:53:20.852804Z] Finished create-virtualenv step (success) 08:53:20 INFO - [mozharness: 2017-02-13 16:53:20.853788Z] Running install step. 08:53:20 INFO - Running pre-action listener: _resource_record_pre_action 08:53:20 INFO - Running main action method: install 08:53:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:21 INFO - Reading from file tmpfile_stdout 08:53:21 INFO - Detecting whether we're running mozinstall >=1.0... 08:53:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:53:21 INFO - Reading from file tmpfile_stdout 08:53:21 INFO - Output received: 08:53:21 INFO - Usage: mozinstall [options] installer 08:53:21 INFO - Options: 08:53:21 INFO - -h, --help show this help message and exit 08:53:21 INFO - -d DEST, --destination=DEST 08:53:21 INFO - Directory to install application into. [default: 08:53:21 INFO - "/builds/slave/test"] 08:53:21 INFO - --app=APP Application being installed. [default: firefox] 08:53:21 INFO - mkdir: /builds/slave/test/build/application 08:53:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:53:43 INFO - Reading from file tmpfile_stdout 08:53:43 INFO - Output received: 08:53:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:53:43 INFO - Running post-action listener: _resource_record_post_action 08:53:43 INFO - [mozharness: 2017-02-13 16:53:43.327234Z] Finished install step (success) 08:53:43 INFO - [mozharness: 2017-02-13 16:53:43.327388Z] Running run-tests step. 08:53:43 INFO - Running pre-action listener: _resource_record_pre_action 08:53:43 INFO - Running main action method: run_tests 08:53:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:53:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:53:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:53:43 INFO - 2017-02-13 08:53:43.342 screenresolution[1907:17746] starting screenresolution argv=screenresolution get 08:53:43 INFO - 2017-02-13 08:53:43.344 screenresolution[1907:17746] Display 0: 1600x1200x32@60 08:53:43 INFO - 2017-02-13 08:53:43.350 screenresolution[1908:17748] starting screenresolution argv=screenresolution list 08:53:43 INFO - Available Modes on Display 0 08:53:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:53:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:53:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:53:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:53:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:53:43 INFO - Intel Iris: 08:53:43 INFO - Chipset Model: Intel Iris 08:53:43 INFO - Type: GPU 08:53:43 INFO - Bus: Built-In 08:53:43 INFO - VRAM (Dynamic, Max): 1536 MB 08:53:43 INFO - Vendor: Intel (0x8086) 08:53:43 INFO - Device ID: 0x0a2e 08:53:43 INFO - Revision ID: 0x0009 08:53:43 INFO - Displays: 08:53:43 INFO - Display: 08:53:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:53:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:53:43 INFO - Display Serial Number: 1600x1200 60 08:53:43 INFO - Main Display: Yes 08:53:43 INFO - Mirror: Off 08:53:43 INFO - Online: Yes 08:53:43 INFO - Rotation: Supported 08:53:43 INFO - Return code: 0 08:53:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:53:43 INFO - Python 2.7.3 08:53:43 INFO - Return code: 0 08:53:43 INFO - grabbing minidump binary from tooltool 08:53: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:53:43 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 0x110150d30>, '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 0x110077db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110145be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:53:43 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:43 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:43 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:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:53:43 INFO - Return code: 0 08:53:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:53:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:53:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:53:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:53:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:53:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0269', '--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:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0269 --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:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f8tQTRwf2o/Render', 08:53:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:43 INFO - 'HOME': '/Users/cltbld', 08:53:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:43 INFO - 'LOGNAME': 'cltbld', 08:53:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:53:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:43 INFO - 'MOZ_NO_REMOTE': '1', 08:53:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:53:43 INFO - 'NO_EM_RESTART': '1', 08:53:43 INFO - 'PAGER': '/bin/cat', 08:53:43 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:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:43 INFO - 'PWD': '/builds/slave/test', 08:53:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:53:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:43 INFO - 'SHELL': '/bin/bash', 08:53:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYbw2ZBuDL/Listeners', 08:53:43 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 08:53:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:43 INFO - 'USER': 'cltbld', 08:53:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:43 INFO - 'XPC_FLAGS': '0x0', 08:53:43 INFO - 'XPC_SERVICE_NAME': '0', 08:53:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0269', '--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:43 INFO - mozversion application_buildid: 20170213070640 08:53:43 INFO - mozversion application_changeset: e1a4314f8e6eae8bbc06394c14132a9c5011371b 08:53:43 INFO - mozversion application_display_name: Nightly 08:53:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:53:43 INFO - mozversion application_name: Firefox 08:53:43 INFO - mozversion application_remotingname: firefox 08:53:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:53:43 INFO - mozversion application_vendor: Mozilla 08:53:43 INFO - mozversion application_version: 54.0a1 08:53:43 INFO - mozversion platform_buildid: 20170213070640 08:53:43 INFO - mozversion platform_changeset: e1a4314f8e6eae8bbc06394c14132a9c5011371b 08:53:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:53:43 INFO - mozversion platform_version: 54.0a1 08:53:43 INFO - using testdate: 1487004823 08:53:43 INFO - actual date: 1487004823 08:53:43 INFO - starting webserver on 'localhost:53485' 08:53:43 INFO - SUITE-START | Running 2 tests 08:53:43 INFO - TEST-START | dromaeo_css 08:53:43 INFO - operating with platform_type : mac_ 08:53:43 INFO - Initialising browser for dromaeo_css test... 08:53:43 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmpmM502p/profile http://localhost:53485/getInfo.html) 08:53:46 INFO - PROCESS | 1918 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 08:53:46 INFO - PROCESS | 1918 | 2017-02-13 08:53:46.642 plugin-container[1920:17910] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 08:53:46 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:46 INFO - PROCESS | 1918 | 2017-02-13 08:53:46.645 plugin-container[1920:17910] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 08:53:46 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:46 INFO - PROCESS | 1918 | 2017-02-13 08:53:46.645 plugin-container[1920:17910] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:53:46 INFO - PROCESS | 1918 | __metrics Screen width/height:1600/1200 08:53:46 INFO - PROCESS | 1918 | colorDepth:24 08:53:46 INFO - PROCESS | 1918 | Browser inner width/height: 1024/689 08:53:46 INFO - PROCESS | 1918 | __metrics 08:53:46 ERROR - PROCESS | 1918 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 08:53:47 INFO - TEST-INFO | 1918: exit 0 08:53:47 INFO - Browser initialized. 08:53:47 INFO - Running cycle 1/1 for dromaeo_css test... 08:53:47 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmpmM502p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:53:49 INFO - PROCESS | 1921 | 2017-02-13 08:53:49.949 plugin-container[1922:18031] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 08:53:49 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:49 INFO - PROCESS | 1921 | 2017-02-13 08:53:49.950 plugin-container[1922:18031] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 08:53:49 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:49 INFO - PROCESS | 1921 | 2017-02-13 08:53:49.950 plugin-container[1922:18031] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:53:59 INFO - PROCESS | 1921 | RSS: Main: 233807872 08:53:59 INFO - PROCESS | 1921 | 08:57:02 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53485/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53485/tests/dromaeo/cssquery-ext.html) 08:57:04 INFO - PROCESS | 1921 | RSS: Main: 255021056 08:57:04 INFO - PROCESS | 1921 | 08:58:47 INFO - PROCESS | 1921 | ************************* 08:58:47 INFO - PROCESS | 1921 | A coding exception was thrown and uncaught in a Task. 08:58:47 INFO - PROCESS | 1921 | 08:58:47 ERROR - PROCESS | 1921 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:47 INFO - PROCESS | 1921 | Full stack: 08:58:47 INFO - PROCESS | 1921 | ************************* 09:00:06 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53485/tests/dromaeo/cssquery-ext.html (next: http://localhost:53485/tests/dromaeo/cssquery-jquery.html) 09:00:07 INFO - PROCESS | 1921 | RSS: Main: 259387392 09:00:07 INFO - PROCESS | 1921 | 09:03:09 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53485/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53485/tests/dromaeo/cssquery-mootools.html) 09:03:10 INFO - PROCESS | 1921 | RSS: Main: 271089664 09:03:10 INFO - PROCESS | 1921 | 09:06:12 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53485/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53485/tests/dromaeo/cssquery-prototype.html) 09:06:12 INFO - PROCESS | 1921 | RSS: Main: 263782400 09:06:12 INFO - PROCESS | 1921 | 09:09:15 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53485/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53485/tests/dromaeo/cssquery-yui.html) 09:09:16 INFO - PROCESS | 1921 | RSS: Main: 267714560 09:09:16 INFO - PROCESS | 1921 | 09:12:19 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53485/tests/dromaeo/cssquery-yui.html (next: http://localhost:53485/tests/dromaeo/cssquery-dojo.html) 09:12:19 INFO - PROCESS | 1921 | RSS: Main: 269029376 09:12:19 INFO - PROCESS | 1921 | 09:12:19 INFO - PROCESS | 1921 | __start_tp_report 09:12:19 INFO - PROCESS | 1921 | _x_x_mozilla_page_load 09:12:19 INFO - PROCESS | 1921 | _x_x_mozilla_page_load_details 09:12:19 INFO - PROCESS | 1921 | |i|pagename|runs| 09:12:19 INFO - PROCESS | 1921 | |0;dojo.html;2689.488997090553;3220.5829435048563;3302.9339413211737;3334.299797042621;3344.832758362081;4906.730129842857;5599.244102046223;5602.887942241158;5634.633748806335;5638.351589567201;5491.670499770016;5571.303587051619;5573.498385145336;5642.463965923241;5673.404292549285;5006.424261209952;5052.646314757959;5077.060743762398;5230.293910322115;5238.79044838206;5143.074246635604;5185.611079169059;5235.481710304008;5244.632875698683;5284.8150314739;4153.854615088488;4975.378077740283;5004.574611158056;5053.5957123430035;5109.6167787415725;5094.414142373637;5115.155999260118;5138.537531622134;5150.819721309774;5211;5084.872878178076;5113.667611605234;5135.589152867799;5143.842635407041;5169.172932330809;5348.8127915523155;5393.19102134679;5418.349798024223;5437.293151890269;5597.3003374578175;6018.337982821886;6053.939460605382;6066.545009124289;6068.059450785136;6117.541184411187;6118.817507115225;6127.617148554337;6138.051223665319;6143.204841796972;6153.569250152491;5701.942980570224;5765.682887441189;5798.275215598051;5798.797042103548;5810.082672717993;6011.09833524966;6045.244344456943;6106.2061931948565;6107.704691583834;6144.07838824171;5985;6014.308354539196;6056.878862422815;6073.271207455134;6123.008104567321;6048.092786082123;6111.51107911366;6111.572189946661;6118.724657390429;6120.418560236771;6137.171481849893;6140.201773769381;6140.662263575457;6141.877162456726;6151.138840562325;6018.127371531104;6042.788502402394;6052.54605904559;6097.420745029215;6137.355577664371;4923.48303428142;4924.581410580141;4925.261210818406;4936.3829521309835;4946.777395017233;3726.5341832270965;4416.668749843775;4421.624161946271;4443.311286750559;4463.285874260103;4660.86017419478;5504.724763761796;5543.223948647193;5554.805581804618;5581.079121944915;6069.241344831896;6100.450959413674;6116.969415152896;6130.122981477904;6130.184124957599;5697.829065128053;5709.172170035405;5733.655980641175;5755;5792;5184.740762961912;5353.9196912046355;5358.97320513395;5360.946390536123;5392.91910621341;6025.969870150621;6044.274687037496;6063.302720187058;6100.969495152496;6144.201253836973;5902.2622172228475;5983.611065280743;6018.187544681412;6031.276246850406;6053.334133245427;6056.848578785565;6090.512758979359;6091.299500557493;6103.42017508336;6105.48103411215;6011;6019.277686677627;6022.909656355246;6033.42682445167;6083.452489275986;5850.683596190993;6124.958757011408;6142.416470435302;6146.6004709693725;6189.102580125947;5728.770849165987;5776.978057679778;5841.036229022164;5883.705814709162;6046.546509011754;5892.410758924073;5974.40255974399;5981.521478281814;6069.605475644069;6108.236470441195;4933.925991110208;5578.023845827045;5902.1736956826935;5919.378465261086;5928.110783382527;5960.582759206987;6042.909356359746;6071.939280607137;6095.1771510845465;6098.176746139215;5602.047651899105;5675.858103547444;5691.743871525874;5742.483176514133;5765.279340082489 09:12:19 INFO - PROCESS | 1921 | |1;ext.html;481.5040508276475;507.2011581758729;509.0175960396436;524.1654210264074;538.4534697282256;31667.049988500323;35541.64458355419;37865;38069.619303806954;38159.427608585895;34975.950721478395;37155.25689486209;39125;39949.80025099878;40008.79995600018;8891.199792018717;10503.789924201512;10905.945470272638;10934.890651093467;11077.944610276938;10688.839667404995;10736.838947415756;10744.409057501834;10758.354498730063;10782.299150555189;10710.571577136905;10722.517486713077;10748.516316765725;10773.407462589554;10777.137828973664;10663.413512256822;10686.572537098507;10714.946425267863;10760.677179684544;10776.461176941122;10577.471126443646;10582.947085264525;10625.681229563126;10657.467126643714;10747.731306717395;35256;35586.64413355859;35625.82187089074;35674.28651426957;35736.92789216328;3671.320805650963;3682.2635472905527;3715.7366495391425;3725.5715592706915;3859.826307816155;23974;25487.853046612763;26287.474250515075;27283.45433091327;27512.449751005068;2621.8307131578154;2976.791624586258;3081.7534597232166;3084.3985422842557;3192.984035079833;453442;453793;454806;456017.7199113983;459083;45138.32292515615;46153.69232538234;51816;53011.73494132582;53030.73484632552;55135.72432137813;55363.16955245675;55396;55430.445695543334;55811.72094139585;13984.51054213118;14041.017128800886;14339.569812905629;14364.425422982964;14430.711385772225;10980.780384392267;11065.114790816715;11066.225364224589;11072.944635276772;11105.111591072695;16518.6696266074;16618;16685.165741712866;16697.332106715596;16763.7427192961;7575.734849280248;7591.734289299959;7602.72401862548;7613.467057305936;7656.349210317074;822.1326500541985;834.5451728807693;837.5226121111031;838.6226198210829;846.4836449765638;28477.288067798057;28598.428031439256;28610.427791444054;28839.423211535654;29203.85398072996;1002.7292630989592;1012.250934308621;1034.4052170002278;1041.9114375278034;1052.6578861869923;13076;14529.636759081108;14675.412983480755;14866.662000420014;15205.695886082216;523290;549678.53536111;552350;559030;559787;49223;51870;55886.16170757442;55920.72039639776;56301.43698563044;1325.8939284857188;1361.1492816989382;1370.8286464191976;1371.4925477573163;1377.2631642071644;27488.17535473939;27774;27787.44425111446;28112.71887281101;28436.86252549875;45409.77295113503;45734.085318294114;45875.85310367268;45931.851703707674;45972.310415344466;505835;508037.4598126986;508708;509958;511071;22001.559968800855;22547.5490490186;23069;24515.38711532226;24789.380265493506;42494.575054249064;42709.78645106754;42746.93132671708;42779;42795.502157424344;29492.852535737184;34129.80545680889;34483.82758086194;36386.27227455489;36409.453858192675;31204.53193202149;35180.824095879354;35217.82391088028;36428.4535731959;36651;34645.9606211814;34908.65091349054;35095;35250.823745881105;35477.46783798194;26950.730492694824;30100.397992040474;30156.09531714052;31718;31973.36053278968;23465.41336466602;23628.409289767893;23714.88142559276;23849.64225536653;23963.52072958566 09:12:19 INFO - PROCESS | 1921 | |2;jquery.html;353.3075172661583;388.6405075305342;399.5046142782949;402.27791114948667;420.84849454196484;18674.533136671573;19386.93371864547;20886.791132088674;21085.26201582945;21111.47221319466;11802.58690945817;12120;12136.575219867307;12227.69430764229;20595.38213853583;5051.6463847530595;5294.52349288564;5300.337457817773;5319.760610772505;5320.76056577453;4987.376577927759;5224.555912747402;5375.22059301403;5805.100209467518;5866.6186697864505;5794.68129252891;5818.912716309259;5827.300723913137;5836.095405212199;5842.0652695568715;5433.1578605316045;5456.154296084113;5603.271574695284;5775.5957083004205;5819.854503637401;4608.953910460886;5207.661502002357;5219.791208351661;5241.8165364212455;5244.213367994793;13665.248411337372;13818.170909745448;23373.649395259086;24157.637635435483;25155.49689006228;2444.4499987502845;2483.925482235536;2487.0692217752294;2524.343670645627;2553.3361326055174;8396.496210227406;9572.377795443273;10498.685039448828;10997.835032474519;14890.032147910459;1766.549529869875;1837.338558119076;1839.9632007359912;1859.9628007439776;1865.4310435317223;114608.85391146065;128290.3585482076;137622;139878;140177;9501.572429240701;9813.705588832347;11900.523979040914;12058.095642826824;12092;15182.544523664308;15923.283452244445;15960;15980.440684575984;15998.440054598033;8172.42793004484;8470.025947016173;8472.534010629475;8580.95709521461;8593.742187734379;6667.799966001027;7951.483153594998;8000.719974800854;8023.358131349478;8163.551004694798;4116.485439320085;4877.853664390074;4879.487653796371;4891.51084891508;4903.705777653352;1761.4803632928317;1854.6290741851683;2042.8365730741803;2083.531205478755;2091.853570250068;809.6437567470294;840.6049156896249;863.4603372891943;869.5652173913044;878.8769572259889;20782.79217207839;22238.110475581117;22312.995915183856;22371.10515579391;22721.431964200696;854.7264875239988;858.9226969572643;889.7330800759746;890.1054440287598;899.3614533681028;10500.632477863392;11535.884641153649;11564.653060408202;11626.825597616042;11732;651830;691095;692284;693719;697507;140893.29553352372;156469;158023;165184;167447;851.7572491839796;1009.8939611340851;1038.5741845843168;1044.8223801953534;1048.7153237284265;19700;19752.703709444082;20106.798932010493;20116.798832011493;20188.091535880627;59734.70132649309;60302;60679.393206067376;61095.38904611075;61688;86392;86907;87290.56354718185;87937;89782;8221.342292616697;8354.289885359813;8698.695545655872;8706.608202631023;8801.779955500908;12619.684507887376;13280.281521174087;13333.000798084675;13430.865691342962;13505.12216705911;10763.600731904802;11174;11483.885161148282;11584.826227606762;11599.76800464003;9304.209142222986;10043.447610381207;11050.171237157407;11189.888101118884;11689.707757305796;11092.389918554558;11234;11513.827292590451;11652.53389864396;11658.65024049246;8373.413861029669;8533.871991920001;8558.82882342362;8614.569271536524;8769.47383156987;8573.571321434029;10794.40630765284;10807.945960270148;11192.272502287326;11244 09:12:19 INFO - PROCESS | 1921 | |3;mootools.html;1353.15427857589;1414.801927730118;1427.0581416265268;1436.2406052585584;1443.6896067345522;8200.318934618404;8232.958835205816;8448.324134069275;8642.006169290526;8649.264812490937;8424.199701028409;8542.572871356439;8544.27373673237;8550.95724521378;8590.87113693295;2246.8314876384256;2278.6582012698063;2281.9885900570475;2284.189112864934;2298.9540209195807;2207.61366760817;2247.1910112359537;2263.818894488445;2273.522560262347;2283.9885800570974;2248.0895237428867;2262.3891549281684;2276.692646492727;2280.429892526868;2283.668868014137;1905.2759951218518;2220.2562141682556;2226.543558570489;2257.9774202258095;2263.7736226377397;1954.0522846419476;2041.652919003773;2199.670049492573;2219.611567975614;2289.8969546370286;8424.157584241588;8538.018127915308;8561.1866872647;8567.228949394585;8600;5121.385433747974;5281.102212623863;5288.312519372495;5288.444713305086;5294.232336311214;2840.3751174741524;2851.6863145054026;3391.3047825195777;3436.8453419595926;3516.753827232095;1196;1198.3349241171147;1217.8148921363936;1228.9446974886152;1251.5181655062775;10350.534225959775;10846.511906964206;10904.127669786398;10929.016388524991;10972.835407468894;4145.689073319513;4426.734395936254;4500.504944456107;4527.886802829889;4539.7049191802425;8815.294776417872;8863.867041994376;8872.512011839282;8875.289976801969;8898.243649289754;5044.117279476077;5106.540411362995;5119.795208191705;5137.0753264412015;5144.99672563854;4636.420447444069;4679.532046795362;4723.338732577442;4726.456457507431;4807.711537307773;3584.121890136933;3626.4741612466573;3628.8185590720886;3690.6862916652385;3746.063484128968;983.9508024598741;987.8863930648067;998.3161534348994;1014.3356101753363;1014.9951547967459;743.9516431431939;745.0947099274408;769.1616138409161;772.0927909706073;804.3766081286932;5395.676259424447;5454.236406903036;5563.248961390161;5633.042382794934;5677.091665333527;472.6998356043937;520.8255234496411;531.4472948133897;571.8027280588191;580.51236960953;3415.3852306585054;3795.658390744791;3849.1339448623835;3894.1043559981363;3908.5309762828642;9662.729258624218;10101.444420556792;10101.494925253708;10104.747381315527;10111.090001899716;8874.890638670166;10055.49722513886;10071.949640251752;10081.344712593806;10092.697219083439;7593.544387336778;7745.767626971087;7777.105632852295;7913.762587122281;7914.287714105643;5419.512243898068;5528.585356098229;5600.803971861128;5655.632383895034;5733.197352370588;7739.7678069658;9020.774480638036;9045.050269721847;9146.405483643543;9350.438973661601;9136.406133601295;9318.767030824283;9408.576614052246;9464.479453630116;9476.431414115175;6821.488388370793;6897.517173797786;6904.447644188353;6907.447404207551;6919.273476284918;7834.843303134019;7852.960735196288;7937.365010799238;8000.439969202333;8007.719729809429;6264.498840092872;7053.647317634201;7153.892691609734;7163.935486760628;7196.532225405332;6987.266337034538;7018.684159212746;7032.835820895522;7199.1001124859395;7225.891611625935;5448.291722076105;6074.878502429839;6112.541559382975;6393;6465.547411681326;4042.8180731867724;4925.679830810986;4995.8751031223055;5027.64806463544;5249.028929648028;3972.781497017692;4727.763611819327;4885.8045678172475;4936.481669424658;5217.452167522356 09:12:19 INFO - PROCESS | 1921 | |4;prototype.html;296.30516438950656;350.2906614106434;369.9741018128732;371.6005294308619;374.33181770539574;4459.598636122749;5349.652272602283;5413.26920865683;5499.312585926759;5529.668219906798;4951.900961980758;5369.785208591652;5435.402105768372;5440.537554307879;5447.972760136204;3416.0264324667473;3734.8692795752154;3942.5412597339023;4098.672106231495;4124.896877578055;3521.2605352875926;3930.1353702185475;4079.796010199478;4112.321466957948;4123.381492776092;4085.487425233475;4097.631213190813;4100.917981640366;4115.32097203961;4123.711340206187;3481.7388695847912;3621.4567814827724;3722.4416337549314;4124.8143833527565;4129.463169787939;3410.5054767058637;3490.5636795400574;3508.0878971467346;3508.7543871928974;3512.947305790415;5211.661242019257;5255.448177941299;5268.4994925482015;5298.523132918056;5298.761555729963;3897.4738410314526;3984.521857377133;4261.957380426187;4543.136804007228;4700.858974230744;4754.001659651477;5625.803096891589;5643.322801263834;5654.180143879155;5711.458401011551;3677.2093999789904;3682.2635472905527;3756.0234339071767;3907.960920390788;3937.684985201177;5350.0904846176245;5448.618596698233;5701.14482827575;5800.680962547058;6048.848778780522;3646.085804952368;3671.596124426311;3851.8251933160095;3864.806759662006;3879.592642772525;3581.283743251308;3970.841166353371;3980.800959951991;3985.6412922836503;4039.8182081805803;3961.445397644332;4109.239790638741;4256.425382573313;4422.425084738963;4709.340692303081;5360.123956615232;5474.084070575346;5677.943220567824;5726.570507211975;5815.41845815415;1202.298747272617;1291.754566632337;1292.7091404434116;1296.2805642868298;1300.2133709106045;731.389289942893;745.4930647159875;779.2013186483786;870.9695160669346;872.3326655108847;4435.933460998088;4585.472670642852;5053.722045287471;5071.721055341918;5135.460776618476;5137.2037334213255;5370.140777475585;5381.78472861089;5533.806316778893;5669.177969194526;4608.88477788058;4786.16242157621;4844.89247177479;5034.439323809631;5044.991001799654;2660.4546068056;2926.6976195593184;2953.278868625547;2965.56170257425;2968.5157421289355;5089.465606111379;5543.695096769717;5625.17794083122;5755.280589926259;5793.21925475774;5432.483914028161;5637.68992705397;5660.5471562274915;5672.808710170828;5854.887571361428;5042.596592272536;5293.814716484904;5457.836264912059;5813.534917206698;5844.480435086993;5306.761195746278;5336.17289320156;5632.5493960482245;5678.955575992722;5680.375158732453;4829.879253018703;5253.894922101613;5424.945750542444;5456.407182637828;5895.882082358243;4868.92883565615;5805.912911306418;5854.063349864172;5928.67392293428;6004.639721616717;3755.4366844973474;4110.753354798721;4286.614204721465;4288.7855607220135;4303.698741088094;5931.584789064724;5943.346231914573;5943.673097979479;5976.432238937294;5981.700914954322;5601.943980560142;5630.5214056805635;5640.520555752807;5724.79963201286;5725.570582206435;5261.736913154404;5401.72991350423;5479.561635069264;5519.8896022080135;5737.5983681141915;5182.689038657692;5205.687658740488;5593.461798005418;5617.775288988395;5639.971800140973;5610.551155907599;5695.772169113189;5744.6265992712015;5751.84305885293;5762.971185144047;5176.741162941913;5242.790288388422;5583.888322233452;5633.408492108433;5647.15292706081 09:12:19 INFO - PROCESS | 1921 | |5;yui.html;667.8330417395651;709;725.5719125715829;725.5864157430264;773.5320131320551;14187.64530886727;14681.412743490248;15275.38898444061;15297.847021529782;15308.617284567892;14003.929980350083;14242.643933901632;14246;14269.215193164371;14275.78586321206;857.5797859049052;875.0724232313736;875.2035647560721;876.7369789063288;878.7759121424028;845.8562044452458;873.1181506678269;874.0953113527501;874.4796845876725;874.514644372373;919.062556192683;923.833709932212;938.5870217104487;941.0119374656617;941.5245301122924;930.4463844012802;932.4032619123766;935.9953200233955;936.1060187520935;939.2345238630528;684.4695361095141;685.2873012067444;691.9238883722786;692.9480288978347;693.7363801755351;15175.924120379437;15450.381984720598;15453.768193476995;15605.68788624221;15629.609259768484;13709.862901370958;13722.313884305744;13988.93005534976;14004.579862604036;14017.859821401757;10755.677329680122;11243.550257989671;11496.540138394454;11563.294659885556;11682.824757628643;3548.183917698918;3606.6032736398975;3615.475756015363;3646.361886669822;3742;90991.54504227503;105144;106555.46722266338;107694;108642.45678771555;10557;10702.949197502367;10768.407737574353;10774.892251077546;10926.617568385069;18142.274309027754;22326.55346893053;22334.441638959157;22339.55320893573;22512;7393.55638661682;7419.29516695913;7518.624068796647;7614.771556853303;7645.006149200568;3591.6049234584175;4041.131156801243;4146.398772178018;4161.831285456025;4168.520620128664;3568.1614820517157;3658.725595580342;3663.3222853772136;3665.065408320861;3678.2091850252323;650.7982525417136;658.2101478226148;669.8358902068927;671.4829581222529;678.6064082831946;630.1677970384156;640.2029473305764;676.235853485568;680.4692339974828;706.0961968680098;9530.094641009093;9840.803183936425;9885.604575817031;10088.69733907984;10207.285490015776;620.3362402229661;645.6352161029004;680.7072958627838;682.969266383014;688.789919074677;4497.257952437876;4843.75781210938;4854.834839638462;4893.339399181065;4904.386951631046;84814.5759271212;95539.76271001223;95718;99772.50113749385;101897;86057.84855378665;86725;87281.56359218163;87685.91611964579;88750;73398;77001.61499192468;77639.61180194176;78973.21026789659;79913.20086799057;8897.377183597346;9251.213646840093;9292.303077269367;9371.156595906401;9447.52762361893;49111.75444122757;49415.752921235166;50008;51136.48863511466;52127;61493.69253153705;64797.67601162152;65412.67293663692;68163;68352.31647683459;10872.347659140476;10885.346879187275;11026.566546348924;11154.721131971766;11632.243904146204;12369.196002259825;12373;12374;12493.562725304571;12900.741985160057;11516.654500365003;11520.423978801195;11526.88473115258;11688.824667629822;11771.646850594496;11348.557129113102;11721.179517433698;12024.398780061138;12042;12053.156279060382;11257.71855703614;11401.771964560496;11411.885881141414;11421;11969.102317326062;9683;9900.801983960424;9956.452395118049;9997.100260976547;10244.984443463123;8439.493630382196;8486.321094312316;9831.524814597975;9887.890262482739;9923.851142233018 09:12:19 INFO - PROCESS | 1921 | __end_tp_report 09:12:19 INFO - PROCESS | 1921 | __start_cc_report 09:12:19 INFO - PROCESS | 1921 | _x_x_mozilla_cycle_collect,425 09:12:19 INFO - PROCESS | 1921 | __end_cc_report 09:12:19 INFO - PROCESS | 1921 | __startTimestamp1487005939465__endTimestamp 09:12:19 INFO - PROCESS | 1921 | 09:12:19 INFO - PROCESS | 1921 | ------- Summary: start ------- 09:12:19 INFO - PROCESS | 1921 | Number of tests: 6 09:12:19 INFO - PROCESS | 1921 | 09:12:19 INFO - PROCESS | 1921 | [#0] dojo.html Cycles:165 Average:5603.03 Median:5798.80 stddev:647.75 (11.2%) stddev-sans-first:608.08 09:12:19 INFO - PROCESS | 1921 | Values: 2689.5 3220.6 3302.9 3334.3 3344.8 4906.7 5599.2 5602.9 5634.6 5638.4 5491.7 5571.3 5573.5 5642.5 5673.4 5006.4 5052.6 5077.1 5230.3 5238.8 5143.1 5185.6 5235.5 5244.6 5284.8 4153.9 4975.4 5004.6 5053.6 5109.6 5094.4 5115.2 5138.5 5150.8 5211.0 5084.9 5113.7 5135.6 5143.8 5169.2 5348.8 5393.2 5418.3 5437.3 5597.3 6018.3 6053.9 6066.5 6068.1 6117.5 6118.8 6127.6 6138.1 6143.2 6153.6 5701.9 5765.7 5798.3 5798.8 5810.1 6011.1 6045.2 6106.2 6107.7 6144.1 5985.0 6014.3 6056.9 6073.3 6123.0 6048.1 6111.5 6111.6 6118.7 6120.4 6137.2 6140.2 6140.7 6141.9 6151.1 6018.1 6042.8 6052.5 6097.4 6137.4 4923.5 4924.6 4925.3 4936.4 4946.8 3726.5 4416.7 4421.6 4443.3 4463.3 4660.9 5504.7 5543.2 5554.8 5581.1 6069.2 6100.5 6117.0 6130.1 6130.2 5697.8 5709.2 5733.7 5755.0 5792.0 5184.7 5353.9 5359.0 5360.9 5392.9 6026.0 6044.3 6063.3 6101.0 6144.2 5902.3 5983.6 6018.2 6031.3 6053.3 6056.8 6090.5 6091.3 6103.4 6105.5 6011.0 6019.3 6022.9 6033.4 6083.5 5850.7 6125.0 6142.4 6146.6 6189.1 5728.8 5777.0 5841.0 5883.7 6046.5 5892.4 5974.4 5981.5 6069.6 6108.2 4933.9 5578.0 5902.2 5919.4 5928.1 5960.6 6042.9 6071.9 6095.2 6098.2 5602.0 5675.9 5691.7 5742.5 5765.3 09:12:19 INFO - PROCESS | 1921 | 09:12:19 INFO - PROCESS | 1921 | [#1] ext.html Cycles:180 Average:62852.68 Median:17583.17 stddev:134875.24 (767.1%) stddev-sans-first:135172.30 09:12:19 INFO - PROCESS | 1921 | Values: 481.5 507.2 509.0 524.2 538.5 31667.0 35541.6 37865.0 38069.6 38159.4 34976.0 37155.3 39125.0 39949.8 40008.8 8891.2 10503.8 10905.9 10934.9 11077.9 10688.8 10736.8 10744.4 10758.4 10782.3 10710.6 10722.5 10748.5 10773.4 10777.1 10663.4 10686.6 10714.9 10760.7 10776.5 10577.5 10582.9 10625.7 10657.5 10747.7 35256.0 35586.6 35625.8 35674.3 35736.9 3671.3 3682.3 3715.7 3725.6 3859.8 23974.0 25487.9 26287.5 27283.5 27512.4 2621.8 2976.8 3081.8 3084.4 3193.0 453442.0 453793.0 454806.0 456017.7 459083.0 45138.3 46153.7 51816.0 53011.7 53030.7 55135.7 55363.2 55396.0 55430.4 55811.7 13984.5 14041.0 14339.6 14364.4 14430.7 10980.8 11065.1 11066.2 11072.9 11105.1 16518.7 16618.0 16685.2 16697.3 16763.7 7575.7 7591.7 7602.7 7613.5 7656.3 822.1 834.5 837.5 838.6 846.5 28477.3 28598.4 28610.4 28839.4 29203.9 1002.7 1012.3 1034.4 1041.9 1052.7 13076.0 14529.6 14675.4 14866.7 15205.7 523290.0 549678.5 552350.0 559030.0 559787.0 49223.0 51870.0 55886.2 55920.7 56301.4 1325.9 1361.1 1370.8 1371.5 1377.3 27488.2 27774.0 27787.4 28112.7 28436.9 45409.8 45734.1 45875.9 45931.9 45972.3 505835.0 508037.5 508708.0 509958.0 511071.0 22001.6 22547.5 23069.0 24515.4 24789.4 42494.6 42709.8 42746.9 42779.0 42795.5 29492.9 34129.8 34483.8 36386.3 36409.5 31204.5 35180.8 35217.8 36428.5 36651.0 34646.0 34908.7 35095.0 35250.8 35477.5 26950.7 30100.4 30156.1 31718.0 31973.4 23465.4 23628.4 23714.9 23849.6 23963.5 09:12:19 INFO - PROCESS | 1921 | 09:12:19 INFO - PROCESS | 1921 | [#2] jquery.html Cycles:180 Average:38903.61 Median:13839.92 stddev:114937.95 (830.5%) stddev-sans-first:115223.93 09:12:19 INFO - PROCESS | 1921 | Values: 353.3 388.6 399.5 402.3 420.8 18674.5 19386.9 20886.8 21085.3 21111.5 11802.6 12120.0 12136.6 12227.7 20595.4 5051.6 5294.5 5300.3 5319.8 5320.8 4987.4 5224.6 5375.2 5805.1 5866.6 5794.7 5818.9 5827.3 5836.1 5842.1 5433.2 5456.2 5603.3 5775.6 5819.9 4609.0 5207.7 5219.8 5241.8 5244.2 13665.2 13818.2 23373.6 24157.6 25155.5 2444.4 2483.9 2487.1 2524.3 2553.3 8396.5 9572.4 10498.7 10997.8 14890.0 1766.5 1837.3 1840.0 1860.0 1865.4 114608.9 128290.4 137622.0 139878.0 140177.0 9501.6 9813.7 11900.5 12058.1 12092.0 15182.5 15923.3 15960.0 15980.4 15998.4 8172.4 8470.0 8472.5 8581.0 8593.7 6667.8 7951.5 8000.7 8023.4 8163.6 4116.5 4877.9 4879.5 4891.5 4903.7 1761.5 1854.6 2042.8 2083.5 2091.9 809.6 840.6 863.5 869.6 878.9 20782.8 22238.1 22313.0 22371.1 22721.4 854.7 858.9 889.7 890.1 899.4 10500.6 11535.9 11564.7 11626.8 11732.0 651830.0 691095.0 692284.0 693719.0 697507.0 140893.3 156469.0 158023.0 165184.0 167447.0 851.8 1009.9 1038.6 1044.8 1048.7 19700.0 19752.7 20106.8 20116.8 20188.1 59734.7 60302.0 60679.4 61095.4 61688.0 86392.0 86907.0 87290.6 87937.0 89782.0 8221.3 8354.3 8698.7 8706.6 8801.8 12619.7 13280.3 13333.0 13430.9 13505.1 10763.6 11174.0 11483.9 11584.8 11599.8 9304.2 10043.4 11050.2 11189.9 11689.7 11092.4 11234.0 11513.8 11652.5 11658.7 8373.4 8533.9 8558.8 8614.6 8769.5 8573.6 10794.4 10807.9 11192.3 11244.0 09:12:19 INFO - PROCESS | 1921 | 09:12:19 INFO - PROCESS | 1921 | [#3] mootools.html Cycles:180 Average:5359.23 Median:2869.14 stddev:2996.29 (104.4%) stddev-sans-first:2989.56 09:12:19 INFO - PROCESS | 1921 | Values: 1353.2 1414.8 1427.1 1436.2 1443.7 8200.3 8233.0 8448.3 8642.0 8649.3 8424.2 8542.6 8544.3 8551.0 8590.9 2246.8 2278.7 2282.0 2284.2 2299.0 2207.6 2247.2 2263.8 2273.5 2284.0 2248.1 2262.4 2276.7 2280.4 2283.7 1905.3 2220.3 2226.5 2258.0 2263.8 1954.1 2041.7 2199.7 2219.6 2289.9 8424.2 8538.0 8561.2 8567.2 8600.0 5121.4 5281.1 5288.3 5288.4 5294.2 2840.4 2851.7 3391.3 3436.8 3516.8 1196.0 1198.3 1217.8 1228.9 1251.5 10350.5 10846.5 10904.1 10929.0 10972.8 4145.7 4426.7 4500.5 4527.9 4539.7 8815.3 8863.9 8872.5 8875.3 8898.2 5044.1 5106.5 5119.8 5137.1 5145.0 4636.4 4679.5 4723.3 4726.5 4807.7 3584.1 3626.5 3628.8 3690.7 3746.1 984.0 987.9 998.3 1014.3 1015.0 744.0 745.1 769.2 772.1 804.4 5395.7 5454.2 5563.2 5633.0 5677.1 472.7 520.8 531.4 571.8 580.5 3415.4 3795.7 3849.1 3894.1 3908.5 9662.7 10101.4 10101.5 10104.7 10111.1 8874.9 10055.5 10071.9 10081.3 10092.7 7593.5 7745.8 7777.1 7913.8 7914.3 5419.5 5528.6 5600.8 5655.6 5733.2 7739.8 9020.8 9045.1 9146.4 9350.4 9136.4 9318.8 9408.6 9464.5 9476.4 6821.5 6897.5 6904.4 6907.4 6919.3 7834.8 7853.0 7937.4 8000.4 8007.7 6264.5 7053.6 7153.9 7163.9 7196.5 6987.3 7018.7 7032.8 7199.1 7225.9 5448.3 6074.9 6112.5 6393.0 6465.5 4042.8 4925.7 4995.9 5027.6 5249.0 3972.8 4727.8 4885.8 4936.5 5217.5 09:12:19 INFO - PROCESS | 1921 | 09:12:19 INFO - PROCESS | 1921 | [#4] prototype.html Cycles:180 Average:4541.91 Median:5208.67 stddev:1400.96 (26.9%) stddev-sans-first:1368.17 09:12:19 INFO - PROCESS | 1921 | Values: 296.3 350.3 370.0 371.6 374.3 4459.6 5349.7 5413.3 5499.3 5529.7 4951.9 5369.8 5435.4 5440.5 5448.0 3416.0 3734.9 3942.5 4098.7 4124.9 3521.3 3930.1 4079.8 4112.3 4123.4 4085.5 4097.6 4100.9 4115.3 4123.7 3481.7 3621.5 3722.4 4124.8 4129.5 3410.5 3490.6 3508.1 3508.8 3512.9 5211.7 5255.4 5268.5 5298.5 5298.8 3897.5 3984.5 4262.0 4543.1 4700.9 4754.0 5625.8 5643.3 5654.2 5711.5 3677.2 3682.3 3756.0 3908.0 3937.7 5350.1 5448.6 5701.1 5800.7 6048.8 3646.1 3671.6 3851.8 3864.8 3879.6 3581.3 3970.8 3980.8 3985.6 4039.8 3961.4 4109.2 4256.4 4422.4 4709.3 5360.1 5474.1 5677.9 5726.6 5815.4 1202.3 1291.8 1292.7 1296.3 1300.2 731.4 745.5 779.2 871.0 872.3 4435.9 4585.5 5053.7 5071.7 5135.5 5137.2 5370.1 5381.8 5533.8 5669.2 4608.9 4786.2 4844.9 5034.4 5045.0 2660.5 2926.7 2953.3 2965.6 2968.5 5089.5 5543.7 5625.2 5755.3 5793.2 5432.5 5637.7 5660.5 5672.8 5854.9 5042.6 5293.8 5457.8 5813.5 5844.5 5306.8 5336.2 5632.5 5679.0 5680.4 4829.9 5253.9 5424.9 5456.4 5895.9 4868.9 5805.9 5854.1 5928.7 6004.6 3755.4 4110.8 4286.6 4288.8 4303.7 5931.6 5943.3 5943.7 5976.4 5981.7 5601.9 5630.5 5640.5 5724.8 5725.6 5261.7 5401.7 5479.6 5519.9 5737.6 5182.7 5205.7 5593.5 5617.8 5640.0 5610.6 5695.8 5744.6 5751.8 5763.0 5176.7 5242.8 5583.9 5633.4 5647.2 09:12:19 INFO - PROCESS | 1921 | 09:12:19 INFO - PROCESS | 1921 | [#5] yui.html Cycles:180 Average:19716.49 Median:50572.24 stddev:28564.13 (56.5%) stddev-sans-first:28608.45 09:12:19 INFO - PROCESS | 1921 | Values: 667.8 709.0 725.6 725.6 773.5 14187.6 14681.4 15275.4 15297.8 15308.6 14003.9 14242.6 14246.0 14269.2 14275.8 857.6 875.1 875.2 876.7 878.8 845.9 873.1 874.1 874.5 874.5 919.1 923.8 938.6 941.0 941.5 930.4 932.4 936.0 936.1 939.2 684.5 685.3 691.9 692.9 693.7 15175.9 15450.4 15453.8 15605.7 15629.6 13709.9 13722.3 13988.9 14004.6 14017.9 10755.7 11243.6 11496.5 11563.3 11682.8 3548.2 3606.6 3615.5 3646.4 3742.0 90991.5 105144.0 106555.5 107694.0 108642.5 10557.0 10702.9 10768.4 10774.9 10926.6 18142.3 22326.6 22334.4 22339.6 22512.0 7393.6 7419.3 7518.6 7614.8 7645.0 3591.6 4041.1 4146.4 4161.8 4168.5 3568.2 3658.7 3663.3 3665.1 3678.2 650.8 658.2 669.8 671.5 678.6 630.2 640.2 676.2 680.5 706.1 9530.1 9840.8 9885.6 10088.7 10207.3 620.3 645.6 680.7 683.0 688.8 4497.3 4843.8 4854.8 4893.3 4904.4 84814.6 95539.8 95718.0 99772.5 101897.0 86057.8 86725.0 87281.6 87685.9 88750.0 73398.0 77001.6 77639.6 78973.2 79913.2 8897.4 9251.2 9292.3 9371.2 9447.5 49111.8 49415.8 50008.0 51136.5 52127.0 61493.7 64797.7 65412.7 68163.0 68352.3 10872.3 10885.3 11026.6 11154.7 11632.2 12369.2 12373.0 12374.0 12493.6 12900.7 11516.7 11520.4 11526.9 11688.8 11771.6 11348.6 11721.2 12024.4 12042.0 12053.2 11257.7 11401.8 11411.9 11421.0 11969.1 9683.0 9900.8 9956.5 9997.1 10245.0 8439.5 8486.3 9831.5 9887.9 9923.9 09:12:19 INFO - PROCESS | 1921 | -------- Summary: end -------- 09:12:19 INFO - PROCESS | 1921 | 09:12:20 INFO - TEST-INFO | 1921: exit 0 09:12:20 INFO - TEST-OK | dromaeo_css | took 1116292ms 09:12:20 INFO - TEST-START | kraken 09:12:20 INFO - operating with platform_type : mac_ 09:12:20 INFO - Initialising browser for kraken test... 09:12:20 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmpGwgRk3/profile http://localhost:53485/getInfo.html) 09:12:22 INFO - PROCESS | 1926 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 09:12:23 INFO - PROCESS | 1926 | 2017-02-13 09:12:23.071 plugin-container[1927:20091] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:12:23 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:23 INFO - PROCESS | 1926 | 2017-02-13 09:12:23.074 plugin-container[1927:20091] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e17, name = 'com.apple.CFPasteboardClient' 09:12:23 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:23 INFO - PROCESS | 1926 | 2017-02-13 09:12:23.074 plugin-container[1927:20091] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:12:23 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 09:12:23 INFO - PROCESS | 1926 | colorDepth:24 09:12:23 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 09:12:23 INFO - PROCESS | 1926 | __metrics 09:12:23 ERROR - PROCESS | 1926 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 09:12:24 INFO - TEST-INFO | 1926: exit 0 09:12:24 INFO - Browser initialized. 09:12:24 INFO - Running cycle 1/1 for kraken test... 09:12:24 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmpGwgRk3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:26 INFO - PROCESS | 1928 | 2017-02-13 09:12:26.371 plugin-container[1929:20210] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4f, name = 'com.apple.tsm.portname' 09:12:26 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:26 INFO - PROCESS | 1928 | 2017-02-13 09:12:26.372 plugin-container[1929:20210] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x440f, name = 'com.apple.CFPasteboardClient' 09:12:26 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:12:26 INFO - PROCESS | 1928 | 2017-02-13 09:12:26.372 plugin-container[1929:20210] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:12:36 INFO - PROCESS | 1928 | RSS: Main: 232747008 09:12:36 INFO - PROCESS | 1928 | 09:12:39 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53485/tests/kraken/driver.html?testName=audio-beat-detection) 09:12:40 INFO - PROCESS | 1928 | RSS: Main: 247369728 09:12:40 INFO - PROCESS | 1928 | 09:12:42 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53485/tests/kraken/driver.html?testName=audio-dft) 09:12:43 INFO - PROCESS | 1928 | RSS: Main: 255352832 09:12:43 INFO - PROCESS | 1928 | 09:12:46 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53485/tests/kraken/driver.html?testName=audio-fft) 09:12:47 INFO - PROCESS | 1928 | RSS: Main: 258007040 09:12:47 INFO - PROCESS | 1928 | 09:12:49 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53485/tests/kraken/driver.html?testName=audio-oscillator) 09:12:50 INFO - PROCESS | 1928 | RSS: Main: 259346432 09:12:50 INFO - PROCESS | 1928 | 09:12:52 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53485/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:12:52 INFO - PROCESS | 1928 | RSS: Main: 259710976 09:12:52 INFO - PROCESS | 1928 | 09:12:55 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53485/tests/kraken/driver.html?testName=imaging-darkroom) 09:12:56 INFO - PROCESS | 1928 | RSS: Main: 266698752 09:12:56 INFO - PROCESS | 1928 | 09:12:59 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53485/tests/kraken/driver.html?testName=imaging-desaturate) 09:13:00 INFO - PROCESS | 1928 | RSS: Main: 266248192 09:13:00 INFO - PROCESS | 1928 | 09:13:03 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53485/tests/kraken/driver.html?testName=json-parse-financial) 09:13:03 INFO - PROCESS | 1928 | RSS: Main: 266170368 09:13:03 INFO - PROCESS | 1928 | 09:13:05 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53485/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:13:06 INFO - PROCESS | 1928 | RSS: Main: 265322496 09:13:06 INFO - PROCESS | 1928 | 09:13:08 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53485/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:13:09 INFO - PROCESS | 1928 | RSS: Main: 265314304 09:13:09 INFO - PROCESS | 1928 | 09:13:11 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53485/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:13:11 INFO - PROCESS | 1928 | RSS: Main: 265363456 09:13:11 INFO - PROCESS | 1928 | 09:13:15 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53485/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:13:15 INFO - PROCESS | 1928 | RSS: Main: 265768960 09:13:15 INFO - PROCESS | 1928 | 09:13:18 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53485/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:13:19 INFO - PROCESS | 1928 | RSS: Main: 265797632 09:13:19 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:53485/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53485/tests/kraken/driver.html?testName=ai-astar) 09:13:21 INFO - PROCESS | 1928 | RSS: Main: 268763136 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | __start_tp_report 09:13:21 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 09:13:21 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 09:13:21 INFO - PROCESS | 1928 | |i|pagename|runs| 09:13:21 INFO - PROCESS | 1928 | |0;ai-astar;109;141;175;153;118;119;119;120;118;117 09:13:21 INFO - PROCESS | 1928 | |1;audio-beat-detection;152;120;143;147;105;118;146;145;135;119 09:13:21 INFO - PROCESS | 1928 | |2;audio-dft;202;215;206;224;197;206;203;194;197;223 09:13:21 INFO - PROCESS | 1928 | |3;audio-fft;89;61;69;104;80;81;60;61;72;63 09:13:21 INFO - PROCESS | 1928 | |4;audio-oscillator;91;72;87;71;70;71;83;72;71;83 09:13:21 INFO - PROCESS | 1928 | |5;imaging-gaussian-blur;91;105;113;91;116;81;120;122;111;130 09:13:21 INFO - PROCESS | 1928 | |6;imaging-darkroom;100;128;101;101;99;104;152;150;100;100 09:13:21 INFO - PROCESS | 1928 | |7;imaging-desaturate;92;88;153;129;93;93;93;96;90;91 09:13:21 INFO - PROCESS | 1928 | |8;json-parse-financial;63;65;68;97;99;96;93;88;93;64 09:13:21 INFO - PROCESS | 1928 | |9;json-stringify-tinderbox;73;76;77;86;82;72;74;75;77;76 09:13:21 INFO - PROCESS | 1928 | |10;stanford-crypto-aes;88;95;93;90;88;107;109;93;90;96 09:13:21 INFO - PROCESS | 1928 | |11;stanford-crypto-ccm;146;161;132;159;160;177;157;161;209;193 09:13:21 INFO - PROCESS | 1928 | |12;stanford-crypto-pbkdf2;178;193;179;176;172;178;175;178;173;175 09:13:21 INFO - PROCESS | 1928 | |13;stanford-crypto-sha256-iterative;70;70;71;90;92;86;91;89;81;69 09:13:21 INFO - PROCESS | 1928 | __end_tp_report 09:13:21 INFO - PROCESS | 1928 | __start_cc_report 09:13:21 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,1022 09:13:21 INFO - PROCESS | 1928 | __end_cc_report 09:13:21 INFO - PROCESS | 1928 | __startTimestamp1487006001630__endTimestamp 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | ------- Summary: start ------- 09:13:21 INFO - PROCESS | 1928 | Number of tests: 14 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#0] ai-astar Cycles:10 Average:128.90 Median:119.50 stddev:20.82 (17.4%) stddev-sans-first:20.81 09:13:21 INFO - PROCESS | 1928 | Values: 109.0 141.0 175.0 153.0 118.0 119.0 119.0 120.0 118.0 117.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:144.00 stddev:16.15 (11.2%) stddev-sans-first:15.60 09:13:21 INFO - PROCESS | 1928 | Values: 152.0 120.0 143.0 147.0 105.0 118.0 146.0 145.0 135.0 119.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#2] audio-dft Cycles:10 Average:206.70 Median:206.00 stddev:10.65 (5.2%) stddev-sans-first:11.16 09:13:21 INFO - PROCESS | 1928 | Values: 202.0 215.0 206.0 224.0 197.0 206.0 203.0 194.0 197.0 223.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#3] audio-fft Cycles:10 Average:74.00 Median:70.50 stddev:14.51 (20.6%) stddev-sans-first:14.34 09:13:21 INFO - PROCESS | 1928 | Values: 89.0 61.0 69.0 104.0 80.0 81.0 60.0 61.0 72.0 63.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:77.50 stddev:7.99 (10.3%) stddev-sans-first:6.71 09:13:21 INFO - PROCESS | 1928 | Values: 91.0 72.0 87.0 71.0 70.0 71.0 83.0 72.0 71.0 83.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#5] imaging-gaussian-blur Cycles:10 Average:108.00 Median:126.00 stddev:15.77 (12.5%) stddev-sans-first:15.48 09:13:21 INFO - PROCESS | 1928 | Values: 91.0 105.0 113.0 91.0 116.0 81.0 120.0 122.0 111.0 130.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#6] imaging-darkroom Cycles:10 Average:113.50 Median:116.00 stddev:21.56 (18.6%) stddev-sans-first:22.31 09:13:21 INFO - PROCESS | 1928 | Values: 100.0 128.0 101.0 101.0 99.0 104.0 152.0 150.0 100.0 100.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#7] imaging-desaturate Cycles:10 Average:101.80 Median:92.50 stddev:21.52 (23.3%) stddev-sans-first:22.54 09:13:21 INFO - PROCESS | 1928 | Values: 92.0 88.0 153.0 129.0 93.0 93.0 93.0 96.0 90.0 91.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#8] json-parse-financial Cycles:10 Average:82.60 Median:93.00 stddev:15.47 (16.6%) stddev-sans-first:14.70 09:13:21 INFO - PROCESS | 1928 | Values: 63.0 65.0 68.0 97.0 99.0 96.0 93.0 88.0 93.0 64.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#9] json-stringify-tinderbox Cycles:10 Average:76.80 Median:76.50 stddev:4.24 (5.5%) stddev-sans-first:4.27 09:13:21 INFO - PROCESS | 1928 | Values: 73.0 76.0 77.0 86.0 82.0 72.0 74.0 75.0 77.0 76.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#10] stanford-crypto-aes Cycles:10 Average:94.90 Median:91.50 stddev:7.43 (8.1%) stddev-sans-first:7.45 09:13:21 INFO - PROCESS | 1928 | Values: 88.0 95.0 93.0 90.0 88.0 107.0 109.0 93.0 90.0 96.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#11] stanford-crypto-ccm Cycles:10 Average:165.50 Median:161.00 stddev:22.28 (13.8%) stddev-sans-first:22.49 09:13:21 INFO - PROCESS | 1928 | Values: 146.0 161.0 132.0 159.0 160.0 177.0 157.0 161.0 209.0 193.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.70 Median:178.00 stddev:5.85 (3.3%) stddev-sans-first:6.20 09:13:21 INFO - PROCESS | 1928 | Values: 178.0 193.0 179.0 176.0 172.0 178.0 175.0 178.0 173.0 175.0 09:13:21 INFO - PROCESS | 1928 | 09:13:21 INFO - PROCESS | 1928 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.90 Median:87.50 stddev:9.87 (11.3%) stddev-sans-first:9.65 09:13:21 INFO - PROCESS | 1928 | Values: 70.0 70.0 71.0 90.0 92.0 86.0 91.0 89.0 81.0 69.0 09:13:21 INFO - PROCESS | 1928 | -------- Summary: end -------- 09:13:21 INFO - PROCESS | 1928 | 09:13:22 INFO - TEST-INFO | 1928: exit 0 09:13:22 INFO - TEST-OK | kraken | took 62321ms 09:13:22 INFO - SUITE-END | took 1178s 09:13:22 INFO - Completed test suite (00:19:38) 09:13:22 INFO - javascript benchmark 09:13:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7085.20623492509, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2689.488997090553, 3220.5829435048563, 3302.9339413211737, 3334.299797042621, 3344.832758362081, 4906.730129842857, 5599.244102046223, 5602.887942241158, 5634.633748806335, 5638.351589567201, 5491.670499770016, 5571.303587051619, 5573.498385145336, 5642.463965923241, 5673.404292549285, 5006.424261209952, 5052.646314757959, 5077.060743762398, 5230.293910322115, 5238.79044838206, 5143.074246635604, 5185.611079169059, 5235.481710304008, 5244.632875698683, 5284.8150314739, 4153.854615088488, 4975.378077740283, 5004.574611158056, 5053.5957123430035, 5109.6167787415725, 5094.414142373637, 5115.155999260118, 5138.537531622134, 5150.819721309774, 5211.0, 5084.872878178076, 5113.667611605234, 5135.589152867799, 5143.842635407041, 5169.172932330809, 5348.8127915523155, 5393.19102134679, 5418.349798024223, 5437.293151890269, 5597.3003374578175, 6018.337982821886, 6053.939460605382, 6066.545009124289, 6068.059450785136, 6117.541184411187, 6118.817507115225, 6127.617148554337, 6138.051223665319, 6143.204841796972, 6153.569250152491, 5701.942980570224, 5765.682887441189, 5798.275215598051, 5798.797042103548, 5810.082672717993, 6011.09833524966, 6045.244344456943, 6106.2061931948565, 6107.704691583834, 6144.07838824171, 5985.0, 6014.308354539196, 6056.878862422815, 6073.271207455134, 6123.008104567321, 6048.092786082123, 6111.51107911366, 6111.572189946661, 6118.724657390429, 6120.418560236771, 6137.171481849893, 6140.201773769381, 6140.662263575457, 6141.877162456726, 6151.138840562325, 6018.127371531104, 6042.788502402394, 6052.54605904559, 6097.420745029215, 6137.355577664371, 4923.48303428142, 4924.581410580141, 4925.261210818406, 4936.3829521309835, 4946.777395017233, 3726.5341832270965, 4416.668749843775, 4421.624161946271, 4443.311286750559, 4463.285874260103, 4660.86017419478, 5504.724763761796, 5543.223948647193, 5554.805581804618, 5581.079121944915, 6069.241344831896, 6100.450959413674, 6116.969415152896, 6130.122981477904, 6130.184124957599, 5697.829065128053, 5709.172170035405, 5733.655980641175, 5755.0, 5792.0, 5184.740762961912, 5353.9196912046355, 5358.97320513395, 5360.946390536123, 5392.91910621341, 6025.969870150621, 6044.274687037496, 6063.302720187058, 6100.969495152496, 6144.201253836973, 5902.2622172228475, 5983.611065280743, 6018.187544681412, 6031.276246850406, 6053.334133245427, 6056.848578785565, 6090.512758979359, 6091.299500557493, 6103.42017508336, 6105.48103411215, 6011.0, 6019.277686677627, 6022.909656355246, 6033.42682445167, 6083.452489275986, 5850.683596190993, 6124.958757011408, 6142.416470435302, 6146.6004709693725, 6189.102580125947, 5728.770849165987, 5776.978057679778, 5841.036229022164, 5883.705814709162, 6046.546509011754, 5892.410758924073, 5974.40255974399, 5981.521478281814, 6069.605475644069, 6108.236470441195, 4933.925991110208, 5578.023845827045, 5902.1736956826935, 5919.378465261086, 5928.110783382527, 5960.582759206987, 6042.909356359746, 6071.939280607137, 6095.1771510845465, 6098.176746139215, 5602.047651899105, 5675.858103547444, 5691.743871525874, 5742.483176514133, 5765.279340082489], "value": 5560.2340866737595, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.5040508276475, 507.2011581758729, 509.0175960396436, 524.1654210264074, 538.4534697282256, 31667.049988500323, 35541.64458355419, 37865.0, 38069.619303806954, 38159.427608585895, 34975.950721478395, 37155.25689486209, 39125.0, 39949.80025099878, 40008.79995600018, 8891.199792018717, 10503.789924201512, 10905.945470272638, 10934.890651093467, 11077.944610276938, 10688.839667404995, 10736.838947415756, 10744.409057501834, 10758.354498730063, 10782.299150555189, 10710.571577136905, 10722.517486713077, 10748.516316765725, 10773.407462589554, 10777.137828973664, 10663.413512256822, 10686.572537098507, 10714.946425267863, 10760.677179684544, 10776.461176941122, 10577.471126443646, 10582.947085264525, 10625.681229563126, 10657.467126643714, 10747.731306717395, 35256.0, 35586.64413355859, 35625.82187089074, 35674.28651426957, 35736.92789216328, 3671.320805650963, 3682.2635472905527, 3715.7366495391425, 3725.5715592706915, 3859.826307816155, 23974.0, 25487.853046612763, 26287.474250515075, 27283.45433091327, 27512.449751005068, 2621.8307131578154, 2976.791624586258, 3081.7534597232166, 3084.3985422842557, 3192.984035079833, 453442.0, 453793.0, 454806.0, 456017.7199113983, 459083.0, 45138.32292515615, 46153.69232538234, 51816.0, 53011.73494132582, 53030.73484632552, 55135.72432137813, 55363.16955245675, 55396.0, 55430.445695543334, 55811.72094139585, 13984.51054213118, 14041.017128800886, 14339.569812905629, 14364.425422982964, 14430.711385772225, 10980.780384392267, 11065.114790816715, 11066.225364224589, 11072.944635276772, 11105.111591072695, 16518.6696266074, 16618.0, 16685.165741712866, 16697.332106715596, 16763.7427192961, 7575.734849280248, 7591.734289299959, 7602.72401862548, 7613.467057305936, 7656.349210317074, 822.1326500541985, 834.5451728807693, 837.5226121111031, 838.6226198210829, 846.4836449765638, 28477.288067798057, 28598.428031439256, 28610.427791444054, 28839.423211535654, 29203.85398072996, 1002.7292630989592, 1012.250934308621, 1034.4052170002278, 1041.9114375278034, 1052.6578861869923, 13076.0, 14529.636759081108, 14675.412983480755, 14866.662000420014, 15205.695886082216, 523290.0, 549678.53536111, 552350.0, 559030.0, 559787.0, 49223.0, 51870.0, 55886.16170757442, 55920.72039639776, 56301.43698563044, 1325.8939284857188, 1361.1492816989382, 1370.8286464191976, 1371.4925477573163, 1377.2631642071644, 27488.17535473939, 27774.0, 27787.44425111446, 28112.71887281101, 28436.86252549875, 45409.77295113503, 45734.085318294114, 45875.85310367268, 45931.851703707674, 45972.310415344466, 505835.0, 508037.4598126986, 508708.0, 509958.0, 511071.0, 22001.559968800855, 22547.5490490186, 23069.0, 24515.38711532226, 24789.380265493506, 42494.575054249064, 42709.78645106754, 42746.93132671708, 42779.0, 42795.502157424344, 29492.852535737184, 34129.80545680889, 34483.82758086194, 36386.27227455489, 36409.453858192675, 31204.53193202149, 35180.824095879354, 35217.82391088028, 36428.4535731959, 36651.0, 34645.9606211814, 34908.65091349054, 35095.0, 35250.823745881105, 35477.46783798194, 26950.730492694824, 30100.397992040474, 30156.09531714052, 31718.0, 31973.36053278968, 23465.41336466602, 23628.409289767893, 23714.88142559276, 23849.64225536653, 23963.52072958566], "value": 18782.55368557391, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.3075172661583, 388.6405075305342, 399.5046142782949, 402.27791114948667, 420.84849454196484, 18674.533136671573, 19386.93371864547, 20886.791132088674, 21085.26201582945, 21111.47221319466, 11802.58690945817, 12120.0, 12136.575219867307, 12227.69430764229, 20595.38213853583, 5051.6463847530595, 5294.52349288564, 5300.337457817773, 5319.760610772505, 5320.76056577453, 4987.376577927759, 5224.555912747402, 5375.22059301403, 5805.100209467518, 5866.6186697864505, 5794.68129252891, 5818.912716309259, 5827.300723913137, 5836.095405212199, 5842.0652695568715, 5433.1578605316045, 5456.154296084113, 5603.271574695284, 5775.5957083004205, 5819.854503637401, 4608.953910460886, 5207.661502002357, 5219.791208351661, 5241.8165364212455, 5244.213367994793, 13665.248411337372, 13818.170909745448, 23373.649395259086, 24157.637635435483, 25155.49689006228, 2444.4499987502845, 2483.925482235536, 2487.0692217752294, 2524.343670645627, 2553.3361326055174, 8396.496210227406, 9572.377795443273, 10498.685039448828, 10997.835032474519, 14890.032147910459, 1766.549529869875, 1837.338558119076, 1839.9632007359912, 1859.9628007439776, 1865.4310435317223, 114608.85391146065, 128290.3585482076, 137622.0, 139878.0, 140177.0, 9501.572429240701, 9813.705588832347, 11900.523979040914, 12058.095642826824, 12092.0, 15182.544523664308, 15923.283452244445, 15960.0, 15980.440684575984, 15998.440054598033, 8172.42793004484, 8470.025947016173, 8472.534010629475, 8580.95709521461, 8593.742187734379, 6667.799966001027, 7951.483153594998, 8000.719974800854, 8023.358131349478, 8163.551004694798, 4116.485439320085, 4877.853664390074, 4879.487653796371, 4891.51084891508, 4903.705777653352, 1761.4803632928317, 1854.6290741851683, 2042.8365730741803, 2083.531205478755, 2091.853570250068, 809.6437567470294, 840.6049156896249, 863.4603372891943, 869.5652173913044, 878.8769572259889, 20782.79217207839, 22238.110475581117, 22312.995915183856, 22371.10515579391, 22721.431964200696, 854.7264875239988, 858.9226969572643, 889.7330800759746, 890.1054440287598, 899.3614533681028, 10500.632477863392, 11535.884641153649, 11564.653060408202, 11626.825597616042, 11732.0, 651830.0, 691095.0, 692284.0, 693719.0, 697507.0, 140893.29553352372, 156469.0, 158023.0, 165184.0, 167447.0, 851.7572491839796, 1009.8939611340851, 1038.5741845843168, 1044.8223801953534, 1048.7153237284265, 19700.0, 19752.703709444082, 20106.798932010493, 20116.798832011493, 20188.091535880627, 59734.70132649309, 60302.0, 60679.393206067376, 61095.38904611075, 61688.0, 86392.0, 86907.0, 87290.56354718185, 87937.0, 89782.0, 8221.342292616697, 8354.289885359813, 8698.695545655872, 8706.608202631023, 8801.779955500908, 12619.684507887376, 13280.281521174087, 13333.000798084675, 13430.865691342962, 13505.12216705911, 10763.600731904802, 11174.0, 11483.885161148282, 11584.826227606762, 11599.76800464003, 9304.209142222986, 10043.447610381207, 11050.171237157407, 11189.888101118884, 11689.707757305796, 11092.389918554558, 11234.0, 11513.827292590451, 11652.53389864396, 11658.65024049246, 8373.413861029669, 8533.871991920001, 8558.82882342362, 8614.569271536524, 8769.47383156987, 8573.571321434029, 10794.40630765284, 10807.945960270148, 11192.272502287326, 11244.0], "value": 9595.6299232521, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.15427857589, 1414.801927730118, 1427.0581416265268, 1436.2406052585584, 1443.6896067345522, 8200.318934618404, 8232.958835205816, 8448.324134069275, 8642.006169290526, 8649.264812490937, 8424.199701028409, 8542.572871356439, 8544.27373673237, 8550.95724521378, 8590.87113693295, 2246.8314876384256, 2278.6582012698063, 2281.9885900570475, 2284.189112864934, 2298.9540209195807, 2207.61366760817, 2247.1910112359537, 2263.818894488445, 2273.522560262347, 2283.9885800570974, 2248.0895237428867, 2262.3891549281684, 2276.692646492727, 2280.429892526868, 2283.668868014137, 1905.2759951218518, 2220.2562141682556, 2226.543558570489, 2257.9774202258095, 2263.7736226377397, 1954.0522846419476, 2041.652919003773, 2199.670049492573, 2219.611567975614, 2289.8969546370286, 8424.157584241588, 8538.018127915308, 8561.1866872647, 8567.228949394585, 8600.0, 5121.385433747974, 5281.102212623863, 5288.312519372495, 5288.444713305086, 5294.232336311214, 2840.3751174741524, 2851.6863145054026, 3391.3047825195777, 3436.8453419595926, 3516.753827232095, 1196.0, 1198.3349241171147, 1217.8148921363936, 1228.9446974886152, 1251.5181655062775, 10350.534225959775, 10846.511906964206, 10904.127669786398, 10929.016388524991, 10972.835407468894, 4145.689073319513, 4426.734395936254, 4500.504944456107, 4527.886802829889, 4539.7049191802425, 8815.294776417872, 8863.867041994376, 8872.512011839282, 8875.289976801969, 8898.243649289754, 5044.117279476077, 5106.540411362995, 5119.795208191705, 5137.0753264412015, 5144.99672563854, 4636.420447444069, 4679.532046795362, 4723.338732577442, 4726.456457507431, 4807.711537307773, 3584.121890136933, 3626.4741612466573, 3628.8185590720886, 3690.6862916652385, 3746.063484128968, 983.9508024598741, 987.8863930648067, 998.3161534348994, 1014.3356101753363, 1014.9951547967459, 743.9516431431939, 745.0947099274408, 769.1616138409161, 772.0927909706073, 804.3766081286932, 5395.676259424447, 5454.236406903036, 5563.248961390161, 5633.042382794934, 5677.091665333527, 472.6998356043937, 520.8255234496411, 531.4472948133897, 571.8027280588191, 580.51236960953, 3415.3852306585054, 3795.658390744791, 3849.1339448623835, 3894.1043559981363, 3908.5309762828642, 9662.729258624218, 10101.444420556792, 10101.494925253708, 10104.747381315527, 10111.090001899716, 8874.890638670166, 10055.49722513886, 10071.949640251752, 10081.344712593806, 10092.697219083439, 7593.544387336778, 7745.767626971087, 7777.105632852295, 7913.762587122281, 7914.287714105643, 5419.512243898068, 5528.585356098229, 5600.803971861128, 5655.632383895034, 5733.197352370588, 7739.7678069658, 9020.774480638036, 9045.050269721847, 9146.405483643543, 9350.438973661601, 9136.406133601295, 9318.767030824283, 9408.576614052246, 9464.479453630116, 9476.431414115175, 6821.488388370793, 6897.517173797786, 6904.447644188353, 6907.447404207551, 6919.273476284918, 7834.843303134019, 7852.960735196288, 7937.365010799238, 8000.439969202333, 8007.719729809429, 6264.498840092872, 7053.647317634201, 7153.892691609734, 7163.935486760628, 7196.532225405332, 6987.266337034538, 7018.684159212746, 7032.835820895522, 7199.1001124859395, 7225.891611625935, 5448.291722076105, 6074.878502429839, 6112.541559382975, 6393.0, 6465.547411681326, 4042.8180731867724, 4925.679830810986, 4995.8751031223055, 5027.64806463544, 5249.028929648028, 3972.781497017692, 4727.763611819327, 4885.8045678172475, 4936.481669424658, 5217.452167522356], "value": 4250.480909149522, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.30516438950656, 350.2906614106434, 369.9741018128732, 371.6005294308619, 374.33181770539574, 4459.598636122749, 5349.652272602283, 5413.26920865683, 5499.312585926759, 5529.668219906798, 4951.900961980758, 5369.785208591652, 5435.402105768372, 5440.537554307879, 5447.972760136204, 3416.0264324667473, 3734.8692795752154, 3942.5412597339023, 4098.672106231495, 4124.896877578055, 3521.2605352875926, 3930.1353702185475, 4079.796010199478, 4112.321466957948, 4123.381492776092, 4085.487425233475, 4097.631213190813, 4100.917981640366, 4115.32097203961, 4123.711340206187, 3481.7388695847912, 3621.4567814827724, 3722.4416337549314, 4124.8143833527565, 4129.463169787939, 3410.5054767058637, 3490.5636795400574, 3508.0878971467346, 3508.7543871928974, 3512.947305790415, 5211.661242019257, 5255.448177941299, 5268.4994925482015, 5298.523132918056, 5298.761555729963, 3897.4738410314526, 3984.521857377133, 4261.957380426187, 4543.136804007228, 4700.858974230744, 4754.001659651477, 5625.803096891589, 5643.322801263834, 5654.180143879155, 5711.458401011551, 3677.2093999789904, 3682.2635472905527, 3756.0234339071767, 3907.960920390788, 3937.684985201177, 5350.0904846176245, 5448.618596698233, 5701.14482827575, 5800.680962547058, 6048.848778780522, 3646.085804952368, 3671.596124426311, 3851.8251933160095, 3864.806759662006, 3879.592642772525, 3581.283743251308, 3970.841166353371, 3980.800959951991, 3985.6412922836503, 4039.8182081805803, 3961.445397644332, 4109.239790638741, 4256.425382573313, 4422.425084738963, 4709.340692303081, 5360.123956615232, 5474.084070575346, 5677.943220567824, 5726.570507211975, 5815.41845815415, 1202.298747272617, 1291.754566632337, 1292.7091404434116, 1296.2805642868298, 1300.2133709106045, 731.389289942893, 745.4930647159875, 779.2013186483786, 870.9695160669346, 872.3326655108847, 4435.933460998088, 4585.472670642852, 5053.722045287471, 5071.721055341918, 5135.460776618476, 5137.2037334213255, 5370.140777475585, 5381.78472861089, 5533.806316778893, 5669.177969194526, 4608.88477788058, 4786.16242157621, 4844.89247177479, 5034.439323809631, 5044.991001799654, 2660.4546068056, 2926.6976195593184, 2953.278868625547, 2965.56170257425, 2968.5157421289355, 5089.465606111379, 5543.695096769717, 5625.17794083122, 5755.280589926259, 5793.21925475774, 5432.483914028161, 5637.68992705397, 5660.5471562274915, 5672.808710170828, 5854.887571361428, 5042.596592272536, 5293.814716484904, 5457.836264912059, 5813.534917206698, 5844.480435086993, 5306.761195746278, 5336.17289320156, 5632.5493960482245, 5678.955575992722, 5680.375158732453, 4829.879253018703, 5253.894922101613, 5424.945750542444, 5456.407182637828, 5895.882082358243, 4868.92883565615, 5805.912911306418, 5854.063349864172, 5928.67392293428, 6004.639721616717, 3755.4366844973474, 4110.753354798721, 4286.614204721465, 4288.7855607220135, 4303.698741088094, 5931.584789064724, 5943.346231914573, 5943.673097979479, 5976.432238937294, 5981.700914954322, 5601.943980560142, 5630.5214056805635, 5640.520555752807, 5724.79963201286, 5725.570582206435, 5261.736913154404, 5401.72991350423, 5479.561635069264, 5519.8896022080135, 5737.5983681141915, 5182.689038657692, 5205.687658740488, 5593.461798005418, 5617.775288988395, 5639.971800140973, 5610.551155907599, 5695.772169113189, 5744.6265992712015, 5751.84305885293, 5762.971185144047, 5176.741162941913, 5242.790288388422, 5583.888322233452, 5633.408492108433, 5647.15292706081], "value": 4097.271201370033, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.8330417395651, 709.0, 725.5719125715829, 725.5864157430264, 773.5320131320551, 14187.64530886727, 14681.412743490248, 15275.38898444061, 15297.847021529782, 15308.617284567892, 14003.929980350083, 14242.643933901632, 14246.0, 14269.215193164371, 14275.78586321206, 857.5797859049052, 875.0724232313736, 875.2035647560721, 876.7369789063288, 878.7759121424028, 845.8562044452458, 873.1181506678269, 874.0953113527501, 874.4796845876725, 874.514644372373, 919.062556192683, 923.833709932212, 938.5870217104487, 941.0119374656617, 941.5245301122924, 930.4463844012802, 932.4032619123766, 935.9953200233955, 936.1060187520935, 939.2345238630528, 684.4695361095141, 685.2873012067444, 691.9238883722786, 692.9480288978347, 693.7363801755351, 15175.924120379437, 15450.381984720598, 15453.768193476995, 15605.68788624221, 15629.609259768484, 13709.862901370958, 13722.313884305744, 13988.93005534976, 14004.579862604036, 14017.859821401757, 10755.677329680122, 11243.550257989671, 11496.540138394454, 11563.294659885556, 11682.824757628643, 3548.183917698918, 3606.6032736398975, 3615.475756015363, 3646.361886669822, 3742.0, 90991.54504227503, 105144.0, 106555.46722266338, 107694.0, 108642.45678771555, 10557.0, 10702.949197502367, 10768.407737574353, 10774.892251077546, 10926.617568385069, 18142.274309027754, 22326.55346893053, 22334.441638959157, 22339.55320893573, 22512.0, 7393.55638661682, 7419.29516695913, 7518.624068796647, 7614.771556853303, 7645.006149200568, 3591.6049234584175, 4041.131156801243, 4146.398772178018, 4161.831285456025, 4168.520620128664, 3568.1614820517157, 3658.725595580342, 3663.3222853772136, 3665.065408320861, 3678.2091850252323, 650.7982525417136, 658.2101478226148, 669.8358902068927, 671.4829581222529, 678.6064082831946, 630.1677970384156, 640.2029473305764, 676.235853485568, 680.4692339974828, 706.0961968680098, 9530.094641009093, 9840.803183936425, 9885.604575817031, 10088.69733907984, 10207.285490015776, 620.3362402229661, 645.6352161029004, 680.7072958627838, 682.969266383014, 688.789919074677, 4497.257952437876, 4843.75781210938, 4854.834839638462, 4893.339399181065, 4904.386951631046, 84814.5759271212, 95539.76271001223, 95718.0, 99772.50113749385, 101897.0, 86057.84855378665, 86725.0, 87281.56359218163, 87685.91611964579, 88750.0, 73398.0, 77001.61499192468, 77639.61180194176, 78973.21026789659, 79913.20086799057, 8897.377183597346, 9251.213646840093, 9292.303077269367, 9371.156595906401, 9447.52762361893, 49111.75444122757, 49415.752921235166, 50008.0, 51136.48863511466, 52127.0, 61493.69253153705, 64797.67601162152, 65412.67293663692, 68163.0, 68352.31647683459, 10872.347659140476, 10885.346879187275, 11026.566546348924, 11154.721131971766, 11632.243904146204, 12369.196002259825, 12373.0, 12374.0, 12493.562725304571, 12900.741985160057, 11516.654500365003, 11520.423978801195, 11526.88473115258, 11688.824667629822, 11771.646850594496, 11348.557129113102, 11721.179517433698, 12024.398780061138, 12042.0, 12053.156279060382, 11257.71855703614, 11401.771964560496, 11411.885881141414, 11421.0, 11969.102317326062, 9683.0, 9900.801983960424, 9956.452395118049, 9997.100260976547, 10244.984443463123, 8439.493630382196, 8486.321094312316, 9831.524814597975, 9887.890262482739, 9923.851142233018], "value": 7247.933492692928, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1621.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 141.0, 175.0, 153.0, 118.0, 119.0, 119.0, 120.0, 118.0, 117.0], "value": 128.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 120.0, 143.0, 147.0, 105.0, 118.0, 146.0, 145.0, 135.0, 119.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 215.0, 206.0, 224.0, 197.0, 206.0, 203.0, 194.0, 197.0, 223.0], "value": 206.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 61.0, 69.0, 104.0, 80.0, 81.0, 60.0, 61.0, 72.0, 63.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 72.0, 87.0, 71.0, 70.0, 71.0, 83.0, 72.0, 71.0, 83.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 105.0, 113.0, 91.0, 116.0, 81.0, 120.0, 122.0, 111.0, 130.0], "value": 108.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 128.0, 101.0, 101.0, 99.0, 104.0, 152.0, 150.0, 100.0, 100.0], "value": 113.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 153.0, 129.0, 93.0, 93.0, 93.0, 96.0, 90.0, 91.0], "value": 101.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 68.0, 97.0, 99.0, 96.0, 93.0, 88.0, 93.0, 64.0], "value": 82.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 77.0, 86.0, 82.0, 72.0, 74.0, 75.0, 77.0, 76.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 95.0, 93.0, 90.0, 88.0, 107.0, 109.0, 93.0, 90.0, 96.0], "value": 94.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 161.0, 132.0, 159.0, 160.0, 177.0, 157.0, 161.0, 209.0, 193.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 193.0, 179.0, 176.0, 172.0, 178.0, 175.0, 178.0, 173.0, 175.0], "value": 177.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 71.0, 90.0, 92.0, 86.0, 91.0, 89.0, 81.0, 69.0], "value": 80.9, "unit": "score"}]}]} 09:13:22 INFO - Return code: 0 09:13:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:13:22 ERROR - # TBPL SUCCESS # 09:13:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:13:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:13:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:13:23 INFO - 2017-02-13 09:13:22.998 screenresolution[1933:20590] starting screenresolution argv=screenresolution get 09:13:23 INFO - 2017-02-13 09:13:23.001 screenresolution[1933:20590] Display 0: 1600x1200x32@60 09:13:23 INFO - 2017-02-13 09:13:23.006 screenresolution[1934:20593] starting screenresolution argv=screenresolution list 09:13:23 INFO - Available Modes on Display 0 09:13:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:13:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:13:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:13:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:13:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:13:23 INFO - Intel Iris: 09:13:23 INFO - Chipset Model: Intel Iris 09:13:23 INFO - Type: GPU 09:13:23 INFO - Bus: Built-In 09:13:23 INFO - VRAM (Dynamic, Max): 1536 MB 09:13:23 INFO - Vendor: Intel (0x8086) 09:13:23 INFO - Device ID: 0x0a2e 09:13:23 INFO - Revision ID: 0x0009 09:13:23 INFO - Displays: 09:13:23 INFO - Display: 09:13:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:13:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:13:23 INFO - Display Serial Number: 1600x1200 60 09:13:23 INFO - Main Display: Yes 09:13:23 INFO - Mirror: Off 09:13:23 INFO - Online: Yes 09:13:23 INFO - Rotation: Supported 09:13:23 INFO - Return code: 0 09:13:23 INFO - Running post-action listener: _resource_record_post_action 09:13:23 INFO - [mozharness: 2017-02-13 17:13:23.236013Z] Finished run-tests step (success) 09:13:23 INFO - Running post-run listener: _resource_record_post_run 09:13:23 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 1287680; Write bytes: 555347968; Read time: 9; Write time: 1670 09:13:23 INFO - TinderboxPrint: CPU usage
54.7% 09:13:23 INFO - TinderboxPrint: I/O read bytes / time
1,287,680 / 9 09:13:23 INFO - TinderboxPrint: I/O write bytes / time
555,347,968 / 1,670 09:13:23 INFO - TinderboxPrint: CPU idle
2,176.1 (45.3%) 09:13:23 INFO - TinderboxPrint: CPU system
967.9 (20.1%) 09:13:23 INFO - TinderboxPrint: CPU user
1,661.7 (34.6%) 09:13:23 INFO - TinderboxPrint: Swap in / out
253,751,296 / 0 09:13:23 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244730368; Write bytes: 240601088; Read time: 17883; Write time: 504 09:13:23 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 1287680; Write bytes: 297408512; Read time: 9; Write time: 1119 09:13:23 INFO - Running post-run listener: _upload_blobber_files 09:13:23 INFO - Blob upload gear active. 09:13:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:13:23 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:23 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:23 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:24 INFO - (blobuploader) - INFO - Open directory for files ... 09:13:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:13:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:13:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:25 INFO - (blobuploader) - INFO - Done attempting. 09:13:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:13:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:13:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:26 INFO - (blobuploader) - INFO - Done attempting. 09:13:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:13:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:13:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:27 INFO - (blobuploader) - INFO - Done attempting. 09:13:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:13:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:13:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:29 INFO - (blobuploader) - INFO - Done attempting. 09:13:29 INFO - (blobuploader) - INFO - Iteration through files over. 09:13:29 INFO - Return code: 0 09:13:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:13:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:13:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eacf10d97847957edda9b7150713be5087e7e491daeb7332b98072cf39d822ed907f6e0c63fa9701916e9a75fbc53d67129bdbcb3841d834a7ac24277bde2a03", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34a0a1a4a9ad99c9e6841951ce0620e9595135c5891d9084b8695176452cfff830447c326c3368ab1171212feee26dd6faabafa38487626ce6b8c415884d8e23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/530117582e6fa5c4f9cc980aa0375863863220c643aca5b9005f1402f9ad22e4f1bee941291d45ee6e986bb25975fe84bcabbd5bce2206f1d8e162e0bdaa3113", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f867a35449382cd6afde196343da1bb18c3ca97d1c735d23787fd8e0ce4557c09e99c085368164d0dfe7e57bd26475cf51f09bc864c0d90a23c182e6dfc40bdc"} 09:13:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:13:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:13:29 INFO - Contents: 09:13:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eacf10d97847957edda9b7150713be5087e7e491daeb7332b98072cf39d822ed907f6e0c63fa9701916e9a75fbc53d67129bdbcb3841d834a7ac24277bde2a03", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34a0a1a4a9ad99c9e6841951ce0620e9595135c5891d9084b8695176452cfff830447c326c3368ab1171212feee26dd6faabafa38487626ce6b8c415884d8e23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/530117582e6fa5c4f9cc980aa0375863863220c643aca5b9005f1402f9ad22e4f1bee941291d45ee6e986bb25975fe84bcabbd5bce2206f1d8e162e0bdaa3113", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f867a35449382cd6afde196343da1bb18c3ca97d1c735d23787fd8e0ce4557c09e99c085368164d0dfe7e57bd26475cf51f09bc864c0d90a23c182e6dfc40bdc"} 09:13:29 INFO - Running post-run listener: copy_logs_to_upload_dir 09:13:29 INFO - Copying logs to upload dir... 09:13:29 INFO - mkdir: /builds/slave/test/build/upload/logs 09:13:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.221233 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-02-13 09:13:29.559617) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-13 09:13:29.562717) ========= 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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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/eacf10d97847957edda9b7150713be5087e7e491daeb7332b98072cf39d822ed907f6e0c63fa9701916e9a75fbc53d67129bdbcb3841d834a7ac24277bde2a03", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34a0a1a4a9ad99c9e6841951ce0620e9595135c5891d9084b8695176452cfff830447c326c3368ab1171212feee26dd6faabafa38487626ce6b8c415884d8e23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/530117582e6fa5c4f9cc980aa0375863863220c643aca5b9005f1402f9ad22e4f1bee941291d45ee6e986bb25975fe84bcabbd5bce2206f1d8e162e0bdaa3113", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f867a35449382cd6afde196343da1bb18c3ca97d1c735d23787fd8e0ce4557c09e99c085368164d0dfe7e57bd26475cf51f09bc864c0d90a23c182e6dfc40bdc"} build_url:https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010774 build_url: 'https://queue.taskcluster.net/v1/task/Pvlu2YejTWGgFEfUu_r7nA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eacf10d97847957edda9b7150713be5087e7e491daeb7332b98072cf39d822ed907f6e0c63fa9701916e9a75fbc53d67129bdbcb3841d834a7ac24277bde2a03", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34a0a1a4a9ad99c9e6841951ce0620e9595135c5891d9084b8695176452cfff830447c326c3368ab1171212feee26dd6faabafa38487626ce6b8c415884d8e23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/530117582e6fa5c4f9cc980aa0375863863220c643aca5b9005f1402f9ad22e4f1bee941291d45ee6e986bb25975fe84bcabbd5bce2206f1d8e162e0bdaa3113", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f867a35449382cd6afde196343da1bb18c3ca97d1c735d23787fd8e0ce4557c09e99c085368164d0dfe7e57bd26475cf51f09bc864c0d90a23c182e6dfc40bdc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-13 09:13:29.596840) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 09:13:29.597171) ========= 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.f8tQTRwf2o/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.UYbw2ZBuDL/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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.005428 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 09:13:29.645349) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-13 09:13:29.645701) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-13 09:13:29.646059) ========= ========= Total master_lag: 0.26 =========