builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0379 starttime: 1489501864.41 results: success (0) buildid: 20170314062236 builduid: e0cebd2b9b474fb8b54a5b9e03d985cb revision: 9a26ed658fdc1d14c6153451e7e45f6ec94292fb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:04.409431) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:04.416494) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:04.416862) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:04.444272) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:04.444668) ========= 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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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-14 07:31:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2017-03-14 07:31:04 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.464608 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:04.932946) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:04.933254) ========= 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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.046178 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:05.001798) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:05.002164) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9a26ed658fdc1d14c6153451e7e45f6ec94292fb --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 9a26ed658fdc1d14c6153451e7e45f6ec94292fb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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-14 07:31:05,107 truncating revision to first 12 chars 2017-03-14 07:31:05,107 Setting DEBUG logging. 2017-03-14 07:31:05,108 attempt 1/10 2017-03-14 07:31:05,108 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9a26ed658fdc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-14 07:31:05,737 unpacking tar archive at: mozilla-central-9a26ed658fdc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.902587 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:05.927822) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:05.928181) ========= script_repo_revision: 9a26ed658fdc1d14c6153451e7e45f6ec94292fb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:05.928610) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:05.928908) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 07:31:05.952287) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-14 07:31:05.952781) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 07:31:06 INFO - MultiFileLogger online at 20170314 07:31:06 in /builds/slave/test 07:31:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:31:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:31:06 INFO - {'append_to_log': False, 07:31:06 INFO - 'base_work_dir': '/builds/slave/test', 07:31:06 INFO - 'blob_upload_branch': 'Firefox', 07:31:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:31:06 INFO - 'branch': 'Firefox', 07:31:06 INFO - 'buildbot_json_path': 'buildprops.json', 07:31:06 INFO - 'config_files': ('talos/mac_config.py',), 07:31:06 INFO - 'default_actions': ('clobber', 07:31:06 INFO - 'read-buildbot-config', 07:31:06 INFO - 'download-and-extract', 07:31:06 INFO - 'populate-webroot', 07:31:06 INFO - 'create-virtualenv', 07:31:06 INFO - 'install', 07:31:06 INFO - 'run-tests'), 07:31:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:31:06 INFO - 'download_minidump_stackwalk': True, 07:31:06 INFO - 'download_symbols': 'ondemand', 07:31:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:31:06 INFO - 'tooltool.py': '/tools/tooltool.py', 07:31:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:31:06 INFO - '/tools/misc-python/virtualenv.py')}, 07:31:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:31:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:31:06 INFO - 'gecko_profile': False, 07:31:06 INFO - 'gecko_profile_interval': 0, 07:31:06 INFO - 'installer_path': 'installer.exe', 07:31:06 INFO - 'log_level': 'info', 07:31:06 INFO - 'log_name': 'talos', 07:31:06 INFO - 'log_to_console': True, 07:31:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:31:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:31:06 INFO - 'opt_config_files': (), 07:31:06 INFO - 'pip_index': False, 07:31:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:06 INFO - 'cmd': ('bash', 07:31:06 INFO - '-c', 07:31:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:06 INFO - 'enabled': True, 07:31:06 INFO - 'halt_on_failure': False, 07:31:06 INFO - 'name': 'check_screen_resolution'},), 07:31:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:06 INFO - 'cmd': ('bash', 07:31:06 INFO - '-c', 07:31:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:06 INFO - 'enabled': True, 07:31:06 INFO - 'halt_on_failure': False, 07:31:06 INFO - 'name': 'check_screen_resolution'},), 07:31:06 INFO - 'run_cmd_checks_enabled': True, 07:31:06 INFO - 'suite': 'dromaeojs', 07:31:06 INFO - 'system_bits': '32', 07:31:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:31:06 INFO - 'title': 't-yosemite-r7-0379', 07:31:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:31:06 INFO - 'use_talos_json': True, 07:31:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:31:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:31:06 INFO - 'work_dir': 'build'} 07:31:06 INFO - [mozharness: 2017-03-14 14:31:06.161713Z] Running clobber step. 07:31:06 INFO - Running pre-action listener: _resource_record_pre_action 07:31:06 INFO - Running main action method: clobber 07:31:06 INFO - rmtree: /builds/slave/test/build 07:31:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:31:08 INFO - Running post-action listener: _resource_record_post_action 07:31:08 INFO - [mozharness: 2017-03-14 14:31:08.601606Z] Finished clobber step (success) 07:31:08 INFO - [mozharness: 2017-03-14 14:31:08.601794Z] Running read-buildbot-config step. 07:31:08 INFO - Running pre-action listener: _resource_record_pre_action 07:31:08 INFO - Running main action method: read_buildbot_config 07:31:08 INFO - Using buildbot properties: 07:31:08 INFO - { 07:31:08 INFO - "project": "", 07:31:08 INFO - "product": "firefox", 07:31:08 INFO - "script_repo_revision": "production", 07:31:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:31:08 INFO - "repository": "", 07:31:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:31:08 INFO - "buildid": "20170314062236", 07:31:08 INFO - "pgo_build": "False", 07:31:08 INFO - "basedir": "/builds/slave/test", 07:31:08 INFO - "buildnumber": 39, 07:31:08 INFO - "slavename": "t-yosemite-r7-0379", 07:31:08 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:31:08 INFO - "platform": "yosemite_r7", 07:31:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:31:08 INFO - "branch": "mozilla-central", 07:31:08 INFO - "repo_path": "mozilla-central", 07:31:08 INFO - "revision": "9a26ed658fdc1d14c6153451e7e45f6ec94292fb", 07:31:08 INFO - "stage_platform": "macosx64", 07:31:08 INFO - "builduid": "e0cebd2b9b474fb8b54a5b9e03d985cb", 07:31:08 INFO - "slavebuilddir": "test" 07:31:08 INFO - } 07:31:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 07:31:08 INFO - Running post-action listener: _resource_record_post_action 07:31:08 INFO - [mozharness: 2017-03-14 14:31:08.605156Z] Finished read-buildbot-config step (success) 07:31:08 INFO - [mozharness: 2017-03-14 14:31:08.605287Z] Running download-and-extract step. 07:31:08 INFO - Running pre-action listener: _resource_record_pre_action 07:31:08 INFO - Running main action method: download_and_extract 07:31:08 INFO - mkdir: /builds/slave/test/build/tests 07:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:08 INFO - trying https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:31:08 INFO - Downloading https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/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 07:31:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/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 07:31:09 INFO - Downloaded 1199 bytes. 07:31:09 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:31:09 INFO - Using the following test package requirements: 07:31:09 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:31:09 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:09 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:31:09 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:09 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:31:09 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:09 INFO - u'jsshell-mac.zip'], 07:31:09 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:09 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:31:09 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:31:09 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:09 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:31:09 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:09 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:31:09 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:09 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:31:09 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:09 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:31:09 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:31:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:31:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:31:09 INFO - Fetch https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:31:11 INFO - Expected file size: 52470500 07:31:11 INFO - Obtained file size: 52470500 07:31:27 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 07:31:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:31:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:31:27 INFO - Fetch https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:31:29 INFO - Expected file size: 52470500 07:31:29 INFO - Obtained file size: 52470500 07:31:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:31:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:31:45 INFO - Fetch https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:31:46 INFO - Expected file size: 14404452 07:31:46 INFO - Obtained file size: 14404452 07:31:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:46 INFO - trying https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:31:46 INFO - Downloading https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:31:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/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 07:31:48 INFO - Downloaded 74982872 bytes. 07:31:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:31:48 INFO - mkdir: /builds/slave/test/properties 07:31:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:31:48 INFO - Writing to file /builds/slave/test/properties/build_url 07:31:48 INFO - Contents: 07:31:48 INFO - build_url:https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:31:49 INFO - Running post-action listener: _resource_record_post_action 07:31:49 INFO - Running post-action listener: set_extra_try_arguments 07:31:49 INFO - [mozharness: 2017-03-14 14:31:49.264071Z] Finished download-and-extract step (success) 07:31:49 INFO - [mozharness: 2017-03-14 14:31:49.264219Z] Running populate-webroot step. 07:31:49 INFO - Running pre-action listener: _resource_record_pre_action 07:31:49 INFO - Running main action method: populate_webroot 07:31:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:31:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:31:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:31:49 INFO - '--tppagecycles', 07:31:49 INFO - '7'], 07:31:49 INFO - 'tests': ['tsvgm']}}, 07:31:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:31:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:31:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:31:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:31:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:31:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:31:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:31:49 INFO - 'talos_options': ['--disable-e10s'], 07:31:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:31:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:31:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:31:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:31:49 INFO - 'talos_options': ['--disable-e10s'], 07:31:49 INFO - 'tests': ['damp', 'tps']}, 07:31:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:31:49 INFO - 'tests': ['damp', 'tps']}, 07:31:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:31:49 INFO - 'tests': ['dromaeo_dom']}, 07:31:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:31:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:31:49 INFO - 'tests': ['basic_compositor_video']}, 07:31:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:31:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:31:49 INFO - 'tests': ['a11yr', 07:31:49 INFO - 'ts_paint', 07:31:49 INFO - 'tpaint', 07:31:49 INFO - 'sessionrestore', 07:31:49 INFO - 'sessionrestore_no_auto_restore', 07:31:49 INFO - 'tabpaint']}, 07:31:49 INFO - 'other-e10s': {'tests': ['a11yr', 07:31:49 INFO - 'ts_paint', 07:31:49 INFO - 'tpaint', 07:31:49 INFO - 'sessionrestore', 07:31:49 INFO - 'sessionrestore_no_auto_restore', 07:31:49 INFO - 'tabpaint']}, 07:31:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:31:49 INFO - 'ts_paint', 07:31:49 INFO - 'tpaint', 07:31:49 INFO - 'sessionrestore', 07:31:49 INFO - 'sessionrestore_no_auto_restore', 07:31:49 INFO - 'tabpaint']}, 07:31:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:31:49 INFO - 'ts_paint', 07:31:49 INFO - 'tpaint', 07:31:49 INFO - 'sessionrestore', 07:31:49 INFO - 'sessionrestore_no_auto_restore', 07:31:49 INFO - 'tabpaint']}, 07:31:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:31:49 INFO - 'tests': ['a11yr', 07:31:49 INFO - 'ts_paint', 07:31:49 INFO - 'tpaint', 07:31:49 INFO - 'sessionrestore', 07:31:49 INFO - 'sessionrestore_no_auto_restore', 07:31:49 INFO - 'tabpaint']}, 07:31:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:31:49 INFO - 'tests': ['a11yr', 07:31:49 INFO - 'ts_paint', 07:31:49 INFO - 'tpaint', 07:31:49 INFO - 'sessionrestore', 07:31:49 INFO - 'sessionrestore_no_auto_restore', 07:31:49 INFO - 'tabpaint']}, 07:31:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:31:49 INFO - 'tests': ['tsvgx', 07:31:49 INFO - 'tsvgr_opacity', 07:31:49 INFO - 'tart', 07:31:49 INFO - 'tscrollx', 07:31:49 INFO - 'cart', 07:31:49 INFO - 'tsvg_static']}, 07:31:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:31:49 INFO - 'tsvgr_opacity', 07:31:49 INFO - 'tart', 07:31:49 INFO - 'tscrollx', 07:31:49 INFO - 'cart', 07:31:49 INFO - 'tsvg_static']}, 07:31:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:31:49 INFO - 'talos_options': ['--disable-e10s'], 07:31:49 INFO - 'tests': ['tp5o']}, 07:31:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:31:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:31:49 INFO - 'talos_options': ['--disable-e10s', 07:31:49 INFO - '--xperf_path', 07:31:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:31:49 INFO - 'tests': ['tp5n']}, 07:31:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:31:49 INFO - 'talos_options': ['--xperf_path', 07:31:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:31:49 INFO - 'tests': ['tp5n']}}, 07:31:49 INFO - 'talos.zip': {'path': '', 07:31:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:31:49 INFO - Running post-action listener: _resource_record_post_action 07:31:49 INFO - [mozharness: 2017-03-14 14:31:49.282289Z] Finished populate-webroot step (success) 07:31:49 INFO - [mozharness: 2017-03-14 14:31:49.282395Z] Running create-virtualenv step. 07:31:49 INFO - Running pre-action listener: _resource_record_pre_action 07:31:49 INFO - Running main action method: create_virtualenv 07:31:49 INFO - Creating virtualenv /builds/slave/test/build/venv 07:31:49 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 07:31:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:31:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:31:49 INFO - Using real prefix '/tools/python27' 07:31:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:31:50 INFO - Installing distribute.............................................................................................................................................................................................done. 07:31:53 INFO - Installing pip.................done. 07:31:53 INFO - Return code: 0 07:31:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:31:53 INFO - Reading from file tmpfile_stdout 07:31:53 INFO - Output received: 07:31:53 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) 07:31:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:53 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 0x1048aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff882ca47d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104688d30>, '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 0x1045afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10467dbe8>, '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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 07:31:53 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 07:31:53 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 07:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PAIQRNS4WZ/Render', 07:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:53 INFO - 'HOME': '/Users/cltbld', 07:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:53 INFO - 'LOGNAME': 'cltbld', 07:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:53 INFO - 'MOZ_NO_REMOTE': '1', 07:31:53 INFO - 'NO_EM_RESTART': '1', 07:31:53 INFO - 'PAGER': '/bin/cat', 07:31:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:53 INFO - 'PWD': '/builds/slave/test', 07:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:53 INFO - 'SHELL': '/bin/bash', 07:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V4ZN28NizZ/Listeners', 07:31:53 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 07:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:53 INFO - 'USER': 'cltbld', 07:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:53 INFO - 'XPC_FLAGS': '0x0', 07:31:53 INFO - 'XPC_SERVICE_NAME': '0', 07:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:53 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 07:31:53 INFO - Cleaning up... 07:31:53 INFO - Return code: 0 07:31:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:53 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 0x1048aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff882ca47d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104688d30>, '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 0x1045afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10467dbe8>, '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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 07:31:53 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 07:31:53 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 07:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PAIQRNS4WZ/Render', 07:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:53 INFO - 'HOME': '/Users/cltbld', 07:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:53 INFO - 'LOGNAME': 'cltbld', 07:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:53 INFO - 'MOZ_NO_REMOTE': '1', 07:31:53 INFO - 'NO_EM_RESTART': '1', 07:31:53 INFO - 'PAGER': '/bin/cat', 07:31:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:53 INFO - 'PWD': '/builds/slave/test', 07:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:53 INFO - 'SHELL': '/bin/bash', 07:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V4ZN28NizZ/Listeners', 07:31:53 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 07:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:53 INFO - 'USER': 'cltbld', 07:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:53 INFO - 'XPC_FLAGS': '0x0', 07:31:53 INFO - 'XPC_SERVICE_NAME': '0', 07:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:53 INFO - Downloading/unpacking psutil>=3.1.1 07:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:31:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:57 INFO - Installing collected packages: psutil 07:31:57 INFO - Running setup.py install for psutil 07:31:57 INFO - building 'psutil._psutil_osx' extension 07:31:57 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 07:31:58 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 07:31:58 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 07:31:58 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 07:31:58 INFO - building 'psutil._psutil_posix' extension 07:31:58 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 07:31:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:31:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:31:58 INFO - ^ 07:31:58 INFO - 1 warning generated. 07:31:58 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 07:31:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:58 INFO - Successfully installed psutil 07:31:58 INFO - Cleaning up... 07:31:58 INFO - Return code: 0 07:31:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:31:58 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')]} 07:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:58 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')]} 07:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:58 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 0x1048aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff882ca47d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104688d30>, '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 0x1045afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10467dbe8>, '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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 07:31:58 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 07:31:58 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 07:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PAIQRNS4WZ/Render', 07:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:58 INFO - 'HOME': '/Users/cltbld', 07:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:58 INFO - 'LOGNAME': 'cltbld', 07:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:58 INFO - 'MOZ_NO_REMOTE': '1', 07:31:58 INFO - 'NO_EM_RESTART': '1', 07:31:58 INFO - 'PAGER': '/bin/cat', 07:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:58 INFO - 'PWD': '/builds/slave/test', 07:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:58 INFO - 'SHELL': '/bin/bash', 07:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V4ZN28NizZ/Listeners', 07:31:58 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 07:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:58 INFO - 'USER': 'cltbld', 07:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:58 INFO - 'XPC_FLAGS': '0x0', 07:31:58 INFO - 'XPC_SERVICE_NAME': '0', 07:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:32:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:32:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:32:01 INFO - Installing collected packages: mozsystemmonitor 07:32:01 INFO - Running setup.py install for mozsystemmonitor 07:32:02 INFO - Successfully installed mozsystemmonitor 07:32:02 INFO - Cleaning up... 07:32:02 INFO - Return code: 0 07:32:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1048aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff882ca47d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104688d30>, '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 0x1045afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10467dbe8>, '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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 07:32:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:32:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:32:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PAIQRNS4WZ/Render', 07:32:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:02 INFO - 'HOME': '/Users/cltbld', 07:32:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:02 INFO - 'LOGNAME': 'cltbld', 07:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:02 INFO - 'MOZ_NO_REMOTE': '1', 07:32:02 INFO - 'NO_EM_RESTART': '1', 07:32:02 INFO - 'PAGER': '/bin/cat', 07:32:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:02 INFO - 'PWD': '/builds/slave/test', 07:32:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:02 INFO - 'SHELL': '/bin/bash', 07:32:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V4ZN28NizZ/Listeners', 07:32:02 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 07:32:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:02 INFO - 'USER': 'cltbld', 07:32:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:02 INFO - 'XPC_FLAGS': '0x0', 07:32:02 INFO - 'XPC_SERVICE_NAME': '0', 07:32:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:02 INFO - Downloading/unpacking jsonschema==2.5.1 07:32:02 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 07:32:02 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 07:32:02 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 07:32:02 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 07:32:02 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 07:32:02 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 07:32:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:32:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:05 INFO - Downloading functools32-3.2.3-2.tar.gz 07:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:32:05 INFO - warning: no files found matching '*.txt' 07:32:05 INFO - no previously-included directories found matching 'build' 07:32:05 INFO - no previously-included directories found matching 'dist' 07:32:05 INFO - no previously-included directories found matching '.git*' 07:32:05 INFO - Installing collected packages: jsonschema, functools32 07:32:05 INFO - Running setup.py install for functools32 07:32:05 INFO - warning: no files found matching '*.txt' 07:32:05 INFO - no previously-included directories found matching 'build' 07:32:05 INFO - no previously-included directories found matching 'dist' 07:32:05 INFO - no previously-included directories found matching '.git*' 07:32:05 INFO - Successfully installed jsonschema functools32 07:32:05 INFO - Cleaning up... 07:32:05 INFO - Return code: 0 07:32:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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')]} 07:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1048aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff882ca47d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104688d30>, '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 0x1045afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10467dbe8>, '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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 07:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PAIQRNS4WZ/Render', 07:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:05 INFO - 'HOME': '/Users/cltbld', 07:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:05 INFO - 'LOGNAME': 'cltbld', 07:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:05 INFO - 'MOZ_NO_REMOTE': '1', 07:32:05 INFO - 'NO_EM_RESTART': '1', 07:32:05 INFO - 'PAGER': '/bin/cat', 07:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:05 INFO - 'PWD': '/builds/slave/test', 07:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:05 INFO - 'SHELL': '/bin/bash', 07:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V4ZN28NizZ/Listeners', 07:32:05 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 07:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:05 INFO - 'USER': 'cltbld', 07:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:05 INFO - 'XPC_FLAGS': '0x0', 07:32:05 INFO - 'XPC_SERVICE_NAME': '0', 07:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:32:05 INFO - Cleaning up... 07:32:05 INFO - Return code: 0 07:32:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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')]} 07:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1048aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff882ca47d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104688d30>, '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 0x1045afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10467dbe8>, '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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 07:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PAIQRNS4WZ/Render', 07:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:05 INFO - 'HOME': '/Users/cltbld', 07:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:05 INFO - 'LOGNAME': 'cltbld', 07:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:05 INFO - 'MOZ_NO_REMOTE': '1', 07:32:05 INFO - 'NO_EM_RESTART': '1', 07:32:05 INFO - 'PAGER': '/bin/cat', 07:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:05 INFO - 'PWD': '/builds/slave/test', 07:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:05 INFO - 'SHELL': '/bin/bash', 07:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V4ZN28NizZ/Listeners', 07:32:05 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 07:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:05 INFO - 'USER': 'cltbld', 07:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:05 INFO - 'XPC_FLAGS': '0x0', 07:32:05 INFO - 'XPC_SERVICE_NAME': '0', 07:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:06 INFO - Downloading/unpacking blobuploader==1.2.4 07:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:08 INFO - Downloading blobuploader-1.2.4.tar.gz 07:32:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:32:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:32:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:32:09 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 07:32:09 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 07:32:09 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 07:32:09 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 07:32:09 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 07:32:09 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 07:32:10 INFO - Downloading docopt-0.6.1.tar.gz 07:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:32:10 INFO - Installing collected packages: blobuploader, requests, docopt 07:32:10 INFO - Running setup.py install for blobuploader 07:32:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:32:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:32:10 INFO - Running setup.py install for requests 07:32:10 INFO - Running setup.py install for docopt 07:32:10 INFO - Successfully installed blobuploader requests docopt 07:32:10 INFO - Cleaning up... 07:32:11 INFO - Return code: 0 07:32:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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')]} 07:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:11 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 0x1048aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff882ca47d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104688d30>, '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 0x1045afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10467dbe8>, '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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 07:32:11 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 07:32:11 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 07:32:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PAIQRNS4WZ/Render', 07:32:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:11 INFO - 'HOME': '/Users/cltbld', 07:32:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:11 INFO - 'LOGNAME': 'cltbld', 07:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:11 INFO - 'MOZ_NO_REMOTE': '1', 07:32:11 INFO - 'NO_EM_RESTART': '1', 07:32:11 INFO - 'PAGER': '/bin/cat', 07:32:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:11 INFO - 'PWD': '/builds/slave/test', 07:32:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:11 INFO - 'SHELL': '/bin/bash', 07:32:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V4ZN28NizZ/Listeners', 07:32:11 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 07:32:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:11 INFO - 'USER': 'cltbld', 07:32:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:11 INFO - 'XPC_FLAGS': '0x0', 07:32:11 INFO - 'XPC_SERVICE_NAME': '0', 07:32:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:11 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-t1Fm4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:11 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-bXztJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:11 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-5OtCvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:11 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-8DfrHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:11 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Ifiinl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:11 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Psbekt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:11 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-IxGrRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:12 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-IssqG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:12 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-qWwj6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:12 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Pv0AX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:12 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Z2L8UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:12 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Z27ZQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:12 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-VPXrHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:12 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-SOHXxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:12 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-H8viBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:12 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-iH8IOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:12 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-LkTnHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:32:13 INFO - Running setup.py install for manifestparser 07:32:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:32:13 INFO - Running setup.py install for mozcrash 07:32:13 INFO - Running setup.py install for mozdebug 07:32:13 INFO - Running setup.py install for mozdevice 07:32:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:32:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:32:13 INFO - Running setup.py install for mozfile 07:32:13 INFO - Running setup.py install for mozhttpd 07:32:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:32:13 INFO - Running setup.py install for mozinfo 07:32:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:32:14 INFO - Running setup.py install for mozInstall 07:32:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:32:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:32:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:32:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:32:14 INFO - Running setup.py install for mozleak 07:32:14 INFO - Running setup.py install for mozlog 07:32:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:32:14 INFO - Running setup.py install for moznetwork 07:32:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:32:14 INFO - Running setup.py install for mozprocess 07:32:14 INFO - Running setup.py install for mozprofile 07:32:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:32:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:32:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:32:15 INFO - Running setup.py install for mozrunner 07:32:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:32:15 INFO - Running setup.py install for mozscreenshot 07:32:15 INFO - Running setup.py install for moztest 07:32:15 INFO - Running setup.py install for mozversion 07:32:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:32:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:32:15 INFO - Cleaning up... 07:32:15 INFO - Return code: 0 07:32:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1048aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff882ca47d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104688d30>, '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 0x1045afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10467dbe8>, '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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 07:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PAIQRNS4WZ/Render', 07:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:15 INFO - 'HOME': '/Users/cltbld', 07:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:15 INFO - 'LOGNAME': 'cltbld', 07:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:15 INFO - 'MOZ_NO_REMOTE': '1', 07:32:15 INFO - 'NO_EM_RESTART': '1', 07:32:15 INFO - 'PAGER': '/bin/cat', 07:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:15 INFO - 'PWD': '/builds/slave/test', 07:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:15 INFO - 'SHELL': '/bin/bash', 07:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V4ZN28NizZ/Listeners', 07:32:15 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 07:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:15 INFO - 'USER': 'cltbld', 07:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:15 INFO - 'XPC_FLAGS': '0x0', 07:32:15 INFO - 'XPC_SERVICE_NAME': '0', 07:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:15 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-6_KlX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:16 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)) 07:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:16 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-49Byrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:16 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)) 07:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:16 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-xzzwXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:16 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)) 07:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:16 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-3dPeta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:16 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)) 07:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:16 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-gEPk0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:16 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)) 07:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:16 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-jx2YGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:16 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)) 07:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:16 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-VglmIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:16 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-tNJkp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:16 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-RIVGFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:16 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-8y6U5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:16 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-CrI_qk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:17 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)) 07:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:17 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-ikz3dh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:17 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)) 07:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:17 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-KkpttA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:17 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)) 07:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:17 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-fsl2eP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:17 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)) 07:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:17 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-DfZlYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:17 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)) 07:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:17 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-3pGLXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:17 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)) 07:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:17 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-F2OClV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:32:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:20 INFO - Downloading blessings-1.6.tar.gz 07:32:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:32:20 INFO - Installing collected packages: blessings 07:32:20 INFO - Running setup.py install for blessings 07:32:20 INFO - Successfully installed blessings 07:32:20 INFO - Cleaning up... 07:32:20 INFO - Return code: 0 07:32:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:32:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:21 INFO - Reading from file tmpfile_stdout 07:32:21 INFO - Current package versions: 07:32:21 INFO - blessings == 1.6 07:32:21 INFO - blobuploader == 1.2.4 07:32:21 INFO - docopt == 0.6.1 07:32:21 INFO - functools32 == 3.2.3-2 07:32:21 INFO - jsonschema == 2.5.1 07:32:21 INFO - manifestparser == 1.1 07:32:21 INFO - mozInstall == 1.12 07:32:21 INFO - mozcrash == 1.0 07:32:21 INFO - mozdebug == 0.1 07:32:21 INFO - mozdevice == 0.49 07:32:21 INFO - mozfile == 1.2 07:32:21 INFO - mozhttpd == 0.7 07:32:21 INFO - mozinfo == 0.9 07:32:21 INFO - mozleak == 0.1 07:32:21 INFO - mozlog == 3.4 07:32:21 INFO - moznetwork == 0.27 07:32:21 INFO - mozprocess == 0.25 07:32:21 INFO - mozprofile == 0.28 07:32:21 INFO - mozrunner == 6.13 07:32:21 INFO - mozscreenshot == 0.1 07:32:21 INFO - mozsystemmonitor == 0.3 07:32:21 INFO - moztest == 0.8 07:32:21 INFO - mozversion == 1.4 07:32:21 INFO - psutil == 3.1.1 07:32:21 INFO - requests == 1.2.3 07:32:21 INFO - wsgiref == 0.1.2 07:32:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:21 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 0x1048aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff882ca47d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104688d30>, '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 0x1045afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10467dbe8>, '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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 07:32:21 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 07:32:21 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 07:32:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PAIQRNS4WZ/Render', 07:32:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:21 INFO - 'HOME': '/Users/cltbld', 07:32:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:21 INFO - 'LOGNAME': 'cltbld', 07:32:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:21 INFO - 'MOZ_NO_REMOTE': '1', 07:32:21 INFO - 'NO_EM_RESTART': '1', 07:32:21 INFO - 'PAGER': '/bin/cat', 07:32:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:21 INFO - 'PWD': '/builds/slave/test', 07:32:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:21 INFO - 'SHELL': '/bin/bash', 07:32:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V4ZN28NizZ/Listeners', 07:32:21 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 07:32:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:21 INFO - 'USER': 'cltbld', 07:32:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:21 INFO - 'XPC_FLAGS': '0x0', 07:32:21 INFO - 'XPC_SERVICE_NAME': '0', 07:32:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:21 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)) 07:32:21 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)) 07:32:21 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)) 07:32:21 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)) 07:32:21 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)) 07:32:21 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)) 07:32:21 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)) 07:32:21 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)) 07:32:21 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)) 07:32:21 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)) 07:32:21 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)) 07:32:21 INFO - Cleaning up... 07:32:21 INFO - Return code: 0 07:32:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:21 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 0x1048aab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff882ca47d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104688d30>, '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 0x1045afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10467dbe8>, '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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 07:32:21 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 07:32:21 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 07:32:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PAIQRNS4WZ/Render', 07:32:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:21 INFO - 'HOME': '/Users/cltbld', 07:32:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:21 INFO - 'LOGNAME': 'cltbld', 07:32:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:21 INFO - 'MOZ_NO_REMOTE': '1', 07:32:21 INFO - 'NO_EM_RESTART': '1', 07:32:21 INFO - 'PAGER': '/bin/cat', 07:32:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:21 INFO - 'PWD': '/builds/slave/test', 07:32:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:21 INFO - 'SHELL': '/bin/bash', 07:32:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V4ZN28NizZ/Listeners', 07:32:21 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 07:32:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:21 INFO - 'USER': 'cltbld', 07:32:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:21 INFO - 'XPC_FLAGS': '0x0', 07:32:21 INFO - 'XPC_SERVICE_NAME': '0', 07:32:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:32:21 INFO - Cleaning up... 07:32:21 INFO - Return code: 0 07:32:21 INFO - Running post-action listener: _resource_record_post_action 07:32:21 INFO - Running post-action listener: _start_resource_monitoring 07:32:21 INFO - Starting resource monitoring. 07:32:21 INFO - [mozharness: 2017-03-14 14:32:21.597820Z] Finished create-virtualenv step (success) 07:32:21 INFO - [mozharness: 2017-03-14 14:32:21.598820Z] Running install step. 07:32:21 INFO - Running pre-action listener: _resource_record_pre_action 07:32:21 INFO - Running main action method: install 07:32:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:21 INFO - Reading from file tmpfile_stdout 07:32:21 INFO - Detecting whether we're running mozinstall >=1.0... 07:32:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:32:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:32:21 INFO - Reading from file tmpfile_stdout 07:32:21 INFO - Output received: 07:32:21 INFO - Usage: mozinstall [options] installer 07:32:21 INFO - Options: 07:32:21 INFO - -h, --help show this help message and exit 07:32:21 INFO - -d DEST, --destination=DEST 07:32:21 INFO - Directory to install application into. [default: 07:32:21 INFO - "/builds/slave/test"] 07:32:21 INFO - --app=APP Application being installed. [default: firefox] 07:32:21 INFO - mkdir: /builds/slave/test/build/application 07:32:21 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'] 07:32:21 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 07:32:42 INFO - Reading from file tmpfile_stdout 07:32:42 INFO - Output received: 07:32:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:32:42 INFO - Running post-action listener: _resource_record_post_action 07:32:42 INFO - [mozharness: 2017-03-14 14:32:42.285268Z] Finished install step (success) 07:32:42 INFO - [mozharness: 2017-03-14 14:32:42.285416Z] Running run-tests step. 07:32:42 INFO - Running pre-action listener: _resource_record_pre_action 07:32:42 INFO - Running main action method: run_tests 07:32:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:32:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:32:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:32:42 INFO - 2017-03-14 07:32:42.299 screenresolution[1632:8331] starting screenresolution argv=screenresolution get 07:32:42 INFO - 2017-03-14 07:32:42.302 screenresolution[1632:8331] Display 0: 1600x1200x32@60 07:32:42 INFO - 2017-03-14 07:32:42.308 screenresolution[1633:8333] starting screenresolution argv=screenresolution list 07:32:42 INFO - Available Modes on Display 0 07:32:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:32:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:32:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:32:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:32:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:32:42 INFO - Intel Iris: 07:32:42 INFO - Chipset Model: Intel Iris 07:32:42 INFO - Type: GPU 07:32:42 INFO - Bus: Built-In 07:32:42 INFO - VRAM (Dynamic, Max): 1536 MB 07:32:42 INFO - Vendor: Intel (0x8086) 07:32:42 INFO - Device ID: 0x0a2e 07:32:42 INFO - Revision ID: 0x0009 07:32:42 INFO - Displays: 07:32:42 INFO - Display: 07:32:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:32:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:32:42 INFO - Display Serial Number: 1600x1200 60 07:32:42 INFO - Main Display: Yes 07:32:42 INFO - Mirror: Off 07:32:42 INFO - Online: Yes 07:32:42 INFO - Rotation: Supported 07:32:42 INFO - Return code: 0 07:32:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:32:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:32:42 INFO - Python 2.7.3 07:32:42 INFO - Return code: 0 07:32:42 INFO - grabbing minidump binary from tooltool 07:32: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')]} 07:32:42 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 0x104688d30>, '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 0x1045afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10467dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:32:42 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 07:32:42 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 07:32:42 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 07:32:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:32:42 INFO - Return code: 0 07:32:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:32:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:32:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:32:42 INFO - ENV: RUST_BACKTRACE is now 1 07:32:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:32:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:32:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:32:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0379 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PAIQRNS4WZ/Render', 07:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:42 INFO - 'HOME': '/Users/cltbld', 07:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:42 INFO - 'LOGNAME': 'cltbld', 07:32:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:32:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:42 INFO - 'MOZ_NO_REMOTE': '1', 07:32:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:32:42 INFO - 'NO_EM_RESTART': '1', 07:32:42 INFO - 'PAGER': '/bin/cat', 07:32:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:42 INFO - 'PWD': '/builds/slave/test', 07:32:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:42 INFO - 'RUST_BACKTRACE': '1', 07:32:42 INFO - 'SHELL': '/bin/bash', 07:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V4ZN28NizZ/Listeners', 07:32:42 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 07:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:42 INFO - 'USER': 'cltbld', 07:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:42 INFO - 'XPC_FLAGS': '0x0', 07:32:42 INFO - 'XPC_SERVICE_NAME': '0', 07:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:32:42 INFO - mozversion application_buildid: 20170314062236 07:32:42 INFO - mozversion application_changeset: 9a26ed658fdc1d14c6153451e7e45f6ec94292fb 07:32:42 INFO - mozversion application_display_name: Nightly 07:32:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:32:42 INFO - mozversion application_name: Firefox 07:32:42 INFO - mozversion application_remotingname: firefox 07:32:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:32:42 INFO - mozversion application_vendor: Mozilla 07:32:42 INFO - mozversion application_version: 55.0a1 07:32:42 INFO - mozversion platform_buildid: 20170314062236 07:32:42 INFO - mozversion platform_changeset: 9a26ed658fdc1d14c6153451e7e45f6ec94292fb 07:32:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:32:42 INFO - mozversion platform_version: 55.0a1 07:32:42 INFO - using testdate: 1489501962 07:32:42 INFO - actual date: 1489501962 07:32:42 INFO - starting webserver on 'localhost:49245' 07:32:42 INFO - SUITE-START | Running 2 tests 07:32:42 INFO - TEST-START | dromaeo_css 07:32:42 INFO - operating with platform_type : mac_ 07:32:42 INFO - Initialising browser for dromaeo_css test... 07:32:42 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmp6xZKji/profile http://localhost:49245/getInfo.html) 07:32:45 INFO - PID 1643 | __metrics Screen width/height:1600/1200 07:32:45 INFO - PID 1643 | colorDepth:24 07:32:45 INFO - PID 1643 | Browser inner width/height: 1024/689 07:32:45 INFO - PID 1643 | __metrics 07:32:45 INFO - PID 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:32:45 INFO - PID 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:32:46 INFO - TEST-INFO | 1643: exit 0 07:32:46 INFO - Browser initialized. 07:32:46 INFO - Running cycle 1/1 for dromaeo_css test... 07:32:46 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmp6xZKji/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:32:58 INFO - PID 1646 | RSS: Main: 233312256 07:32:58 INFO - PID 1646 | 07:36:01 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 07:36:05 INFO - PID 1646 | RSS: Main: 502824960 07:36:05 INFO - PID 1646 | 07:37:48 INFO - PID 1646 | ************************* 07:37:48 INFO - PID 1646 | A coding exception was thrown and uncaught in a Task. 07:37:48 INFO - PID 1646 | 07:37:48 ERROR - PID 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:37:48 INFO - PID 1646 | Full stack: 07:37:48 INFO - PID 1646 | ************************* 07:39:08 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 07:39:09 INFO - PID 1646 | RSS: Main: 371363840 07:39:09 INFO - PID 1646 | 07:42:11 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 07:42:12 INFO - PID 1646 | RSS: Main: 353742848 07:42:12 INFO - PID 1646 | 07:45:14 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 07:45:15 INFO - PID 1646 | RSS: Main: 327340032 07:45:15 INFO - PID 1646 | 07:48:19 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 07:48:19 INFO - PID 1646 | RSS: Main: 330452992 07:48:19 INFO - PID 1646 | 07:51:23 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 07:51:23 INFO - PID 1646 | RSS: Main: 419069952 07:51:23 INFO - PID 1646 | 07:51:23 INFO - PID 1646 | __start_tp_report 07:51:23 INFO - PID 1646 | _x_x_mozilla_page_load 07:51:23 INFO - PID 1646 | _x_x_mozilla_page_load_details 07:51:23 INFO - PID 1646 | |i|pagename|runs| 07:51:23 INFO - PID 1646 | |0;dojo.html;3325.5177999190114;3375.8480868360934;3383.0865666270124;3428.6057103433095;3458.204612939024;5759.136129580565;5965.2245208122995;5998.520118390529;6027.8493037674025;6037.698115094244;5943.108533719944;5968.970155149229;6035.637861728288;6050.6369617823075;6078.726457309421;5214.869628259305;5268.130758424855;5273.235380869772;5288.206768984644;5302.390225124122;5396.541293990015;5406.053940560406;5406.216098665691;5433.565314774828;5443.1835224716215;5242.7640756166065;5281.9207711884355;5291.074062039128;5349.384820745606;5358.758855851496;5317.813876514303;5318.787248510055;5345.198220266952;5345.679259244419;5389.353277606712;5231.973840130775;5262.789488420459;5265.921011184835;5274.340707411574;5286.127788914824;5929.258842644669;5929.897686633634;5933.021051526493;5986.97006514969;6021.729022194012;6144.771045790815;6207.162033125515;6219.968900155515;6222.81331560054;6246.469050130744;6140.995089002448;6169.29053158888;6221.751129954841;6235.563510554263;6241.032639940771;5841.240638716939;5934.287885453773;5936.020556608154;5954.672493012882;5955.940440595582;6243.25080990275;6249.187605611332;6310.842228944313;6379.266384365857;6382.170317858649;6179.0422484514975;6212.844678882973;6240.968795155995;6248.812535623939;6310.242770867525;6236.719347629368;6272.996320588684;6287.968560157261;6292.024736165901;6313.589616674902;6246.375362463808;6249.187605611332;6263.6241825490615;6268.279147897959;6274.749010039547;6211.068339748984;6237.968810156011;6239.126522286883;6244.843878903063;6248.28144763349;5044.722540260247;5062.6709263898465;5065.898682026413;5067.645264831426;5074.923876141861;4493.393391892118;4545.454545454567;4547.886302842456;4593.563611456906;4607.976960115178;5832.766689332464;5843.795467158629;5845;5846.561507886926;5853.502452291603;6148.9692551536955;6220.409061139185;6231;6239.688015599202;6242.843928901813;4677.579017888406;5832.591718579659;5891.793787217427;5961.314448838352;5981.940180598225;5595.804146854841;5600.467955544217;5600.719964001783;5604.859878502988;5619.960307343154;6450.6774661266745;6459.128017717642;6480.222373315232;6493.675316234169;6498.707558159988;6205.193324867739;6241.188645476059;6264.123022776906;6265.655388953652;6306.0225665022;6279.654618995816;6279.654618995999;6290.21372328459;6311.558190926591;6388.329225431263;6219.471344935731;6231.189945307262;6259.374062593704;6260.7495700171485;6273.215848018997;6268.717907694074;6273.905891411724;6275.811725648238;6277.246730392382;6278.591891527036;4757.5718185363485;5981.192539007178;6258.0925765762795;6289.716962736779;6313.2424109107205;6127.250613123678;6148.862460444832;6151.769646070893;6157.1072194532435;6161.260648722003;5995.850103747442;6029.186059881859;6041;6061.878762424815;6065.878682426415;6149.7847575334645;6150.846228844314;6153.507719382349;6154.292256390572;6165.383461653799;5943.881122377615;6018.21763170785;6080.209572755691;6137.346341658241;6181 07:51:23 INFO - PID 1646 | |1;ext.html;765.1888997662478;780.8711562592171;854.5001174313027;859.0636206534875;860.802015536427;36188.45717314242;36644.63355366449;37013.62986370135;37017.25965480689;37020.99999999996;38318.85043448701;38674.419883701696;38760.51155396014;38898;39047.21905561894;7162.068931038958;7338.889916651255;8915.866262006075;9091.136342047495;9147.313951453667;10471.47642617866;10691;10735.355878647268;10735.892641073606;10743.677689669323;10772.892271077306;10833.349999000045;10843.891561084367;10849.969252920999;10856.728581785479;7123.89314160288;7332.7066917323245;10744.946275268612;10836.458177091194;10841.403722795243;7172.318629730167;7250.564966102051;7264.8547029059655;10296.176305895511;10517.684469465928;36595;36880.631193688256;36991.81504092462;37003.88988330354;38216.23567528634;3755.906102347436;3762.6613604775703;3783.810809459516;3813.9809300953316;3889.565894879839;27736;27839;28059.719402805913;28105.156845294674;28845.85577072122;3032.969670303291;3056.2970516781265;3095.6440009398984;3160.194150491633;3162.652108268089;338004;338662.99999999756;338859;339220;339294;38571;39019.804900975316;39208;46547.76726116348;50762.49237507577;48474.27288590674;48596.51403485991;48672.75663621659;48722.5127748718;48729;14103.929480352532;14245.216513091673;14259.429622815178;14316;14323;11605;11615.245009074382;11631.069514438825;11650.941745291218;11749.236299640495;10367.94816025915;10718.89281107179;10990.505427255792;12493.000559955182;12838.871611283954;5367.75845086972;5417.51242388187;5426.5077103796775;5436.782528698887;5451.073317536028;972.8686627305365;1114.570890207276;1130.3161587239765;1133.9603113890973;1166;25122.24633261005;25596;25650.48699026009;25769.61345579818;25989.220323390327;1182.8757980411933;1195.946182421793;1199.7540504196618;1220.365409986802;1244.0731654917145;11225.158113141548;13259.535916243078;13274.469021239236;13310.534131305358;13328.06703530763;275204;318965;323618;326734;435271;51346.48653513417;51401.48598513967;51423.71440713937;51444.48555514397;51572.22641660378;1372.3069849725825;1418.5460652591485;1426.6005518455056;1431.5347512058622;1445.645816774897;27648.308792280353;27704.445911081264;28144.71855281421;28527.714722852506;28619.998300059397;43645.781771090944;43773.34339984839;43828.780856095516;43892.56107438885;43978.34032489579;338363;339573;341719;341841;342268.5773142237;25099.74900251047;25138.62292065657;25536.744632553437;29847.403051938407;29959.55060674135;36720.81639591785;37983.05042373963;39025;39355.6064439352;39360;31572.842135789175;32925.50611740874;33352.83323583366;33577.49633755544;33868.661313386554;32257.677423226407;32394.05635661868;33236;33346.33307333888;33716.66283337135;32769.180770480925;34008.65991340055;34586.48120278248;34706.82646586751;34753.957381278604;27830.025949092495;28594.857025714737;29458.26354341073;29695.703042969293;29779.55330670085;20110.49723756918;20778.896105519376;21028.894855525625;22293.219737309115;23236 07:51:23 INFO - PID 1646 | |2;jquery.html;350.8000439749343;376.1255081934503;402.18156052433284;415.7339302846179;426.46052743279756;13970.860291397083;17034.82965170348;17625.06124326323;21559.353219403423;21981.890090549565;21182.093611574954;22240.66556006641;22456.101755929743;23047.888535047765;23401.648975265383;4056.9594304057023;6497.8375540611505;6784.355486228825;6797.048413222128;6804.897926531106;7355.374793142562;7571.4321425893;7572.167061623217;7585.12771031333;7585.1656317805;7217.530860494051;7545.622718864062;7585.1656317805;7613.124490683559;7630.2751238632245;7479.775606731807;7568.886466703004;7617.2001939796355;7633.9618301908695;7667.5782831944225;7405.296496832846;7457.254274572553;7463.216362281991;7465.925340746631;7470.813229669247;26383.73616263832;27164;27236.59145112825;27280.590791138147;27509.587356189673;3239.3683231769814;3253.8047717137047;3275.901722948315;3305.4876494143446;3306.6197387300526;16247.593810154722;16373.836261637469;16611.83388166115;16808.411705590366;17518.737218941726;1987.7714062882808;2050.1389416445127;2052.7170518425983;2053.2505635443104;2055.085486958304;139958;145525;145589;145730.99999999895;149162.25418872945;14010.719785604231;18300.725489117674;20007.299744508873;21059.368218953456;21339.14643414277;29205.95558310898;29281.707182928323;32747;34415;34437;10168;10663.680089597325;10808.62169824068;10813.945930270298;10991.450427478594;7405.851882962311;7533.924660753432;7586;7737.535747855147;7751.9224807752325;9362.970073291932;9679.322447428753;10017.69946901594;10214.795704086026;10244.48777561119;3540.503944718967;3729.4592284119194;3744.4570537271943;4060.5739743679533;4167.374893765898;953.6042542344987;957.4303289542711;961.624966263158;984.2009842009836;995.621663767778;21957.83448461856;22125.596621435827;22316.330510084725;22451.88774056152;22525.395936650057;938.7606160429046;978.8042391521582;978.8238117138841;986.7927735175693;993.076438911819;11194.440277986068;11244.55021799119;11321.584801899762;11558.30650160993;11598.76802463946;663899;671037;674664;683833.969961794;703442;129413;137947;148133;155745;159907;1221.6518292286833;1229.932353720536;1254.8996080313736;1263.8546567144713;1264.6648638110723;16616.252268648183;19071.518193224285;20068.19727210895;20072.996350182726;20075.337251135734;45597.316040260084;60685.48286292878;60952.78094437998;61833;62580;89777;91135;91192.54403727938;91915;92157.53921230351;8616.267617252604;11599.710007249887;11868.703282418008;11957.249832538391;12040.397980101136;13148.013898957424;13261.867381326449;13321;13611.455541778614;13643.317834108453;11501.482433290355;11594.88405115938;11727.354995474989;11789.70525736829;11807.940960295144;10712.464376781285;11264.605738799102;11425.771484570429;11557.42212889369;11671.533138674358;11347.602833900773;11767.587889452978;11790.882091178979;11897.167198296382;11918.821217681569;10243.436610986468;11262.38056906878;11382.317060976367;11702.2978621283;11748.58879939198;9792.314537982273;10890.401027943539;11535.76928461443;11715.472803724024;11817.82273265919 07:51:23 INFO - PID 1646 | |3;mootools.html;1460.1968917095598;1534.294224656658;1566.4908904606;1593.0362130910798;1600.9599760006;10381.740456488582;10826.945865270663;10844.566217351316;10861.83707244392;11076.058535024533;9298.86051709225;10653.041226289632;11030.172737044713;11043.613473528429;11050.392228427434;2304.3893368257427;2373.15752907717;2375.893084811179;2377.3699969508093;2380.119355838342;2290.8167346612313;2295.598270302699;2332.8483648562874;2338.8128949683983;2343.1096183450263;2226.743924448685;2267.4218074391006;2314.918977835776;2327.4065113396146;2338.8947497362574;2212.192549719341;2232;2235.3182279404773;2237.7538470768204;2238.3173132194675;2294.5410917816503;2318.026428899849;2327.93016209514;2329.324495896188;2337.5091230841545;10748.032677059102;10815.567377304898;10822.24244302899;10830.620928267474;10830.78338433227;5537.695426751527;5768.942310576883;5774.913376299359;5784.942150578482;5786.479216870502;3193.2495863472063;3307.601988558276;3466.584009918826;3471.427214509603;3478.47822826578;1133.4899295317057;1162.4187906046977;1173.847399838024;1179.6225207933464;1221.7067903703048;13523.797143042862;13882.930585347009;13914.791278130837;14803.703925921422;14860.62848428787;4274.829006839692;4382.802773875183;4383.430154080013;4719.764011799396;4920.5571498565305;10522.421266830244;12584.81122783159;12609.684757881127;12645.747085058247;12667.49330026807;5794.710264486843;5795.13073039039;5806.82579522615;5812.128180772834;5815.592908496449;4927.162382395001;5155.458676838878;5223.347081614798;5226.817061402909;5257.395399529027;3467.046562195366;3528.3825330567047;3843.7117216208894;4017.3773065174946;4064.349704047397;1109.9778004439866;1115.8493603363604;1127.4750206819433;1143.1197977557235;1144.6108323170163;890.950997695133;918.3066784577601;926.8609708543637;937.7655586103474;969.9515024248759;6396.328385519547;6410.320118466676;6632.6352050636715;6661.533692641566;6712.630805305755;980.7646164920368;983.1348413396165;995.1740055753708;1005.9688818960477;1009.8030883977553;4087.181204364089;4127.643091958266;4154.5222299435345;4167.5832416758085;4258.063226090255;12539.686507837378;12983.935080324538;12986.870131298756;12993.805092923614;12993.960483161325;12929.54746583884;13065.673358166121;13066.909294512832;13088.869111308764;13096.672583185496;9526.428414295164;9545.284103692251;9691.854622180674;9809.656662016796;9974.251931105051;6371.108044873814;6375.840603984938;6411.455026322815;6434.388733070351;6451.838564126207;11645.825312620487;11908.345041022827;11973.281603103842;11998.280103193836;12092.395380230777;10962.945185274022;11210.27133236337;11312;12263.325517096646;12265.264084154622;8505.95747021261;8839.51382673959;9030.548472576476;9073.50095744714;9165.358424910459;9757.951210243904;9866.851997219905;9962.402255864672;9977.95231500711;9999.55002024896;8883.73348799537;8904.465732056098;8920.643174272956;8921.286297096347;9022.323325750463;8583;8684.522351270742;8801.383903126976;8822.426542274989;8883.466991980502;7491.587962662106;7708.306252437307;7806.0632724073475;7838.255365740246;7891.250331218525;5440.129579267298;5539.501444869981;5583;5592.972035139961;5791.276090488689;5764.711764411846;5931.228940237742;5968.16445697594;6128.816135515942;6130.233720784902 07:51:23 INFO - PID 1646 | |4;prototype.html;327.99672003279966;376.30759402699033;382.3881789137379;383.90594304395427;387.56600577861917;4810.9759451202735;5441.238226648263;5867.911981320283;5932.673702946337;5976;5284.784499565102;5732.398098199692;5804.941950580493;5850.414958504148;5869.033873652321;4016.3172260715673;4498.370228168059;4743.596794272491;4746.335513028179;4778.880527986811;4788.377510923575;4790.425148982127;4790.772290884738;4797.210203938902;4811.687240329385;4325.680667396443;4468.3744275801255;4530.388397566319;4757.571818536331;4765.761711914408;4753.431367648667;4753.619710423158;4766.952330476685;4821.445533763627;4843.830465933675;4380.707691231094;4385.188740083095;4387.341898715186;4389.892851431299;4390.517043125254;5544.0575102232715;5672.12082127271;5716.399778023289;5725.312962444492;5730.68481233532;5057.216131499624;5517.172424136412;5568.470995255444;5577.2191893135;5580.553555715533;5185.688858668492;5235.476452354772;5729.799457019026;6332.588381755218;6349.714262858182;4279.7218180818145;4697.436307643071;4914.459409464956;4935.70385776851;4948.826791062295;5942.30644266382;6000.669963152025;6233.563650544464;6347.333529979332;6455.806325810233;4867.148249056401;4876.609871210295;4883.387387747538;4888.560029597282;4898.167311557044;4321.935170972438;4446.555344465528;4964.748090001055;4992.5756310714;5139.866955213619;5492.423295553989;5498.917516237259;5528.806491772769;5534.225208470817;5566.916496252559;5459.890802183934;5553.389906743824;5594.104943209067;6218.378162183745;6273.780417685451;1524.6645737937636;1752.3165965273554;1775.75367160542;1779.2260366740509;1786.8391844734037;1112.3770688414513;1241.497787676903;1276.9233845969272;1297.7469393468373;1309.9803502947464;5194.68831870089;5851.590388672837;5862.882742345129;5867.823965281048;5929.673867937305;5142.408623008327;6447.742090316428;6453.548251622341;6456.192975878015;6494.6103233806125;5633.267675202197;5755.769769209269;5787.826365208966;5829.09649004405;5892.646441213541;3093.9845300773354;3335.933281334408;3344.227559393525;3354.278830051551;3375.6118046424485;6353.618782873043;6524.934750652527;6632.469402447792;6643.933560664332;6661.169827349197;5611.158326251095;6183.5671502994355;6511.348865113546;6537.869242615121;6551.246606640202;6278.905816412666;6619.867602648016;6647.268800432045;6653.63405012729;6667.799966000931;5055.570276526537;5671.270262569956;6219.253689557282;6329.810105696836;6440.484761218998;6160.860240855277;6225.470834978896;6430.453411460078;6438.710258038306;6477.740890364521;5743.8564035899435;6200.410960958882;6394.808155755335;6718.550191815912;6742.393184613408;5006;5024.321716568216;5060.3927528696795;5077.2637967493765;5106.66806657566;5937;6011.368806275453;6495;6506.60960342381;6506.674666266762;5110;5875.618084824473;6174.228221472316;6269.49844012487;6274.717637706223;5204.119345139968;5538.061678412523;5951;6211.751529938932;6253.5622506424115;4891.700900576723;5101.566366858859;5881.17663527098;6048.758049677964;6089.634621922699;5214;5252.343457067866;5695.259616249861;6005.699715014319;6261.186045814015;5026.698398096126;6112.816615501542;6112.969435152796;6128.8774224516155;6163.599366041193 07:51:23 INFO - PID 1646 | |5;yui.html;646.9644169570672;705.9294070592941;745.962701864907;813.0934008580433;858.2018722587994;14245.216513091777;15470.22648867555;15482.922585387085;15506.689866202683;15507.302171402285;14484.782728259059;14595.41618335268;14601.415943362279;14616.196109213988;14618;916.9495677737739;937.8827646544182;942.6135284533343;943.118184497497;944.7779771753667;948.3977674176913;956.115593076405;960.2174228004154;960.6781728121053;961.9230461563059;968.7529679931642;997.3866072365479;1016.192127258831;1021.4892553723139;1022.4325499347859;1016.4714348538755;1016.6848277034143;1017.9745506362326;1019.7603563162651;1020.1787561013381;753.3558807219857;754.6000619671582;755.3881356101521;755.7166062726477;755.8677231484523;15854;15999;16099.597510062225;16132.677346453123;16617.08606026668;14371.928140359336;14598.635034124125;14716.24771969246;14730.63173420673;14953.7756933645;11544.134189935705;11643.592474263445;11695.56888015321;11910.880891191064;12160.905518503288;3567.5362202913716;3752.6810221131236;3867.284552357795;3879.806009699532;3881.6700580450697;103985.96014039838;105102.47448762707;107314.92685073128;107488.4625576875;110532.89467105306;10989.120870330355;11272.492737826817;11396.487158077907;11480.540778368939;11713;24788.132415365377;24813.379665508506;24971.250862474153;25004.124855629965;25012.499750005263;7716.7684969451;7811;7819.218078192249;7838.098618658927;7911.643976021093;4130.793460326971;4148.211839750437;4230.5769423057445;4332.263515202423;4443.822247110144;3437;3509.701675357623;3510.8069056201643;3522;3522.806245656462;613.1538476901936;628.7547856336032;632.7785275153751;638.2149955554694;639.1499305923111;518.7743331650744;535.8660334916271;562.5443390853421;615.0098341669908;692.9792106236821;7913.960430197927;7917.7624671259955;8667.869981950276;10155.543000564992;10243.436610986319;516.1844286028141;553.6788662575697;587.2541871822762;598.8921994041027;704.4610872682402;4665.486796452387;4803;4824.49342819006;4850.916295195641;4861.29511220871;86679.56660216658;93366;96064.51967740117;98854;106006;77614;81456;82926;82931.5853420729;83369.33261334739;76450.61774691091;77322.61338693496;77982.61008694921;78283.99999999886;79230.41539169184;10416.427096509764;10568.260221784401;10763;10789.244752867538;10822.458877056273;47875.28187077127;49165.2625210615;49173.508264918324;49552.752236238586;50029.749256268886;60386.69806650939;62953.05570416539;64658.35341646523;65721;65894.67052664707;13015;13059.804102938273;13262.867371326163;13717.108387954751;13917.72164556683;14047.698790784361;14207.360668770138;14375.640608984859;14409.495667651581;14549.272536373352;13107.803382949072;13390.93304533471;13492.797608036084;13554.593362198755;13928.860711392756;13002.154859934075;13311.935045196555;13379.130356526795;13634.522791702242;13822.170669759847;12980.984730011147;13022.616182603808;13480.595582132551;13993.930030349784;14334;10776.461176940964;11067.280626759235;11366.318020918772;11823.11326650522;11879.762404751684;10743;10777.191710621579;11029.393383363991;11135.164862635173;11225.943870280596 07:51:23 INFO - PID 1646 | __end_tp_report 07:51:23 INFO - PID 1646 | __start_cc_report 07:51:23 INFO - PID 1646 | _x_x_mozilla_cycle_collect,3424 07:51:23 INFO - PID 1646 | __end_cc_report 07:51:23 INFO - PID 1646 | __startTimestamp1489503083618__endTimestamp 07:51:23 INFO - PID 1646 | 07:51:23 INFO - PID 1646 | ------- Summary: start ------- 07:51:23 INFO - PID 1646 | Number of tests: 6 07:51:23 INFO - PID 1646 | 07:51:23 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:5831.69 Median:6065.88 stddev:633.49 (10.4%) stddev-sans-first:604.15 07:51:23 INFO - PID 1646 | Values: 3325.5 3375.8 3383.1 3428.6 3458.2 5759.1 5965.2 5998.5 6027.8 6037.7 5943.1 5969.0 6035.6 6050.6 6078.7 5214.9 5268.1 5273.2 5288.2 5302.4 5396.5 5406.1 5406.2 5433.6 5443.2 5242.8 5281.9 5291.1 5349.4 5358.8 5317.8 5318.8 5345.2 5345.7 5389.4 5232.0 5262.8 5265.9 5274.3 5286.1 5929.3 5929.9 5933.0 5987.0 6021.7 6144.8 6207.2 6220.0 6222.8 6246.5 6141.0 6169.3 6221.8 6235.6 6241.0 5841.2 5934.3 5936.0 5954.7 5955.9 6243.3 6249.2 6310.8 6379.3 6382.2 6179.0 6212.8 6241.0 6248.8 6310.2 6236.7 6273.0 6288.0 6292.0 6313.6 6246.4 6249.2 6263.6 6268.3 6274.7 6211.1 6238.0 6239.1 6244.8 6248.3 5044.7 5062.7 5065.9 5067.6 5074.9 4493.4 4545.5 4547.9 4593.6 4608.0 5832.8 5843.8 5845.0 5846.6 5853.5 6149.0 6220.4 6231.0 6239.7 6242.8 4677.6 5832.6 5891.8 5961.3 5981.9 5595.8 5600.5 5600.7 5604.9 5620.0 6450.7 6459.1 6480.2 6493.7 6498.7 6205.2 6241.2 6264.1 6265.7 6306.0 6279.7 6279.7 6290.2 6311.6 6388.3 6219.5 6231.2 6259.4 6260.7 6273.2 6268.7 6273.9 6275.8 6277.2 6278.6 4757.6 5981.2 6258.1 6289.7 6313.2 6127.3 6148.9 6151.8 6157.1 6161.3 5995.9 6029.2 6041.0 6061.9 6065.9 6149.8 6150.8 6153.5 6154.3 6165.4 5943.9 6018.2 6080.2 6137.3 6181.0 07:51:23 INFO - PID 1646 | 07:51:23 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:48000.05 Median:179751.59 stddev:89529.19 (49.8%) stddev-sans-first:89710.10 07:51:23 INFO - PID 1646 | Values: 765.2 780.9 854.5 859.1 860.8 36188.5 36644.6 37013.6 37017.3 37021.0 38318.9 38674.4 38760.5 38898.0 39047.2 7162.1 7338.9 8915.9 9091.1 9147.3 10471.5 10691.0 10735.4 10735.9 10743.7 10772.9 10833.3 10843.9 10850.0 10856.7 7123.9 7332.7 10744.9 10836.5 10841.4 7172.3 7250.6 7264.9 10296.2 10517.7 36595.0 36880.6 36991.8 37003.9 38216.2 3755.9 3762.7 3783.8 3814.0 3889.6 27736.0 27839.0 28059.7 28105.2 28845.9 3033.0 3056.3 3095.6 3160.2 3162.7 338004.0 338663.0 338859.0 339220.0 339294.0 38571.0 39019.8 39208.0 46547.8 50762.5 48474.3 48596.5 48672.8 48722.5 48729.0 14103.9 14245.2 14259.4 14316.0 14323.0 11605.0 11615.2 11631.1 11650.9 11749.2 10367.9 10718.9 10990.5 12493.0 12838.9 5367.8 5417.5 5426.5 5436.8 5451.1 972.9 1114.6 1130.3 1134.0 1166.0 25122.2 25596.0 25650.5 25769.6 25989.2 1182.9 1195.9 1199.8 1220.4 1244.1 11225.2 13259.5 13274.5 13310.5 13328.1 275204.0 318965.0 323618.0 326734.0 435271.0 51346.5 51401.5 51423.7 51444.5 51572.2 1372.3 1418.5 1426.6 1431.5 1445.6 27648.3 27704.4 28144.7 28527.7 28620.0 43645.8 43773.3 43828.8 43892.6 43978.3 338363.0 339573.0 341719.0 341841.0 342268.6 25099.7 25138.6 25536.7 29847.4 29959.6 36720.8 37983.1 39025.0 39355.6 39360.0 31572.8 32925.5 33352.8 33577.5 33868.7 32257.7 32394.1 33236.0 33346.3 33716.7 32769.2 34008.7 34586.5 34706.8 34754.0 27830.0 28594.9 29458.3 29695.7 29779.6 20110.5 20778.9 21028.9 22293.2 23236.0 07:51:23 INFO - PID 1646 | 07:51:23 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:40775.30 Median:22384.11 stddev:113605.50 (507.5%) stddev-sans-first:113883.65 07:51:23 INFO - PID 1646 | Values: 350.8 376.1 402.2 415.7 426.5 13970.9 17034.8 17625.1 21559.4 21981.9 21182.1 22240.7 22456.1 23047.9 23401.6 4057.0 6497.8 6784.4 6797.0 6804.9 7355.4 7571.4 7572.2 7585.1 7585.2 7217.5 7545.6 7585.2 7613.1 7630.3 7479.8 7568.9 7617.2 7634.0 7667.6 7405.3 7457.3 7463.2 7465.9 7470.8 26383.7 27164.0 27236.6 27280.6 27509.6 3239.4 3253.8 3275.9 3305.5 3306.6 16247.6 16373.8 16611.8 16808.4 17518.7 1987.8 2050.1 2052.7 2053.3 2055.1 139958.0 145525.0 145589.0 145731.0 149162.3 14010.7 18300.7 20007.3 21059.4 21339.1 29206.0 29281.7 32747.0 34415.0 34437.0 10168.0 10663.7 10808.6 10813.9 10991.5 7405.9 7533.9 7586.0 7737.5 7751.9 9363.0 9679.3 10017.7 10214.8 10244.5 3540.5 3729.5 3744.5 4060.6 4167.4 953.6 957.4 961.6 984.2 995.6 21957.8 22125.6 22316.3 22451.9 22525.4 938.8 978.8 978.8 986.8 993.1 11194.4 11244.6 11321.6 11558.3 11598.8 663899.0 671037.0 674664.0 683834.0 703442.0 129413.0 137947.0 148133.0 155745.0 159907.0 1221.7 1229.9 1254.9 1263.9 1264.7 16616.3 19071.5 20068.2 20073.0 20075.3 45597.3 60685.5 60952.8 61833.0 62580.0 89777.0 91135.0 91192.5 91915.0 92157.5 8616.3 11599.7 11868.7 11957.2 12040.4 13148.0 13261.9 13321.0 13611.5 13643.3 11501.5 11594.9 11727.4 11789.7 11807.9 10712.5 11264.6 11425.8 11557.4 11671.5 11347.6 11767.6 11790.9 11897.2 11918.8 10243.4 11262.4 11382.3 11702.3 11748.6 9792.3 10890.4 11535.8 11715.5 11817.8 07:51:23 INFO - PID 1646 | 07:51:23 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:6569.14 Median:3474.95 stddev:4055.90 (116.7%) stddev-sans-first:4049.11 07:51:23 INFO - PID 1646 | Values: 1460.2 1534.3 1566.5 1593.0 1601.0 10381.7 10826.9 10844.6 10861.8 11076.1 9298.9 10653.0 11030.2 11043.6 11050.4 2304.4 2373.2 2375.9 2377.4 2380.1 2290.8 2295.6 2332.8 2338.8 2343.1 2226.7 2267.4 2314.9 2327.4 2338.9 2212.2 2232.0 2235.3 2237.8 2238.3 2294.5 2318.0 2327.9 2329.3 2337.5 10748.0 10815.6 10822.2 10830.6 10830.8 5537.7 5768.9 5774.9 5784.9 5786.5 3193.2 3307.6 3466.6 3471.4 3478.5 1133.5 1162.4 1173.8 1179.6 1221.7 13523.8 13882.9 13914.8 14803.7 14860.6 4274.8 4382.8 4383.4 4719.8 4920.6 10522.4 12584.8 12609.7 12645.7 12667.5 5794.7 5795.1 5806.8 5812.1 5815.6 4927.2 5155.5 5223.3 5226.8 5257.4 3467.0 3528.4 3843.7 4017.4 4064.3 1110.0 1115.8 1127.5 1143.1 1144.6 891.0 918.3 926.9 937.8 970.0 6396.3 6410.3 6632.6 6661.5 6712.6 980.8 983.1 995.2 1006.0 1009.8 4087.2 4127.6 4154.5 4167.6 4258.1 12539.7 12983.9 12986.9 12993.8 12994.0 12929.5 13065.7 13066.9 13088.9 13096.7 9526.4 9545.3 9691.9 9809.7 9974.3 6371.1 6375.8 6411.5 6434.4 6451.8 11645.8 11908.3 11973.3 11998.3 12092.4 10962.9 11210.3 11312.0 12263.3 12265.3 8506.0 8839.5 9030.5 9073.5 9165.4 9758.0 9866.9 9962.4 9978.0 9999.6 8883.7 8904.5 8920.6 8921.3 9022.3 8583.0 8684.5 8801.4 8822.4 8883.5 7491.6 7708.3 7806.1 7838.3 7891.3 5440.1 5539.5 5583.0 5593.0 5791.3 5764.7 5931.2 5968.2 6128.8 6130.2 07:51:23 INFO - PID 1646 | 07:51:23 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5167.00 Median:5567.69 stddev:1449.44 (26.0%) stddev-sans-first:1407.27 07:51:23 INFO - PID 1646 | Values: 328.0 376.3 382.4 383.9 387.6 4811.0 5441.2 5867.9 5932.7 5976.0 5284.8 5732.4 5804.9 5850.4 5869.0 4016.3 4498.4 4743.6 4746.3 4778.9 4788.4 4790.4 4790.8 4797.2 4811.7 4325.7 4468.4 4530.4 4757.6 4765.8 4753.4 4753.6 4767.0 4821.4 4843.8 4380.7 4385.2 4387.3 4389.9 4390.5 5544.1 5672.1 5716.4 5725.3 5730.7 5057.2 5517.2 5568.5 5577.2 5580.6 5185.7 5235.5 5729.8 6332.6 6349.7 4279.7 4697.4 4914.5 4935.7 4948.8 5942.3 6000.7 6233.6 6347.3 6455.8 4867.1 4876.6 4883.4 4888.6 4898.2 4321.9 4446.6 4964.7 4992.6 5139.9 5492.4 5498.9 5528.8 5534.2 5566.9 5459.9 5553.4 5594.1 6218.4 6273.8 1524.7 1752.3 1775.8 1779.2 1786.8 1112.4 1241.5 1276.9 1297.7 1310.0 5194.7 5851.6 5862.9 5867.8 5929.7 5142.4 6447.7 6453.5 6456.2 6494.6 5633.3 5755.8 5787.8 5829.1 5892.6 3094.0 3335.9 3344.2 3354.3 3375.6 6353.6 6524.9 6632.5 6643.9 6661.2 5611.2 6183.6 6511.3 6537.9 6551.2 6278.9 6619.9 6647.3 6653.6 6667.8 5055.6 5671.3 6219.3 6329.8 6440.5 6160.9 6225.5 6430.5 6438.7 6477.7 5743.9 6200.4 6394.8 6718.6 6742.4 5006.0 5024.3 5060.4 5077.3 5106.7 5937.0 6011.4 6495.0 6506.6 6506.7 5110.0 5875.6 6174.2 6269.5 6274.7 5204.1 5538.1 5951.0 6211.8 6253.6 4891.7 5101.6 5881.2 6048.8 6089.6 5214.0 5252.3 5695.3 6005.7 6261.2 5026.7 6112.8 6113.0 6128.9 6163.6 07:51:23 INFO - PID 1646 | 07:51:23 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:20167.01 Median:14224.75 stddev:28287.75 (198.9%) stddev-sans-first:28329.13 07:51:23 INFO - PID 1646 | Values: 647.0 705.9 746.0 813.1 858.2 14245.2 15470.2 15482.9 15506.7 15507.3 14484.8 14595.4 14601.4 14616.2 14618.0 916.9 937.9 942.6 943.1 944.8 948.4 956.1 960.2 960.7 961.9 968.8 997.4 1016.2 1021.5 1022.4 1016.5 1016.7 1018.0 1019.8 1020.2 753.4 754.6 755.4 755.7 755.9 15854.0 15999.0 16099.6 16132.7 16617.1 14371.9 14598.6 14716.2 14730.6 14953.8 11544.1 11643.6 11695.6 11910.9 12160.9 3567.5 3752.7 3867.3 3879.8 3881.7 103986.0 105102.5 107314.9 107488.5 110532.9 10989.1 11272.5 11396.5 11480.5 11713.0 24788.1 24813.4 24971.3 25004.1 25012.5 7716.8 7811.0 7819.2 7838.1 7911.6 4130.8 4148.2 4230.6 4332.3 4443.8 3437.0 3509.7 3510.8 3522.0 3522.8 613.2 628.8 632.8 638.2 639.1 518.8 535.9 562.5 615.0 693.0 7914.0 7917.8 8667.9 10155.5 10243.4 516.2 553.7 587.3 598.9 704.5 4665.5 4803.0 4824.5 4850.9 4861.3 86679.6 93366.0 96064.5 98854.0 106006.0 77614.0 81456.0 82926.0 82931.6 83369.3 76450.6 77322.6 77982.6 78284.0 79230.4 10416.4 10568.3 10763.0 10789.2 10822.5 47875.3 49165.3 49173.5 49552.8 50029.7 60386.7 62953.1 64658.4 65721.0 65894.7 13015.0 13059.8 13262.9 13717.1 13917.7 14047.7 14207.4 14375.6 14409.5 14549.3 13107.8 13390.9 13492.8 13554.6 13928.9 13002.2 13311.9 13379.1 13634.5 13822.2 12981.0 13022.6 13480.6 13993.9 14334.0 10776.5 11067.3 11366.3 11823.1 11879.8 10743.0 10777.2 11029.4 11135.2 11225.9 07:51:23 INFO - PID 1646 | -------- Summary: end -------- 07:51:23 INFO - PID 1646 | 07:51:24 INFO - TEST-INFO | 1646: exit 0 07:51:24 INFO - TEST-OK | dromaeo_css | took 1121386ms 07:51:24 INFO - TEST-START | kraken 07:51:24 INFO - operating with platform_type : mac_ 07:51:24 INFO - Initialising browser for kraken test... 07:51:24 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpmlgmcd/profile http://localhost:49245/getInfo.html) 07:51:26 INFO - PID 1651 | __metrics Screen width/height:1600/1200 07:51:26 INFO - PID 1651 | colorDepth:24 07:51:26 INFO - PID 1651 | Browser inner width/height: 1024/689 07:51:26 INFO - PID 1651 | __metrics 07:51:26 INFO - PID 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:51:26 INFO - PID 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:51:27 INFO - TEST-INFO | 1651: exit 0 07:51:27 INFO - Browser initialized. 07:51:27 INFO - Running cycle 1/1 for kraken test... 07:51:27 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpmlgmcd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:51:39 INFO - PID 1652 | RSS: Main: 233680896 07:51:39 INFO - PID 1652 | 07:51:42 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 07:51:43 INFO - PID 1652 | RSS: Main: 284823552 07:51:43 INFO - PID 1652 | 07:51:45 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 07:51:46 INFO - PID 1652 | RSS: Main: 286289920 07:51:46 INFO - PID 1652 | 07:51:49 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 07:51:50 INFO - PID 1652 | RSS: Main: 380764160 07:51:50 INFO - PID 1652 | 07:51:52 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 07:51:53 INFO - PID 1652 | RSS: Main: 282476544 07:51:53 INFO - PID 1652 | 07:51:55 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:51:55 INFO - PID 1652 | RSS: Main: 286212096 07:51:55 INFO - PID 1652 | 07:51:58 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 07:51:59 INFO - PID 1652 | RSS: Main: 326619136 07:51:59 INFO - PID 1652 | 07:52:02 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 07:52:03 INFO - PID 1652 | RSS: Main: 340664320 07:52:03 INFO - PID 1652 | 07:52:06 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 07:52:06 INFO - PID 1652 | RSS: Main: 350158848 07:52:06 INFO - PID 1652 | 07:52:08 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:52:09 INFO - PID 1652 | RSS: Main: 303058944 07:52:09 INFO - PID 1652 | 07:52:10 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:52:11 INFO - PID 1652 | RSS: Main: 308629504 07:52:11 INFO - PID 1652 | 07:52:13 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:52:14 INFO - PID 1652 | RSS: Main: 310489088 07:52:14 INFO - PID 1652 | 07:52:17 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:52:17 INFO - PID 1652 | RSS: Main: 325201920 07:52:17 INFO - PID 1652 | 07:52:20 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:52:21 INFO - PID 1652 | RSS: Main: 319307776 07:52:21 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 07:52:23 INFO - PID 1652 | RSS: Main: 380567552 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | __start_tp_report 07:52:23 INFO - PID 1652 | _x_x_mozilla_page_load 07:52:23 INFO - PID 1652 | _x_x_mozilla_page_load_details 07:52:23 INFO - PID 1652 | |i|pagename|runs| 07:52:23 INFO - PID 1652 | |0;ai-astar;119;117;117;116;116;117;117;114;116;117 07:52:23 INFO - PID 1652 | |1;audio-beat-detection;135;111;112;147;113;111;110;109;127;111 07:52:23 INFO - PID 1652 | |2;audio-dft;185;187;184;181;185;184;183;182;184;185 07:52:23 INFO - PID 1652 | |3;audio-fft;85;75;81;118;64;63;61;56;79;60 07:52:23 INFO - PID 1652 | |4;audio-oscillator;90;69;89;70;69;68;84;68;69;85 07:52:23 INFO - PID 1652 | |5;imaging-gaussian-blur;88;89;87;87;89;88;88;88;88;87 07:52:23 INFO - PID 1652 | |6;imaging-darkroom;94;95;95;95;94;95;98;94;95;95 07:52:23 INFO - PID 1652 | |7;imaging-desaturate;122;137;86;83;94;133;83;83;85;85 07:52:23 INFO - PID 1652 | |8;json-parse-financial;62;62;61;60;61;61;61;62;61;61 07:52:23 INFO - PID 1652 | |9;json-stringify-tinderbox;54;54;67;62;69;63;59;57;58;58 07:52:23 INFO - PID 1652 | |10;stanford-crypto-aes;82;82;83;83;83;83;83;82;80;82 07:52:23 INFO - PID 1652 | |11;stanford-crypto-ccm;150;146;128;139;136;140;139;136;186;137 07:52:23 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;174;173;182;183;185;182;180;190;185;185 07:52:23 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;66;67;67;69;67;68;69;68;67;69 07:52:23 INFO - PID 1652 | __end_tp_report 07:52:23 INFO - PID 1652 | __start_cc_report 07:52:23 INFO - PID 1652 | _x_x_mozilla_cycle_collect,2135 07:52:23 INFO - PID 1652 | __end_cc_report 07:52:23 INFO - PID 1652 | __startTimestamp1489503143579__endTimestamp 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | ------- Summary: start ------- 07:52:23 INFO - PID 1652 | Number of tests: 14 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:116.60 Median:117.00 stddev:1.26 (1.1%) stddev-sans-first:1.00 07:52:23 INFO - PID 1652 | Values: 119.0 117.0 117.0 116.0 116.0 117.0 117.0 114.0 116.0 117.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:118.60 Median:112.50 stddev:13.17 (11.7%) stddev-sans-first:12.56 07:52:23 INFO - PID 1652 | Values: 135.0 111.0 112.0 147.0 113.0 111.0 110.0 109.0 127.0 111.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:1.70 (0.9%) stddev-sans-first:1.76 07:52:23 INFO - PID 1652 | Values: 185.0 187.0 184.0 181.0 185.0 184.0 183.0 182.0 184.0 185.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:74.20 Median:69.50 stddev:18.38 (26.5%) stddev-sans-first:19.08 07:52:23 INFO - PID 1652 | Values: 85.0 75.0 81.0 118.0 64.0 63.0 61.0 56.0 79.0 60.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:77.00 stddev:9.55 (12.4%) stddev-sans-first:8.71 07:52:23 INFO - PID 1652 | Values: 90.0 69.0 89.0 70.0 69.0 68.0 84.0 68.0 69.0 85.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:88.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 07:52:23 INFO - PID 1652 | Values: 88.0 89.0 87.0 87.0 89.0 88.0 88.0 88.0 88.0 87.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:1.15 (1.2%) stddev-sans-first:1.17 07:52:23 INFO - PID 1652 | Values: 94.0 95.0 95.0 95.0 94.0 95.0 98.0 94.0 95.0 95.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:99.10 Median:84.00 stddev:22.32 (26.6%) stddev-sans-first:22.08 07:52:23 INFO - PID 1652 | Values: 122.0 137.0 86.0 83.0 94.0 133.0 83.0 83.0 85.0 85.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:61.20 Median:61.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 07:52:23 INFO - PID 1652 | Values: 62.0 62.0 61.0 60.0 61.0 61.0 61.0 62.0 61.0 61.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.50 stddev:5.09 (8.4%) stddev-sans-first:4.89 07:52:23 INFO - PID 1652 | Values: 54.0 54.0 67.0 62.0 69.0 63.0 59.0 57.0 58.0 58.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:0.95 (1.1%) stddev-sans-first:1.00 07:52:23 INFO - PID 1652 | Values: 82.0 82.0 83.0 83.0 83.0 83.0 83.0 82.0 80.0 82.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:139.50 stddev:15.99 (11.5%) stddev-sans-first:16.80 07:52:23 INFO - PID 1652 | Values: 150.0 146.0 128.0 139.0 136.0 140.0 139.0 136.0 186.0 137.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:184.00 stddev:5.17 (2.8%) stddev-sans-first:4.63 07:52:23 INFO - PID 1652 | Values: 174.0 173.0 182.0 183.0 185.0 182.0 180.0 190.0 185.0 185.0 07:52:23 INFO - PID 1652 | 07:52:23 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.06 (1.6%) stddev-sans-first:0.93 07:52:23 INFO - PID 1652 | Values: 66.0 67.0 67.0 69.0 67.0 68.0 69.0 68.0 67.0 69.0 07:52:23 INFO - PID 1652 | -------- Summary: end -------- 07:52:23 INFO - PID 1652 | 07:52:24 INFO - TEST-INFO | 1652: exit 0 07:52:24 INFO - TEST-OK | kraken | took 60233ms 07:52:24 INFO - SUITE-END | took 1181s 07:52:24 INFO - Completed test suite (00:19:41) 07:52:24 INFO - javascript benchmark 07:52:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7747.209323619583, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3325.5177999190114, 3375.8480868360934, 3383.0865666270124, 3428.6057103433095, 3458.204612939024, 5759.136129580565, 5965.2245208122995, 5998.520118390529, 6027.8493037674025, 6037.698115094244, 5943.108533719944, 5968.970155149229, 6035.637861728288, 6050.6369617823075, 6078.726457309421, 5214.869628259305, 5268.130758424855, 5273.235380869772, 5288.206768984644, 5302.390225124122, 5396.541293990015, 5406.053940560406, 5406.216098665691, 5433.565314774828, 5443.1835224716215, 5242.7640756166065, 5281.9207711884355, 5291.074062039128, 5349.384820745606, 5358.758855851496, 5317.813876514303, 5318.787248510055, 5345.198220266952, 5345.679259244419, 5389.353277606712, 5231.973840130775, 5262.789488420459, 5265.921011184835, 5274.340707411574, 5286.127788914824, 5929.258842644669, 5929.897686633634, 5933.021051526493, 5986.97006514969, 6021.729022194012, 6144.771045790815, 6207.162033125515, 6219.968900155515, 6222.81331560054, 6246.469050130744, 6140.995089002448, 6169.29053158888, 6221.751129954841, 6235.563510554263, 6241.032639940771, 5841.240638716939, 5934.287885453773, 5936.020556608154, 5954.672493012882, 5955.940440595582, 6243.25080990275, 6249.187605611332, 6310.842228944313, 6379.266384365857, 6382.170317858649, 6179.0422484514975, 6212.844678882973, 6240.968795155995, 6248.812535623939, 6310.242770867525, 6236.719347629368, 6272.996320588684, 6287.968560157261, 6292.024736165901, 6313.589616674902, 6246.375362463808, 6249.187605611332, 6263.6241825490615, 6268.279147897959, 6274.749010039547, 6211.068339748984, 6237.968810156011, 6239.126522286883, 6244.843878903063, 6248.28144763349, 5044.722540260247, 5062.6709263898465, 5065.898682026413, 5067.645264831426, 5074.923876141861, 4493.393391892118, 4545.454545454567, 4547.886302842456, 4593.563611456906, 4607.976960115178, 5832.766689332464, 5843.795467158629, 5845.0, 5846.561507886926, 5853.502452291603, 6148.9692551536955, 6220.409061139185, 6231.0, 6239.688015599202, 6242.843928901813, 4677.579017888406, 5832.591718579659, 5891.793787217427, 5961.314448838352, 5981.940180598225, 5595.804146854841, 5600.467955544217, 5600.719964001783, 5604.859878502988, 5619.960307343154, 6450.6774661266745, 6459.128017717642, 6480.222373315232, 6493.675316234169, 6498.707558159988, 6205.193324867739, 6241.188645476059, 6264.123022776906, 6265.655388953652, 6306.0225665022, 6279.654618995816, 6279.654618995999, 6290.21372328459, 6311.558190926591, 6388.329225431263, 6219.471344935731, 6231.189945307262, 6259.374062593704, 6260.7495700171485, 6273.215848018997, 6268.717907694074, 6273.905891411724, 6275.811725648238, 6277.246730392382, 6278.591891527036, 4757.5718185363485, 5981.192539007178, 6258.0925765762795, 6289.716962736779, 6313.2424109107205, 6127.250613123678, 6148.862460444832, 6151.769646070893, 6157.1072194532435, 6161.260648722003, 5995.850103747442, 6029.186059881859, 6041.0, 6061.878762424815, 6065.878682426415, 6149.7847575334645, 6150.846228844314, 6153.507719382349, 6154.292256390572, 6165.383461653799, 5943.881122377615, 6018.21763170785, 6080.209572755691, 6137.346341658241, 6181.0], "value": 5792.222254645247, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.1888997662478, 780.8711562592171, 854.5001174313027, 859.0636206534875, 860.802015536427, 36188.45717314242, 36644.63355366449, 37013.62986370135, 37017.25965480689, 37020.99999999996, 38318.85043448701, 38674.419883701696, 38760.51155396014, 38898.0, 39047.21905561894, 7162.068931038958, 7338.889916651255, 8915.866262006075, 9091.136342047495, 9147.313951453667, 10471.47642617866, 10691.0, 10735.355878647268, 10735.892641073606, 10743.677689669323, 10772.892271077306, 10833.349999000045, 10843.891561084367, 10849.969252920999, 10856.728581785479, 7123.89314160288, 7332.7066917323245, 10744.946275268612, 10836.458177091194, 10841.403722795243, 7172.318629730167, 7250.564966102051, 7264.8547029059655, 10296.176305895511, 10517.684469465928, 36595.0, 36880.631193688256, 36991.81504092462, 37003.88988330354, 38216.23567528634, 3755.906102347436, 3762.6613604775703, 3783.810809459516, 3813.9809300953316, 3889.565894879839, 27736.0, 27839.0, 28059.719402805913, 28105.156845294674, 28845.85577072122, 3032.969670303291, 3056.2970516781265, 3095.6440009398984, 3160.194150491633, 3162.652108268089, 338004.0, 338662.99999999756, 338859.0, 339220.0, 339294.0, 38571.0, 39019.804900975316, 39208.0, 46547.76726116348, 50762.49237507577, 48474.27288590674, 48596.51403485991, 48672.75663621659, 48722.5127748718, 48729.0, 14103.929480352532, 14245.216513091673, 14259.429622815178, 14316.0, 14323.0, 11605.0, 11615.245009074382, 11631.069514438825, 11650.941745291218, 11749.236299640495, 10367.94816025915, 10718.89281107179, 10990.505427255792, 12493.000559955182, 12838.871611283954, 5367.75845086972, 5417.51242388187, 5426.5077103796775, 5436.782528698887, 5451.073317536028, 972.8686627305365, 1114.570890207276, 1130.3161587239765, 1133.9603113890973, 1166.0, 25122.24633261005, 25596.0, 25650.48699026009, 25769.61345579818, 25989.220323390327, 1182.8757980411933, 1195.946182421793, 1199.7540504196618, 1220.365409986802, 1244.0731654917145, 11225.158113141548, 13259.535916243078, 13274.469021239236, 13310.534131305358, 13328.06703530763, 275204.0, 318965.0, 323618.0, 326734.0, 435271.0, 51346.48653513417, 51401.48598513967, 51423.71440713937, 51444.48555514397, 51572.22641660378, 1372.3069849725825, 1418.5460652591485, 1426.6005518455056, 1431.5347512058622, 1445.645816774897, 27648.308792280353, 27704.445911081264, 28144.71855281421, 28527.714722852506, 28619.998300059397, 43645.781771090944, 43773.34339984839, 43828.780856095516, 43892.56107438885, 43978.34032489579, 338363.0, 339573.0, 341719.0, 341841.0, 342268.5773142237, 25099.74900251047, 25138.62292065657, 25536.744632553437, 29847.403051938407, 29959.55060674135, 36720.81639591785, 37983.05042373963, 39025.0, 39355.6064439352, 39360.0, 31572.842135789175, 32925.50611740874, 33352.83323583366, 33577.49633755544, 33868.661313386554, 32257.677423226407, 32394.05635661868, 33236.0, 33346.33307333888, 33716.66283337135, 32769.180770480925, 34008.65991340055, 34586.48120278248, 34706.82646586751, 34753.957381278604, 27830.025949092495, 28594.857025714737, 29458.26354341073, 29695.703042969293, 29779.55330670085, 20110.49723756918, 20778.896105519376, 21028.894855525625, 22293.219737309115, 23236.0], "value": 17727.81468970952, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.8000439749343, 376.1255081934503, 402.18156052433284, 415.7339302846179, 426.46052743279756, 13970.860291397083, 17034.82965170348, 17625.06124326323, 21559.353219403423, 21981.890090549565, 21182.093611574954, 22240.66556006641, 22456.101755929743, 23047.888535047765, 23401.648975265383, 4056.9594304057023, 6497.8375540611505, 6784.355486228825, 6797.048413222128, 6804.897926531106, 7355.374793142562, 7571.4321425893, 7572.167061623217, 7585.12771031333, 7585.1656317805, 7217.530860494051, 7545.622718864062, 7585.1656317805, 7613.124490683559, 7630.2751238632245, 7479.775606731807, 7568.886466703004, 7617.2001939796355, 7633.9618301908695, 7667.5782831944225, 7405.296496832846, 7457.254274572553, 7463.216362281991, 7465.925340746631, 7470.813229669247, 26383.73616263832, 27164.0, 27236.59145112825, 27280.590791138147, 27509.587356189673, 3239.3683231769814, 3253.8047717137047, 3275.901722948315, 3305.4876494143446, 3306.6197387300526, 16247.593810154722, 16373.836261637469, 16611.83388166115, 16808.411705590366, 17518.737218941726, 1987.7714062882808, 2050.1389416445127, 2052.7170518425983, 2053.2505635443104, 2055.085486958304, 139958.0, 145525.0, 145589.0, 145730.99999999895, 149162.25418872945, 14010.719785604231, 18300.725489117674, 20007.299744508873, 21059.368218953456, 21339.14643414277, 29205.95558310898, 29281.707182928323, 32747.0, 34415.0, 34437.0, 10168.0, 10663.680089597325, 10808.62169824068, 10813.945930270298, 10991.450427478594, 7405.851882962311, 7533.924660753432, 7586.0, 7737.535747855147, 7751.9224807752325, 9362.970073291932, 9679.322447428753, 10017.69946901594, 10214.795704086026, 10244.48777561119, 3540.503944718967, 3729.4592284119194, 3744.4570537271943, 4060.5739743679533, 4167.374893765898, 953.6042542344987, 957.4303289542711, 961.624966263158, 984.2009842009836, 995.621663767778, 21957.83448461856, 22125.596621435827, 22316.330510084725, 22451.88774056152, 22525.395936650057, 938.7606160429046, 978.8042391521582, 978.8238117138841, 986.7927735175693, 993.076438911819, 11194.440277986068, 11244.55021799119, 11321.584801899762, 11558.30650160993, 11598.76802463946, 663899.0, 671037.0, 674664.0, 683833.969961794, 703442.0, 129413.0, 137947.0, 148133.0, 155745.0, 159907.0, 1221.6518292286833, 1229.932353720536, 1254.8996080313736, 1263.8546567144713, 1264.6648638110723, 16616.252268648183, 19071.518193224285, 20068.19727210895, 20072.996350182726, 20075.337251135734, 45597.316040260084, 60685.48286292878, 60952.78094437998, 61833.0, 62580.0, 89777.0, 91135.0, 91192.54403727938, 91915.0, 92157.53921230351, 8616.267617252604, 11599.710007249887, 11868.703282418008, 11957.249832538391, 12040.397980101136, 13148.013898957424, 13261.867381326449, 13321.0, 13611.455541778614, 13643.317834108453, 11501.482433290355, 11594.88405115938, 11727.354995474989, 11789.70525736829, 11807.940960295144, 10712.464376781285, 11264.605738799102, 11425.771484570429, 11557.42212889369, 11671.533138674358, 11347.602833900773, 11767.587889452978, 11790.882091178979, 11897.167198296382, 11918.821217681569, 10243.436610986468, 11262.38056906878, 11382.317060976367, 11702.2978621283, 11748.58879939198, 9792.314537982273, 10890.401027943539, 11535.76928461443, 11715.472803724024, 11817.82273265919], "value": 11643.81967197364, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1460.1968917095598, 1534.294224656658, 1566.4908904606, 1593.0362130910798, 1600.9599760006, 10381.740456488582, 10826.945865270663, 10844.566217351316, 10861.83707244392, 11076.058535024533, 9298.86051709225, 10653.041226289632, 11030.172737044713, 11043.613473528429, 11050.392228427434, 2304.3893368257427, 2373.15752907717, 2375.893084811179, 2377.3699969508093, 2380.119355838342, 2290.8167346612313, 2295.598270302699, 2332.8483648562874, 2338.8128949683983, 2343.1096183450263, 2226.743924448685, 2267.4218074391006, 2314.918977835776, 2327.4065113396146, 2338.8947497362574, 2212.192549719341, 2232.0, 2235.3182279404773, 2237.7538470768204, 2238.3173132194675, 2294.5410917816503, 2318.026428899849, 2327.93016209514, 2329.324495896188, 2337.5091230841545, 10748.032677059102, 10815.567377304898, 10822.24244302899, 10830.620928267474, 10830.78338433227, 5537.695426751527, 5768.942310576883, 5774.913376299359, 5784.942150578482, 5786.479216870502, 3193.2495863472063, 3307.601988558276, 3466.584009918826, 3471.427214509603, 3478.47822826578, 1133.4899295317057, 1162.4187906046977, 1173.847399838024, 1179.6225207933464, 1221.7067903703048, 13523.797143042862, 13882.930585347009, 13914.791278130837, 14803.703925921422, 14860.62848428787, 4274.829006839692, 4382.802773875183, 4383.430154080013, 4719.764011799396, 4920.5571498565305, 10522.421266830244, 12584.81122783159, 12609.684757881127, 12645.747085058247, 12667.49330026807, 5794.710264486843, 5795.13073039039, 5806.82579522615, 5812.128180772834, 5815.592908496449, 4927.162382395001, 5155.458676838878, 5223.347081614798, 5226.817061402909, 5257.395399529027, 3467.046562195366, 3528.3825330567047, 3843.7117216208894, 4017.3773065174946, 4064.349704047397, 1109.9778004439866, 1115.8493603363604, 1127.4750206819433, 1143.1197977557235, 1144.6108323170163, 890.950997695133, 918.3066784577601, 926.8609708543637, 937.7655586103474, 969.9515024248759, 6396.328385519547, 6410.320118466676, 6632.6352050636715, 6661.533692641566, 6712.630805305755, 980.7646164920368, 983.1348413396165, 995.1740055753708, 1005.9688818960477, 1009.8030883977553, 4087.181204364089, 4127.643091958266, 4154.5222299435345, 4167.5832416758085, 4258.063226090255, 12539.686507837378, 12983.935080324538, 12986.870131298756, 12993.805092923614, 12993.960483161325, 12929.54746583884, 13065.673358166121, 13066.909294512832, 13088.869111308764, 13096.672583185496, 9526.428414295164, 9545.284103692251, 9691.854622180674, 9809.656662016796, 9974.251931105051, 6371.108044873814, 6375.840603984938, 6411.455026322815, 6434.388733070351, 6451.838564126207, 11645.825312620487, 11908.345041022827, 11973.281603103842, 11998.280103193836, 12092.395380230777, 10962.945185274022, 11210.27133236337, 11312.0, 12263.325517096646, 12265.264084154622, 8505.95747021261, 8839.51382673959, 9030.548472576476, 9073.50095744714, 9165.358424910459, 9757.951210243904, 9866.851997219905, 9962.402255864672, 9977.95231500711, 9999.55002024896, 8883.73348799537, 8904.465732056098, 8920.643174272956, 8921.286297096347, 9022.323325750463, 8583.0, 8684.522351270742, 8801.383903126976, 8822.426542274989, 8883.466991980502, 7491.587962662106, 7708.306252437307, 7806.0632724073475, 7838.255365740246, 7891.250331218525, 5440.129579267298, 5539.501444869981, 5583.0, 5592.972035139961, 5791.276090488689, 5764.711764411846, 5931.228940237742, 5968.16445697594, 6128.816135515942, 6130.233720784902], "value": 5035.874786637361, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.99672003279966, 376.30759402699033, 382.3881789137379, 383.90594304395427, 387.56600577861917, 4810.9759451202735, 5441.238226648263, 5867.911981320283, 5932.673702946337, 5976.0, 5284.784499565102, 5732.398098199692, 5804.941950580493, 5850.414958504148, 5869.033873652321, 4016.3172260715673, 4498.370228168059, 4743.596794272491, 4746.335513028179, 4778.880527986811, 4788.377510923575, 4790.425148982127, 4790.772290884738, 4797.210203938902, 4811.687240329385, 4325.680667396443, 4468.3744275801255, 4530.388397566319, 4757.571818536331, 4765.761711914408, 4753.431367648667, 4753.619710423158, 4766.952330476685, 4821.445533763627, 4843.830465933675, 4380.707691231094, 4385.188740083095, 4387.341898715186, 4389.892851431299, 4390.517043125254, 5544.0575102232715, 5672.12082127271, 5716.399778023289, 5725.312962444492, 5730.68481233532, 5057.216131499624, 5517.172424136412, 5568.470995255444, 5577.2191893135, 5580.553555715533, 5185.688858668492, 5235.476452354772, 5729.799457019026, 6332.588381755218, 6349.714262858182, 4279.7218180818145, 4697.436307643071, 4914.459409464956, 4935.70385776851, 4948.826791062295, 5942.30644266382, 6000.669963152025, 6233.563650544464, 6347.333529979332, 6455.806325810233, 4867.148249056401, 4876.609871210295, 4883.387387747538, 4888.560029597282, 4898.167311557044, 4321.935170972438, 4446.555344465528, 4964.748090001055, 4992.5756310714, 5139.866955213619, 5492.423295553989, 5498.917516237259, 5528.806491772769, 5534.225208470817, 5566.916496252559, 5459.890802183934, 5553.389906743824, 5594.104943209067, 6218.378162183745, 6273.780417685451, 1524.6645737937636, 1752.3165965273554, 1775.75367160542, 1779.2260366740509, 1786.8391844734037, 1112.3770688414513, 1241.497787676903, 1276.9233845969272, 1297.7469393468373, 1309.9803502947464, 5194.68831870089, 5851.590388672837, 5862.882742345129, 5867.823965281048, 5929.673867937305, 5142.408623008327, 6447.742090316428, 6453.548251622341, 6456.192975878015, 6494.6103233806125, 5633.267675202197, 5755.769769209269, 5787.826365208966, 5829.09649004405, 5892.646441213541, 3093.9845300773354, 3335.933281334408, 3344.227559393525, 3354.278830051551, 3375.6118046424485, 6353.618782873043, 6524.934750652527, 6632.469402447792, 6643.933560664332, 6661.169827349197, 5611.158326251095, 6183.5671502994355, 6511.348865113546, 6537.869242615121, 6551.246606640202, 6278.905816412666, 6619.867602648016, 6647.268800432045, 6653.63405012729, 6667.799966000931, 5055.570276526537, 5671.270262569956, 6219.253689557282, 6329.810105696836, 6440.484761218998, 6160.860240855277, 6225.470834978896, 6430.453411460078, 6438.710258038306, 6477.740890364521, 5743.8564035899435, 6200.410960958882, 6394.808155755335, 6718.550191815912, 6742.393184613408, 5006.0, 5024.321716568216, 5060.3927528696795, 5077.2637967493765, 5106.66806657566, 5937.0, 6011.368806275453, 6495.0, 6506.60960342381, 6506.674666266762, 5110.0, 5875.618084824473, 6174.228221472316, 6269.49844012487, 6274.717637706223, 5204.119345139968, 5538.061678412523, 5951.0, 6211.751529938932, 6253.5622506424115, 4891.700900576723, 5101.566366858859, 5881.17663527098, 6048.758049677964, 6089.634621922699, 5214.0, 5252.343457067866, 5695.259616249861, 6005.699715014319, 6261.186045814015, 5026.698398096126, 6112.816615501542, 6112.969435152796, 6128.8774224516155, 6163.599366041193], "value": 4727.978518027685, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.9644169570672, 705.9294070592941, 745.962701864907, 813.0934008580433, 858.2018722587994, 14245.216513091777, 15470.22648867555, 15482.922585387085, 15506.689866202683, 15507.302171402285, 14484.782728259059, 14595.41618335268, 14601.415943362279, 14616.196109213988, 14618.0, 916.9495677737739, 937.8827646544182, 942.6135284533343, 943.118184497497, 944.7779771753667, 948.3977674176913, 956.115593076405, 960.2174228004154, 960.6781728121053, 961.9230461563059, 968.7529679931642, 997.3866072365479, 1016.192127258831, 1021.4892553723139, 1022.4325499347859, 1016.4714348538755, 1016.6848277034143, 1017.9745506362326, 1019.7603563162651, 1020.1787561013381, 753.3558807219857, 754.6000619671582, 755.3881356101521, 755.7166062726477, 755.8677231484523, 15854.0, 15999.0, 16099.597510062225, 16132.677346453123, 16617.08606026668, 14371.928140359336, 14598.635034124125, 14716.24771969246, 14730.63173420673, 14953.7756933645, 11544.134189935705, 11643.592474263445, 11695.56888015321, 11910.880891191064, 12160.905518503288, 3567.5362202913716, 3752.6810221131236, 3867.284552357795, 3879.806009699532, 3881.6700580450697, 103985.96014039838, 105102.47448762707, 107314.92685073128, 107488.4625576875, 110532.89467105306, 10989.120870330355, 11272.492737826817, 11396.487158077907, 11480.540778368939, 11713.0, 24788.132415365377, 24813.379665508506, 24971.250862474153, 25004.124855629965, 25012.499750005263, 7716.7684969451, 7811.0, 7819.218078192249, 7838.098618658927, 7911.643976021093, 4130.793460326971, 4148.211839750437, 4230.5769423057445, 4332.263515202423, 4443.822247110144, 3437.0, 3509.701675357623, 3510.8069056201643, 3522.0, 3522.806245656462, 613.1538476901936, 628.7547856336032, 632.7785275153751, 638.2149955554694, 639.1499305923111, 518.7743331650744, 535.8660334916271, 562.5443390853421, 615.0098341669908, 692.9792106236821, 7913.960430197927, 7917.7624671259955, 8667.869981950276, 10155.543000564992, 10243.436610986319, 516.1844286028141, 553.6788662575697, 587.2541871822762, 598.8921994041027, 704.4610872682402, 4665.486796452387, 4803.0, 4824.49342819006, 4850.916295195641, 4861.29511220871, 86679.56660216658, 93366.0, 96064.51967740117, 98854.0, 106006.0, 77614.0, 81456.0, 82926.0, 82931.5853420729, 83369.33261334739, 76450.61774691091, 77322.61338693496, 77982.61008694921, 78283.99999999886, 79230.41539169184, 10416.427096509764, 10568.260221784401, 10763.0, 10789.244752867538, 10822.458877056273, 47875.28187077127, 49165.2625210615, 49173.508264918324, 49552.752236238586, 50029.749256268886, 60386.69806650939, 62953.05570416539, 64658.35341646523, 65721.0, 65894.67052664707, 13015.0, 13059.804102938273, 13262.867371326163, 13717.108387954751, 13917.72164556683, 14047.698790784361, 14207.360668770138, 14375.640608984859, 14409.495667651581, 14549.272536373352, 13107.803382949072, 13390.93304533471, 13492.797608036084, 13554.593362198755, 13928.860711392756, 13002.154859934075, 13311.935045196555, 13379.130356526795, 13634.522791702242, 13822.170669759847, 12980.984730011147, 13022.616182603808, 13480.595582132551, 13993.930030349784, 14334.0, 10776.461176940964, 11067.280626759235, 11366.318020918772, 11823.11326650522, 11879.762404751684, 10743.0, 10777.191710621579, 11029.393383363991, 11135.164862635173, 11225.943870280596], "value": 7594.3346839115, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1448.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 114.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 111.0, 112.0, 147.0, 113.0, 111.0, 110.0, 109.0, 127.0, 111.0], "value": 118.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 184.0, 181.0, 185.0, 184.0, 183.0, 182.0, 184.0, 185.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 75.0, 81.0, 118.0, 64.0, 63.0, 61.0, 56.0, 79.0, 60.0], "value": 74.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 89.0, 70.0, 69.0, 68.0, 84.0, 68.0, 69.0, 85.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 87.0, 87.0, 89.0, 88.0, 88.0, 88.0, 88.0, 87.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 98.0, 94.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 137.0, 86.0, 83.0, 94.0, 133.0, 83.0, 83.0, 85.0, 85.0], "value": 99.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 60.0, 61.0, 61.0, 61.0, 62.0, 61.0, 61.0], "value": 61.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 67.0, 62.0, 69.0, 63.0, 59.0, 57.0, 58.0, 58.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 83.0, 83.0, 83.0, 83.0, 83.0, 82.0, 80.0, 82.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 146.0, 128.0, 139.0, 136.0, 140.0, 139.0, 136.0, 186.0, 137.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 182.0, 183.0, 185.0, 182.0, 180.0, 190.0, 185.0, 185.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 69.0, 67.0, 68.0, 69.0, 68.0, 67.0, 69.0], "value": 67.7, "unit": "score"}]}]} 07:52:24 INFO - Return code: 0 07:52:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:52:24 ERROR - # TBPL SUCCESS # 07:52:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:52:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:52:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:52:24 INFO - 2017-03-14 07:52:24.893 screenresolution[1657:10932] starting screenresolution argv=screenresolution get 07:52:24 INFO - 2017-03-14 07:52:24.896 screenresolution[1657:10932] Display 0: 1600x1200x32@60 07:52:24 INFO - 2017-03-14 07:52:24.901 screenresolution[1658:10935] starting screenresolution argv=screenresolution list 07:52:24 INFO - Available Modes on Display 0 07:52:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:52:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:52:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:52:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:52:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:52:25 INFO - Intel Iris: 07:52:25 INFO - Chipset Model: Intel Iris 07:52:25 INFO - Type: GPU 07:52:25 INFO - Bus: Built-In 07:52:25 INFO - VRAM (Dynamic, Max): 1536 MB 07:52:25 INFO - Vendor: Intel (0x8086) 07:52:25 INFO - Device ID: 0x0a2e 07:52:25 INFO - Revision ID: 0x0009 07:52:25 INFO - Displays: 07:52:25 INFO - Display: 07:52:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:52:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:52:25 INFO - Display Serial Number: 1600x1200 60 07:52:25 INFO - Main Display: Yes 07:52:25 INFO - Mirror: Off 07:52:25 INFO - Online: Yes 07:52:25 INFO - Rotation: Supported 07:52:25 INFO - Return code: 0 07:52:25 INFO - Running post-action listener: _resource_record_post_action 07:52:25 INFO - [mozharness: 2017-03-14 14:52:25.143407Z] Finished run-tests step (success) 07:52:25 INFO - Running post-run listener: _resource_record_post_run 07:52:25 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 32697344; Write bytes: 513974272; Read time: 205; Write time: 1559 07:52:25 INFO - TinderboxPrint: CPU usage
53.4% 07:52:25 INFO - TinderboxPrint: I/O read bytes / time
32,697,344 / 205 07:52:25 INFO - TinderboxPrint: I/O write bytes / time
513,974,272 / 1,559 07:52:25 INFO - TinderboxPrint: CPU idle
2,244.1 (46.6%) 07:52:25 INFO - TinderboxPrint: CPU system
958.6 (19.9%) 07:52:25 INFO - TinderboxPrint: CPU user
1,611.0 (33.5%) 07:52:25 INFO - TinderboxPrint: Swap in / out
239,255,552 / 0 07:52:25 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203647488; Write bytes: 202354688; Read time: 15599; Write time: 427 07:52:25 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 31759360; Write bytes: 301703168; Read time: 191; Write time: 1098 07:52:25 INFO - Running post-run listener: _upload_blobber_files 07:52:25 INFO - Blob upload gear active. 07:52:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:52:25 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 07:52:25 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'] 07:52:25 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 07:52:26 INFO - (blobuploader) - INFO - Open directory for files ... 07:52:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:52:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:52:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:27 INFO - (blobuploader) - INFO - Done attempting. 07:52:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:52:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:52:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:28 INFO - (blobuploader) - INFO - Done attempting. 07:52:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:52:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:52:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:29 INFO - (blobuploader) - INFO - Done attempting. 07:52:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:52:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:52:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:31 INFO - (blobuploader) - INFO - Done attempting. 07:52:31 INFO - (blobuploader) - INFO - Iteration through files over. 07:52:31 INFO - Return code: 0 07:52:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:52:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:52:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f383645f6978e8de89ab20ea78fcde39e1451de4121258a31f5dabfc5810a68b11de2c2cec8bebfc17fd8be0f04dd6086d77c5038db85cfe79af0980da14f14d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe963f225e0649fb38eb83ea425c53e883946a398104091846233cc84f20a4ff4a5afc964e7ccb488dde1ed3c3d700e11369ddc4ede4c91cd2414cad2d559fa2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/942524637a523b43ade75b11fac15082a01bf86bcc20b01cf8d25a701d95df24afc62b508e54e28e05d386d7cf9d121a9eaf62a3e43b9e478a3b2bdf5668fbe8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8025b66f026b2e776a178164f5e27191a414e1a7d9d5a30cca38556b4ecd4061babd49351a1352aa94d36338e5a9737372cd953ee8d464a404d5efce75337c71"} 07:52:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:52:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:52:31 INFO - Contents: 07:52:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f383645f6978e8de89ab20ea78fcde39e1451de4121258a31f5dabfc5810a68b11de2c2cec8bebfc17fd8be0f04dd6086d77c5038db85cfe79af0980da14f14d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe963f225e0649fb38eb83ea425c53e883946a398104091846233cc84f20a4ff4a5afc964e7ccb488dde1ed3c3d700e11369ddc4ede4c91cd2414cad2d559fa2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/942524637a523b43ade75b11fac15082a01bf86bcc20b01cf8d25a701d95df24afc62b508e54e28e05d386d7cf9d121a9eaf62a3e43b9e478a3b2bdf5668fbe8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8025b66f026b2e776a178164f5e27191a414e1a7d9d5a30cca38556b4ecd4061babd49351a1352aa94d36338e5a9737372cd953ee8d464a404d5efce75337c71"} 07:52:31 INFO - Running post-run listener: copy_logs_to_upload_dir 07:52:31 INFO - Copying logs to upload dir... 07:52:31 INFO - mkdir: /builds/slave/test/build/upload/logs 07:52:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.340660 ========= master_lag: 2.23 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-14 07:52:33.528074) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 07:52:33.531850) ========= 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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f383645f6978e8de89ab20ea78fcde39e1451de4121258a31f5dabfc5810a68b11de2c2cec8bebfc17fd8be0f04dd6086d77c5038db85cfe79af0980da14f14d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe963f225e0649fb38eb83ea425c53e883946a398104091846233cc84f20a4ff4a5afc964e7ccb488dde1ed3c3d700e11369ddc4ede4c91cd2414cad2d559fa2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/942524637a523b43ade75b11fac15082a01bf86bcc20b01cf8d25a701d95df24afc62b508e54e28e05d386d7cf9d121a9eaf62a3e43b9e478a3b2bdf5668fbe8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8025b66f026b2e776a178164f5e27191a414e1a7d9d5a30cca38556b4ecd4061babd49351a1352aa94d36338e5a9737372cd953ee8d464a404d5efce75337c71"} build_url:https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010607 build_url: 'https://queue.taskcluster.net/v1/task/ckqEXrYRQhi1YLacmUKuXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f383645f6978e8de89ab20ea78fcde39e1451de4121258a31f5dabfc5810a68b11de2c2cec8bebfc17fd8be0f04dd6086d77c5038db85cfe79af0980da14f14d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe963f225e0649fb38eb83ea425c53e883946a398104091846233cc84f20a4ff4a5afc964e7ccb488dde1ed3c3d700e11369ddc4ede4c91cd2414cad2d559fa2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/942524637a523b43ade75b11fac15082a01bf86bcc20b01cf8d25a701d95df24afc62b508e54e28e05d386d7cf9d121a9eaf62a3e43b9e478a3b2bdf5668fbe8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8025b66f026b2e776a178164f5e27191a414e1a7d9d5a30cca38556b4ecd4061babd49351a1352aa94d36338e5a9737372cd953ee8d464a404d5efce75337c71"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 07:52:33.595161) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 07:52:33.595488) ========= 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.PAIQRNS4WZ/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.V4ZN28NizZ/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.004853 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 07:52:33.643152) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 07:52:33.643442) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 07:52:33.643768) ========= ========= Total master_lag: 2.40 =========