builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0372 starttime: 1489420791.49 results: success (0) buildid: 20170313072445 builduid: ad41c4f65eb94f29b1ed0d4ffd4f0bf4 revision: 8d9fd089cabd435590250c5e15b781c06a96f0a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:51.487855) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:51.488280) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:51.488565) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:51.515759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:51.516160) ========= 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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-13 08:59:51-- 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% 223M=0s 2017-03-13 08:59:51 (223 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126234 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:51.666631) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:51.666941) ========= 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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.026729 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:51.715130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-13 08:59:51.715525) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8d9fd089cabd435590250c5e15b781c06a96f0a1 --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 8d9fd089cabd435590250c5e15b781c06a96f0a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kN2qOaCCaH/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.8AfO6dOfpB/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-13 08:59:51,822 truncating revision to first 12 chars 2017-03-13 08:59:51,823 Setting DEBUG logging. 2017-03-13 08:59:51,823 attempt 1/10 2017-03-13 08:59:51,823 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8d9fd089cabd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-13 08:59:53,750 unpacking tar archive at: mozilla-central-8d9fd089cabd/testing/mozharness/ program finished with exit code 0 elapsedTime=2.259265 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-13 08:59:53.998526) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:53.998847) ========= script_repo_revision: 8d9fd089cabd435590250c5e15b781c06a96f0a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:53.999243) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:53.999528) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-13 08:59:54.019418) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-13 08:59:54.019754) ========= /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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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:59:54 INFO - MultiFileLogger online at 20170313 08:59:54 in /builds/slave/test 08:59:54 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:59:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:54 INFO - {'append_to_log': False, 08:59:54 INFO - 'base_work_dir': '/builds/slave/test', 08:59:54 INFO - 'blob_upload_branch': 'Firefox', 08:59:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:54 INFO - 'branch': 'Firefox', 08:59:54 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:54 INFO - 'config_files': ('talos/mac_config.py',), 08:59:54 INFO - 'default_actions': ('clobber', 08:59:54 INFO - 'read-buildbot-config', 08:59:54 INFO - 'download-and-extract', 08:59:54 INFO - 'populate-webroot', 08:59:54 INFO - 'create-virtualenv', 08:59:54 INFO - 'install', 08:59:54 INFO - 'run-tests'), 08:59:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:54 INFO - 'download_minidump_stackwalk': True, 08:59:54 INFO - 'download_symbols': 'ondemand', 08:59:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:54 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:54 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:54 INFO - 'gecko_profile': False, 08:59:54 INFO - 'gecko_profile_interval': 0, 08:59:54 INFO - 'installer_path': 'installer.exe', 08:59:54 INFO - 'log_level': 'info', 08:59:54 INFO - 'log_name': 'talos', 08:59:54 INFO - 'log_to_console': True, 08:59:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:59:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:59:54 INFO - 'opt_config_files': (), 08:59:54 INFO - 'pip_index': False, 08:59:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:59:54 INFO - 'cmd': ('bash', 08:59:54 INFO - '-c', 08:59:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:59:54 INFO - 'enabled': True, 08:59:54 INFO - 'halt_on_failure': False, 08:59:54 INFO - 'name': 'check_screen_resolution'},), 08:59:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:59:54 INFO - 'cmd': ('bash', 08:59:54 INFO - '-c', 08:59:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:59:54 INFO - 'enabled': True, 08:59:54 INFO - 'halt_on_failure': False, 08:59:54 INFO - 'name': 'check_screen_resolution'},), 08:59:54 INFO - 'run_cmd_checks_enabled': True, 08:59:54 INFO - 'suite': 'dromaeojs-e10s', 08:59:54 INFO - 'system_bits': '32', 08:59:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:54 INFO - 'title': 't-yosemite-r7-0372', 08:59:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:54 INFO - 'use_talos_json': True, 08:59:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:54 INFO - 'work_dir': 'build'} 08:59:54 INFO - [mozharness: 2017-03-13 15:59:54.219550Z] Running clobber step. 08:59:54 INFO - Running pre-action listener: _resource_record_pre_action 08:59:54 INFO - Running main action method: clobber 08:59:54 INFO - rmtree: /builds/slave/test/build 08:59:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:56 INFO - Running post-action listener: _resource_record_post_action 08:59:56 INFO - [mozharness: 2017-03-13 15:59:56.780191Z] Finished clobber step (success) 08:59:56 INFO - [mozharness: 2017-03-13 15:59:56.780314Z] Running read-buildbot-config step. 08:59:56 INFO - Running pre-action listener: _resource_record_pre_action 08:59:56 INFO - Running main action method: read_buildbot_config 08:59:56 INFO - Using buildbot properties: 08:59:56 INFO - { 08:59:56 INFO - "project": "", 08:59:56 INFO - "product": "firefox", 08:59:56 INFO - "script_repo_revision": "production", 08:59:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:59:56 INFO - "repository": "", 08:59:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:59:56 INFO - "buildid": "20170313072445", 08:59:56 INFO - "pgo_build": "False", 08:59:56 INFO - "basedir": "/builds/slave/test", 08:59:56 INFO - "buildnumber": 170, 08:59:56 INFO - "slavename": "t-yosemite-r7-0372", 08:59:56 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:59:56 INFO - "platform": "yosemite_r7", 08:59:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:59:56 INFO - "branch": "mozilla-central", 08:59:56 INFO - "repo_path": "mozilla-central", 08:59:56 INFO - "revision": "8d9fd089cabd435590250c5e15b781c06a96f0a1", 08:59:56 INFO - "stage_platform": "macosx64", 08:59:56 INFO - "builduid": "ad41c4f65eb94f29b1ed0d4ffd4f0bf4", 08:59:56 INFO - "slavebuilddir": "test" 08:59:56 INFO - } 08:59:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 08:59:56 INFO - Running post-action listener: _resource_record_post_action 08:59:56 INFO - [mozharness: 2017-03-13 15:59:56.783683Z] Finished read-buildbot-config step (success) 08:59:56 INFO - [mozharness: 2017-03-13 15:59:56.783789Z] Running download-and-extract step. 08:59:56 INFO - Running pre-action listener: _resource_record_pre_action 08:59:56 INFO - Running main action method: download_and_extract 08:59:56 INFO - mkdir: /builds/slave/test/build/tests 08:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:56 INFO - trying https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:59:56 INFO - Downloading https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 08:59:57 INFO - Downloaded 1199 bytes. 08:59:57 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:59:57 INFO - Using the following test package requirements: 08:59:57 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:59:57 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:59:57 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:59:57 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:59:57 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:59:57 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:59:57 INFO - u'jsshell-mac.zip'], 08:59:57 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:59:57 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:59:57 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:59:57 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:59:57 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:59:57 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:59:57 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:59:57 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:59:57 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:59:57 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:59:57 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:59:57 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:59:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:59:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:59:57 INFO - Fetch https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:59:59 INFO - Expected file size: 52470959 08:59:59 INFO - Obtained file size: 52470959 09:00:15 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:00:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:00:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:00:15 INFO - Fetch https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:00:18 INFO - Expected file size: 52470959 09:00:18 INFO - Obtained file size: 52470959 09:00:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:00:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:00:33 INFO - Fetch https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:00:34 INFO - Expected file size: 14404438 09:00:34 INFO - Obtained file size: 14404438 09:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:34 INFO - trying https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:00:34 INFO - Downloading https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:00:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:00:37 INFO - Downloaded 74901835 bytes. 09:00:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:00:37 INFO - mkdir: /builds/slave/test/properties 09:00:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:37 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:37 INFO - Contents: 09:00:37 INFO - build_url:https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:00:38 INFO - Running post-action listener: _resource_record_post_action 09:00:38 INFO - Running post-action listener: set_extra_try_arguments 09:00:38 INFO - [mozharness: 2017-03-13 16:00:38.241753Z] Finished download-and-extract step (success) 09:00:38 INFO - [mozharness: 2017-03-13 16:00:38.241904Z] Running populate-webroot step. 09:00:38 INFO - Running pre-action listener: _resource_record_pre_action 09:00:38 INFO - Running main action method: populate_webroot 09:00:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:38 INFO - '--tppagecycles', 09:00:38 INFO - '7'], 09:00:38 INFO - 'tests': ['tsvgm']}}, 09:00:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:38 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:00:38 INFO - 'talos_options': ['--disable-e10s'], 09:00:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:00:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:38 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:00:38 INFO - 'talos_options': ['--disable-e10s'], 09:00:38 INFO - 'tests': ['damp', 'tps']}, 09:00:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:00:38 INFO - 'tests': ['damp', 'tps']}, 09:00:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:38 INFO - 'tests': ['dromaeo_dom']}, 09:00:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:00:38 INFO - 'tests': ['basic_compositor_video']}, 09:00:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:00:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:38 INFO - 'tests': ['a11yr', 09:00:38 INFO - 'ts_paint', 09:00:38 INFO - 'tpaint', 09:00:38 INFO - 'sessionrestore', 09:00:38 INFO - 'sessionrestore_no_auto_restore', 09:00:38 INFO - 'tabpaint']}, 09:00:38 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:38 INFO - 'ts_paint', 09:00:38 INFO - 'tpaint', 09:00:38 INFO - 'sessionrestore', 09:00:38 INFO - 'sessionrestore_no_auto_restore', 09:00:38 INFO - 'tabpaint']}, 09:00:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:38 INFO - 'ts_paint', 09:00:38 INFO - 'tpaint', 09:00:38 INFO - 'sessionrestore', 09:00:38 INFO - 'sessionrestore_no_auto_restore', 09:00:38 INFO - 'tabpaint']}, 09:00:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:38 INFO - 'ts_paint', 09:00:38 INFO - 'tpaint', 09:00:38 INFO - 'sessionrestore', 09:00:38 INFO - 'sessionrestore_no_auto_restore', 09:00:38 INFO - 'tabpaint']}, 09:00:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:38 INFO - 'tests': ['a11yr', 09:00:38 INFO - 'ts_paint', 09:00:38 INFO - 'tpaint', 09:00:38 INFO - 'sessionrestore', 09:00:38 INFO - 'sessionrestore_no_auto_restore', 09:00:38 INFO - 'tabpaint']}, 09:00:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:38 INFO - 'tests': ['a11yr', 09:00:38 INFO - 'ts_paint', 09:00:38 INFO - 'tpaint', 09:00:38 INFO - 'sessionrestore', 09:00:38 INFO - 'sessionrestore_no_auto_restore', 09:00:38 INFO - 'tabpaint']}, 09:00:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:38 INFO - 'tests': ['tsvgx', 09:00:38 INFO - 'tsvgr_opacity', 09:00:38 INFO - 'tart', 09:00:38 INFO - 'tscrollx', 09:00:38 INFO - 'cart', 09:00:38 INFO - 'tsvg_static']}, 09:00:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:38 INFO - 'tsvgr_opacity', 09:00:38 INFO - 'tart', 09:00:38 INFO - 'tscrollx', 09:00:38 INFO - 'cart', 09:00:38 INFO - 'tsvg_static']}, 09:00:38 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:00:38 INFO - 'talos_options': ['--disable-e10s'], 09:00:38 INFO - 'tests': ['tp5o']}, 09:00:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:00:38 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:00:38 INFO - 'talos_options': ['--disable-e10s', 09:00:38 INFO - '--xperf_path', 09:00:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:38 INFO - 'tests': ['tp5n']}, 09:00:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:00:38 INFO - 'talos_options': ['--xperf_path', 09:00:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:38 INFO - 'tests': ['tp5n']}}, 09:00:38 INFO - 'talos.zip': {'path': '', 09:00:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:38 INFO - Running post-action listener: _resource_record_post_action 09:00:38 INFO - [mozharness: 2017-03-13 16:00:38.260169Z] Finished populate-webroot step (success) 09:00:38 INFO - [mozharness: 2017-03-13 16:00:38.260280Z] Running create-virtualenv step. 09:00:38 INFO - Running pre-action listener: _resource_record_pre_action 09:00:38 INFO - Running main action method: create_virtualenv 09:00:38 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:00:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:38 INFO - Using real prefix '/tools/python27' 09:00:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:39 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:42 INFO - Installing pip.................done. 09:00:42 INFO - Return code: 0 09:00:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:00:42 INFO - Reading from file tmpfile_stdout 09:00:42 INFO - Output received: 09:00:42 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:00:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10226ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3f24ea610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102048d30>, '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 0x101f6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10203dbe8>, '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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:00:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kN2qOaCCaH/Render', 09:00:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:42 INFO - 'HOME': '/Users/cltbld', 09:00:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:42 INFO - 'LOGNAME': 'cltbld', 09:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:42 INFO - 'MOZ_NO_REMOTE': '1', 09:00:42 INFO - 'NO_EM_RESTART': '1', 09:00:42 INFO - 'PAGER': '/bin/cat', 09:00:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:42 INFO - 'PWD': '/builds/slave/test', 09:00:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:42 INFO - 'SHELL': '/bin/bash', 09:00:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AfO6dOfpB/Listeners', 09:00:42 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 09:00:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:42 INFO - 'USER': 'cltbld', 09:00:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:42 INFO - 'XPC_FLAGS': '0x0', 09:00:42 INFO - 'XPC_SERVICE_NAME': '0', 09:00:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:00:42 INFO - Cleaning up... 09:00:42 INFO - Return code: 0 09:00:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10226ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3f24ea610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102048d30>, '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 0x101f6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10203dbe8>, '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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:00:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kN2qOaCCaH/Render', 09:00:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:42 INFO - 'HOME': '/Users/cltbld', 09:00:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:42 INFO - 'LOGNAME': 'cltbld', 09:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:42 INFO - 'MOZ_NO_REMOTE': '1', 09:00:42 INFO - 'NO_EM_RESTART': '1', 09:00:42 INFO - 'PAGER': '/bin/cat', 09:00:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:42 INFO - 'PWD': '/builds/slave/test', 09:00:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:42 INFO - 'SHELL': '/bin/bash', 09:00:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AfO6dOfpB/Listeners', 09:00:42 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 09:00:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:42 INFO - 'USER': 'cltbld', 09:00:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:42 INFO - 'XPC_FLAGS': '0x0', 09:00:42 INFO - 'XPC_SERVICE_NAME': '0', 09:00:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:42 INFO - Downloading/unpacking psutil>=3.1.1 09:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:46 INFO - Installing collected packages: psutil 09:00:46 INFO - Running setup.py install for psutil 09:00:46 INFO - building 'psutil._psutil_osx' extension 09:00:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:00:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:00:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:00:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:00:47 INFO - building 'psutil._psutil_posix' extension 09:00:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:00:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:00:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:00:47 INFO - ^ 09:00:47 INFO - 1 warning generated. 09:00:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:00:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:47 INFO - Successfully installed psutil 09:00:47 INFO - Cleaning up... 09:00:47 INFO - Return code: 0 09:00:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:48 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 0x10226ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3f24ea610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102048d30>, '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 0x101f6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10203dbe8>, '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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:00:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kN2qOaCCaH/Render', 09:00:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:48 INFO - 'HOME': '/Users/cltbld', 09:00:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:48 INFO - 'LOGNAME': 'cltbld', 09:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:48 INFO - 'MOZ_NO_REMOTE': '1', 09:00:48 INFO - 'NO_EM_RESTART': '1', 09:00:48 INFO - 'PAGER': '/bin/cat', 09:00:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:48 INFO - 'PWD': '/builds/slave/test', 09:00:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:48 INFO - 'SHELL': '/bin/bash', 09:00:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AfO6dOfpB/Listeners', 09:00:48 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 09:00:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:48 INFO - 'USER': 'cltbld', 09:00:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:48 INFO - 'XPC_FLAGS': '0x0', 09:00:48 INFO - 'XPC_SERVICE_NAME': '0', 09:00:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:00:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:00:51 INFO - Installing collected packages: mozsystemmonitor 09:00:51 INFO - Running setup.py install for mozsystemmonitor 09:00:51 INFO - Successfully installed mozsystemmonitor 09:00:51 INFO - Cleaning up... 09:00:52 INFO - Return code: 0 09:00:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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', '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 0x10226ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3f24ea610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102048d30>, '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 0x101f6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10203dbe8>, '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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:00: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 jsonschema==2.5.1 09:00:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kN2qOaCCaH/Render', 09:00:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:52 INFO - 'HOME': '/Users/cltbld', 09:00:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:52 INFO - 'LOGNAME': 'cltbld', 09:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:52 INFO - 'MOZ_NO_REMOTE': '1', 09:00:52 INFO - 'NO_EM_RESTART': '1', 09:00:52 INFO - 'PAGER': '/bin/cat', 09:00:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:52 INFO - 'PWD': '/builds/slave/test', 09:00:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:52 INFO - 'SHELL': '/bin/bash', 09:00:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AfO6dOfpB/Listeners', 09:00:52 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 09:00:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:52 INFO - 'USER': 'cltbld', 09:00:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:52 INFO - 'XPC_FLAGS': '0x0', 09:00:52 INFO - 'XPC_SERVICE_NAME': '0', 09:00:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:52 INFO - Downloading/unpacking jsonschema==2.5.1 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:55 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:55 INFO - warning: no files found matching '*.txt' 09:00:55 INFO - no previously-included directories found matching 'build' 09:00:55 INFO - no previously-included directories found matching 'dist' 09:00:55 INFO - no previously-included directories found matching '.git*' 09:00:55 INFO - Installing collected packages: jsonschema, functools32 09:00:55 INFO - Running setup.py install for functools32 09:00:55 INFO - warning: no files found matching '*.txt' 09:00:55 INFO - no previously-included directories found matching 'build' 09:00:55 INFO - no previously-included directories found matching 'dist' 09:00:55 INFO - no previously-included directories found matching '.git*' 09:00:55 INFO - Successfully installed jsonschema functools32 09:00:55 INFO - Cleaning up... 09:00:55 INFO - Return code: 0 09:00:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:55 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 0x10226ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3f24ea610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102048d30>, '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 0x101f6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10203dbe8>, '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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:00:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:00:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kN2qOaCCaH/Render', 09:00:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:55 INFO - 'HOME': '/Users/cltbld', 09:00:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:55 INFO - 'LOGNAME': 'cltbld', 09:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:55 INFO - 'MOZ_NO_REMOTE': '1', 09:00:55 INFO - 'NO_EM_RESTART': '1', 09:00:55 INFO - 'PAGER': '/bin/cat', 09:00:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:55 INFO - 'PWD': '/builds/slave/test', 09:00:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:55 INFO - 'SHELL': '/bin/bash', 09:00:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AfO6dOfpB/Listeners', 09:00:55 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 09:00:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:55 INFO - 'USER': 'cltbld', 09:00:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:55 INFO - 'XPC_FLAGS': '0x0', 09:00:55 INFO - 'XPC_SERVICE_NAME': '0', 09:00:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:00:55 INFO - Cleaning up... 09:00:55 INFO - Return code: 0 09:00:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:55 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 0x10226ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3f24ea610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102048d30>, '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 0x101f6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10203dbe8>, '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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:00:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:00:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kN2qOaCCaH/Render', 09:00:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:55 INFO - 'HOME': '/Users/cltbld', 09:00:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:55 INFO - 'LOGNAME': 'cltbld', 09:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:55 INFO - 'MOZ_NO_REMOTE': '1', 09:00:55 INFO - 'NO_EM_RESTART': '1', 09:00:55 INFO - 'PAGER': '/bin/cat', 09:00:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:55 INFO - 'PWD': '/builds/slave/test', 09:00:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:55 INFO - 'SHELL': '/bin/bash', 09:00:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AfO6dOfpB/Listeners', 09:00:55 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 09:00:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:55 INFO - 'USER': 'cltbld', 09:00:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:55 INFO - 'XPC_FLAGS': '0x0', 09:00:55 INFO - 'XPC_SERVICE_NAME': '0', 09:00:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:56 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:58 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:00 INFO - Downloading docopt-0.6.1.tar.gz 09:01:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:01:00 INFO - Installing collected packages: blobuploader, requests, docopt 09:01:00 INFO - Running setup.py install for blobuploader 09:01:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:01:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:01:00 INFO - Running setup.py install for requests 09:01:00 INFO - Running setup.py install for docopt 09:01:00 INFO - Successfully installed blobuploader requests docopt 09:01:00 INFO - Cleaning up... 09:01:01 INFO - Return code: 0 09:01:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:01 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 0x10226ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3f24ea610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102048d30>, '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 0x101f6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10203dbe8>, '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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:01:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:01:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kN2qOaCCaH/Render', 09:01:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:01 INFO - 'HOME': '/Users/cltbld', 09:01:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:01 INFO - 'LOGNAME': 'cltbld', 09:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:01 INFO - 'MOZ_NO_REMOTE': '1', 09:01:01 INFO - 'NO_EM_RESTART': '1', 09:01:01 INFO - 'PAGER': '/bin/cat', 09:01:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:01 INFO - 'PWD': '/builds/slave/test', 09:01:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:01 INFO - 'SHELL': '/bin/bash', 09:01:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AfO6dOfpB/Listeners', 09:01:01 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 09:01:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:01 INFO - 'USER': 'cltbld', 09:01:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:01 INFO - 'XPC_FLAGS': '0x0', 09:01:01 INFO - 'XPC_SERVICE_NAME': '0', 09:01:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:01 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-5Hob5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:01 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-tzJXXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:01 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-kcI4I0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:01 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Pc7qPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:01 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-yd57vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:01 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-C2hkmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:01 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-xpBiwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:02 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-GKBgua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:02 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-u_81te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:02 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-ttzrD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:02 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-gp9hF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:02 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-PMS9C5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:02 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-pPQnZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:02 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-bqSfdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:02 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-r4CVsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:02 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-DDzsmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:02 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-QjTTSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:01:03 INFO - Running setup.py install for manifestparser 09:01:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:01:03 INFO - Running setup.py install for mozcrash 09:01:03 INFO - Running setup.py install for mozdebug 09:01:03 INFO - Running setup.py install for mozdevice 09:01:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:01:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:01:03 INFO - Running setup.py install for mozfile 09:01:03 INFO - Running setup.py install for mozhttpd 09:01:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:01:03 INFO - Running setup.py install for mozinfo 09:01:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:01:04 INFO - Running setup.py install for mozInstall 09:01:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:01:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:01:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:01:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:01:04 INFO - Running setup.py install for mozleak 09:01:04 INFO - Running setup.py install for mozlog 09:01:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:01:04 INFO - Running setup.py install for moznetwork 09:01:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:01:04 INFO - Running setup.py install for mozprocess 09:01:04 INFO - Running setup.py install for mozprofile 09:01:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:01:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:01:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:01:05 INFO - Running setup.py install for mozrunner 09:01:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:01:05 INFO - Running setup.py install for mozscreenshot 09:01:05 INFO - Running setup.py install for moztest 09:01:05 INFO - Running setup.py install for mozversion 09:01:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:01:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:01:05 INFO - Cleaning up... 09:01:05 INFO - Return code: 0 09:01:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01: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')]} 09:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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')]} 09:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:05 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 0x10226ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3f24ea610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102048d30>, '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 0x101f6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10203dbe8>, '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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:01:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kN2qOaCCaH/Render', 09:01:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:05 INFO - 'HOME': '/Users/cltbld', 09:01:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:05 INFO - 'LOGNAME': 'cltbld', 09:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:05 INFO - 'MOZ_NO_REMOTE': '1', 09:01:05 INFO - 'NO_EM_RESTART': '1', 09:01:05 INFO - 'PAGER': '/bin/cat', 09:01:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:05 INFO - 'PWD': '/builds/slave/test', 09:01:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:05 INFO - 'SHELL': '/bin/bash', 09:01:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AfO6dOfpB/Listeners', 09:01:05 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 09:01:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:05 INFO - 'USER': 'cltbld', 09:01:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:05 INFO - 'XPC_FLAGS': '0x0', 09:01:05 INFO - 'XPC_SERVICE_NAME': '0', 09:01:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:05 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-4v5Mu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:06 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-60LwPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:06 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-vNDcLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:06 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-bZkoYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:06 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-btpQvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:06 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-CSBzz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:06 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Pc2ts9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:06 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-OAh2e8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:06 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-jSR2fD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:06 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-PBP3SO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:07 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-H4vgtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:07 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-3GInEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:07 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-BnV20b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:07 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-IlzIsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:07 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-_xqhM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:07 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-T6_3hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:07 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-W5W2CN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:10 INFO - Downloading blessings-1.6.tar.gz 09:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:01:10 INFO - Installing collected packages: blessings 09:01:10 INFO - Running setup.py install for blessings 09:01:10 INFO - Successfully installed blessings 09:01:10 INFO - Cleaning up... 09:01:10 INFO - Return code: 0 09:01:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:01:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:11 INFO - Reading from file tmpfile_stdout 09:01:11 INFO - Current package versions: 09:01:11 INFO - blessings == 1.6 09:01:11 INFO - blobuploader == 1.2.4 09:01:11 INFO - docopt == 0.6.1 09:01:11 INFO - functools32 == 3.2.3-2 09:01:11 INFO - jsonschema == 2.5.1 09:01:11 INFO - manifestparser == 1.1 09:01:11 INFO - mozInstall == 1.12 09:01:11 INFO - mozcrash == 1.0 09:01:11 INFO - mozdebug == 0.1 09:01:11 INFO - mozdevice == 0.49 09:01:11 INFO - mozfile == 1.2 09:01:11 INFO - mozhttpd == 0.7 09:01:11 INFO - mozinfo == 0.9 09:01:11 INFO - mozleak == 0.1 09:01:11 INFO - mozlog == 3.4 09:01:11 INFO - moznetwork == 0.27 09:01:11 INFO - mozprocess == 0.25 09:01:11 INFO - mozprofile == 0.28 09:01:11 INFO - mozrunner == 6.13 09:01:11 INFO - mozscreenshot == 0.1 09:01:11 INFO - mozsystemmonitor == 0.3 09:01:11 INFO - moztest == 0.8 09:01:11 INFO - mozversion == 1.4 09:01:11 INFO - psutil == 3.1.1 09:01:11 INFO - requests == 1.2.3 09:01:11 INFO - wsgiref == 0.1.2 09:01:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:11 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 0x10226ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3f24ea610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102048d30>, '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 0x101f6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10203dbe8>, '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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:01:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:01:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kN2qOaCCaH/Render', 09:01:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:11 INFO - 'HOME': '/Users/cltbld', 09:01:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:11 INFO - 'LOGNAME': 'cltbld', 09:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:11 INFO - 'MOZ_NO_REMOTE': '1', 09:01:11 INFO - 'NO_EM_RESTART': '1', 09:01:11 INFO - 'PAGER': '/bin/cat', 09:01:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:11 INFO - 'PWD': '/builds/slave/test', 09:01:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:11 INFO - 'SHELL': '/bin/bash', 09:01:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AfO6dOfpB/Listeners', 09:01:11 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 09:01:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:11 INFO - 'USER': 'cltbld', 09:01:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:11 INFO - 'XPC_FLAGS': '0x0', 09:01:11 INFO - 'XPC_SERVICE_NAME': '0', 09:01:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:01:11 INFO - Cleaning up... 09:01:11 INFO - Return code: 0 09:01:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:11 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 0x10226ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3f24ea610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102048d30>, '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 0x101f6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10203dbe8>, '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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:01:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:01:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:01:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kN2qOaCCaH/Render', 09:01:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:11 INFO - 'HOME': '/Users/cltbld', 09:01:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:11 INFO - 'LOGNAME': 'cltbld', 09:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:11 INFO - 'MOZ_NO_REMOTE': '1', 09:01:11 INFO - 'NO_EM_RESTART': '1', 09:01:11 INFO - 'PAGER': '/bin/cat', 09:01:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:11 INFO - 'PWD': '/builds/slave/test', 09:01:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:11 INFO - 'SHELL': '/bin/bash', 09:01:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AfO6dOfpB/Listeners', 09:01:11 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 09:01:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:11 INFO - 'USER': 'cltbld', 09:01:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:11 INFO - 'XPC_FLAGS': '0x0', 09:01:11 INFO - 'XPC_SERVICE_NAME': '0', 09:01:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:01:11 INFO - Cleaning up... 09:01:11 INFO - Return code: 0 09:01:11 INFO - Running post-action listener: _resource_record_post_action 09:01:11 INFO - Running post-action listener: _start_resource_monitoring 09:01:11 INFO - Starting resource monitoring. 09:01:11 INFO - [mozharness: 2017-03-13 16:01:11.611667Z] Finished create-virtualenv step (success) 09:01:11 INFO - [mozharness: 2017-03-13 16:01:11.612631Z] Running install step. 09:01:11 INFO - Running pre-action listener: _resource_record_pre_action 09:01:11 INFO - Running main action method: install 09:01:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:11 INFO - Reading from file tmpfile_stdout 09:01:11 INFO - Detecting whether we're running mozinstall >=1.0... 09:01:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:01:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:01:11 INFO - Reading from file tmpfile_stdout 09:01:11 INFO - Output received: 09:01:11 INFO - Usage: mozinstall [options] installer 09:01:11 INFO - Options: 09:01:11 INFO - -h, --help show this help message and exit 09:01:11 INFO - -d DEST, --destination=DEST 09:01:11 INFO - Directory to install application into. [default: 09:01:11 INFO - "/builds/slave/test"] 09:01:11 INFO - --app=APP Application being installed. [default: firefox] 09:01:11 INFO - mkdir: /builds/slave/test/build/application 09:01:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:01:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:01:32 INFO - Reading from file tmpfile_stdout 09:01:32 INFO - Output received: 09:01:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:01:32 INFO - Running post-action listener: _resource_record_post_action 09:01:32 INFO - [mozharness: 2017-03-13 16:01:32.029162Z] Finished install step (success) 09:01:32 INFO - [mozharness: 2017-03-13 16:01:32.029326Z] Running run-tests step. 09:01:32 INFO - Running pre-action listener: _resource_record_pre_action 09:01:32 INFO - Running main action method: run_tests 09:01:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:01:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:01:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:01:32 INFO - 2017-03-13 09:01:32.044 screenresolution[1627:7479] starting screenresolution argv=screenresolution get 09:01:32 INFO - 2017-03-13 09:01:32.046 screenresolution[1627:7479] Display 0: 1600x1200x32@60 09:01:32 INFO - 2017-03-13 09:01:32.052 screenresolution[1628:7482] starting screenresolution argv=screenresolution list 09:01:32 INFO - Available Modes on Display 0 09:01:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:01:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:01:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:01:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:01:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:01:32 INFO - Intel Iris: 09:01:32 INFO - Chipset Model: Intel Iris 09:01:32 INFO - Type: GPU 09:01:32 INFO - Bus: Built-In 09:01:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:01:32 INFO - Vendor: Intel (0x8086) 09:01:32 INFO - Device ID: 0x0a2e 09:01:32 INFO - Revision ID: 0x0009 09:01:32 INFO - Displays: 09:01:32 INFO - Display: 09:01:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:01:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:01:32 INFO - Display Serial Number: 1600x1200 60 09:01:32 INFO - Main Display: Yes 09:01:32 INFO - Mirror: Off 09:01:32 INFO - Online: Yes 09:01:32 INFO - Rotation: Supported 09:01:32 INFO - Return code: 0 09:01:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:01:32 INFO - Python 2.7.3 09:01:32 INFO - Return code: 0 09:01:32 INFO - grabbing minidump binary from tooltool 09:01:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:32 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 0x102048d30>, '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 0x101f6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10203dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:01:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:01:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:01:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:01:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:01:32 INFO - Return code: 0 09:01:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:01:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:01:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:01:32 INFO - ENV: RUST_BACKTRACE is now 1 09:01:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:01:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:01:32 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/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:01:32 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/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0372 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kN2qOaCCaH/Render', 09:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:32 INFO - 'HOME': '/Users/cltbld', 09:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:32 INFO - 'LOGNAME': 'cltbld', 09:01:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:01:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:32 INFO - 'MOZ_NO_REMOTE': '1', 09:01:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:01:32 INFO - 'NO_EM_RESTART': '1', 09:01:32 INFO - 'PAGER': '/bin/cat', 09:01:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:32 INFO - 'PWD': '/builds/slave/test', 09:01:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:32 INFO - 'RUST_BACKTRACE': '1', 09:01:32 INFO - 'SHELL': '/bin/bash', 09:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AfO6dOfpB/Listeners', 09:01:32 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 09:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:32 INFO - 'USER': 'cltbld', 09:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:32 INFO - 'XPC_FLAGS': '0x0', 09:01:32 INFO - 'XPC_SERVICE_NAME': '0', 09:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:32 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/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:01:32 INFO - mozversion application_buildid: 20170313072445 09:01:32 INFO - mozversion application_changeset: 8d9fd089cabd435590250c5e15b781c06a96f0a1 09:01:32 INFO - mozversion application_display_name: Nightly 09:01:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:01:32 INFO - mozversion application_name: Firefox 09:01:32 INFO - mozversion application_remotingname: firefox 09:01:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:01:32 INFO - mozversion application_vendor: Mozilla 09:01:32 INFO - mozversion application_version: 55.0a1 09:01:32 INFO - mozversion platform_buildid: 20170313072445 09:01:32 INFO - mozversion platform_changeset: 8d9fd089cabd435590250c5e15b781c06a96f0a1 09:01:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:01:32 INFO - mozversion platform_version: 55.0a1 09:01:32 INFO - using testdate: 1489420892 09:01:32 INFO - actual date: 1489420892 09:01:32 INFO - starting webserver on 'localhost:49249' 09:01:32 INFO - SUITE-START | Running 2 tests 09:01:32 INFO - TEST-START | dromaeo_css 09:01:32 INFO - operating with platform_type : mac_ 09:01:32 INFO - Initialising browser for dromaeo_css test... 09:01:32 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpAsrQIS/profile http://localhost:49249/getInfo.html) 09:01:35 INFO - PID 1638 | 2017-03-13 09:01:35.676 plugin-container[1640:7665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 09:01:35 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:35 INFO - PID 1638 | 2017-03-13 09:01:35.679 plugin-container[1640:7665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a27, name = 'com.apple.CFPasteboardClient' 09:01:35 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:35 INFO - PID 1638 | 2017-03-13 09:01:35.679 plugin-container[1640:7665] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:01:35 INFO - PID 1638 | __metrics Screen width/height:1600/1200 09:01:35 INFO - PID 1638 | colorDepth:24 09:01:35 INFO - PID 1638 | Browser inner width/height: 1024/689 09:01:35 INFO - PID 1638 | __metrics 09:01:35 ERROR - PID 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:01:36 INFO - TEST-INFO | 1638: exit 0 09:01:36 INFO - Browser initialized. 09:01:36 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:36 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpAsrQIS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:39 INFO - PID 1641 | 2017-03-13 09:01:39.186 plugin-container[1643:7789] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 09:01:39 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:39 INFO - PID 1641 | 2017-03-13 09:01:39.187 plugin-container[1643:7789] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 09:01:39 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:39 INFO - PID 1641 | 2017-03-13 09:01:39.187 plugin-container[1643:7789] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:01:48 INFO - PID 1641 | RSS: Main: 226717696 09:01:48 INFO - PID 1641 | 09:04:52 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 09:04:55 INFO - PID 1641 | RSS: Main: 250744832 09:04:55 INFO - PID 1641 | 09:06:37 INFO - PID 1641 | ************************* 09:06:37 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 09:06:37 INFO - PID 1641 | 09:06:37 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:06:37 INFO - PID 1641 | Full stack: 09:06:37 INFO - PID 1641 | ************************* 09:07:57 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 09:07:58 INFO - PID 1641 | RSS: Main: 258179072 09:07:58 INFO - PID 1641 | 09:11:00 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 09:11:01 INFO - PID 1641 | RSS: Main: 270635008 09:11:01 INFO - PID 1641 | 09:14:02 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 09:14:03 INFO - PID 1641 | RSS: Main: 263847936 09:14:03 INFO - PID 1641 | 09:17:06 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 09:17:07 INFO - PID 1641 | RSS: Main: 274034688 09:17:07 INFO - PID 1641 | 09:20:10 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 09:20:10 INFO - PID 1641 | RSS: Main: 267874304 09:20:10 INFO - PID 1641 | 09:20:10 INFO - PID 1641 | __start_tp_report 09:20:10 INFO - PID 1641 | _x_x_mozilla_page_load 09:20:10 INFO - PID 1641 | _x_x_mozilla_page_load_details 09:20:10 INFO - PID 1641 | |i|pagename|runs| 09:20:10 INFO - PID 1641 | |0;dojo.html;3060.9234769130767;3451.8791842285523;3453.930921381574;3487.7907325560454;3495.8426870790813;5713.571482138836;5853.09277062055;5877.41225877412;5913.6451812891255;5936.792212272571;5734.971325143369;5783.016887129188;5786.768529258825;5792.797252096167;5800.216970708953;5218.13900706383;5224.981128679909;5241.2400201970695;5304.575633949275;5317.229001794737;4423.7566933818625;5319.494648008453;5329.546988505982;5329.653572517774;5332.013577488157;5340.572754179656;5372.865678358053;5379.488948549901;5447.073997420428;5447.918281225785;5409.486098820606;5409.783608655669;5417.729113544307;5420.214068959979;5434.239206511092;5114.974425127888;5232.372115346183;5251.870847767748;5315.867103322409;5407.567394608422;5638.718064096779;5815.738291776838;5825.097109947965;5847.532197424239;5913.645181289094;6324.968375158141;6345.968270158666;6348;6348.77779277723;6352.523560732963;6227.844303892393;6309.0536419536975;6397.1363865877975;6408.647524386157;6461.93538064618;4855.708657480492;5836.64980101191;5849.590528662995;5898.085796701518;5917.911231331534;5285.286486324374;6361.9045714314325;6395.264544577433;6396.9360306397275;6402.8719425611225;6122.337166850181;6259.530535209878;6300.842478938063;6309.7160627770945;6312.652804095819;6210.254769427697;6252.624842509373;6260.718267677966;6280.497560195174;6312.274088479791;6104.246950856165;6128.509719222451;6266.185395898595;6279.654618995999;6302.432781049728;6231.158793562901;6248.50011999039;6255.186825712628;6291.370862913764;6292.8426789329715;5138.075146473596;5152.098382782998;5155.845324640267;5169.3796744390165;5185.859110995575;4473.7986790594505;4500.639948804088;4519.209138400766;4529.365888775574;4585.426821647294;6108.6759897029115;6123.709696704001;6130.581587307925;6136.365905528278;6141.884937092159;4723.173444647173;6041.456268935817;6154.461384653837;6308.211473565804;6326.082718005863;5886.8822623547285;5966.284045914518;5975.043992961106;6110.877782444415;6140.232470941132;5503.3120859892515;5527.39198688144;5551.639143455663;5558.666480011212;5583.804566840222;6578.375054369827;6614.768483103068;6625;6631.3700198481065;6660.666966651745;6221.595596286227;6288.056791481315;6292.559520833681;6299.811005669837;6328.778492752732;6231.376862313732;6369.458596019205;6491.091247225298;6551.213854337319;6622;6165.784197553064;6173.290071641819;6235.251769787655;6239.781607643892;6244;5707.972564938351;5911.861158113856;6267.2792628848265;6273.37266273369;6274.749010039547;6056.485198757979;6132.600268413531;6142.846428839315;6152.753889844356;6187.783427580013;5112.463191365002;6112.777444511026;6113.810786210516;6115.727150604437;6145.525073982123;5659.377468478547;5820.272465941757;5923.318818335946;5949.910751338647;6116.487945662109;6106.36966090081;6159.81520554384;6212.720427580679;6252.749890004349;6260.40526150015;5119.667221630582;6120.7551697931585;6127.080937859357;6132.509399248139;6149.96925015372 09:20:10 INFO - PID 1641 | |1;ext.html;773.4701729315418;825.1665817524299;853.9402241843073;859.5143743784757;929.5723966975191;36532.63467365329;37253.81373093134;37339.43990840133;37352.066198345055;37525.812370938176;29433;35576.93269201928;36091;36111.45832812503;36201.45697814528;7213.783586492414;7389.66746496409;7621.314081732643;7785.143634200262;7815.257550532719;10602.78794424111;10828.783424331508;10866.511007004665;10870.72823179419;10893.673189804318;10924.563017479291;11010.779784404307;11012.5044373003;11012.834807477895;11029.393383363911;10877.238593298433;10953.726156846064;10966.396848173386;10968.725781855437;10985.450727463594;10622.57509699611;10697.518611662494;10724.14206863449;10733.624323148651;10759.892401075967;37973;38082.85751427462;38279;38342.42486362707;38393.808030959946;3798.810059497042;3802.6957843372466;3824.063104539378;3841.615838416164;3992.2414741199077;20741.377758667266;27792.58311125335;27798.583021254697;27834.860825695945;27918.743656535302;3100.58142150809;3160.1309639848996;3208.727258183057;3271.345730853815;3283.9014829555153;309640;310734;311451.4427427871;312349;312705;37322.25355492875;37929.86210413692;47333;49183.75408122937;49785;51966.74016629893;52039;52179.73910130425;52245.2163217552;52418.689532761986;14313.28433578317;14428.85571144296;14453.638659033608;14505.709885802225;14605.269736513132;11692.766144677058;11726.472308746126;11781.469833857496;11784.882151178379;11795.174337796443;13571;13788.896888248915;14013.789793153112;14042.227677477837;14184.432622695183;5074.67014644047;6084.6349219047;6086.087086936995;6086.969565152146;6108.664023478751;952.8665986761858;957.3203025851584;971.121135372489;978.1392374710351;996.8604395384652;22575.77424225737;26926.461470770475;27716.429991905472;28414.573781393297;28510.287242819097;1009.8084260572595;1018.2057994764095;1055.5619417941623;1055.8416237564427;1059.7456610413599;12560.183588066746;13839.962002849827;13908.095973761672;14444.855551444562;14796.926015369854;399485;453999;458935;461847;464239.6788016106;53695;53771.19343209855;54114.91770164575;54228.457715422344;54257.91484170294;1374.6357215337944;1447.862453066957;1455.628814652257;1458.3145921416917;1471.315838135251;25037.87481062583;27108;27770;27808.58287125654;28204.71795282103;32411.675883240867;40274;43386;43640.56359436365;43809.78095109504;389246;389409.158862623;392329;423081;424206;33549.83225083859;33579;34145;34276.31447371088;34300.48549271813;40624.98437539085;41167.1766564673;41212.793936030124;41474.511529308336;42941.57058429376;30251.848740756155;31608.73565057372;35217.47173792446;35362.46956295606;36966;27201.72798272071;27239.688260788957;34611;34997.47503787394;35463.290734185684;34301.313973720884;35088.29823403569;36197.457038144974;36321.45517817288;36372.45441318435;30119.698803012565;31946.36107277888;32018.35963280768;32119.357612848078;32124;23688.763112368655;24114.51770964606;24203.152889648776;24463.021479140636;24553.386165346008 09:20:10 INFO - PID 1641 | |2;jquery.html;400.0199511197567;410.4930410942487;415.6633127166994;425.20274485339985;432.08182611949604;20010.699839502402;21019.2643257486;22284.220052298173;22351.206099237595;22511.98696058677;21457.892710536464;22201.1119555218;22277.55444891101;22567.54864902701;22772.544549109007;6507.869842603157;6880.483963702717;6890.000949862267;6893.620850853201;6929.133858267716;6428.260750013738;6780.254172041096;6855.485838562127;6855.794326170223;6882.414994725455;6653.667316634173;6813.523053386265;6820.795376138699;6831.419329357011;6869.793906182797;5780.682062486561;6795.93204067958;6823.590584564942;6838.14523184602;6840.31596840317;6536.11762412073;6601.900971485432;6656.267810540837;6669.299723529008;6679.499037572153;27165.592516112276;27285.863570682217;27395.726042739516;27508.17475475739;27575.31219692657;3321.1697075731067;3329.184349834282;3344.698977092049;3345.96654033459;3495.423255162895;14316.928415357961;16378.26297816601;16712.66574668512;17568.594512439104;17651.852629579036;2017.9495512612154;2112.883791391488;2114.694982469116;2117.565898990719;2120.946976325589;111448;123652;126565;129861.35069324687;167819;22322.888385558294;23199.884000580227;23327.18354857572;23468.530629387657;23561.52876942486;36539.817300913855;36629.08427289339;36680.44979325312;36931.26137477289;36977;10566.788664226673;10751.569937202425;10923.508442120125;10946.4526773661;11078.612248571262;7325.7802265932105;8623.353248506388;8687.565621719015;8701.346744118597;8879.822403551892;8939.910600894038;10838;10873.510692018877;11004.39475828837;11088.889111108947;4337.58694342518;4415;4421.823127074946;4453.955460445419;4481.395011673382;774.5468900693045;905.8777065096259;930.8689941720706;937.2847688729609;951.3816019587324;22783.658245126335;23722.288331350086;23951.401214969766;24371.75628243695;24494.14270500524;855.8801767752519;898.5507246376811;922.5756152170077;937.2174234514257;1007.969760907174;11055.170862185369;11377;11383.430828458544;11723.706907327385;11934.641960741192;699835;722902;731319;747986;753251;141069;141392.2930385362;160122;166760.4985925212;176887;1292.9676758081123;1330.8402991641065;1356.8371795384617;1360.1702961193664;1360.177092858826;19146.234150634376;19961.900190498956;20026.599468010267;20043.599128017067;20092.296769612993;55572;59473.702631486565;60125.79748405095;61527.384726153956;63207.73584528376;91412.08587914036;92261;92576.07423925675;92721.5363923176;92845.53577232071;11762.529498819951;12387.318697471726;12437.253764774143;12615.30615816139;12636.178648387826;13680.452781888613;13886.166829990232;14208.786868197192;14338.845454000104;14356.282185890872;9673.613055477703;11584.015358694269;11599;11803.763924721628;11994.400279986141;11408.372539510076;11511.942440287745;11637.06903447739;11702.35637039971;11867.228630139014;11772;11832.230904991147;11982;12113.69715757113;12162.054071348542;10324.48377581133;12003.579874704343;12104.152709310263;12377.381130943237;12389;11589.82615260755;11639.941800291283;11837.526498939946;11958.402079896145;11991.22057066288 09:20:10 INFO - PID 1641 | |3;mootools.html;1368.4868174434587;1522.2997421186253;1544.4748785412958;1552.526479423776;1633.2650307361687;10506.317089389184;10569.41868197249;10845.457727113651;11043.282186657872;11117.72205694858;10031.799364012715;11060.612878549253;11247.943760281207;11379.658610241686;11467.942660286708;2115.841311901606;2299.8390112692077;2347.5070235250532;2352.0356653771955;2363.1728894886737;2205.845590808644;2310.7111611048617;2351.447409858682;2353.576356255873;2360.9173678921243;2315.316981490456;2324.5118525109747;2327.77886100821;2334.3347146063375;2334.3580515358326;2287.588234117858;2297.035245197013;2310.0759696121518;2313.6760853480528;2314.4445333120098;2248.2917880867476;2279.2706333973133;2280.6350983842503;2282.8402011859175;2288.645259984705;10180.541875615616;10219.897801021969;10240.692779216635;10304;10362.740931476697;5614.663120212759;5788.565857560658;5864.739081097585;5887.587868849182;5894.557908156862;3384.6615338466204;3470.2538954124734;3677.797721125337;3702.5556933167923;3706.5366829146355;1132.909367250618;1222.9205101668363;1225.6323103069044;1225.6323103069044;1775.5738622730453;12082.577109801116;13703.520376786864;13889;15344.846551534454;15431.228438578026;4609.101225261042;4704.294355846582;4707.340972263886;4711.411073615798;4736.171170045228;11239;11278.323300601825;11290.209685321948;11294.209405341548;11301.378424186585;5646.57650676201;5687.60186786921;5690.3456102548735;5691.743871525791;5702.54379649627;4416.447944006999;4987.202047672355;5180.715060671624;5235.214717792362;5242.16125419931;3553.6091029986683;3670.7430479866152;3700.5929347771726;3737.4206997915367;3770.849166033383;1047.1936608811172;1049.4333060147587;1049.8267785815408;1057.957681692739;1060.2843080920502;785.5051317669831;824.3734761581242;886.8935727712716;895.583553647557;931.9114684104999;6059.27288725347;6198.442140207403;6367.204099487564;6385.329540398284;6595.94464885616;966.6939068386137;994.6866736977973;997.4962643864802;1008.1178968402647;1091.247039542713;3285.5071739239306;3303.487959366302;3350.363430948112;3379.2565635560136;3388.423967925459;13600.86399135996;13940.302984850718;14068.577942661737;14078.929605352112;14115.71768564623;12678.80981785274;13152.276624785538;13175.802362964563;13216.405261763244;14046.648833779238;9754.317197796228;10039.397636141855;10062.345947513388;10063.899361006443;10070.597176112873;4846.854594362175;6003.729832157651;6080.513558915188;6492.967535162294;6518.282988871126;9791.85312220303;11879.46542405611;11978.820317695066;12184.817227741769;12220.450079746255;10345.948270258901;11326.093912486816;11739.882601173878;12319.507219711111;12358.691032724253;8825;9135.223506002065;9283.164515193665;9294.977552469358;9306.860397094184;10031.197504199501;10078.6472473466;10113.039261270167;10174.796504070026;10228.232882533988;7864.134945155914;8910.821783564423;8916.86624700643;9003.954980225057;9051.456912585267;8832.823343533222;9036.367454278137;9050.275977921881;9064;9077;7430.814229644302;7608.201138880338;7682.500637458547;7707.92292077072;7713.730019449293;5538.003159431341;5567.832965011056;5638.126090455822;5771.307443106854;5867.618604790675;5746.712664366849;5866.266716660418;5898.380670029585;5927.644341339534;5975.55183361241 09:20:10 INFO - PID 1641 | |4;prototype.html;300.5566788986247;323.8422639065342;384.44831666558474;388.16157100662565;398.06256266492403;5884.735186916589;5895.528357731382;5964.761409543618;6048.848778780527;6066.787662431816;5788.5369170466365;5967.820965371035;5973.163757074013;5986.221791167142;6004.609700369473;4741.312509686093;4893.168161412551;4904.730239836798;4929.21132618781;4951.8514444566545;4261.509926358477;4881.219004959207;4898.338724272213;4907.386576677915;4908.263760435945;3992.1017771001584;4723;4915.901681966358;4916.729579873106;4930.816604015046;3970.3647416413523;4525.615322697575;4756.619470442356;4874.610031197513;4905.950940490585;4094.344904815245;4438.933415998763;4453.51011388747;4481.77591120446;4489.326601009842;4498.885523803807;4927.457979622239;4951.366049203754;5283.181106928432;5400.6498375406145;5016.84612539118;5222.529972302473;5445.210444485528;5664.20701101846;5748.712564371806;5196.870078248036;5253.789848406097;5952.851178720523;6404.231492220917;6509.772157974496;4819.927701084486;4827.879303017417;5044.344235249431;5050.83830718933;5054.797808087672;5948.375420580864;6302.55882088254;6471.773487927947;6734.696938637822;6882.621455819928;4220.978895105505;4998.700077995332;5153.819616313411;5159.277701121846;5199.506046925536;4415.712978656441;5309.601779866525;5321.281626980308;5321.36143662763;5347.625666203328;5555.333359996826;5643.927653745775;5673.978683836866;5692.971535142297;5703.315602127771;6390;6397.296297407282;6404.743810247631;6406.903896441557;6439.7424103035355;1464.3776395032282;1496.2219645784348;1738.3133662203359;1806.9186886590135;1823.8723289369882;1118.7078923842973;1215.4105258949403;1236.3632729144497;1305.9412326445333;1348.3258370814592;5140.794368225229;5423.83728488146;5941.7914326263335;6092.573519853568;6154.569180157346;5913.338997451164;5923.614965027259;6327.033278876061;6642.641862138968;6654.633995130217;4942.9011419771405;5584.97207513968;6076.605020673642;6101.420365065312;6252.937470625236;2967.8722085607765;3465.1163953191776;3516.841742121611;3521.102118959649;3527.7530572859655;5928.288605367384;6561.671916404161;6922.307769223037;7010.824729381806;7084.539504932163;6802.319768023158;6903.585784852925;6925.896111558331;6934.8613027740175;6944.1322082839915;6816.727330906709;6970.514742628686;7079.29207079288;7102.360787529147;7123.89314160288;5542.7782888685615;6728.226253980659;6746.595204287562;6764.661766911733;6810.62541560219;5626.465485778845;6635.469162466895;6669.0663307136065;6671.199456065109;6676.399124078856;6606.042123892104;6752.56907513285;6996.755113571001;7020.612117426608;7025.578465292099;4353.151135528668;5156.871078223075;5232.686038837682;5254.290670759398;5262.815801447084;4764.761761911821;5444.482774136451;5751.338596061507;6621.370969757865;6634.303398143318;6378.107065010995;6382.170317858649;6433.29233784274;6450.387213214737;6454.419102280817;5569.610127291213;5926.821590778404;6404.772849919031;6452.387023232785;6460.83562006733;5190.8183213587345;6246.625202487865;6247.5939063960695;6289.874202516015;6309.936900631119;4988.725620090785;6223.944454682709;6399.656287000221;6431.360003199155;6444.258910225384;5039.815643323889;6212.88761820983;6230.937690623217;6241.687915604292;6268.091126786681 09:20:10 INFO - PID 1641 | |5;yui.html;681.4582406986442;706.8338940349021;758.9279018493241;806.2743530822261;812.1269635142222;15090.17004064776;15271.847281527182;15320.233988300582;15376.769348459755;16013.919930400332;12529.310887901162;14673.633159171026;14823;14824.110553366778;15258.237088145605;825.2778818533783;951.8001219743862;963.6819849449699;966.3960024984385;973.0755782007086;850.4727069217095;963.7735132243918;985.1921424431932;987.4963768478057;989.1048601016056;1006.9093781559658;1024.8975102489767;1025.364274150028;1025.52825700178;1027.9874323791098;1019.3476175247847;1020.0921180149672;1020.5356562763925;1021.3004092196832;1024.0783295034491;755.6297414267025;756.6443771427421;760.8630446519625;761.394691220481;765.2462324610254;16130.870839041272;16551.83448165527;16597.087160206185;16708.83291167085;17009.979401235967;14592.41630334796;14752.852471475255;14759.262036898219;14821.184834834079;14880.925595371953;10448.52981615829;11747.471363788565;11763.470643821049;11894.286342819458;12018.579349722717;3635.4546817977252;3749.737518373715;3774.2828862516035;3776.509053823013;3845.4616353710503;101085.18045781179;101756.37000332343;105283;105926;106744.4662776689;11288;11316.868313168618;11452.885471145348;11493.310401375944;11790.056795456343;23989;24112.63831042536;24419.755802442105;24431.755682443305;24568.385790355384;7670.884936725954;7704.73033443838;7765.495242809199;7837;8001.199880011952;3404.829758512114;3933.527976642821;3972.106276087915;4006.1787333596544;4029.4560234368573;3495.667911548399;3528.5060091587197;3580.8925732228076;3583.372909740785;3594.4069228577514;609.1715267236555;610.957232993695;612.3447910735471;620.2742790934527;627.4635186915214;511.6162877841619;572.0875204049535;610.3652201710172;638.8850006998692;663.9244424033097;9697.272704547187;9888.505574721235;9920.156786673213;10222.791650672394;10288.434136122436;598.9907006693735;658.2035736758576;687.73684184522;698.4238003647012;703.7018682789059;4476.7090139140855;4867.101830286191;4881;4881.487443818419;4884.804607815648;97042;100565.49717251513;102137;102757;104051.95948040574;76556;79092.60453697809;79347;80034.5998270005;83310.58344708358;80040.59979700064;80605.19394805976;80705;81352.59323703461;81840;7402.814929626802;8916.598753056258;9048;9367.725951308292;9479.336446448959;37859.62140378561;46511;51407.485925141766;52070.21894671659;53775.19337209867;52413.7379313101;66774.33225667875;67091;67303.66348168228;68650.31349686439;13262.204267743966;13532.188068715908;13812.654683632989;14204.573862784133;14392.06451580644;13092.01809864283;13504;14007.649808754863;14365.569032929028;14603.853961460249;13346.06577539563;13494.647279699797;13659.112157709716;14126.929365353108;14330.996830221784;13097.563892986844;13325.13386629866;13574.524891628746;13621.09304697363;14144.78782818238;12187.451564679433;13258.636788048676;13581.45674173022;13978.510752124033;14104.576862694135;10139.442330671589;10931.344119352865;11097;11108.666739997812;11670.824937626112;8150.103488616366;9540.666076687283;9991.350562213433;10768.461576920967;11229.382383968634 09:20:10 INFO - PID 1641 | __end_tp_report 09:20:10 INFO - PID 1641 | __start_cc_report 09:20:10 INFO - PID 1641 | _x_x_mozilla_cycle_collect,422 09:20:10 INFO - PID 1641 | __end_cc_report 09:20:10 INFO - PID 1641 | __startTimestamp1489422010409__endTimestamp 09:20:10 INFO - PID 1641 | 09:20:10 INFO - PID 1641 | ------- Summary: start ------- 09:20:10 INFO - PID 1641 | Number of tests: 6 09:20:10 INFO - PID 1641 | 09:20:10 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:5814.94 Median:6108.68 stddev:659.07 (10.8%) stddev-sans-first:624.68 09:20:10 INFO - PID 1641 | Values: 3060.9 3451.9 3453.9 3487.8 3495.8 5713.6 5853.1 5877.4 5913.6 5936.8 5735.0 5783.0 5786.8 5792.8 5800.2 5218.1 5225.0 5241.2 5304.6 5317.2 4423.8 5319.5 5329.5 5329.7 5332.0 5340.6 5372.9 5379.5 5447.1 5447.9 5409.5 5409.8 5417.7 5420.2 5434.2 5115.0 5232.4 5251.9 5315.9 5407.6 5638.7 5815.7 5825.1 5847.5 5913.6 6325.0 6346.0 6348.0 6348.8 6352.5 6227.8 6309.1 6397.1 6408.6 6461.9 4855.7 5836.6 5849.6 5898.1 5917.9 5285.3 6361.9 6395.3 6396.9 6402.9 6122.3 6259.5 6300.8 6309.7 6312.7 6210.3 6252.6 6260.7 6280.5 6312.3 6104.2 6128.5 6266.2 6279.7 6302.4 6231.2 6248.5 6255.2 6291.4 6292.8 5138.1 5152.1 5155.8 5169.4 5185.9 4473.8 4500.6 4519.2 4529.4 4585.4 6108.7 6123.7 6130.6 6136.4 6141.9 4723.2 6041.5 6154.5 6308.2 6326.1 5886.9 5966.3 5975.0 6110.9 6140.2 5503.3 5527.4 5551.6 5558.7 5583.8 6578.4 6614.8 6625.0 6631.4 6660.7 6221.6 6288.1 6292.6 6299.8 6328.8 6231.4 6369.5 6491.1 6551.2 6622.0 6165.8 6173.3 6235.3 6239.8 6244.0 5708.0 5911.9 6267.3 6273.4 6274.7 6056.5 6132.6 6142.8 6152.8 6187.8 5112.5 6112.8 6113.8 6115.7 6145.5 5659.4 5820.3 5923.3 5949.9 6116.5 6106.4 6159.8 6212.7 6252.7 6260.4 5119.7 6120.8 6127.1 6132.5 6150.0 09:20:10 INFO - PID 1641 | 09:20:10 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:52765.30 Median:18416.87 stddev:103802.95 (563.6%) stddev-sans-first:104020.74 09:20:10 INFO - PID 1641 | Values: 773.5 825.2 853.9 859.5 929.6 36532.6 37253.8 37339.4 37352.1 37525.8 29433.0 35576.9 36091.0 36111.5 36201.5 7213.8 7389.7 7621.3 7785.1 7815.3 10602.8 10828.8 10866.5 10870.7 10893.7 10924.6 11010.8 11012.5 11012.8 11029.4 10877.2 10953.7 10966.4 10968.7 10985.5 10622.6 10697.5 10724.1 10733.6 10759.9 37973.0 38082.9 38279.0 38342.4 38393.8 3798.8 3802.7 3824.1 3841.6 3992.2 20741.4 27792.6 27798.6 27834.9 27918.7 3100.6 3160.1 3208.7 3271.3 3283.9 309640.0 310734.0 311451.4 312349.0 312705.0 37322.3 37929.9 47333.0 49183.8 49785.0 51966.7 52039.0 52179.7 52245.2 52418.7 14313.3 14428.9 14453.6 14505.7 14605.3 11692.8 11726.5 11781.5 11784.9 11795.2 13571.0 13788.9 14013.8 14042.2 14184.4 5074.7 6084.6 6086.1 6087.0 6108.7 952.9 957.3 971.1 978.1 996.9 22575.8 26926.5 27716.4 28414.6 28510.3 1009.8 1018.2 1055.6 1055.8 1059.7 12560.2 13840.0 13908.1 14444.9 14796.9 399485.0 453999.0 458935.0 461847.0 464239.7 53695.0 53771.2 54114.9 54228.5 54257.9 1374.6 1447.9 1455.6 1458.3 1471.3 25037.9 27108.0 27770.0 27808.6 28204.7 32411.7 40274.0 43386.0 43640.6 43809.8 389246.0 389409.2 392329.0 423081.0 424206.0 33549.8 33579.0 34145.0 34276.3 34300.5 40625.0 41167.2 41212.8 41474.5 42941.6 30251.8 31608.7 35217.5 35362.5 36966.0 27201.7 27239.7 34611.0 34997.5 35463.3 34301.3 35088.3 36197.5 36321.5 36372.5 30119.7 31946.4 32018.4 32119.4 32124.0 23688.8 24114.5 24203.2 24463.0 24553.4 09:20:10 INFO - PID 1641 | 09:20:10 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:42706.44 Median:22991.77 stddev:121736.16 (529.5%) stddev-sans-first:122036.21 09:20:10 INFO - PID 1641 | Values: 400.0 410.5 415.7 425.2 432.1 20010.7 21019.3 22284.2 22351.2 22512.0 21457.9 22201.1 22277.6 22567.5 22772.5 6507.9 6880.5 6890.0 6893.6 6929.1 6428.3 6780.3 6855.5 6855.8 6882.4 6653.7 6813.5 6820.8 6831.4 6869.8 5780.7 6795.9 6823.6 6838.1 6840.3 6536.1 6601.9 6656.3 6669.3 6679.5 27165.6 27285.9 27395.7 27508.2 27575.3 3321.2 3329.2 3344.7 3346.0 3495.4 14316.9 16378.3 16712.7 17568.6 17651.9 2017.9 2112.9 2114.7 2117.6 2120.9 111448.0 123652.0 126565.0 129861.4 167819.0 22322.9 23199.9 23327.2 23468.5 23561.5 36539.8 36629.1 36680.4 36931.3 36977.0 10566.8 10751.6 10923.5 10946.5 11078.6 7325.8 8623.4 8687.6 8701.3 8879.8 8939.9 10838.0 10873.5 11004.4 11088.9 4337.6 4415.0 4421.8 4454.0 4481.4 774.5 905.9 930.9 937.3 951.4 22783.7 23722.3 23951.4 24371.8 24494.1 855.9 898.6 922.6 937.2 1008.0 11055.2 11377.0 11383.4 11723.7 11934.6 699835.0 722902.0 731319.0 747986.0 753251.0 141069.0 141392.3 160122.0 166760.5 176887.0 1293.0 1330.8 1356.8 1360.2 1360.2 19146.2 19961.9 20026.6 20043.6 20092.3 55572.0 59473.7 60125.8 61527.4 63207.7 91412.1 92261.0 92576.1 92721.5 92845.5 11762.5 12387.3 12437.3 12615.3 12636.2 13680.5 13886.2 14208.8 14338.8 14356.3 9673.6 11584.0 11599.0 11803.8 11994.4 11408.4 11511.9 11637.1 11702.4 11867.2 11772.0 11832.2 11982.0 12113.7 12162.1 10324.5 12003.6 12104.2 12377.4 12389.0 11589.8 11639.9 11837.5 11958.4 11991.2 09:20:10 INFO - PID 1641 | 09:20:10 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:6544.12 Median:2351.74 stddev:4102.23 (174.4%) stddev-sans-first:4095.30 09:20:10 INFO - PID 1641 | Values: 1368.5 1522.3 1544.5 1552.5 1633.3 10506.3 10569.4 10845.5 11043.3 11117.7 10031.8 11060.6 11247.9 11379.7 11467.9 2115.8 2299.8 2347.5 2352.0 2363.2 2205.8 2310.7 2351.4 2353.6 2360.9 2315.3 2324.5 2327.8 2334.3 2334.4 2287.6 2297.0 2310.1 2313.7 2314.4 2248.3 2279.3 2280.6 2282.8 2288.6 10180.5 10219.9 10240.7 10304.0 10362.7 5614.7 5788.6 5864.7 5887.6 5894.6 3384.7 3470.3 3677.8 3702.6 3706.5 1132.9 1222.9 1225.6 1225.6 1775.6 12082.6 13703.5 13889.0 15344.8 15431.2 4609.1 4704.3 4707.3 4711.4 4736.2 11239.0 11278.3 11290.2 11294.2 11301.4 5646.6 5687.6 5690.3 5691.7 5702.5 4416.4 4987.2 5180.7 5235.2 5242.2 3553.6 3670.7 3700.6 3737.4 3770.8 1047.2 1049.4 1049.8 1058.0 1060.3 785.5 824.4 886.9 895.6 931.9 6059.3 6198.4 6367.2 6385.3 6595.9 966.7 994.7 997.5 1008.1 1091.2 3285.5 3303.5 3350.4 3379.3 3388.4 13600.9 13940.3 14068.6 14078.9 14115.7 12678.8 13152.3 13175.8 13216.4 14046.6 9754.3 10039.4 10062.3 10063.9 10070.6 4846.9 6003.7 6080.5 6493.0 6518.3 9791.9 11879.5 11978.8 12184.8 12220.5 10345.9 11326.1 11739.9 12319.5 12358.7 8825.0 9135.2 9283.2 9295.0 9306.9 10031.2 10078.6 10113.0 10174.8 10228.2 7864.1 8910.8 8916.9 9004.0 9051.5 8832.8 9036.4 9050.3 9064.0 9077.0 7430.8 7608.2 7682.5 7707.9 7713.7 5538.0 5567.8 5638.1 5771.3 5867.6 5746.7 5866.3 5898.4 5927.6 5975.6 09:20:10 INFO - PID 1641 | 09:20:10 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:5326.44 Median:5683.48 stddev:1552.22 (27.3%) stddev-sans-first:1510.04 09:20:10 INFO - PID 1641 | Values: 300.6 323.8 384.4 388.2 398.1 5884.7 5895.5 5964.8 6048.8 6066.8 5788.5 5967.8 5973.2 5986.2 6004.6 4741.3 4893.2 4904.7 4929.2 4951.9 4261.5 4881.2 4898.3 4907.4 4908.3 3992.1 4723.0 4915.9 4916.7 4930.8 3970.4 4525.6 4756.6 4874.6 4906.0 4094.3 4438.9 4453.5 4481.8 4489.3 4498.9 4927.5 4951.4 5283.2 5400.6 5016.8 5222.5 5445.2 5664.2 5748.7 5196.9 5253.8 5952.9 6404.2 6509.8 4819.9 4827.9 5044.3 5050.8 5054.8 5948.4 6302.6 6471.8 6734.7 6882.6 4221.0 4998.7 5153.8 5159.3 5199.5 4415.7 5309.6 5321.3 5321.4 5347.6 5555.3 5643.9 5674.0 5693.0 5703.3 6390.0 6397.3 6404.7 6406.9 6439.7 1464.4 1496.2 1738.3 1806.9 1823.9 1118.7 1215.4 1236.4 1305.9 1348.3 5140.8 5423.8 5941.8 6092.6 6154.6 5913.3 5923.6 6327.0 6642.6 6654.6 4942.9 5585.0 6076.6 6101.4 6252.9 2967.9 3465.1 3516.8 3521.1 3527.8 5928.3 6561.7 6922.3 7010.8 7084.5 6802.3 6903.6 6925.9 6934.9 6944.1 6816.7 6970.5 7079.3 7102.4 7123.9 5542.8 6728.2 6746.6 6764.7 6810.6 5626.5 6635.5 6669.1 6671.2 6676.4 6606.0 6752.6 6996.8 7020.6 7025.6 4353.2 5156.9 5232.7 5254.3 5262.8 4764.8 5444.5 5751.3 6621.4 6634.3 6378.1 6382.2 6433.3 6450.4 6454.4 5569.6 5926.8 6404.8 6452.4 6460.8 5190.8 6246.6 6247.6 6289.9 6309.9 4988.7 6223.9 6399.7 6431.4 6444.3 5039.8 6212.9 6230.9 6241.7 6268.1 09:20:10 INFO - PID 1641 | 09:20:10 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:20187.89 Median:3554.70 stddev:28548.58 (803.1%) stddev-sans-first:28591.09 09:20:10 INFO - PID 1641 | Values: 681.5 706.8 758.9 806.3 812.1 15090.2 15271.8 15320.2 15376.8 16013.9 12529.3 14673.6 14823.0 14824.1 15258.2 825.3 951.8 963.7 966.4 973.1 850.5 963.8 985.2 987.5 989.1 1006.9 1024.9 1025.4 1025.5 1028.0 1019.3 1020.1 1020.5 1021.3 1024.1 755.6 756.6 760.9 761.4 765.2 16130.9 16551.8 16597.1 16708.8 17010.0 14592.4 14752.9 14759.3 14821.2 14880.9 10448.5 11747.5 11763.5 11894.3 12018.6 3635.5 3749.7 3774.3 3776.5 3845.5 101085.2 101756.4 105283.0 105926.0 106744.5 11288.0 11316.9 11452.9 11493.3 11790.1 23989.0 24112.6 24419.8 24431.8 24568.4 7670.9 7704.7 7765.5 7837.0 8001.2 3404.8 3933.5 3972.1 4006.2 4029.5 3495.7 3528.5 3580.9 3583.4 3594.4 609.2 611.0 612.3 620.3 627.5 511.6 572.1 610.4 638.9 663.9 9697.3 9888.5 9920.2 10222.8 10288.4 599.0 658.2 687.7 698.4 703.7 4476.7 4867.1 4881.0 4881.5 4884.8 97042.0 100565.5 102137.0 102757.0 104052.0 76556.0 79092.6 79347.0 80034.6 83310.6 80040.6 80605.2 80705.0 81352.6 81840.0 7402.8 8916.6 9048.0 9367.7 9479.3 37859.6 46511.0 51407.5 52070.2 53775.2 52413.7 66774.3 67091.0 67303.7 68650.3 13262.2 13532.2 13812.7 14204.6 14392.1 13092.0 13504.0 14007.6 14365.6 14603.9 13346.1 13494.6 13659.1 14126.9 14331.0 13097.6 13325.1 13574.5 13621.1 14144.8 12187.5 13258.6 13581.5 13978.5 14104.6 10139.4 10931.3 11097.0 11108.7 11670.8 8150.1 9540.7 9991.4 10768.5 11229.4 09:20:10 INFO - PID 1641 | -------- Summary: end -------- 09:20:10 INFO - PID 1641 | 09:20:11 INFO - TEST-INFO | 1641: exit 0 09:20:11 INFO - TEST-OK | dromaeo_css | took 1118441ms 09:20:11 INFO - TEST-START | kraken 09:20:11 INFO - operating with platform_type : mac_ 09:20:11 INFO - Initialising browser for kraken test... 09:20:11 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpuUmNnr/profile http://localhost:49249/getInfo.html) 09:20:13 INFO - PID 1653 | 2017-03-13 09:20:13.912 plugin-container[1654:10216] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 09:20:13 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:13 INFO - PID 1653 | 2017-03-13 09:20:13.915 plugin-container[1654:10216] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 09:20:13 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:13 INFO - PID 1653 | 2017-03-13 09:20:13.915 plugin-container[1654:10216] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:20:13 INFO - PID 1653 | __metrics Screen width/height:1600/1200 09:20:13 INFO - PID 1653 | colorDepth:24 09:20:13 INFO - PID 1653 | Browser inner width/height: 1024/689 09:20:13 INFO - PID 1653 | __metrics 09:20:14 ERROR - PID 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:20:14 INFO - TEST-INFO | 1653: exit 0 09:20:14 INFO - Browser initialized. 09:20:14 INFO - Running cycle 1/1 for kraken test... 09:20:14 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpuUmNnr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:17 INFO - PID 1655 | 2017-03-13 09:20:17.195 plugin-container[1656:10347] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 09:20:17 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:17 INFO - PID 1655 | 2017-03-13 09:20:17.195 plugin-container[1656:10347] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 09:20:17 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:17 INFO - PID 1655 | 2017-03-13 09:20:17.195 plugin-container[1656:10347] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:20:26 INFO - PID 1655 | RSS: Main: 228745216 09:20:26 INFO - PID 1655 | 09:20:29 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 09:20:30 INFO - PID 1655 | RSS: Main: 241836032 09:20:30 INFO - PID 1655 | 09:20:33 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 09:20:34 INFO - PID 1655 | RSS: Main: 250634240 09:20:34 INFO - PID 1655 | 09:20:37 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 09:20:38 INFO - PID 1655 | RSS: Main: 252895232 09:20:38 INFO - PID 1655 | 09:20:40 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 09:20:40 INFO - PID 1655 | RSS: Main: 254029824 09:20:40 INFO - PID 1655 | 09:20:43 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:43 INFO - PID 1655 | RSS: Main: 255672320 09:20:43 INFO - PID 1655 | 09:20:47 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:47 INFO - PID 1655 | RSS: Main: 261095424 09:20:47 INFO - PID 1655 | 09:20:51 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:51 INFO - PID 1655 | RSS: Main: 258781184 09:20:51 INFO - PID 1655 | 09:20:55 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 09:20:55 INFO - PID 1655 | RSS: Main: 259862528 09:20:55 INFO - PID 1655 | 09:20:57 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:57 INFO - PID 1655 | RSS: Main: 260313088 09:20:57 INFO - PID 1655 | 09:20:59 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:21:00 INFO - PID 1655 | RSS: Main: 260284416 09:21:00 INFO - PID 1655 | 09:21:02 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:21:03 INFO - PID 1655 | RSS: Main: 260145152 09:21:03 INFO - PID 1655 | 09:21:06 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:21:07 INFO - PID 1655 | RSS: Main: 260739072 09:21:07 INFO - PID 1655 | 09:21:10 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:21:11 INFO - PID 1655 | RSS: Main: 258613248 09:21:11 INFO - PID 1655 | 09:21:12 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 09:21:13 INFO - PID 1655 | RSS: Main: 261722112 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | __start_tp_report 09:21:13 INFO - PID 1655 | _x_x_mozilla_page_load 09:21:13 INFO - PID 1655 | _x_x_mozilla_page_load_details 09:21:13 INFO - PID 1655 | |i|pagename|runs| 09:21:13 INFO - PID 1655 | |0;ai-astar;109;179;179;122;121;120;121;121;126;178 09:21:13 INFO - PID 1655 | |1;audio-beat-detection;148;123;150;171;114;97;131;146;169;98 09:21:13 INFO - PID 1655 | |2;audio-dft;202;203;212;272;243;278;233;189;183;184 09:21:13 INFO - PID 1655 | |3;audio-fft;80;57;58;89;58;61;61;55;75;60 09:21:13 INFO - PID 1655 | |4;audio-oscillator;86;72;85;84;103;71;87;72;70;82 09:21:13 INFO - PID 1655 | |5;imaging-gaussian-blur;116;80;136;137;90;136;76;119;137;113 09:21:13 INFO - PID 1655 | |6;imaging-darkroom;95;139;95;155;124;154;117;108;153;154 09:21:13 INFO - PID 1655 | |7;imaging-desaturate;92;95;78;141;141;104;143;140;91;91 09:21:13 INFO - PID 1655 | |8;json-parse-financial;68;65;67;67;66;69;67;68;67;68 09:21:13 INFO - PID 1655 | |9;json-stringify-tinderbox;58;62;65;66;60;58;59;59;75;89 09:21:13 INFO - PID 1655 | |10;stanford-crypto-aes;84;79;91;108;106;85;85;88;106;89 09:21:13 INFO - PID 1655 | |11;stanford-crypto-ccm;145;148;132;133;142;152;153;149;192;153 09:21:13 INFO - PID 1655 | |12;stanford-crypto-pbkdf2;186;189;242;244;217;187;184;185;202;243 09:21:13 INFO - PID 1655 | |13;stanford-crypto-sha256-iterative;70;72;70;96;108;94;103;73;68;72 09:21:13 INFO - PID 1655 | __end_tp_report 09:21:13 INFO - PID 1655 | __start_cc_report 09:21:13 INFO - PID 1655 | _x_x_mozilla_cycle_collect,991 09:21:13 INFO - PID 1655 | __end_cc_report 09:21:13 INFO - PID 1655 | __startTimestamp1489422073236__endTimestamp 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | ------- Summary: start ------- 09:21:13 INFO - PID 1655 | Number of tests: 14 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#0] ai-astar Cycles:10 Average:137.60 Median:124.00 stddev:28.66 (23.1%) stddev-sans-first:28.47 09:21:13 INFO - PID 1655 | Values: 109.0 179.0 179.0 122.0 121.0 120.0 121.0 121.0 126.0 178.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#1] audio-beat-detection Cycles:10 Average:134.70 Median:159.50 stddev:26.63 (16.7%) stddev-sans-first:27.80 09:21:13 INFO - PID 1655 | Values: 148.0 123.0 150.0 171.0 114.0 97.0 131.0 146.0 169.0 98.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#2] audio-dft Cycles:10 Average:219.90 Median:222.50 stddev:35.07 (15.8%) stddev-sans-first:36.59 09:21:13 INFO - PID 1655 | Values: 202.0 203.0 212.0 272.0 243.0 278.0 233.0 189.0 183.0 184.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#3] audio-fft Cycles:10 Average:65.40 Median:61.00 stddev:11.64 (19.1%) stddev-sans-first:11.08 09:21:13 INFO - PID 1655 | Values: 80.0 57.0 58.0 89.0 58.0 61.0 61.0 55.0 75.0 60.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:83.00 stddev:10.29 (12.4%) stddev-sans-first:10.77 09:21:13 INFO - PID 1655 | Values: 86.0 72.0 85.0 84.0 103.0 71.0 87.0 72.0 70.0 82.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:137.00 stddev:24.11 (17.6%) stddev-sans-first:25.56 09:21:13 INFO - PID 1655 | Values: 116.0 80.0 136.0 137.0 90.0 136.0 76.0 119.0 137.0 113.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#6] imaging-darkroom Cycles:10 Average:129.40 Median:154.00 stddev:24.77 (16.1%) stddev-sans-first:22.93 09:21:13 INFO - PID 1655 | Values: 95.0 139.0 95.0 155.0 124.0 154.0 117.0 108.0 153.0 154.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#7] imaging-desaturate Cycles:10 Average:111.60 Median:84.50 stddev:26.28 (31.1%) stddev-sans-first:26.90 09:21:13 INFO - PID 1655 | Values: 92.0 95.0 78.0 141.0 141.0 104.0 143.0 140.0 91.0 91.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:67.50 stddev:1.14 (1.7%) stddev-sans-first:1.17 09:21:13 INFO - PID 1655 | Values: 68.0 65.0 67.0 67.0 66.0 69.0 67.0 68.0 67.0 68.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:63.50 stddev:9.89 (15.6%) stddev-sans-first:10.15 09:21:13 INFO - PID 1655 | Values: 58.0 62.0 65.0 66.0 60.0 58.0 59.0 59.0 75.0 89.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#10] stanford-crypto-aes Cycles:10 Average:92.10 Median:85.00 stddev:10.57 (12.4%) stddev-sans-first:10.79 09:21:13 INFO - PID 1655 | Values: 84.0 79.0 91.0 108.0 106.0 85.0 85.0 88.0 106.0 89.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:150.50 stddev:16.64 (11.1%) stddev-sans-first:17.56 09:21:13 INFO - PID 1655 | Values: 145.0 148.0 132.0 133.0 142.0 152.0 153.0 149.0 192.0 153.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:207.90 Median:209.50 stddev:26.21 (12.5%) stddev-sans-first:26.58 09:21:13 INFO - PID 1655 | Values: 186.0 189.0 242.0 244.0 217.0 187.0 184.0 185.0 202.0 243.0 09:21:13 INFO - PID 1655 | 09:21:13 INFO - PID 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.60 Median:72.00 stddev:15.70 (21.8%) stddev-sans-first:15.98 09:21:13 INFO - PID 1655 | Values: 70.0 72.0 70.0 96.0 108.0 94.0 103.0 73.0 68.0 72.0 09:21:13 INFO - PID 1655 | -------- Summary: end -------- 09:21:13 INFO - PID 1655 | 09:21:14 INFO - TEST-INFO | 1655: exit 0 09:21:14 INFO - TEST-OK | kraken | took 63109ms 09:21:14 INFO - SUITE-END | took 1181s 09:21:14 INFO - Completed test suite (00:19:41) 09:21:14 INFO - javascript benchmark 09:21:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7846.396260430543, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3060.9234769130767, 3451.8791842285523, 3453.930921381574, 3487.7907325560454, 3495.8426870790813, 5713.571482138836, 5853.09277062055, 5877.41225877412, 5913.6451812891255, 5936.792212272571, 5734.971325143369, 5783.016887129188, 5786.768529258825, 5792.797252096167, 5800.216970708953, 5218.13900706383, 5224.981128679909, 5241.2400201970695, 5304.575633949275, 5317.229001794737, 4423.7566933818625, 5319.494648008453, 5329.546988505982, 5329.653572517774, 5332.013577488157, 5340.572754179656, 5372.865678358053, 5379.488948549901, 5447.073997420428, 5447.918281225785, 5409.486098820606, 5409.783608655669, 5417.729113544307, 5420.214068959979, 5434.239206511092, 5114.974425127888, 5232.372115346183, 5251.870847767748, 5315.867103322409, 5407.567394608422, 5638.718064096779, 5815.738291776838, 5825.097109947965, 5847.532197424239, 5913.645181289094, 6324.968375158141, 6345.968270158666, 6348.0, 6348.77779277723, 6352.523560732963, 6227.844303892393, 6309.0536419536975, 6397.1363865877975, 6408.647524386157, 6461.93538064618, 4855.708657480492, 5836.64980101191, 5849.590528662995, 5898.085796701518, 5917.911231331534, 5285.286486324374, 6361.9045714314325, 6395.264544577433, 6396.9360306397275, 6402.8719425611225, 6122.337166850181, 6259.530535209878, 6300.842478938063, 6309.7160627770945, 6312.652804095819, 6210.254769427697, 6252.624842509373, 6260.718267677966, 6280.497560195174, 6312.274088479791, 6104.246950856165, 6128.509719222451, 6266.185395898595, 6279.654618995999, 6302.432781049728, 6231.158793562901, 6248.50011999039, 6255.186825712628, 6291.370862913764, 6292.8426789329715, 5138.075146473596, 5152.098382782998, 5155.845324640267, 5169.3796744390165, 5185.859110995575, 4473.7986790594505, 4500.639948804088, 4519.209138400766, 4529.365888775574, 4585.426821647294, 6108.6759897029115, 6123.709696704001, 6130.581587307925, 6136.365905528278, 6141.884937092159, 4723.173444647173, 6041.456268935817, 6154.461384653837, 6308.211473565804, 6326.082718005863, 5886.8822623547285, 5966.284045914518, 5975.043992961106, 6110.877782444415, 6140.232470941132, 5503.3120859892515, 5527.39198688144, 5551.639143455663, 5558.666480011212, 5583.804566840222, 6578.375054369827, 6614.768483103068, 6625.0, 6631.3700198481065, 6660.666966651745, 6221.595596286227, 6288.056791481315, 6292.559520833681, 6299.811005669837, 6328.778492752732, 6231.376862313732, 6369.458596019205, 6491.091247225298, 6551.213854337319, 6622.0, 6165.784197553064, 6173.290071641819, 6235.251769787655, 6239.781607643892, 6244.0, 5707.972564938351, 5911.861158113856, 6267.2792628848265, 6273.37266273369, 6274.749010039547, 6056.485198757979, 6132.600268413531, 6142.846428839315, 6152.753889844356, 6187.783427580013, 5112.463191365002, 6112.777444511026, 6113.810786210516, 6115.727150604437, 6145.525073982123, 5659.377468478547, 5820.272465941757, 5923.318818335946, 5949.910751338647, 6116.487945662109, 6106.36966090081, 6159.81520554384, 6212.720427580679, 6252.749890004349, 6260.40526150015, 5119.667221630582, 6120.7551697931585, 6127.080937859357, 6132.509399248139, 6149.96925015372], "value": 5774.649085359119, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.4701729315418, 825.1665817524299, 853.9402241843073, 859.5143743784757, 929.5723966975191, 36532.63467365329, 37253.81373093134, 37339.43990840133, 37352.066198345055, 37525.812370938176, 29433.0, 35576.93269201928, 36091.0, 36111.45832812503, 36201.45697814528, 7213.783586492414, 7389.66746496409, 7621.314081732643, 7785.143634200262, 7815.257550532719, 10602.78794424111, 10828.783424331508, 10866.511007004665, 10870.72823179419, 10893.673189804318, 10924.563017479291, 11010.779784404307, 11012.5044373003, 11012.834807477895, 11029.393383363911, 10877.238593298433, 10953.726156846064, 10966.396848173386, 10968.725781855437, 10985.450727463594, 10622.57509699611, 10697.518611662494, 10724.14206863449, 10733.624323148651, 10759.892401075967, 37973.0, 38082.85751427462, 38279.0, 38342.42486362707, 38393.808030959946, 3798.810059497042, 3802.6957843372466, 3824.063104539378, 3841.615838416164, 3992.2414741199077, 20741.377758667266, 27792.58311125335, 27798.583021254697, 27834.860825695945, 27918.743656535302, 3100.58142150809, 3160.1309639848996, 3208.727258183057, 3271.345730853815, 3283.9014829555153, 309640.0, 310734.0, 311451.4427427871, 312349.0, 312705.0, 37322.25355492875, 37929.86210413692, 47333.0, 49183.75408122937, 49785.0, 51966.74016629893, 52039.0, 52179.73910130425, 52245.2163217552, 52418.689532761986, 14313.28433578317, 14428.85571144296, 14453.638659033608, 14505.709885802225, 14605.269736513132, 11692.766144677058, 11726.472308746126, 11781.469833857496, 11784.882151178379, 11795.174337796443, 13571.0, 13788.896888248915, 14013.789793153112, 14042.227677477837, 14184.432622695183, 5074.67014644047, 6084.6349219047, 6086.087086936995, 6086.969565152146, 6108.664023478751, 952.8665986761858, 957.3203025851584, 971.121135372489, 978.1392374710351, 996.8604395384652, 22575.77424225737, 26926.461470770475, 27716.429991905472, 28414.573781393297, 28510.287242819097, 1009.8084260572595, 1018.2057994764095, 1055.5619417941623, 1055.8416237564427, 1059.7456610413599, 12560.183588066746, 13839.962002849827, 13908.095973761672, 14444.855551444562, 14796.926015369854, 399485.0, 453999.0, 458935.0, 461847.0, 464239.6788016106, 53695.0, 53771.19343209855, 54114.91770164575, 54228.457715422344, 54257.91484170294, 1374.6357215337944, 1447.862453066957, 1455.628814652257, 1458.3145921416917, 1471.315838135251, 25037.87481062583, 27108.0, 27770.0, 27808.58287125654, 28204.71795282103, 32411.675883240867, 40274.0, 43386.0, 43640.56359436365, 43809.78095109504, 389246.0, 389409.158862623, 392329.0, 423081.0, 424206.0, 33549.83225083859, 33579.0, 34145.0, 34276.31447371088, 34300.48549271813, 40624.98437539085, 41167.1766564673, 41212.793936030124, 41474.511529308336, 42941.57058429376, 30251.848740756155, 31608.73565057372, 35217.47173792446, 35362.46956295606, 36966.0, 27201.72798272071, 27239.688260788957, 34611.0, 34997.47503787394, 35463.290734185684, 34301.313973720884, 35088.29823403569, 36197.457038144974, 36321.45517817288, 36372.45441318435, 30119.698803012565, 31946.36107277888, 32018.35963280768, 32119.357612848078, 32124.0, 23688.763112368655, 24114.51770964606, 24203.152889648776, 24463.021479140636, 24553.386165346008], "value": 18418.271079458027, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.0199511197567, 410.4930410942487, 415.6633127166994, 425.20274485339985, 432.08182611949604, 20010.699839502402, 21019.2643257486, 22284.220052298173, 22351.206099237595, 22511.98696058677, 21457.892710536464, 22201.1119555218, 22277.55444891101, 22567.54864902701, 22772.544549109007, 6507.869842603157, 6880.483963702717, 6890.000949862267, 6893.620850853201, 6929.133858267716, 6428.260750013738, 6780.254172041096, 6855.485838562127, 6855.794326170223, 6882.414994725455, 6653.667316634173, 6813.523053386265, 6820.795376138699, 6831.419329357011, 6869.793906182797, 5780.682062486561, 6795.93204067958, 6823.590584564942, 6838.14523184602, 6840.31596840317, 6536.11762412073, 6601.900971485432, 6656.267810540837, 6669.299723529008, 6679.499037572153, 27165.592516112276, 27285.863570682217, 27395.726042739516, 27508.17475475739, 27575.31219692657, 3321.1697075731067, 3329.184349834282, 3344.698977092049, 3345.96654033459, 3495.423255162895, 14316.928415357961, 16378.26297816601, 16712.66574668512, 17568.594512439104, 17651.852629579036, 2017.9495512612154, 2112.883791391488, 2114.694982469116, 2117.565898990719, 2120.946976325589, 111448.0, 123652.0, 126565.0, 129861.35069324687, 167819.0, 22322.888385558294, 23199.884000580227, 23327.18354857572, 23468.530629387657, 23561.52876942486, 36539.817300913855, 36629.08427289339, 36680.44979325312, 36931.26137477289, 36977.0, 10566.788664226673, 10751.569937202425, 10923.508442120125, 10946.4526773661, 11078.612248571262, 7325.7802265932105, 8623.353248506388, 8687.565621719015, 8701.346744118597, 8879.822403551892, 8939.910600894038, 10838.0, 10873.510692018877, 11004.39475828837, 11088.889111108947, 4337.58694342518, 4415.0, 4421.823127074946, 4453.955460445419, 4481.395011673382, 774.5468900693045, 905.8777065096259, 930.8689941720706, 937.2847688729609, 951.3816019587324, 22783.658245126335, 23722.288331350086, 23951.401214969766, 24371.75628243695, 24494.14270500524, 855.8801767752519, 898.5507246376811, 922.5756152170077, 937.2174234514257, 1007.969760907174, 11055.170862185369, 11377.0, 11383.430828458544, 11723.706907327385, 11934.641960741192, 699835.0, 722902.0, 731319.0, 747986.0, 753251.0, 141069.0, 141392.2930385362, 160122.0, 166760.4985925212, 176887.0, 1292.9676758081123, 1330.8402991641065, 1356.8371795384617, 1360.1702961193664, 1360.177092858826, 19146.234150634376, 19961.900190498956, 20026.599468010267, 20043.599128017067, 20092.296769612993, 55572.0, 59473.702631486565, 60125.79748405095, 61527.384726153956, 63207.73584528376, 91412.08587914036, 92261.0, 92576.07423925675, 92721.5363923176, 92845.53577232071, 11762.529498819951, 12387.318697471726, 12437.253764774143, 12615.30615816139, 12636.178648387826, 13680.452781888613, 13886.166829990232, 14208.786868197192, 14338.845454000104, 14356.282185890872, 9673.613055477703, 11584.015358694269, 11599.0, 11803.763924721628, 11994.400279986141, 11408.372539510076, 11511.942440287745, 11637.06903447739, 11702.35637039971, 11867.228630139014, 11772.0, 11832.230904991147, 11982.0, 12113.69715757113, 12162.054071348542, 10324.48377581133, 12003.579874704343, 12104.152709310263, 12377.381130943237, 12389.0, 11589.82615260755, 11639.941800291283, 11837.526498939946, 11958.402079896145, 11991.22057066288], "value": 11961.888888684582, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.4868174434587, 1522.2997421186253, 1544.4748785412958, 1552.526479423776, 1633.2650307361687, 10506.317089389184, 10569.41868197249, 10845.457727113651, 11043.282186657872, 11117.72205694858, 10031.799364012715, 11060.612878549253, 11247.943760281207, 11379.658610241686, 11467.942660286708, 2115.841311901606, 2299.8390112692077, 2347.5070235250532, 2352.0356653771955, 2363.1728894886737, 2205.845590808644, 2310.7111611048617, 2351.447409858682, 2353.576356255873, 2360.9173678921243, 2315.316981490456, 2324.5118525109747, 2327.77886100821, 2334.3347146063375, 2334.3580515358326, 2287.588234117858, 2297.035245197013, 2310.0759696121518, 2313.6760853480528, 2314.4445333120098, 2248.2917880867476, 2279.2706333973133, 2280.6350983842503, 2282.8402011859175, 2288.645259984705, 10180.541875615616, 10219.897801021969, 10240.692779216635, 10304.0, 10362.740931476697, 5614.663120212759, 5788.565857560658, 5864.739081097585, 5887.587868849182, 5894.557908156862, 3384.6615338466204, 3470.2538954124734, 3677.797721125337, 3702.5556933167923, 3706.5366829146355, 1132.909367250618, 1222.9205101668363, 1225.6323103069044, 1225.6323103069044, 1775.5738622730453, 12082.577109801116, 13703.520376786864, 13889.0, 15344.846551534454, 15431.228438578026, 4609.101225261042, 4704.294355846582, 4707.340972263886, 4711.411073615798, 4736.171170045228, 11239.0, 11278.323300601825, 11290.209685321948, 11294.209405341548, 11301.378424186585, 5646.57650676201, 5687.60186786921, 5690.3456102548735, 5691.743871525791, 5702.54379649627, 4416.447944006999, 4987.202047672355, 5180.715060671624, 5235.214717792362, 5242.16125419931, 3553.6091029986683, 3670.7430479866152, 3700.5929347771726, 3737.4206997915367, 3770.849166033383, 1047.1936608811172, 1049.4333060147587, 1049.8267785815408, 1057.957681692739, 1060.2843080920502, 785.5051317669831, 824.3734761581242, 886.8935727712716, 895.583553647557, 931.9114684104999, 6059.27288725347, 6198.442140207403, 6367.204099487564, 6385.329540398284, 6595.94464885616, 966.6939068386137, 994.6866736977973, 997.4962643864802, 1008.1178968402647, 1091.247039542713, 3285.5071739239306, 3303.487959366302, 3350.363430948112, 3379.2565635560136, 3388.423967925459, 13600.86399135996, 13940.302984850718, 14068.577942661737, 14078.929605352112, 14115.71768564623, 12678.80981785274, 13152.276624785538, 13175.802362964563, 13216.405261763244, 14046.648833779238, 9754.317197796228, 10039.397636141855, 10062.345947513388, 10063.899361006443, 10070.597176112873, 4846.854594362175, 6003.729832157651, 6080.513558915188, 6492.967535162294, 6518.282988871126, 9791.85312220303, 11879.46542405611, 11978.820317695066, 12184.817227741769, 12220.450079746255, 10345.948270258901, 11326.093912486816, 11739.882601173878, 12319.507219711111, 12358.691032724253, 8825.0, 9135.223506002065, 9283.164515193665, 9294.977552469358, 9306.860397094184, 10031.197504199501, 10078.6472473466, 10113.039261270167, 10174.796504070026, 10228.232882533988, 7864.134945155914, 8910.821783564423, 8916.86624700643, 9003.954980225057, 9051.456912585267, 8832.823343533222, 9036.367454278137, 9050.275977921881, 9064.0, 9077.0, 7430.814229644302, 7608.201138880338, 7682.500637458547, 7707.92292077072, 7713.730019449293, 5538.003159431341, 5567.832965011056, 5638.126090455822, 5771.307443106854, 5867.618604790675, 5746.712664366849, 5866.266716660418, 5898.380670029585, 5927.644341339534, 5975.55183361241], "value": 4996.437518980448, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.5566788986247, 323.8422639065342, 384.44831666558474, 388.16157100662565, 398.06256266492403, 5884.735186916589, 5895.528357731382, 5964.761409543618, 6048.848778780527, 6066.787662431816, 5788.5369170466365, 5967.820965371035, 5973.163757074013, 5986.221791167142, 6004.609700369473, 4741.312509686093, 4893.168161412551, 4904.730239836798, 4929.21132618781, 4951.8514444566545, 4261.509926358477, 4881.219004959207, 4898.338724272213, 4907.386576677915, 4908.263760435945, 3992.1017771001584, 4723.0, 4915.901681966358, 4916.729579873106, 4930.816604015046, 3970.3647416413523, 4525.615322697575, 4756.619470442356, 4874.610031197513, 4905.950940490585, 4094.344904815245, 4438.933415998763, 4453.51011388747, 4481.77591120446, 4489.326601009842, 4498.885523803807, 4927.457979622239, 4951.366049203754, 5283.181106928432, 5400.6498375406145, 5016.84612539118, 5222.529972302473, 5445.210444485528, 5664.20701101846, 5748.712564371806, 5196.870078248036, 5253.789848406097, 5952.851178720523, 6404.231492220917, 6509.772157974496, 4819.927701084486, 4827.879303017417, 5044.344235249431, 5050.83830718933, 5054.797808087672, 5948.375420580864, 6302.55882088254, 6471.773487927947, 6734.696938637822, 6882.621455819928, 4220.978895105505, 4998.700077995332, 5153.819616313411, 5159.277701121846, 5199.506046925536, 4415.712978656441, 5309.601779866525, 5321.281626980308, 5321.36143662763, 5347.625666203328, 5555.333359996826, 5643.927653745775, 5673.978683836866, 5692.971535142297, 5703.315602127771, 6390.0, 6397.296297407282, 6404.743810247631, 6406.903896441557, 6439.7424103035355, 1464.3776395032282, 1496.2219645784348, 1738.3133662203359, 1806.9186886590135, 1823.8723289369882, 1118.7078923842973, 1215.4105258949403, 1236.3632729144497, 1305.9412326445333, 1348.3258370814592, 5140.794368225229, 5423.83728488146, 5941.7914326263335, 6092.573519853568, 6154.569180157346, 5913.338997451164, 5923.614965027259, 6327.033278876061, 6642.641862138968, 6654.633995130217, 4942.9011419771405, 5584.97207513968, 6076.605020673642, 6101.420365065312, 6252.937470625236, 2967.8722085607765, 3465.1163953191776, 3516.841742121611, 3521.102118959649, 3527.7530572859655, 5928.288605367384, 6561.671916404161, 6922.307769223037, 7010.824729381806, 7084.539504932163, 6802.319768023158, 6903.585784852925, 6925.896111558331, 6934.8613027740175, 6944.1322082839915, 6816.727330906709, 6970.514742628686, 7079.29207079288, 7102.360787529147, 7123.89314160288, 5542.7782888685615, 6728.226253980659, 6746.595204287562, 6764.661766911733, 6810.62541560219, 5626.465485778845, 6635.469162466895, 6669.0663307136065, 6671.199456065109, 6676.399124078856, 6606.042123892104, 6752.56907513285, 6996.755113571001, 7020.612117426608, 7025.578465292099, 4353.151135528668, 5156.871078223075, 5232.686038837682, 5254.290670759398, 5262.815801447084, 4764.761761911821, 5444.482774136451, 5751.338596061507, 6621.370969757865, 6634.303398143318, 6378.107065010995, 6382.170317858649, 6433.29233784274, 6450.387213214737, 6454.419102280817, 5569.610127291213, 5926.821590778404, 6404.772849919031, 6452.387023232785, 6460.83562006733, 5190.8183213587345, 6246.625202487865, 6247.5939063960695, 6289.874202516015, 6309.936900631119, 4988.725620090785, 6223.944454682709, 6399.656287000221, 6431.360003199155, 6444.258910225384, 5039.815643323889, 6212.88761820983, 6230.937690623217, 6241.687915604292, 6268.091126786681], "value": 4847.96655672666, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.4582406986442, 706.8338940349021, 758.9279018493241, 806.2743530822261, 812.1269635142222, 15090.17004064776, 15271.847281527182, 15320.233988300582, 15376.769348459755, 16013.919930400332, 12529.310887901162, 14673.633159171026, 14823.0, 14824.110553366778, 15258.237088145605, 825.2778818533783, 951.8001219743862, 963.6819849449699, 966.3960024984385, 973.0755782007086, 850.4727069217095, 963.7735132243918, 985.1921424431932, 987.4963768478057, 989.1048601016056, 1006.9093781559658, 1024.8975102489767, 1025.364274150028, 1025.52825700178, 1027.9874323791098, 1019.3476175247847, 1020.0921180149672, 1020.5356562763925, 1021.3004092196832, 1024.0783295034491, 755.6297414267025, 756.6443771427421, 760.8630446519625, 761.394691220481, 765.2462324610254, 16130.870839041272, 16551.83448165527, 16597.087160206185, 16708.83291167085, 17009.979401235967, 14592.41630334796, 14752.852471475255, 14759.262036898219, 14821.184834834079, 14880.925595371953, 10448.52981615829, 11747.471363788565, 11763.470643821049, 11894.286342819458, 12018.579349722717, 3635.4546817977252, 3749.737518373715, 3774.2828862516035, 3776.509053823013, 3845.4616353710503, 101085.18045781179, 101756.37000332343, 105283.0, 105926.0, 106744.4662776689, 11288.0, 11316.868313168618, 11452.885471145348, 11493.310401375944, 11790.056795456343, 23989.0, 24112.63831042536, 24419.755802442105, 24431.755682443305, 24568.385790355384, 7670.884936725954, 7704.73033443838, 7765.495242809199, 7837.0, 8001.199880011952, 3404.829758512114, 3933.527976642821, 3972.106276087915, 4006.1787333596544, 4029.4560234368573, 3495.667911548399, 3528.5060091587197, 3580.8925732228076, 3583.372909740785, 3594.4069228577514, 609.1715267236555, 610.957232993695, 612.3447910735471, 620.2742790934527, 627.4635186915214, 511.6162877841619, 572.0875204049535, 610.3652201710172, 638.8850006998692, 663.9244424033097, 9697.272704547187, 9888.505574721235, 9920.156786673213, 10222.791650672394, 10288.434136122436, 598.9907006693735, 658.2035736758576, 687.73684184522, 698.4238003647012, 703.7018682789059, 4476.7090139140855, 4867.101830286191, 4881.0, 4881.487443818419, 4884.804607815648, 97042.0, 100565.49717251513, 102137.0, 102757.0, 104051.95948040574, 76556.0, 79092.60453697809, 79347.0, 80034.5998270005, 83310.58344708358, 80040.59979700064, 80605.19394805976, 80705.0, 81352.59323703461, 81840.0, 7402.814929626802, 8916.598753056258, 9048.0, 9367.725951308292, 9479.336446448959, 37859.62140378561, 46511.0, 51407.485925141766, 52070.21894671659, 53775.19337209867, 52413.7379313101, 66774.33225667875, 67091.0, 67303.66348168228, 68650.31349686439, 13262.204267743966, 13532.188068715908, 13812.654683632989, 14204.573862784133, 14392.06451580644, 13092.01809864283, 13504.0, 14007.649808754863, 14365.569032929028, 14603.853961460249, 13346.06577539563, 13494.647279699797, 13659.112157709716, 14126.929365353108, 14330.996830221784, 13097.563892986844, 13325.13386629866, 13574.524891628746, 13621.09304697363, 14144.78782818238, 12187.451564679433, 13258.636788048676, 13581.45674173022, 13978.510752124033, 14104.576862694135, 10139.442330671589, 10931.344119352865, 11097.0, 11108.666739997812, 11670.824937626112, 8150.103488616366, 9540.666076687283, 9991.350562213433, 10768.461576920967, 11229.382383968634], "value": 7571.645563219922, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1658.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 179.0, 179.0, 122.0, 121.0, 120.0, 121.0, 121.0, 126.0, 178.0], "value": 137.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 123.0, 150.0, 171.0, 114.0, 97.0, 131.0, 146.0, 169.0, 98.0], "value": 134.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 203.0, 212.0, 272.0, 243.0, 278.0, 233.0, 189.0, 183.0, 184.0], "value": 219.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 58.0, 89.0, 58.0, 61.0, 61.0, 55.0, 75.0, 60.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 85.0, 84.0, 103.0, 71.0, 87.0, 72.0, 70.0, 82.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 80.0, 136.0, 137.0, 90.0, 136.0, 76.0, 119.0, 137.0, 113.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 139.0, 95.0, 155.0, 124.0, 154.0, 117.0, 108.0, 153.0, 154.0], "value": 129.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 78.0, 141.0, 141.0, 104.0, 143.0, 140.0, 91.0, 91.0], "value": 111.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 67.0, 67.0, 66.0, 69.0, 67.0, 68.0, 67.0, 68.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 65.0, 66.0, 60.0, 58.0, 59.0, 59.0, 75.0, 89.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 79.0, 91.0, 108.0, 106.0, 85.0, 85.0, 88.0, 106.0, 89.0], "value": 92.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 148.0, 132.0, 133.0, 142.0, 152.0, 153.0, 149.0, 192.0, 153.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 189.0, 242.0, 244.0, 217.0, 187.0, 184.0, 185.0, 202.0, 243.0], "value": 207.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 70.0, 96.0, 108.0, 94.0, 103.0, 73.0, 68.0, 72.0], "value": 82.6, "unit": "score"}]}]} 09:21:14 INFO - Return code: 0 09:21:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:21:14 ERROR - # TBPL SUCCESS # 09:21:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:21:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:21:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:21:14 INFO - 2017-03-13 09:21:14.563 screenresolution[1660:10740] starting screenresolution argv=screenresolution get 09:21:14 INFO - 2017-03-13 09:21:14.566 screenresolution[1660:10740] Display 0: 1600x1200x32@60 09:21:14 INFO - 2017-03-13 09:21:14.572 screenresolution[1661:10743] starting screenresolution argv=screenresolution list 09:21:14 INFO - Available Modes on Display 0 09:21:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:21:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:21:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:21:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:21:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:21:14 INFO - Intel Iris: 09:21:14 INFO - Chipset Model: Intel Iris 09:21:14 INFO - Type: GPU 09:21:14 INFO - Bus: Built-In 09:21:14 INFO - VRAM (Dynamic, Max): 1536 MB 09:21:14 INFO - Vendor: Intel (0x8086) 09:21:14 INFO - Device ID: 0x0a2e 09:21:14 INFO - Revision ID: 0x0009 09:21:14 INFO - Displays: 09:21:14 INFO - Display: 09:21:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:21:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:21:14 INFO - Display Serial Number: 1600x1200 60 09:21:14 INFO - Main Display: Yes 09:21:14 INFO - Mirror: Off 09:21:14 INFO - Online: Yes 09:21:14 INFO - Rotation: Supported 09:21:14 INFO - Return code: 0 09:21:14 INFO - Running post-action listener: _resource_record_post_action 09:21:14 INFO - [mozharness: 2017-03-13 16:21:14.812273Z] Finished run-tests step (success) 09:21:14 INFO - Running post-run listener: _resource_record_post_run 09:21:14 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 38631936; Write bytes: 518026240; Read time: 247; Write time: 1663 09:21:14 INFO - TinderboxPrint: CPU usage
54.3% 09:21:14 INFO - TinderboxPrint: I/O read bytes / time
38,631,936 / 247 09:21:14 INFO - TinderboxPrint: I/O write bytes / time
518,026,240 / 1,663 09:21:14 INFO - TinderboxPrint: CPU idle
2,195.8 (45.7%) 09:21:14 INFO - TinderboxPrint: CPU system
966.5 (20.1%) 09:21:14 INFO - TinderboxPrint: CPU user
1,647.4 (34.3%) 09:21:14 INFO - TinderboxPrint: Swap in / out
249,176,064 / 0 09:21:14 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207651328; Write bytes: 204769280; Read time: 15735; Write time: 443 09:21:14 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 36551168; Write bytes: 302271488; Read time: 226; Write time: 1183 09:21:15 INFO - Running post-run listener: _upload_blobber_files 09:21:15 INFO - Blob upload gear active. 09:21:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:21:15 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:21:15 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:21:15 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:21:15 INFO - (blobuploader) - INFO - Open directory for files ... 09:21:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:21:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:21:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:17 INFO - (blobuploader) - INFO - Done attempting. 09:21:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:21:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:21:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:18 INFO - (blobuploader) - INFO - Done attempting. 09:21:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:21:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:21:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:19 INFO - (blobuploader) - INFO - Done attempting. 09:21:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:21:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:21:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:21 INFO - (blobuploader) - INFO - Done attempting. 09:21:21 INFO - (blobuploader) - INFO - Iteration through files over. 09:21:21 INFO - Return code: 0 09:21:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:21:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:21:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/218ef42fbd9ff6883ec158f115c35a809e6872cf92eb2b31afcb3795f46e96f8a6f268668333baa9f859ca3a546ccf3646f48ace55ec633937aeeaa738e06044", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94098eeb190b63e33ab95fd9ff00988196e0358c97b4e7aa789e128a3402ef44726886b4dbfeb4a9f44631882902f746552cb1d861621316237fd0f0bed799d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9430d990edf2af040acc8ee71dd819e04d10c2d03406d2bf1915d0c9bca3eb28181a0658e246b2b904c71c0b9e681440e9dd4d68b2cc3c9a8c36b0bd0fab1910", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc4b7d6a09f5dbc424f55126c49890c1ddb4be4c4908beabc6831cec45ab38a84e8faa0289b4322c9d1285dfc17c524e6432dc8fc21e390f4f13cd30d5267d30"} 09:21:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:21:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:21:21 INFO - Contents: 09:21:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/218ef42fbd9ff6883ec158f115c35a809e6872cf92eb2b31afcb3795f46e96f8a6f268668333baa9f859ca3a546ccf3646f48ace55ec633937aeeaa738e06044", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94098eeb190b63e33ab95fd9ff00988196e0358c97b4e7aa789e128a3402ef44726886b4dbfeb4a9f44631882902f746552cb1d861621316237fd0f0bed799d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9430d990edf2af040acc8ee71dd819e04d10c2d03406d2bf1915d0c9bca3eb28181a0658e246b2b904c71c0b9e681440e9dd4d68b2cc3c9a8c36b0bd0fab1910", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc4b7d6a09f5dbc424f55126c49890c1ddb4be4c4908beabc6831cec45ab38a84e8faa0289b4322c9d1285dfc17c524e6432dc8fc21e390f4f13cd30d5267d30"} 09:21:21 INFO - Running post-run listener: copy_logs_to_upload_dir 09:21:21 INFO - Copying logs to upload dir... 09:21:21 INFO - mkdir: /builds/slave/test/build/upload/logs 09:21:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.319541 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-13 09:21:21.396397) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-13 09:21:21.400811) ========= 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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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/218ef42fbd9ff6883ec158f115c35a809e6872cf92eb2b31afcb3795f46e96f8a6f268668333baa9f859ca3a546ccf3646f48ace55ec633937aeeaa738e06044", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94098eeb190b63e33ab95fd9ff00988196e0358c97b4e7aa789e128a3402ef44726886b4dbfeb4a9f44631882902f746552cb1d861621316237fd0f0bed799d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9430d990edf2af040acc8ee71dd819e04d10c2d03406d2bf1915d0c9bca3eb28181a0658e246b2b904c71c0b9e681440e9dd4d68b2cc3c9a8c36b0bd0fab1910", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc4b7d6a09f5dbc424f55126c49890c1ddb4be4c4908beabc6831cec45ab38a84e8faa0289b4322c9d1285dfc17c524e6432dc8fc21e390f4f13cd30d5267d30"} build_url:https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011162 build_url: 'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/218ef42fbd9ff6883ec158f115c35a809e6872cf92eb2b31afcb3795f46e96f8a6f268668333baa9f859ca3a546ccf3646f48ace55ec633937aeeaa738e06044", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94098eeb190b63e33ab95fd9ff00988196e0358c97b4e7aa789e128a3402ef44726886b4dbfeb4a9f44631882902f746552cb1d861621316237fd0f0bed799d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9430d990edf2af040acc8ee71dd819e04d10c2d03406d2bf1915d0c9bca3eb28181a0658e246b2b904c71c0b9e681440e9dd4d68b2cc3c9a8c36b0bd0fab1910", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc4b7d6a09f5dbc424f55126c49890c1ddb4be4c4908beabc6831cec45ab38a84e8faa0289b4322c9d1285dfc17c524e6432dc8fc21e390f4f13cd30d5267d30"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-13 09:21:21.436702) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 09:21:21.437027) ========= 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.kN2qOaCCaH/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.8AfO6dOfpB/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.005044 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 09:21:21.486854) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-13 09:21:21.487150) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-13 09:21:21.487467) ========= ========= Total master_lag: 0.20 =========