builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0188 starttime: 1491346225.6 results: success (0) buildid: 20170404141628 builduid: ac16112cd27a4a5099a4dfd60548bb0b revision: 859544c286eb85cc99ae95f6b4e58daac3e088b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:25.596490) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:25.596946) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:25.597248) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:25.743134) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:25.743456) ========= 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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-04 15:50:25-- 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% 8.11M=0.001s 2017-04-04 15:50:25 (8.11 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.157487 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:25.924365) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:25.924692) ========= 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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.021440 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:25.973360) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-04 15:50:25.973721) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 859544c286eb85cc99ae95f6b4e58daac3e088b0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 859544c286eb85cc99ae95f6b4e58daac3e088b0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1Vp9hgr4uC/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.BnhCxRCijH/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-04 15:50:26,058 truncating revision to first 12 chars 2017-04-04 15:50:26,058 Setting DEBUG logging. 2017-04-04 15:50:26,058 attempt 1/10 2017-04-04 15:50:26,058 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/859544c286eb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-04 15:50:27,468 unpacking tar archive at: pine-859544c286eb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.684127 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-04 15:50:27.681614) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:27.681943) ========= script_repo_revision: 859544c286eb85cc99ae95f6b4e58daac3e088b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:27.682359) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:27.682645) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 15:50:27.702509) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2017-04-04 15:50:27.704050) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1Vp9hgr4uC/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.BnhCxRCijH/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 15:50:27 INFO - MultiFileLogger online at 20170404 15:50:27 in /builds/slave/test 15:50:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 15:50:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:27 INFO - {'append_to_log': False, 15:50:27 INFO - 'base_work_dir': '/builds/slave/test', 15:50:27 INFO - 'blob_upload_branch': 'Pine', 15:50:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:27 INFO - 'branch': 'Pine', 15:50:27 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:27 INFO - 'config_files': ('talos/mac_config.py',), 15:50:27 INFO - 'default_actions': ('clobber', 15:50:27 INFO - 'read-buildbot-config', 15:50:27 INFO - 'download-and-extract', 15:50:27 INFO - 'populate-webroot', 15:50:27 INFO - 'create-virtualenv', 15:50:27 INFO - 'install', 15:50:27 INFO - 'run-tests'), 15:50:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:27 INFO - 'download_minidump_stackwalk': True, 15:50:27 INFO - 'download_symbols': 'ondemand', 15:50:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:50:27 INFO - 'tooltool.py': '/tools/tooltool.py', 15:50:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:50:27 INFO - '/tools/misc-python/virtualenv.py')}, 15:50:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:27 INFO - 'gecko_profile': False, 15:50:27 INFO - 'gecko_profile_interval': 0, 15:50:27 INFO - 'installer_path': 'installer.exe', 15:50:27 INFO - 'log_level': 'info', 15:50:27 INFO - 'log_name': 'talos', 15:50:27 INFO - 'log_to_console': True, 15:50:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:50:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:50:27 INFO - 'opt_config_files': (), 15:50:27 INFO - 'pip_index': False, 15:50:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:27 INFO - 'cmd': ('bash', 15:50:27 INFO - '-c', 15:50:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:27 INFO - 'enabled': True, 15:50:27 INFO - 'halt_on_failure': False, 15:50:27 INFO - 'name': 'check_screen_resolution'},), 15:50:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:27 INFO - 'cmd': ('bash', 15:50:27 INFO - '-c', 15:50:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:27 INFO - 'enabled': True, 15:50:27 INFO - 'halt_on_failure': False, 15:50:27 INFO - 'name': 'check_screen_resolution'},), 15:50:27 INFO - 'run_cmd_checks_enabled': True, 15:50:27 INFO - 'suite': 'dromaeojs', 15:50:27 INFO - 'system_bits': '32', 15:50:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:27 INFO - 'title': 't-yosemite-r7-0188', 15:50:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:27 INFO - 'use_talos_json': True, 15:50:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:27 INFO - 'work_dir': 'build'} 15:50:27 INFO - [mozharness: 2017-04-04 22:50:27.916253Z] Running clobber step. 15:50:27 INFO - Running pre-action listener: _resource_record_pre_action 15:50:27 INFO - Running main action method: clobber 15:50:27 INFO - rmtree: /builds/slave/test/build 15:50:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:31 INFO - Running post-action listener: _resource_record_post_action 15:50:31 INFO - [mozharness: 2017-04-04 22:50:31.033177Z] Finished clobber step (success) 15:50:31 INFO - [mozharness: 2017-04-04 22:50:31.033332Z] Running read-buildbot-config step. 15:50:31 INFO - Running pre-action listener: _resource_record_pre_action 15:50:31 INFO - Running main action method: read_buildbot_config 15:50:31 INFO - Using buildbot properties: 15:50:31 INFO - { 15:50:31 INFO - "project": "", 15:50:31 INFO - "product": "firefox", 15:50:31 INFO - "script_repo_revision": "default", 15:50:31 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 15:50:31 INFO - "repository": "", 15:50:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 15:50:31 INFO - "buildid": "20170404141628", 15:50:31 INFO - "pgo_build": "False", 15:50:31 INFO - "basedir": "/builds/slave/test", 15:50:31 INFO - "buildnumber": 2, 15:50:31 INFO - "slavename": "t-yosemite-r7-0188", 15:50:31 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:50:31 INFO - "platform": "yosemite_r7", 15:50:31 INFO - "scheduler": "tests-pine-macosx64-talos", 15:50:31 INFO - "branch": "pine", 15:50:31 INFO - "repo_path": "projects/pine", 15:50:31 INFO - "revision": "859544c286eb85cc99ae95f6b4e58daac3e088b0", 15:50:31 INFO - "stage_platform": "macosx64", 15:50:31 INFO - "builduid": "ac16112cd27a4a5099a4dfd60548bb0b", 15:50:31 INFO - "slavebuilddir": "test" 15:50:31 INFO - } 15:50:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 15:50:31 INFO - Running post-action listener: _resource_record_post_action 15:50:31 INFO - [mozharness: 2017-04-04 22:50:31.036763Z] Finished read-buildbot-config step (success) 15:50:31 INFO - [mozharness: 2017-04-04 22:50:31.036870Z] Running download-and-extract step. 15:50:31 INFO - Running pre-action listener: _resource_record_pre_action 15:50:31 INFO - Running main action method: download_and_extract 15:50:31 INFO - mkdir: /builds/slave/test/build/tests 15:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:31 INFO - trying https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:50:31 INFO - Downloading https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/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 15:50:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/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 15:50:32 INFO - Downloaded 1325 bytes. 15:50:32 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:50:32 INFO - Using the following test package requirements: 15:50:32 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:50:32 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:50:32 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:50:32 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:50:32 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:50:32 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:50:32 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:50:32 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:50:32 INFO - u'jsshell-mac.zip'], 15:50:32 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:50:32 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:50:32 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:50:32 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:50:32 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:50:32 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:50:32 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:50:32 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:50:32 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:50:32 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:50:32 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:50:32 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:50:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:50:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:50:32 INFO - Fetch https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:50:33 INFO - Expected file size: 52517467 15:50:33 INFO - Obtained file size: 52517467 15:50:50 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 15:50:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:50:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:50:50 INFO - Fetch https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:50:51 INFO - Expected file size: 52517467 15:50:51 INFO - Obtained file size: 52517467 15:51:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:51:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:51:07 INFO - Fetch https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:51:09 INFO - Expected file size: 14404152 15:51:09 INFO - Obtained file size: 14404152 15:51:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:10 INFO - trying https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:51:10 INFO - Downloading https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:51:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/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 15:51:12 INFO - Downloaded 70925245 bytes. 15:51:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:51:12 INFO - mkdir: /builds/slave/test/properties 15:51:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:51:12 INFO - Writing to file /builds/slave/test/properties/build_url 15:51:12 INFO - Contents: 15:51:12 INFO - build_url:https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:51:12 INFO - Running post-action listener: _resource_record_post_action 15:51:12 INFO - Running post-action listener: set_extra_try_arguments 15:51:12 INFO - [mozharness: 2017-04-04 22:51:12.852729Z] Finished download-and-extract step (success) 15:51:12 INFO - [mozharness: 2017-04-04 22:51:12.852866Z] Running populate-webroot step. 15:51:12 INFO - Running pre-action listener: _resource_record_pre_action 15:51:12 INFO - Running main action method: populate_webroot 15:51:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:51:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:51:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:51:12 INFO - '--tppagecycles', 15:51:12 INFO - '7'], 15:51:12 INFO - 'tests': ['tsvgm']}}, 15:51:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:51:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:51:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:51:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:51:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:51:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:51:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:51:12 INFO - 'talos_options': ['--disable-e10s'], 15:51:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:51:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:51:12 INFO - 'talos_options': ['--disable-e10s'], 15:51:12 INFO - 'tests': ['damp', 'tps']}, 15:51:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:51:12 INFO - 'tests': ['damp', 'tps']}, 15:51:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:51:12 INFO - 'tests': ['dromaeo_dom']}, 15:51:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:51:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:51:12 INFO - 'tests': ['basic_compositor_video']}, 15:51:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:51:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:51:12 INFO - 'tests': ['a11yr', 15:51:12 INFO - 'ts_paint', 15:51:12 INFO - 'tpaint', 15:51:12 INFO - 'sessionrestore', 15:51:12 INFO - 'sessionrestore_no_auto_restore', 15:51:12 INFO - 'tabpaint']}, 15:51:12 INFO - 'other-e10s': {'tests': ['a11yr', 15:51:12 INFO - 'ts_paint', 15:51:12 INFO - 'tpaint', 15:51:12 INFO - 'sessionrestore', 15:51:12 INFO - 'sessionrestore_no_auto_restore', 15:51:12 INFO - 'tabpaint']}, 15:51:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:51:12 INFO - 'ts_paint', 15:51:12 INFO - 'tpaint', 15:51:12 INFO - 'sessionrestore', 15:51:12 INFO - 'sessionrestore_no_auto_restore', 15:51:12 INFO - 'tabpaint']}, 15:51:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:51:12 INFO - 'ts_paint', 15:51:12 INFO - 'tpaint', 15:51:12 INFO - 'sessionrestore', 15:51:12 INFO - 'sessionrestore_no_auto_restore', 15:51:12 INFO - 'tabpaint']}, 15:51:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:51:12 INFO - 'tests': ['a11yr', 15:51:12 INFO - 'ts_paint', 15:51:12 INFO - 'tpaint', 15:51:12 INFO - 'sessionrestore', 15:51:12 INFO - 'sessionrestore_no_auto_restore', 15:51:12 INFO - 'tabpaint']}, 15:51:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:51:12 INFO - 'tests': ['a11yr', 15:51:12 INFO - 'ts_paint', 15:51:12 INFO - 'tpaint', 15:51:12 INFO - 'sessionrestore', 15:51:12 INFO - 'sessionrestore_no_auto_restore', 15:51:12 INFO - 'tabpaint']}, 15:51:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:51:12 INFO - 'tests': ['tsvgx', 15:51:12 INFO - 'tsvgr_opacity', 15:51:12 INFO - 'tart', 15:51:12 INFO - 'tscrollx', 15:51:12 INFO - 'cart', 15:51:12 INFO - 'tsvg_static']}, 15:51:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:51:12 INFO - 'tsvgr_opacity', 15:51:12 INFO - 'tart', 15:51:12 INFO - 'tscrollx', 15:51:12 INFO - 'cart', 15:51:12 INFO - 'tsvg_static']}, 15:51:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:51:12 INFO - 'talos_options': ['--disable-e10s'], 15:51:12 INFO - 'tests': ['tp5o']}, 15:51:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:51:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:51:12 INFO - 'talos_options': ['--disable-e10s', 15:51:12 INFO - '--xperf_path', 15:51:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:12 INFO - 'tests': ['tp5n']}, 15:51:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:51:12 INFO - 'talos_options': ['--xperf_path', 15:51:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:12 INFO - 'tests': ['tp5n']}}, 15:51:12 INFO - 'talos.zip': {'path': '', 15:51:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:51:12 INFO - Running post-action listener: _resource_record_post_action 15:51:12 INFO - [mozharness: 2017-04-04 22:51:12.871569Z] Finished populate-webroot step (success) 15:51:12 INFO - [mozharness: 2017-04-04 22:51:12.871701Z] Running create-virtualenv step. 15:51:12 INFO - Running pre-action listener: _resource_record_pre_action 15:51:12 INFO - Running main action method: create_virtualenv 15:51:12 INFO - Creating virtualenv /builds/slave/test/build/venv 15:51:12 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 15:51:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:51:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:51:12 INFO - Using real prefix '/tools/python27' 15:51:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:51:13 INFO - Installing distribute.............................................................................................................................................................................................done. 15:51:16 INFO - Installing pip.................done. 15:51:16 INFO - Return code: 0 15:51:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:51:17 INFO - Reading from file tmpfile_stdout 15:51:17 INFO - Output received: 15:51:17 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) 15:51:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:51:17 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')]} 15:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:17 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')]} 15:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:17 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 0x10e571b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e574768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911c73720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e34fd30>, '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 0x10e276db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e344be8>, '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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 15:51:17 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 15:51:17 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 15:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Vp9hgr4uC/Render', 15:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:17 INFO - 'HOME': '/Users/cltbld', 15:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:17 INFO - 'LOGNAME': 'cltbld', 15:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:17 INFO - 'MOZ_NO_REMOTE': '1', 15:51:17 INFO - 'NO_EM_RESTART': '1', 15:51:17 INFO - 'PAGER': '/bin/cat', 15:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:17 INFO - 'PWD': '/builds/slave/test', 15:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:17 INFO - 'SHELL': '/bin/bash', 15:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BnhCxRCijH/Listeners', 15:51:17 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 15:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:17 INFO - 'USER': 'cltbld', 15:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:17 INFO - 'XPC_FLAGS': '0x0', 15:51:17 INFO - 'XPC_SERVICE_NAME': '0', 15:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:17 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 15:51:17 INFO - Cleaning up... 15:51:17 INFO - Return code: 0 15:51:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:51:17 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')]} 15:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:17 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')]} 15:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:17 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 0x10e571b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e574768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911c73720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e34fd30>, '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 0x10e276db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e344be8>, '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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 15:51:17 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 15:51:17 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 15:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Vp9hgr4uC/Render', 15:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:17 INFO - 'HOME': '/Users/cltbld', 15:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:17 INFO - 'LOGNAME': 'cltbld', 15:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:17 INFO - 'MOZ_NO_REMOTE': '1', 15:51:17 INFO - 'NO_EM_RESTART': '1', 15:51:17 INFO - 'PAGER': '/bin/cat', 15:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:17 INFO - 'PWD': '/builds/slave/test', 15:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:17 INFO - 'SHELL': '/bin/bash', 15:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BnhCxRCijH/Listeners', 15:51:17 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 15:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:17 INFO - 'USER': 'cltbld', 15:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:17 INFO - 'XPC_FLAGS': '0x0', 15:51:17 INFO - 'XPC_SERVICE_NAME': '0', 15:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:17 INFO - Downloading/unpacking psutil>=3.1.1 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:51:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:21 INFO - Installing collected packages: psutil 15:51:21 INFO - Running setup.py install for psutil 15:51:21 INFO - building 'psutil._psutil_osx' extension 15:51:21 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 15:51:21 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 15:51:21 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 15:51:21 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 15:51:21 INFO - building 'psutil._psutil_posix' extension 15:51:21 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 15:51:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:51:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:51:21 INFO - ^ 15:51:21 INFO - 1 warning generated. 15:51:21 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 15:51:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:21 INFO - Successfully installed psutil 15:51:21 INFO - Cleaning up... 15:51:22 INFO - Return code: 0 15:51:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:51:22 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')]} 15:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:22 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')]} 15:51:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:22 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 0x10e571b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e574768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911c73720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e34fd30>, '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 0x10e276db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e344be8>, '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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 15:51:22 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 15:51:22 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 15:51:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Vp9hgr4uC/Render', 15:51:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:22 INFO - 'HOME': '/Users/cltbld', 15:51:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:22 INFO - 'LOGNAME': 'cltbld', 15:51:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:22 INFO - 'MOZ_NO_REMOTE': '1', 15:51:22 INFO - 'NO_EM_RESTART': '1', 15:51:22 INFO - 'PAGER': '/bin/cat', 15:51:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:22 INFO - 'PWD': '/builds/slave/test', 15:51:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:22 INFO - 'SHELL': '/bin/bash', 15:51:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BnhCxRCijH/Listeners', 15:51:22 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 15:51:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:22 INFO - 'USER': 'cltbld', 15:51:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:22 INFO - 'XPC_FLAGS': '0x0', 15:51:22 INFO - 'XPC_SERVICE_NAME': '0', 15:51:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:51:22 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 15:51:22 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 15:51:22 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 15:51:22 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 15:51:22 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 15:51:22 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 15:51:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:51:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:51:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:51:26 INFO - Installing collected packages: mozsystemmonitor 15:51:26 INFO - Running setup.py install for mozsystemmonitor 15:51:26 INFO - Successfully installed mozsystemmonitor 15:51:26 INFO - Cleaning up... 15:51:26 INFO - Return code: 0 15:51:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:26 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 0x10e571b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e574768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911c73720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e34fd30>, '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 0x10e276db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e344be8>, '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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 15:51:26 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 15:51:26 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 15:51:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Vp9hgr4uC/Render', 15:51:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:26 INFO - 'HOME': '/Users/cltbld', 15:51:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:26 INFO - 'LOGNAME': 'cltbld', 15:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:26 INFO - 'MOZ_NO_REMOTE': '1', 15:51:26 INFO - 'NO_EM_RESTART': '1', 15:51:26 INFO - 'PAGER': '/bin/cat', 15:51:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:26 INFO - 'PWD': '/builds/slave/test', 15:51:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:26 INFO - 'SHELL': '/bin/bash', 15:51:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BnhCxRCijH/Listeners', 15:51:26 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 15:51:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:26 INFO - 'USER': 'cltbld', 15:51:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:26 INFO - 'XPC_FLAGS': '0x0', 15:51:26 INFO - 'XPC_SERVICE_NAME': '0', 15:51:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:26 INFO - Downloading/unpacking jsonschema==2.5.1 15:51:26 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 15:51:26 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 15:51:26 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 15:51:26 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 15:51:26 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 15:51:26 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 15:51:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:51:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:51:29 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 15:51:29 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 15:51:29 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 15:51:29 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 15:51:29 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 15:51:29 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 15:51:29 INFO - Downloading functools32-3.2.3-2.tar.gz 15:51:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:51:29 INFO - warning: no files found matching '*.txt' 15:51:29 INFO - no previously-included directories found matching 'build' 15:51:29 INFO - no previously-included directories found matching 'dist' 15:51:29 INFO - no previously-included directories found matching '.git*' 15:51:29 INFO - Installing collected packages: jsonschema, functools32 15:51:29 INFO - Running setup.py install for functools32 15:51:29 INFO - warning: no files found matching '*.txt' 15:51:29 INFO - no previously-included directories found matching 'build' 15:51:29 INFO - no previously-included directories found matching 'dist' 15:51:29 INFO - no previously-included directories found matching '.git*' 15:51:29 INFO - Successfully installed jsonschema functools32 15:51:29 INFO - Cleaning up... 15:51:30 INFO - Return code: 0 15:51:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:51:30 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')]} 15:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:30 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')]} 15:51:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:30 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 0x10e571b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e574768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911c73720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e34fd30>, '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 0x10e276db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e344be8>, '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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 15:51:30 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 15:51:30 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 15:51:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Vp9hgr4uC/Render', 15:51:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:30 INFO - 'HOME': '/Users/cltbld', 15:51:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:30 INFO - 'LOGNAME': 'cltbld', 15:51:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:30 INFO - 'MOZ_NO_REMOTE': '1', 15:51:30 INFO - 'NO_EM_RESTART': '1', 15:51:30 INFO - 'PAGER': '/bin/cat', 15:51:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:30 INFO - 'PWD': '/builds/slave/test', 15:51:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:30 INFO - 'SHELL': '/bin/bash', 15:51:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BnhCxRCijH/Listeners', 15:51:30 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 15:51:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:30 INFO - 'USER': 'cltbld', 15:51:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:30 INFO - 'XPC_FLAGS': '0x0', 15:51:30 INFO - 'XPC_SERVICE_NAME': '0', 15:51:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:51:30 INFO - Cleaning up... 15:51:30 INFO - Return code: 0 15:51:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:51:30 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')]} 15:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:30 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')]} 15:51:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:30 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 0x10e571b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e574768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911c73720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e34fd30>, '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 0x10e276db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e344be8>, '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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 15:51:30 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 15:51:30 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 15:51:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Vp9hgr4uC/Render', 15:51:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:30 INFO - 'HOME': '/Users/cltbld', 15:51:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:30 INFO - 'LOGNAME': 'cltbld', 15:51:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:30 INFO - 'MOZ_NO_REMOTE': '1', 15:51:30 INFO - 'NO_EM_RESTART': '1', 15:51:30 INFO - 'PAGER': '/bin/cat', 15:51:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:30 INFO - 'PWD': '/builds/slave/test', 15:51:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:30 INFO - 'SHELL': '/bin/bash', 15:51:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BnhCxRCijH/Listeners', 15:51:30 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 15:51:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:30 INFO - 'USER': 'cltbld', 15:51:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:30 INFO - 'XPC_FLAGS': '0x0', 15:51:30 INFO - 'XPC_SERVICE_NAME': '0', 15:51:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:30 INFO - Downloading/unpacking blobuploader==1.2.4 15:51:30 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 15:51:30 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 15:51:30 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 15:51:30 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 15:51:30 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 15:51:30 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 15:51:34 INFO - Downloading blobuploader-1.2.4.tar.gz 15:51:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:51:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:51:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:35 INFO - Downloading docopt-0.6.1.tar.gz 15:51:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:51:35 INFO - Installing collected packages: blobuploader, requests, docopt 15:51:35 INFO - Running setup.py install for blobuploader 15:51:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:51:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:51:35 INFO - Running setup.py install for requests 15:51:36 INFO - Running setup.py install for docopt 15:51:36 INFO - Successfully installed blobuploader requests docopt 15:51:36 INFO - Cleaning up... 15:51:36 INFO - Return code: 0 15:51:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:36 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 0x10e571b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e574768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911c73720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e34fd30>, '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 0x10e276db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e344be8>, '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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 15:51:36 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 15:51:36 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 15:51:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Vp9hgr4uC/Render', 15:51:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:36 INFO - 'HOME': '/Users/cltbld', 15:51:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:36 INFO - 'LOGNAME': 'cltbld', 15:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:36 INFO - 'MOZ_NO_REMOTE': '1', 15:51:36 INFO - 'NO_EM_RESTART': '1', 15:51:36 INFO - 'PAGER': '/bin/cat', 15:51:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:36 INFO - 'PWD': '/builds/slave/test', 15:51:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:36 INFO - 'SHELL': '/bin/bash', 15:51:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BnhCxRCijH/Listeners', 15:51:36 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 15:51:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:36 INFO - 'USER': 'cltbld', 15:51:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:36 INFO - 'XPC_FLAGS': '0x0', 15:51:36 INFO - 'XPC_SERVICE_NAME': '0', 15:51:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Ga7dbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-zlFbQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ht4miu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-fL9bOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Gygmkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-UDqZKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-1kuywk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-J0vYrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-aD_BUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-kMtLGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-d7rSyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-VrAuAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:37 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-L7wq34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:38 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-zIkoFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:38 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-5gkcXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:38 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-DEma8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:38 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-i0h_9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:38 INFO - Running setup.py install for manifestparser 15:51:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:51:38 INFO - Running setup.py install for mozcrash 15:51:38 INFO - Running setup.py install for mozdebug 15:51:38 INFO - Running setup.py install for mozdevice 15:51:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:51:38 INFO - Running setup.py install for mozfile 15:51:39 INFO - Running setup.py install for mozhttpd 15:51:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:51:39 INFO - Running setup.py install for mozinfo 15:51:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:51:39 INFO - Running setup.py install for mozInstall 15:51:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:51:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:51:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:51:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:51:39 INFO - Running setup.py install for mozleak 15:51:39 INFO - Running setup.py install for mozlog 15:51:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:51:39 INFO - Running setup.py install for moznetwork 15:51:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Running setup.py install for mozprocess 15:51:40 INFO - Running setup.py install for mozprofile 15:51:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Running setup.py install for mozrunner 15:51:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Running setup.py install for mozscreenshot 15:51:40 INFO - Running setup.py install for moztest 15:51:40 INFO - Running setup.py install for mozversion 15:51:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:51:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:40 INFO - Cleaning up... 15:51:40 INFO - Return code: 0 15:51:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:40 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')]} 15:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:40 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')]} 15:51:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:40 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 0x10e571b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e574768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911c73720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e34fd30>, '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 0x10e276db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e344be8>, '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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 15:51:40 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 15:51:40 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 15:51:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Vp9hgr4uC/Render', 15:51:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:40 INFO - 'HOME': '/Users/cltbld', 15:51:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:40 INFO - 'LOGNAME': 'cltbld', 15:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:40 INFO - 'MOZ_NO_REMOTE': '1', 15:51:40 INFO - 'NO_EM_RESTART': '1', 15:51:40 INFO - 'PAGER': '/bin/cat', 15:51:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:40 INFO - 'PWD': '/builds/slave/test', 15:51:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:40 INFO - 'SHELL': '/bin/bash', 15:51:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BnhCxRCijH/Listeners', 15:51:40 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 15:51:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:40 INFO - 'USER': 'cltbld', 15:51:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:40 INFO - 'XPC_FLAGS': '0x0', 15:51:40 INFO - 'XPC_SERVICE_NAME': '0', 15:51:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-EvWo5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:41 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)) 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-b3mo5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:41 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)) 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-gBZkMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:41 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)) 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-GVIuWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:41 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)) 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-cuTadb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:41 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)) 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-XJKJr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:41 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)) 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-UPbskw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:41 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)) 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-1VnXYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:42 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)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-xg5yoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:42 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)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-LwDHvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:42 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)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-9wwI_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:42 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)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-mlPWTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:42 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)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-tScS5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:42 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)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-mB3xUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:42 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)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-BhrnvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:42 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)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ZjTJHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:42 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)) 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Ube0aF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:43 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)) 15:51:43 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)) 15:51:43 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)) 15:51:43 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)) 15:51:43 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)) 15:51:43 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)) 15:51:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:43 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 15:51:43 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 15:51:43 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 15:51:43 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 15:51:43 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 15:51:43 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 15:51:45 INFO - Downloading blessings-1.6.tar.gz 15:51:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:45 INFO - Installing collected packages: blessings 15:51:45 INFO - Running setup.py install for blessings 15:51:45 INFO - Successfully installed blessings 15:51:45 INFO - Cleaning up... 15:51:45 INFO - Return code: 0 15:51:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:51:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:46 INFO - Reading from file tmpfile_stdout 15:51:46 INFO - Current package versions: 15:51:46 INFO - blessings == 1.6 15:51:46 INFO - blobuploader == 1.2.4 15:51:46 INFO - docopt == 0.6.1 15:51:46 INFO - functools32 == 3.2.3-2 15:51:46 INFO - jsonschema == 2.5.1 15:51:46 INFO - manifestparser == 1.1 15:51:46 INFO - mozInstall == 1.12 15:51:46 INFO - mozcrash == 1.0 15:51:46 INFO - mozdebug == 0.1 15:51:46 INFO - mozdevice == 0.49 15:51:46 INFO - mozfile == 1.2 15:51:46 INFO - mozhttpd == 0.7 15:51:46 INFO - mozinfo == 0.9 15:51:46 INFO - mozleak == 0.1 15:51:46 INFO - mozlog == 3.4 15:51:46 INFO - moznetwork == 0.27 15:51:46 INFO - mozprocess == 0.25 15:51:46 INFO - mozprofile == 0.28 15:51:46 INFO - mozrunner == 6.13 15:51:46 INFO - mozscreenshot == 0.1 15:51:46 INFO - mozsystemmonitor == 0.3 15:51:46 INFO - moztest == 0.8 15:51:46 INFO - mozversion == 1.4 15:51:46 INFO - psutil == 3.1.1 15:51:46 INFO - requests == 1.2.3 15:51:46 INFO - wsgiref == 0.1.2 15:51:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:46 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 0x10e571b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e574768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911c73720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e34fd30>, '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 0x10e276db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e344be8>, '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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 15:51:46 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 15:51:46 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 15:51:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Vp9hgr4uC/Render', 15:51:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:46 INFO - 'HOME': '/Users/cltbld', 15:51:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:46 INFO - 'LOGNAME': 'cltbld', 15:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:46 INFO - 'MOZ_NO_REMOTE': '1', 15:51:46 INFO - 'NO_EM_RESTART': '1', 15:51:46 INFO - 'PAGER': '/bin/cat', 15:51:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:46 INFO - 'PWD': '/builds/slave/test', 15:51:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:46 INFO - 'SHELL': '/bin/bash', 15:51:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BnhCxRCijH/Listeners', 15:51:46 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 15:51:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:46 INFO - 'USER': 'cltbld', 15:51:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:46 INFO - 'XPC_FLAGS': '0x0', 15:51:46 INFO - 'XPC_SERVICE_NAME': '0', 15:51:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:46 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)) 15:51:46 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)) 15:51:46 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)) 15:51:46 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)) 15:51:46 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)) 15:51:46 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)) 15:51:46 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)) 15:51:46 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)) 15:51:46 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)) 15:51:46 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)) 15:51:46 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)) 15:51:46 INFO - Cleaning up... 15:51:46 INFO - Return code: 0 15:51:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e571b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e574768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911c73720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e34fd30>, '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 0x10e276db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e344be8>, '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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 15:51:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:51:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:51:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Vp9hgr4uC/Render', 15:51:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:46 INFO - 'HOME': '/Users/cltbld', 15:51:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:46 INFO - 'LOGNAME': 'cltbld', 15:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:46 INFO - 'MOZ_NO_REMOTE': '1', 15:51:46 INFO - 'NO_EM_RESTART': '1', 15:51:46 INFO - 'PAGER': '/bin/cat', 15:51:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:46 INFO - 'PWD': '/builds/slave/test', 15:51:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:46 INFO - 'SHELL': '/bin/bash', 15:51:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BnhCxRCijH/Listeners', 15:51:46 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 15:51:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:46 INFO - 'USER': 'cltbld', 15:51:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:46 INFO - 'XPC_FLAGS': '0x0', 15:51:46 INFO - 'XPC_SERVICE_NAME': '0', 15:51:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:51:46 INFO - Cleaning up... 15:51:46 INFO - Return code: 0 15:51:46 INFO - Running post-action listener: _resource_record_post_action 15:51:46 INFO - Running post-action listener: _start_resource_monitoring 15:51:46 INFO - Starting resource monitoring. 15:51:46 INFO - [mozharness: 2017-04-04 22:51:46.787284Z] Finished create-virtualenv step (success) 15:51:46 INFO - [mozharness: 2017-04-04 22:51:46.788319Z] Running install step. 15:51:46 INFO - Running pre-action listener: _resource_record_pre_action 15:51:46 INFO - Running main action method: install 15:51:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:47 INFO - Reading from file tmpfile_stdout 15:51:47 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:51:47 INFO - Reading from file tmpfile_stdout 15:51:47 INFO - Output received: 15:51:47 INFO - Usage: mozinstall [options] installer 15:51:47 INFO - Options: 15:51:47 INFO - -h, --help show this help message and exit 15:51:47 INFO - -d DEST, --destination=DEST 15:51:47 INFO - Directory to install application into. [default: 15:51:47 INFO - "/builds/slave/test"] 15:51:47 INFO - --app=APP Application being installed. [default: firefox] 15:51:47 INFO - mkdir: /builds/slave/test/build/application 15:51:47 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'] 15:51:47 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 15:52:06 INFO - Reading from file tmpfile_stdout 15:52:06 INFO - Output received: 15:52:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:52:06 INFO - Running post-action listener: _resource_record_post_action 15:52:06 INFO - [mozharness: 2017-04-04 22:52:06.731221Z] Finished install step (success) 15:52:06 INFO - [mozharness: 2017-04-04 22:52:06.731367Z] Running run-tests step. 15:52:06 INFO - Running pre-action listener: _resource_record_pre_action 15:52:06 INFO - Running main action method: run_tests 15:52:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:52:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:52:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:52:06 INFO - 2017-04-04 15:52:06.746 screenresolution[1900:18772] starting screenresolution argv=screenresolution get 15:52:06 INFO - 2017-04-04 15:52:06.748 screenresolution[1900:18772] Display 0: 1600x1200x32@60 15:52:06 INFO - 2017-04-04 15:52:06.754 screenresolution[1901:18775] starting screenresolution argv=screenresolution list 15:52:06 INFO - Available Modes on Display 0 15:52:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:52:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:52:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:52:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:52:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:52:06 INFO - Intel Iris: 15:52:06 INFO - Chipset Model: Intel Iris 15:52:06 INFO - Type: GPU 15:52:06 INFO - Bus: Built-In 15:52:06 INFO - VRAM (Dynamic, Max): 1536 MB 15:52:06 INFO - Vendor: Intel (0x8086) 15:52:06 INFO - Device ID: 0x0a2e 15:52:06 INFO - Revision ID: 0x0009 15:52:06 INFO - Displays: 15:52:06 INFO - Display: 15:52:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:52:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:52:06 INFO - Display Serial Number: 1600x1200 60 15:52:06 INFO - Main Display: Yes 15:52:06 INFO - Mirror: Off 15:52:06 INFO - Online: Yes 15:52:06 INFO - Rotation: Supported 15:52:06 INFO - Return code: 0 15:52:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:52:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:52:06 INFO - Python 2.7.3 15:52:06 INFO - Return code: 0 15:52:06 INFO - grabbing minidump binary from tooltool 15:52:06 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')]} 15:52:06 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 0x10e34fd30>, '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 0x10e276db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e344be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:52:06 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 15:52:06 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 15:52:06 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 15:52:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:52:07 INFO - Return code: 0 15:52:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:52:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:52:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:52:07 INFO - ENV: RUST_BACKTRACE is now 1 15:52:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:52:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:52:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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 15:52:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0188 --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 15:52:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Vp9hgr4uC/Render', 15:52:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:07 INFO - 'HOME': '/Users/cltbld', 15:52:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:07 INFO - 'LOGNAME': 'cltbld', 15:52:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:52:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:07 INFO - 'MOZ_NO_REMOTE': '1', 15:52:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:52:07 INFO - 'NO_EM_RESTART': '1', 15:52:07 INFO - 'PAGER': '/bin/cat', 15:52:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:07 INFO - 'PWD': '/builds/slave/test', 15:52:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:52:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:07 INFO - 'RUST_BACKTRACE': '1', 15:52:07 INFO - 'SHELL': '/bin/bash', 15:52:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BnhCxRCijH/Listeners', 15:52:07 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 15:52:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:07 INFO - 'USER': 'cltbld', 15:52:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:07 INFO - 'XPC_FLAGS': '0x0', 15:52:07 INFO - 'XPC_SERVICE_NAME': '0', 15:52:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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 15:52:07 INFO - mozversion application_buildid: 20170404141628 15:52:07 INFO - mozversion application_changeset: 859544c286eb85cc99ae95f6b4e58daac3e088b0 15:52:07 INFO - mozversion application_display_name: Nightly 15:52:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:52:07 INFO - mozversion application_name: Firefox 15:52:07 INFO - mozversion application_remotingname: firefox 15:52:07 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 15:52:07 INFO - mozversion application_vendor: Mozilla 15:52:07 INFO - mozversion application_version: 55.0a1 15:52:07 INFO - mozversion platform_buildid: 20170404141628 15:52:07 INFO - mozversion platform_changeset: 859544c286eb85cc99ae95f6b4e58daac3e088b0 15:52:07 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 15:52:07 INFO - mozversion platform_version: 55.0a1 15:52:07 INFO - using testdate: 1491346327 15:52:07 INFO - actual date: 1491346327 15:52:07 INFO - starting webserver on 'localhost:56708' 15:52:07 INFO - SUITE-START | Running 2 tests 15:52:07 INFO - TEST-START | dromaeo_css 15:52:07 INFO - operating with platform_type : mac_ 15:52:07 INFO - Initialising browser for dromaeo_css test... 15:52:07 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpOwYKAq/profile http://localhost:56708/getInfo.html) 15:52:10 INFO - PID 1911 | __metrics Screen width/height:1600/1200 15:52:10 INFO - PID 1911 | colorDepth:24 15:52:10 INFO - PID 1911 | Browser inner width/height: 1024/689 15:52:10 INFO - PID 1911 | __metrics 15:52:10 INFO - PID 1911 | 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" 15:52:10 INFO - PID 1911 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:52:11 INFO - TEST-INFO | 1911: exit 0 15:52:11 INFO - Browser initialized. 15:52:11 INFO - Running cycle 1/1 for dromaeo_css test... 15:52:11 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpOwYKAq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:52:23 INFO - PID 1913 | RSS: Main: 239300608 15:52:23 INFO - PID 1913 | 15:55:28 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:56708/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56708/tests/dromaeo/cssquery-ext.html) 15:55:30 INFO - PID 1913 | RSS: Main: 374263808 15:55:30 INFO - PID 1913 | 15:57:13 INFO - PID 1913 | ************************* 15:57:13 INFO - PID 1913 | A coding exception was thrown and uncaught in a Task. 15:57:13 INFO - PID 1913 | 15:57:13 ERROR - PID 1913 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:57:13 INFO - PID 1913 | Full stack: 15:57:13 INFO - PID 1913 | ************************* 15:57:13 INFO - PID 1913 | ************************* 15:57:13 INFO - PID 1913 | A coding exception was thrown and uncaught in a Task. 15:57:13 INFO - PID 1913 | 15:57:13 ERROR - PID 1913 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:57:13 INFO - PID 1913 | Full stack: 15:57:13 INFO - PID 1913 | ************************* 15:57:13 INFO - PID 1913 | ************************* 15:57:13 INFO - PID 1913 | A coding exception was thrown and uncaught in a Task. 15:57:13 INFO - PID 1913 | 15:57:13 ERROR - PID 1913 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:57:13 INFO - PID 1913 | Full stack: 15:57:13 INFO - PID 1913 | ************************* 15:57:13 INFO - PID 1913 | ************************* 15:57:13 INFO - PID 1913 | A coding exception was thrown and uncaught in a Task. 15:57:13 INFO - PID 1913 | 15:57:13 ERROR - PID 1913 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:57:13 INFO - PID 1913 | Full stack: 15:57:13 INFO - PID 1913 | ************************* 15:58:33 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:56708/tests/dromaeo/cssquery-ext.html (next: http://localhost:56708/tests/dromaeo/cssquery-jquery.html) 15:58:33 INFO - PID 1913 | RSS: Main: 368734208 15:58:33 INFO - PID 1913 | 16:01:36 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:56708/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56708/tests/dromaeo/cssquery-mootools.html) 16:01:36 INFO - PID 1913 | RSS: Main: 336367616 16:01:36 INFO - PID 1913 | 16:04:39 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:56708/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56708/tests/dromaeo/cssquery-prototype.html) 16:04:39 INFO - PID 1913 | RSS: Main: 327311360 16:04:39 INFO - PID 1913 | 16:07:43 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:56708/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56708/tests/dromaeo/cssquery-yui.html) 16:07:44 INFO - PID 1913 | RSS: Main: 339431424 16:07:44 INFO - PID 1913 | 16:10:46 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:56708/tests/dromaeo/cssquery-yui.html (next: http://localhost:56708/tests/dromaeo/cssquery-dojo.html) 16:10:47 INFO - PID 1913 | RSS: Main: 447930368 16:10:47 INFO - PID 1913 | 16:10:47 INFO - PID 1913 | __start_tp_report 16:10:47 INFO - PID 1913 | _x_x_mozilla_page_load 16:10:47 INFO - PID 1913 | _x_x_mozilla_page_load_details 16:10:47 INFO - PID 1913 | |i|pagename|runs| 16:10:47 INFO - PID 1913 | |0;dojo.html;2893.6527616686;3433.3133373325327;3453.067671728634;3471.895843124704;3500.352434799562;5932;6140.7850725224625;6164.6301221926715;6171.660558669277;6176.073588961653;6097.917917222698;6135;6155.415235552616;6201.28685201202;6232.407921247485;4657.371254880582;4974.830914735035;5326.440723724011;5351.625386222966;5383.057964856154;5383.192521121824;5436.537894278991;5441.455854414547;5446.2919820423285;5486.780528778844;5206.583473322125;5264.52619264268;5273.367195936474;5276.050310944029;5401.135818269097;5300.992811365828;5308.761105750251;5312.123499622558;5317.255584218213;5317.73411329432;4330.783460826946;5224.164133738622;5263.394709608406;5283.577313814885;5285.365756109253;6101.0848372744;6101.664408457533;6103.176071230371;6116.816495505142;6131.662758548278;6386.10594516768;6388.64862432566;6392.872142557169;6395;6405.29541750407;6437.935620643827;6454.4513716333695;6532.248791389004;6543.967280163569;6654.500912431587;6288.717007734663;6296.9370306296805;6303.401176888188;6310.0850376695125;6312.021636646327;6826.761063362758;6835.14560679905;6843.520953533205;6843.8973415398805;6851.314868513208;6300.495960323163;6376.489880809617;6388.297287298394;6419.743210271537;6430.80707578764;6626.768063117768;6627.271000189975;6633.86732265352;6634.933650663528;6637.834054148685;6504.284528701839;6513.088167656532;6526.836829079311;6543.607383556907;6552.57408268461;6308.684565771693;6380.553361264667;6381.776637817654;6440.420362167428;6541.803745887631;5241.73791310433;5257.78968841242;5293.258943747878;5330.200469929541;5335.413104558495;4267.4025636410925;4477.686561940633;4676.462206846189;4681.485036645966;4728.4562275338085;5968.134620480006;6108.410620383957;6267.091271765568;6325.240971083499;6327.746890124436;5729.684867332337;6516.218053833571;6536.281009088996;6551.95168772994;6577.967110164419;6299.842503937347;6300.873982520324;6302.558820882494;6307.684615769193;6324.936750632435;5813.94186058134;5836.533077353802;5839.270091238595;5842.270663939707;5862.472377486047;6579.605223686594;6667.366600172976;6678.933210668026;6713.731450742111;6797.66011699423;6368.554201205871;6445.967770161119;6634.701438435185;6671.166104236971;6675.365840245169;6690.866182676416;6733.898991515033;6752.966235168793;6837.726490940307;6853.314668533107;6467.417932386108;6491.513136514686;6508.837279068061;6511.446527045254;6518.57629254097;6370.713317900798;6424.775132870327;6520.250171230369;6524.869502610016;6655.866882662416;6306.716197771019;6386.489080873612;6398.13625160616;6398.168238129014;6408.519361048034;6376.936230637634;6386.61680299165;6409.198850143732;6412.422881940648;6415.422611964946;5564.443555644403;5992.280926288873;6288.65412402304;6318.241810982711;6327.557071004985;6331.0819931110655;6337.081123237197;6339.683015849097;6410.743570257324;6414.069959855888;6345.587536810092;6347.555671103163;6347.650879201688;6349.587276826992;6353.714082866374 16:10:47 INFO - PID 1913 | |1;ext.html;712.4193782067614;811.8701007838747;1124.8931351521603;1143.5997400909678;1143.6454699043302;38313.23373532527;39022.024449388744;39087.60912390882;39093.60906390935;39217.80391098041;40989.59010409895;41391;41516.16967660645;41517;41547.58452415475;6109.816705498843;8578.914210857905;8729.301655867515;8730.86903696442;8745.256653184455;6178.382161783808;8525.914740852606;8685.392022558424;8693.95653021734;8717.346199035097;8737.344699147558;8751.693690720826;8786;8788.296936245117;8803.119688031209;8684.044755076937;8751.606177722018;8773.473591584525;8774.868376974318;8776.254018408443;8485.236328730443;8548.444351117158;8549.615267312924;8555.657773689107;8558.743237702878;38941.80529097365;39099.41350879739;39225.80387098046;39231.80384098061;39261.80369098165;3492.703120234783;3532.1699400640828;3532.717382609385;3534.9469757953652;3547;30974.845125774453;30984.845075774476;31029.8448507756;31040.223994400094;31054.91307804216;3093.1184612385364;3107.829069401182;3157.8894738684035;3159.636641786201;3172.333809899924;332264;333691;338224;338926;339177.3041134803;41186.38220426696;41732.79133604313;41859.7907010463;49834.75082624564;49962.250566241535;42900.78549607232;43065.35401968973;43094.353584696255;43244;52003.47996520062;14144.222067786164;14526.782098268535;14534.200618965848;14584;14598.854011459962;11793;11795.764084718257;11801.409929503663;11813.40932953349;11816.704582385337;11895.226810257305;12938.611841644766;12950.546730864564;12962.481500739865;13062.34688265602;5609.691466969356;5621.4659607337235;5659.405762394889;5672.688002159921;5672.943270567324;962.4706411473801;971.1745016735719;973.8301827551276;977.3158788847693;987.2792861211356;23959.864287885026;24034.66470978786;24041.0534933409;24056.679190982464;24100.638490422658;1059.645018918671;1070.277562645227;1076.8169411200115;1084.1976937066513;1106.623747925709;12003.399830008639;12164.39178041112;12168.209066410654;12179.695507612381;12227.0829687772;448277;448619;449250;449324;450008;53936;54172;54203.45796541984;54261.914761704546;54308.728456357465;1439.5825210688993;1448.478547722819;1498.7901693762883;1502.8797696184279;1503.6992601479749;19210;23516.882415587814;23549.411264718517;23557.882210588836;23630.527389452458;38495.615043849204;38571.614283856805;38594.6140538591;38630.22739545249;38670.6132938667;437760;439442;439844;440571;442327;26530.071447499246;26664.40251321071;31504.842475787475;33842.66157338395;34208.315833683686;41925.371119432624;42451.51419700296;42770.78614606907;43043.7847810759;43379.349309759746;27854.58218126689;27941.580876286465;29413.411731765675;29823;29912.25219369533;23855.642165367186;26404.20787376382;29515;29725.702742972295;30328.393432130793;34548.82725586456;34985.82507087448;35593.64406355903;35740;35852.10369740777;29157.125286241448;29232.853835730686;29935.850320749127;30008.549871751504;30258;21919;23841.52316953686;23856.64215036741;23954.161604343764;23972.160974365812 16:10:47 INFO - PID 1913 | |2;jquery.html;418.96582095876505;428.13730333378265;432.76846886915496;436.9191699535586;449.438202247191;20697.793022069793;22055;22693.77306226937;22952.540949181028;22994.310170694866;17147.571310717238;20515.97420128991;21697.80662063586;22840.428989275275;23113.30660080196;6016.127661489081;6237.500999920018;6245.562810603237;6253.593516421441;6291.36424529621;5754.827355179351;6116.388361163892;6187.969060154693;6197.287311959115;6201.906971395432;5886.558508111887;6141.723622436979;6166.568340216164;6168.876622467548;6172.135900973867;5384.165454354581;5961.046232602785;6007.4893634041155;6137.570370074097;6147.415995480422;5339.866503337409;5346.572274218053;5414.675119492804;5417.566594672456;5428.077226871442;25874.22377328683;25893.482130357286;25947.481050378887;25968.480630387476;26129.346766330804;2125.171183238538;2199.285232299493;2199.4061603367163;2201.119552179125;2209.8895055247335;15500.379984800595;16730.414435494822;16776.83223167765;16826.24281907305;17244.879082859574;2035.0740413111998;2069.730934978443;2114.7356580427445;2118.195085867376;2120.215520257499;121052.39473802663;152109;152179;154057;175089;9425.90574094264;11737.237079589799;11970.700732481757;12037.277763334228;12193.915038462037;15995.360185592446;16089.919550402174;16105.597360066093;16113.597160071093;16135.354585816436;8603.655853765904;8925.285977121815;8928.508932008803;9095.727128186165;9120.726378208665;8602.268807151337;8612.612432440555;8656;8800.735977920673;8819.250363719155;6065.939340606626;6535.44448721864;7356.301151390717;7642.617869106522;7682.504460470473;2008.7689915659887;2126.9468263293543;2136.2523116908956;2140.957180856374;2296.1159953418055;854.9957250213834;889.8798662180652;895.5701263393607;902.8058967322057;950.8621249918723;18437.70936034465;18456;19681.11434985429;20310;20711.27510537154;878.5870640798814;900.4597241654955;945.3004776465503;961.6730311694058;963.5712108111823;8657.350698697623;11877.940610297066;13024.739505209844;13253.07228493996;13347.66630834237;629809;645592;651104;701418;716499;83318;122934;132508.67491325157;133639;158076.20961895346;1103.3545375955157;1202.7714734200474;1244.6079484962208;1250.0686988193854;1254.7051442910908;16013.439529616408;20278;20535.58928821445;20546.383608491768;20807.959602019535;63060;64861.72983945728;65175.34824651883;65703.67148164229;65965.34034659591;76221;90930.54534727284;93015.53492232495;93740.53129734307;94119.23523059001;6195.380461953769;8348.582570871553;8404.159584041792;8602.39783215169;8778.648794207378;10103.797924041331;13556.661083472993;13601.659958501117;13874.791878121634;14038.26176855024;11628.011619012479;11692.123090768397;12097.455614497192;12116.75766484683;12167.148299618942;9371.29715271371;11156.944215279196;11235.045021173291;11261.324320540794;11578.247413918069;11863.644090677293;11889.227200231957;12187.573434929735;12215.511379545074;12257.958073563846;7782.338501227459;8302.875456868273;8495.108013658724;8715.782105447415;8741.562921854009;11494.195406321478;11761.411929403666;11813.586524471602;11853.52585896589;11859.16985810985 16:10:47 INFO - PID 1913 | |3;mootools.html;1278.3735969375007;1430.2920054572987;1451.920144392058;1455.3960106555783;1459.6569806095572;10428.687139385811;10896.727581810448;10917.454127293624;10919;10938.453077346121;10558.366498010106;11002.50488728007;11012.449377531131;11018.173636977239;11019.118470522359;2453.950920981579;2498.9250322490266;2499.9250022499355;2501.7123030851503;2507.2352932355625;2052.620265250926;2336.684547586071;2404.2306461932103;2455.9631605525933;2457.8156638252112;2121.4802373418534;2142.7642959274467;2381.785639292463;2467.8025757939413;2476.616124500696;2223.710917580704;2251.5046689728233;2417.2385698504913;2420.1408499982604;2428;2377.7978871795917;2405.314485371678;2441.7314095449487;2445.963310550343;2448.179859746987;10884.346939183675;10994.34033957965;10998.560057597686;11001.669949901516;11011.559537618487;6170.7531698732;6412.93587064128;6416.967915160394;6421;6432.163818703585;3806.1816709407613;3978.403239514096;4172.332426811726;4180.561041090703;4187.581241875819;1221.4564518789143;1231.667449788561;1246.7880460321767;1257.5912828330738;1272.274803362084;15029.924850375788;15189.696206075816;15200.859935504881;15238.923805381013;15246.237688115662;5119.180931051014;5336.626436149432;5395.7841686332895;5397;5440.591955603346;11381.260218085798;11387.715307117223;11395.373254470918;11399.316041037564;11409.600663976888;6200.3799620037635;6303.905441418382;6317.778877739257;6319.6208227506495;6321.8735625288155;4679.181143299977;5776.393478684761;5827.912581311284;5984.042553191469;6001.639901605918;4120.1553681495225;4209.684273679486;4214.093969796447;4255.276602977439;4274.893127671833;1129.0797999630274;1248.3134276148082;1267.2776517385003;1285.5629086110766;1286.7104901397297;580.4188159058618;721.6572128239045;745.8769303065042;752.7177308509309;791.1890312429806;6366.904496432558;6406.583572067801;6449.3873082057125;6599.47204223661;6633.402993730491;563.3605857351935;591.1635036423413;594.9405059494103;598.850287428143;694.9200841903246;3766.472693822867;3970.3647416413237;4040.7979601019833;4098.200850834059;4112.876613701594;13271.402786874614;14088.859111408754;14102.647433814236;14115.153090814378;14143.151410915378;13995.230262335466;14001.22993235382;14018.719625607635;14021.579352619437;14033.368498417596;9965.35225210359;9995.150412214898;10084.092431681183;10104.595816167419;10231;6310.242770867525;6377.489800816016;6436.13112229843;6468.773592924225;6568.310327415553;12304.384780761106;12328.445219964944;12339.50641974311;12642.55751048709;12667.113302069136;11164.218504704593;12589.307588082744;12879.80680289815;12890.677733056373;12938.223706577259;8888.511131887808;8889.555522223993;8988.50563219003;9049.27605791522;9109.134632209929;9929.851052234368;10026.24803139782;10117.29178957466;10133.543990520297;10170.08469237772;8612.526311052952;8627.180417860292;8673.522956237468;8765.780855478664;8767.167119123673;8399.790005249917;8450.915490845013;8496.150384961455;8498.53258070812;8503.1921967413;7655.88516172269;7818.257265559762;7823.295903368724;7826.452148349788;7827.686892524235;5767.3367562729145;5847.590668653153;5949.553783466344;6011.158437818796;6042.0332746760305;5806;5903.321118071305;5976;6162.445379915829;6202.162708034538 16:10:47 INFO - PID 1913 | |4;prototype.html;353.8885251145891;385.79360042377334;393.45899388341024;395.1366264711605;403.3647005965603;5612.775488980441;5938.910916336258;5970.283565972079;5988.341282458926;6028.8794224115545;5617.38208797032;5739.397363276858;5810.535157187425;5874.76500939963;5890.911636325459;3714.9257014859686;4477.955220447787;4490.281554951209;4499.257622492293;4519.5932366087045;4075.816588253536;4089.161721847029;4130.070734084822;4506.166359223538;4525.027119169378;4330.826766929319;4457.59881610655;4470.798814053375;4471.351654010166;4487.304467807478;3938.1139243670086;4420.07178492517;4436.268015777393;4447.3773671685985;4481.341903495711;3922.9803850980848;3940.6059394060653;3940.8817735467983;3976.16500534888;3991.600839916014;5467.17992301154;5659.773609055633;5755.971220143914;5782.248307720011;5782.45066718661;4406.581374769392;5274.4397531666355;5299.5230429261555;5362.946370536322;5366.248725178478;5175.896482070375;6324.683765811691;6329.132890813473;6338.87322253557;6350.618962862243;4347.456567929009;4444;4495.505494395614;4513.7066090704;4531.750753708545;6378.394052564999;6548.312427195171;6561.3438656134485;6575.309592492815;6598.307177746364;3850.4801851749653;4509.233430316854;4581.198290299184;4617.815287388534;4621.491635920046;4670.719756814602;4682.297655351656;4686.882827929261;4687.484376718558;4689.812407503661;4371.715838470489;4882.462024462283;4962.9007419851405;5111.386633603917;5306.893862122736;4878.390201224847;5445.646033007842;5626.7902400983985;6285.654289014148;6324.620522768557;1279.7248591552861;1520.9771853422208;1542.2520077762501;1597.297189236732;1605.75913612959;1096.2490693874722;1098.8791232964368;1114.7213196700825;1124.100719424457;1133.5465813674596;5881.676507792027;5882.294124705063;5893.351731309553;5901.527877769769;5923.970380148072;5127.897442051138;5336.546393556591;6369.0764839099;6448.065030570541;6454.870902581922;4647.442306923191;5855.788348369954;5905.232319798399;5949.702514874239;5955.553333500005;2791.0510426455103;2879.985600071986;3348.9330213395597;3392.3384939936477;3404.8253352593315;5194.844154675366;6666;6667.3997141115005;6683.7660681875905;6729.49528785343;5780.364159942403;6734.090897728842;6744.190697116377;6744.696488658022;6749.291324410964;5916.911246331309;6211.565190436626;6757.493188010821;6779.830504237335;6781.728730850809;6262.968685156545;6498.350164983463;6562.343765623399;6579.572327798678;6632.1709786276715;5939.16851640762;6339.080833279241;6448.967755161194;6494.545381823227;6513.902291465726;5043.653727049339;5209.843704688866;5478.643888147258;5702.315722113373;6663.800085997428;3782.527184101987;4279.496494550723;4290.377895205115;4308.827646894089;4314.137172565437;5722.4277572242445;6293.82162008183;6471.831936301392;6514.54398192122;6522.28254891971;5684.431556844283;6278.780242691484;6282.083964390905;6299.433051025429;6306.211723534559;5601.327840659147;5603.495685388335;5680.630759000652;5868.882622347443;6243.250809902841;5016.648834581544;5776.797812076557;6075.453209211192;6169.606716305924;6171.6297022178815;6288.245410550763;6291.96854015727;6293.653849038347;6294.527910406646;6305.337939516285;5049.091163590589;5927.140564618193;6046.637201767908;6112.297085835008;6135.233095863017 16:10:47 INFO - PID 1913 | |5;yui.html;805.7784109369925;807.4751411582474;822.6421506644614;828.0063923292047;834.8121672623661;14275.143491390523;15221.695566088672;15693.529194124167;15997.920010399961;16075;14493.5651930442;14599.708005839877;14764.704705905875;14764.852351476482;17236.310547578112;986.6793292180053;1010.7675234696025;1011.357787804742;1023.0894503891542;1024.160188645311;1020.275604320933;1020.7703266764955;1021.0096206679509;1021.2748948246754;1021.7496713305213;1072.7908057928707;1073.9140868730522;1074.1406874500408;1076.3272954403499;1076.4779082145153;1067.2849191042021;1069.8395240713917;1070.7911957168355;1071.3304184884407;1074.1567869222622;787.9290863822283;791.1811275330064;791.2957467853615;794.1700922535964;794.3367288314207;15185.620359490991;15854.60363490922;16279.83720162795;17021.744673829904;17075.57311067221;14724.263786810723;14726.558203253919;14931.626709332246;15010.399584016735;15153.999999999889;13165;13634.181949083088;13685.589432317047;13712.725745485035;13812.37844297009;3362.3947689415895;3416.385050690875;3427.485877118427;3430.1596108953217;3433.484977253407;106329.46835265851;109105;109687;112280.43859780648;112323.43838280838;7005.859882802315;11137.220394572465;11143.387113708663;11441.656750297505;11841.28952262867;22899.08403663868;22906.198283060014;23342.533149337258;24238.757612424004;25022.87488562582;7844.725434609762;7969.920300797034;8029.959850200712;8093.27160555541;8254.876176857351;4178.644815190682;4239.59723826236;4262.458803555428;4302.569743025735;4421.955780442218;4136.131412403429;4142.047329114261;4225.19721252961;4236.343366778093;4239.915201696011;874.2437791310471;909.1045320359441;922.4972390047441;928.7770934975557;929.8558723397884;566.289306919813;609.2780055634088;634.7905191286865;643.9259485159266;648.419664400359;9794.804103917882;9903.5305818001;9945.2187022654;9967.040864371802;10080.647177348757;601.3205078261624;601.9556070218168;612.5834432585796;646.2568046746186;651.2900937977627;4274.337477690963;4407.823687052482;4558.6581006423985;4635.675502714777;4645.488996210414;79761.80357294645;99206.50396748114;99604.5019774911;101105.49447252863;103271;64179;83218;85351;85602;87025.12974870297;58780;75498;76295;76422.61788691022;78533;8667.566621668766;9121;9309.120442131234;9328.486933218737;9548.18840398574;35363.646363536034;35461.82269088638;36676.26647466982;36805;38606.80696596499;63523;63809.68095159494;64493;65335.543575117226;67729;12654.959959885533;12689.302088385228;12782.552610658582;13019.088663793444;13066.869331306565;10794.622188223375;13270.601881943558;13315.600531984055;13596.796048059485;13611.523596674466;10616.813173207875;12609.558665446666;12650.873491265338;12787.808182877077;12898.67753306175;12834.55079072228;12950.352482375654;12987;13201.735965280832;13709.862901371258;12251.53668076985;12372.566960156351;12498.937505312415;12640.63074066342;12820.23078615286;7859.410544209093;8513.702020429255;9084;9130.119699127712;9342.912737614184;10198.541065651914;10577.259591828499;10620.415877126832;10645.201609879136;10690.305130166515 16:10:47 INFO - PID 1913 | __end_tp_report 16:10:47 INFO - PID 1913 | __start_cc_report 16:10:47 INFO - PID 1913 | _x_x_mozilla_cycle_collect,1969 16:10:47 INFO - PID 1913 | __end_cc_report 16:10:47 INFO - PID 1913 | __startTimestamp1491347447167__endTimestamp 16:10:47 INFO - PID 1913 | 16:10:47 INFO - PID 1913 | ------- Summary: start ------- 16:10:47 INFO - PID 1913 | Number of tests: 6 16:10:47 INFO - PID 1913 | 16:10:47 INFO - PID 1913 | [#0] dojo.html Cycles:165 Average:6036.56 Median:6327.56 stddev:741.76 (11.7%) stddev-sans-first:701.86 16:10:47 INFO - PID 1913 | Values: 2893.7 3433.3 3453.1 3471.9 3500.4 5932.0 6140.8 6164.6 6171.7 6176.1 6097.9 6135.0 6155.4 6201.3 6232.4 4657.4 4974.8 5326.4 5351.6 5383.1 5383.2 5436.5 5441.5 5446.3 5486.8 5206.6 5264.5 5273.4 5276.1 5401.1 5301.0 5308.8 5312.1 5317.3 5317.7 4330.8 5224.2 5263.4 5283.6 5285.4 6101.1 6101.7 6103.2 6116.8 6131.7 6386.1 6388.6 6392.9 6395.0 6405.3 6437.9 6454.5 6532.2 6544.0 6654.5 6288.7 6296.9 6303.4 6310.1 6312.0 6826.8 6835.1 6843.5 6843.9 6851.3 6300.5 6376.5 6388.3 6419.7 6430.8 6626.8 6627.3 6633.9 6634.9 6637.8 6504.3 6513.1 6526.8 6543.6 6552.6 6308.7 6380.6 6381.8 6440.4 6541.8 5241.7 5257.8 5293.3 5330.2 5335.4 4267.4 4477.7 4676.5 4681.5 4728.5 5968.1 6108.4 6267.1 6325.2 6327.7 5729.7 6516.2 6536.3 6552.0 6578.0 6299.8 6300.9 6302.6 6307.7 6324.9 5813.9 5836.5 5839.3 5842.3 5862.5 6579.6 6667.4 6678.9 6713.7 6797.7 6368.6 6446.0 6634.7 6671.2 6675.4 6690.9 6733.9 6753.0 6837.7 6853.3 6467.4 6491.5 6508.8 6511.4 6518.6 6370.7 6424.8 6520.3 6524.9 6655.9 6306.7 6386.5 6398.1 6398.2 6408.5 6376.9 6386.6 6409.2 6412.4 6415.4 5564.4 5992.3 6288.7 6318.2 6327.6 6331.1 6337.1 6339.7 6410.7 6414.1 6345.6 6347.6 6347.7 6349.6 6353.7 16:10:47 INFO - PID 1913 | 16:10:47 INFO - PID 1913 | [#1] ext.html Cycles:180 Average:53737.38 Median:35666.82 stddev:109340.89 (306.6%) stddev-sans-first:109575.14 16:10:47 INFO - PID 1913 | Values: 712.4 811.9 1124.9 1143.6 1143.6 38313.2 39022.0 39087.6 39093.6 39217.8 40989.6 41391.0 41516.2 41517.0 41547.6 6109.8 8578.9 8729.3 8730.9 8745.3 6178.4 8525.9 8685.4 8694.0 8717.3 8737.3 8751.7 8786.0 8788.3 8803.1 8684.0 8751.6 8773.5 8774.9 8776.3 8485.2 8548.4 8549.6 8555.7 8558.7 38941.8 39099.4 39225.8 39231.8 39261.8 3492.7 3532.2 3532.7 3534.9 3547.0 30974.8 30984.8 31029.8 31040.2 31054.9 3093.1 3107.8 3157.9 3159.6 3172.3 332264.0 333691.0 338224.0 338926.0 339177.3 41186.4 41732.8 41859.8 49834.8 49962.3 42900.8 43065.4 43094.4 43244.0 52003.5 14144.2 14526.8 14534.2 14584.0 14598.9 11793.0 11795.8 11801.4 11813.4 11816.7 11895.2 12938.6 12950.5 12962.5 13062.3 5609.7 5621.5 5659.4 5672.7 5672.9 962.5 971.2 973.8 977.3 987.3 23959.9 24034.7 24041.1 24056.7 24100.6 1059.6 1070.3 1076.8 1084.2 1106.6 12003.4 12164.4 12168.2 12179.7 12227.1 448277.0 448619.0 449250.0 449324.0 450008.0 53936.0 54172.0 54203.5 54261.9 54308.7 1439.6 1448.5 1498.8 1502.9 1503.7 19210.0 23516.9 23549.4 23557.9 23630.5 38495.6 38571.6 38594.6 38630.2 38670.6 437760.0 439442.0 439844.0 440571.0 442327.0 26530.1 26664.4 31504.8 33842.7 34208.3 41925.4 42451.5 42770.8 43043.8 43379.3 27854.6 27941.6 29413.4 29823.0 29912.3 23855.6 26404.2 29515.0 29725.7 30328.4 34548.8 34985.8 35593.6 35740.0 35852.1 29157.1 29232.9 29935.9 30008.5 30258.0 21919.0 23841.5 23856.6 23954.2 23972.2 16:10:47 INFO - PID 1913 | 16:10:47 INFO - PID 1913 | [#2] jquery.html Cycles:180 Average:39045.00 Median:24493.77 stddev:112113.14 (457.7%) stddev-sans-first:112390.13 16:10:47 INFO - PID 1913 | Values: 419.0 428.1 432.8 436.9 449.4 20697.8 22055.0 22693.8 22952.5 22994.3 17147.6 20516.0 21697.8 22840.4 23113.3 6016.1 6237.5 6245.6 6253.6 6291.4 5754.8 6116.4 6188.0 6197.3 6201.9 5886.6 6141.7 6166.6 6168.9 6172.1 5384.2 5961.0 6007.5 6137.6 6147.4 5339.9 5346.6 5414.7 5417.6 5428.1 25874.2 25893.5 25947.5 25968.5 26129.3 2125.2 2199.3 2199.4 2201.1 2209.9 15500.4 16730.4 16776.8 16826.2 17244.9 2035.1 2069.7 2114.7 2118.2 2120.2 121052.4 152109.0 152179.0 154057.0 175089.0 9425.9 11737.2 11970.7 12037.3 12193.9 15995.4 16089.9 16105.6 16113.6 16135.4 8603.7 8925.3 8928.5 9095.7 9120.7 8602.3 8612.6 8656.0 8800.7 8819.3 6065.9 6535.4 7356.3 7642.6 7682.5 2008.8 2126.9 2136.3 2141.0 2296.1 855.0 889.9 895.6 902.8 950.9 18437.7 18456.0 19681.1 20310.0 20711.3 878.6 900.5 945.3 961.7 963.6 8657.4 11877.9 13024.7 13253.1 13347.7 629809.0 645592.0 651104.0 701418.0 716499.0 83318.0 122934.0 132508.7 133639.0 158076.2 1103.4 1202.8 1244.6 1250.1 1254.7 16013.4 20278.0 20535.6 20546.4 20808.0 63060.0 64861.7 65175.3 65703.7 65965.3 76221.0 90930.5 93015.5 93740.5 94119.2 6195.4 8348.6 8404.2 8602.4 8778.6 10103.8 13556.7 13601.7 13874.8 14038.3 11628.0 11692.1 12097.5 12116.8 12167.1 9371.3 11156.9 11235.0 11261.3 11578.2 11863.6 11889.2 12187.6 12215.5 12258.0 7782.3 8302.9 8495.1 8715.8 8741.6 11494.2 11761.4 11813.6 11853.5 11859.2 16:10:47 INFO - PID 1913 | 16:10:47 INFO - PID 1913 | [#3] mootools.html Cycles:180 Average:6784.27 Median:2504.47 stddev:4212.31 (168.2%) stddev-sans-first:4203.81 16:10:47 INFO - PID 1913 | Values: 1278.4 1430.3 1451.9 1455.4 1459.7 10428.7 10896.7 10917.5 10919.0 10938.5 10558.4 11002.5 11012.4 11018.2 11019.1 2454.0 2498.9 2499.9 2501.7 2507.2 2052.6 2336.7 2404.2 2456.0 2457.8 2121.5 2142.8 2381.8 2467.8 2476.6 2223.7 2251.5 2417.2 2420.1 2428.0 2377.8 2405.3 2441.7 2446.0 2448.2 10884.3 10994.3 10998.6 11001.7 11011.6 6170.8 6412.9 6417.0 6421.0 6432.2 3806.2 3978.4 4172.3 4180.6 4187.6 1221.5 1231.7 1246.8 1257.6 1272.3 15029.9 15189.7 15200.9 15238.9 15246.2 5119.2 5336.6 5395.8 5397.0 5440.6 11381.3 11387.7 11395.4 11399.3 11409.6 6200.4 6303.9 6317.8 6319.6 6321.9 4679.2 5776.4 5827.9 5984.0 6001.6 4120.2 4209.7 4214.1 4255.3 4274.9 1129.1 1248.3 1267.3 1285.6 1286.7 580.4 721.7 745.9 752.7 791.2 6366.9 6406.6 6449.4 6599.5 6633.4 563.4 591.2 594.9 598.9 694.9 3766.5 3970.4 4040.8 4098.2 4112.9 13271.4 14088.9 14102.6 14115.2 14143.2 13995.2 14001.2 14018.7 14021.6 14033.4 9965.4 9995.2 10084.1 10104.6 10231.0 6310.2 6377.5 6436.1 6468.8 6568.3 12304.4 12328.4 12339.5 12642.6 12667.1 11164.2 12589.3 12879.8 12890.7 12938.2 8888.5 8889.6 8988.5 9049.3 9109.1 9929.9 10026.2 10117.3 10133.5 10170.1 8612.5 8627.2 8673.5 8765.8 8767.2 8399.8 8450.9 8496.2 8498.5 8503.2 7655.9 7818.3 7823.3 7826.5 7827.7 5767.3 5847.6 5949.6 6011.2 6042.0 5806.0 5903.3 5976.0 6162.4 6202.2 16:10:47 INFO - PID 1913 | 16:10:47 INFO - PID 1913 | [#4] prototype.html Cycles:180 Average:5077.15 Median:5615.08 stddev:1543.57 (27.5%) stddev-sans-first:1506.64 16:10:47 INFO - PID 1913 | Values: 353.9 385.8 393.5 395.1 403.4 5612.8 5938.9 5970.3 5988.3 6028.9 5617.4 5739.4 5810.5 5874.8 5890.9 3714.9 4478.0 4490.3 4499.3 4519.6 4075.8 4089.2 4130.1 4506.2 4525.0 4330.8 4457.6 4470.8 4471.4 4487.3 3938.1 4420.1 4436.3 4447.4 4481.3 3923.0 3940.6 3940.9 3976.2 3991.6 5467.2 5659.8 5756.0 5782.2 5782.5 4406.6 5274.4 5299.5 5362.9 5366.2 5175.9 6324.7 6329.1 6338.9 6350.6 4347.5 4444.0 4495.5 4513.7 4531.8 6378.4 6548.3 6561.3 6575.3 6598.3 3850.5 4509.2 4581.2 4617.8 4621.5 4670.7 4682.3 4686.9 4687.5 4689.8 4371.7 4882.5 4962.9 5111.4 5306.9 4878.4 5445.6 5626.8 6285.7 6324.6 1279.7 1521.0 1542.3 1597.3 1605.8 1096.2 1098.9 1114.7 1124.1 1133.5 5881.7 5882.3 5893.4 5901.5 5924.0 5127.9 5336.5 6369.1 6448.1 6454.9 4647.4 5855.8 5905.2 5949.7 5955.6 2791.1 2880.0 3348.9 3392.3 3404.8 5194.8 6666.0 6667.4 6683.8 6729.5 5780.4 6734.1 6744.2 6744.7 6749.3 5916.9 6211.6 6757.5 6779.8 6781.7 6263.0 6498.4 6562.3 6579.6 6632.2 5939.2 6339.1 6449.0 6494.5 6513.9 5043.7 5209.8 5478.6 5702.3 6663.8 3782.5 4279.5 4290.4 4308.8 4314.1 5722.4 6293.8 6471.8 6514.5 6522.3 5684.4 6278.8 6282.1 6299.4 6306.2 5601.3 5603.5 5680.6 5868.9 6243.3 5016.6 5776.8 6075.5 6169.6 6171.6 6288.2 6292.0 6293.7 6294.5 6305.3 5049.1 5927.1 6046.6 6112.3 6135.2 16:10:47 INFO - PID 1913 | 16:10:47 INFO - PID 1913 | [#5] yui.html Cycles:180 Average:19623.97 Median:14192.63 stddev:28157.58 (198.4%) stddev-sans-first:28201.11 16:10:47 INFO - PID 1913 | Values: 805.8 807.5 822.6 828.0 834.8 14275.1 15221.7 15693.5 15997.9 16075.0 14493.6 14599.7 14764.7 14764.9 17236.3 986.7 1010.8 1011.4 1023.1 1024.2 1020.3 1020.8 1021.0 1021.3 1021.7 1072.8 1073.9 1074.1 1076.3 1076.5 1067.3 1069.8 1070.8 1071.3 1074.2 787.9 791.2 791.3 794.2 794.3 15185.6 15854.6 16279.8 17021.7 17075.6 14724.3 14726.6 14931.6 15010.4 15154.0 13165.0 13634.2 13685.6 13712.7 13812.4 3362.4 3416.4 3427.5 3430.2 3433.5 106329.5 109105.0 109687.0 112280.4 112323.4 7005.9 11137.2 11143.4 11441.7 11841.3 22899.1 22906.2 23342.5 24238.8 25022.9 7844.7 7969.9 8030.0 8093.3 8254.9 4178.6 4239.6 4262.5 4302.6 4422.0 4136.1 4142.0 4225.2 4236.3 4239.9 874.2 909.1 922.5 928.8 929.9 566.3 609.3 634.8 643.9 648.4 9794.8 9903.5 9945.2 9967.0 10080.6 601.3 602.0 612.6 646.3 651.3 4274.3 4407.8 4558.7 4635.7 4645.5 79761.8 99206.5 99604.5 101105.5 103271.0 64179.0 83218.0 85351.0 85602.0 87025.1 58780.0 75498.0 76295.0 76422.6 78533.0 8667.6 9121.0 9309.1 9328.5 9548.2 35363.6 35461.8 36676.3 36805.0 38606.8 63523.0 63809.7 64493.0 65335.5 67729.0 12655.0 12689.3 12782.6 13019.1 13066.9 10794.6 13270.6 13315.6 13596.8 13611.5 10616.8 12609.6 12650.9 12787.8 12898.7 12834.6 12950.4 12987.0 13201.7 13709.9 12251.5 12372.6 12498.9 12640.6 12820.2 7859.4 8513.7 9084.0 9130.1 9342.9 10198.5 10577.3 10620.4 10645.2 10690.3 16:10:47 INFO - PID 1913 | -------- Summary: end -------- 16:10:47 INFO - PID 1913 | 16:10:47 INFO - TEST-INFO | 1913: exit 0 16:10:47 INFO - TEST-OK | dromaeo_css | took 1120526ms 16:10:47 INFO - TEST-START | kraken 16:10:47 INFO - operating with platform_type : mac_ 16:10:47 INFO - Initialising browser for kraken test... 16:10:47 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmp5P7yOa/profile http://localhost:56708/getInfo.html) 16:10:50 INFO - PID 1915 | __metrics Screen width/height:1600/1200 16:10:50 INFO - PID 1915 | colorDepth:24 16:10:50 INFO - PID 1915 | Browser inner width/height: 1024/689 16:10:50 INFO - PID 1915 | __metrics 16:10:50 INFO - PID 1915 | 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" 16:10:50 INFO - PID 1915 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:10:51 INFO - TEST-INFO | 1915: exit 0 16:10:51 INFO - Browser initialized. 16:10:51 INFO - Running cycle 1/1 for kraken test... 16:10:51 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmp5P7yOa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:04 INFO - PID 1916 | RSS: Main: 231256064 16:11:04 INFO - PID 1916 | 16:11:07 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56708/tests/kraken/driver.html?testName=audio-beat-detection) 16:11:08 INFO - PID 1916 | RSS: Main: 292392960 16:11:08 INFO - PID 1916 | 16:11:10 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56708/tests/kraken/driver.html?testName=audio-dft) 16:11:11 INFO - PID 1916 | RSS: Main: 296194048 16:11:11 INFO - PID 1916 | 16:11:15 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56708/tests/kraken/driver.html?testName=audio-fft) 16:11:15 INFO - PID 1916 | RSS: Main: 388321280 16:11:15 INFO - PID 1916 | 16:11:18 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56708/tests/kraken/driver.html?testName=audio-oscillator) 16:11:18 INFO - PID 1916 | RSS: Main: 291110912 16:11:18 INFO - PID 1916 | 16:11:20 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56708/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:11:21 INFO - PID 1916 | RSS: Main: 293281792 16:11:21 INFO - PID 1916 | 16:11:24 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56708/tests/kraken/driver.html?testName=imaging-darkroom) 16:11:25 INFO - PID 1916 | RSS: Main: 334987264 16:11:25 INFO - PID 1916 | 16:11:28 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56708/tests/kraken/driver.html?testName=imaging-desaturate) 16:11:29 INFO - PID 1916 | RSS: Main: 344473600 16:11:29 INFO - PID 1916 | 16:11:32 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56708/tests/kraken/driver.html?testName=json-parse-financial) 16:11:32 INFO - PID 1916 | RSS: Main: 349188096 16:11:32 INFO - PID 1916 | 16:11:34 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56708/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:11:35 INFO - PID 1916 | RSS: Main: 309862400 16:11:35 INFO - PID 1916 | 16:11:36 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56708/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:11:37 INFO - PID 1916 | RSS: Main: 313708544 16:11:37 INFO - PID 1916 | 16:11:40 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56708/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:11:40 INFO - PID 1916 | RSS: Main: 315752448 16:11:40 INFO - PID 1916 | 16:11:43 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56708/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:11:44 INFO - PID 1916 | RSS: Main: 332419072 16:11:44 INFO - PID 1916 | 16:11:47 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56708/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:11:48 INFO - PID 1916 | RSS: Main: 326737920 16:11:48 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:56708/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56708/tests/kraken/driver.html?testName=ai-astar) 16:11:50 INFO - PID 1916 | RSS: Main: 389550080 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | __start_tp_report 16:11:50 INFO - PID 1916 | _x_x_mozilla_page_load 16:11:50 INFO - PID 1916 | _x_x_mozilla_page_load_details 16:11:50 INFO - PID 1916 | |i|pagename|runs| 16:11:50 INFO - PID 1916 | |0;ai-astar;110;122;126;179;170;124;123;122;122;123 16:11:50 INFO - PID 1916 | |1;audio-beat-detection;159;122;118;163;116;125;117;114;142;118 16:11:50 INFO - PID 1916 | |2;audio-dft;214;216;219;298;218;217;217;210;209;214 16:11:50 INFO - PID 1916 | |3;audio-fft;81;62;61;97;71;64;63;65;109;88 16:11:50 INFO - PID 1916 | |4;audio-oscillator;94;75;96;75;76;76;89;72;76;90 16:11:50 INFO - PID 1916 | |5;imaging-gaussian-blur;92;92;92;91;90;90;95;91;92;96 16:11:50 INFO - PID 1916 | |6;imaging-darkroom;101;99;131;144;100;99;101;100;97;100 16:11:50 INFO - PID 1916 | |7;imaging-desaturate;101;134;146;101;101;105;102;101;102;95 16:11:50 INFO - PID 1916 | |8;json-parse-financial;48;51;50;51;52;51;51;52;52;52 16:11:50 INFO - PID 1916 | |9;json-stringify-tinderbox;59;67;70;71;67;66;63;66;65;65 16:11:50 INFO - PID 1916 | |10;stanford-crypto-aes;104;117;117;90;92;89;92;87;90;92 16:11:50 INFO - PID 1916 | |11;stanford-crypto-ccm;151;153;151;150;151;158;163;166;201;200 16:11:50 INFO - PID 1916 | |12;stanford-crypto-pbkdf2;199;210;210;206;201;200;202;199;202;201 16:11:50 INFO - PID 1916 | |13;stanford-crypto-sha256-iterative;73;71;63;72;110;72;80;101;96;105 16:11:50 INFO - PID 1916 | __end_tp_report 16:11:50 INFO - PID 1916 | __start_cc_report 16:11:50 INFO - PID 1916 | _x_x_mozilla_cycle_collect,2114 16:11:50 INFO - PID 1916 | __end_cc_report 16:11:50 INFO - PID 1916 | __startTimestamp1491347510820__endTimestamp 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | ------- Summary: start ------- 16:11:50 INFO - PID 1916 | Number of tests: 14 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#0] ai-astar Cycles:10 Average:132.10 Median:123.50 stddev:22.85 (18.5%) stddev-sans-first:22.79 16:11:50 INFO - PID 1916 | Values: 110.0 122.0 126.0 179.0 170.0 124.0 123.0 122.0 122.0 123.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:123.50 stddev:18.46 (15.0%) stddev-sans-first:16.18 16:11:50 INFO - PID 1916 | Values: 159.0 122.0 118.0 163.0 116.0 125.0 117.0 114.0 142.0 118.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#2] audio-dft Cycles:10 Average:223.20 Median:217.00 stddev:26.49 (12.2%) stddev-sans-first:27.88 16:11:50 INFO - PID 1916 | Values: 214.0 216.0 219.0 298.0 218.0 217.0 217.0 210.0 209.0 214.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#3] audio-fft Cycles:10 Average:76.10 Median:68.00 stddev:16.93 (24.9%) stddev-sans-first:17.86 16:11:50 INFO - PID 1916 | Values: 81.0 62.0 61.0 97.0 71.0 64.0 63.0 65.0 109.0 88.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:82.50 stddev:9.18 (11.1%) stddev-sans-first:8.63 16:11:50 INFO - PID 1916 | Values: 94.0 75.0 96.0 75.0 76.0 76.0 89.0 72.0 76.0 90.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:92.00 stddev:1.97 (2.1%) stddev-sans-first:2.09 16:11:50 INFO - PID 1916 | Values: 92.0 92.0 92.0 91.0 90.0 90.0 95.0 91.0 92.0 96.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#6] imaging-darkroom Cycles:10 Average:107.20 Median:137.50 stddev:16.30 (11.9%) stddev-sans-first:17.14 16:11:50 INFO - PID 1916 | Values: 101.0 99.0 131.0 144.0 100.0 99.0 101.0 100.0 97.0 100.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#7] imaging-desaturate Cycles:10 Average:108.80 Median:103.50 stddev:16.86 (16.3%) stddev-sans-first:17.65 16:11:50 INFO - PID 1916 | Values: 101.0 134.0 146.0 101.0 101.0 105.0 102.0 101.0 102.0 95.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.50 stddev:1.25 (2.4%) stddev-sans-first:0.71 16:11:50 INFO - PID 1916 | Values: 48.0 51.0 50.0 51.0 52.0 51.0 51.0 52.0 52.0 52.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.50 stddev:3.38 (5.1%) stddev-sans-first:2.50 16:11:50 INFO - PID 1916 | Values: 59.0 67.0 70.0 71.0 67.0 66.0 63.0 66.0 65.0 65.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#10] stanford-crypto-aes Cycles:10 Average:97.00 Median:90.00 stddev:11.48 (12.8%) stddev-sans-first:11.89 16:11:50 INFO - PID 1916 | Values: 104.0 117.0 117.0 90.0 92.0 89.0 92.0 87.0 90.0 92.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#11] stanford-crypto-ccm Cycles:10 Average:164.40 Median:160.50 stddev:19.80 (12.3%) stddev-sans-first:20.40 16:11:50 INFO - PID 1916 | Values: 151.0 153.0 151.0 150.0 151.0 158.0 163.0 166.0 201.0 200.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.00 Median:202.00 stddev:4.19 (2.1%) stddev-sans-first:4.19 16:11:50 INFO - PID 1916 | Values: 199.0 210.0 210.0 206.0 201.0 200.0 202.0 199.0 202.0 201.0 16:11:50 INFO - PID 1916 | 16:11:50 INFO - PID 1916 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.30 Median:72.00 stddev:16.94 (23.5%) stddev-sans-first:17.47 16:11:50 INFO - PID 1916 | Values: 73.0 71.0 63.0 72.0 110.0 72.0 80.0 101.0 96.0 105.0 16:11:50 INFO - PID 1916 | -------- Summary: end -------- 16:11:50 INFO - PID 1916 | 16:11:51 INFO - TEST-INFO | 1916: exit 0 16:11:52 INFO - TEST-OK | kraken | took 64164ms 16:11:52 INFO - SUITE-END | took 1184s 16:11:52 INFO - Completed test suite (00:19:45) 16:11:52 INFO - javascript benchmark 16:11:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7625.892298606042, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2893.6527616686, 3433.3133373325327, 3453.067671728634, 3471.895843124704, 3500.352434799562, 5932.0, 6140.7850725224625, 6164.6301221926715, 6171.660558669277, 6176.073588961653, 6097.917917222698, 6135.0, 6155.415235552616, 6201.28685201202, 6232.407921247485, 4657.371254880582, 4974.830914735035, 5326.440723724011, 5351.625386222966, 5383.057964856154, 5383.192521121824, 5436.537894278991, 5441.455854414547, 5446.2919820423285, 5486.780528778844, 5206.583473322125, 5264.52619264268, 5273.367195936474, 5276.050310944029, 5401.135818269097, 5300.992811365828, 5308.761105750251, 5312.123499622558, 5317.255584218213, 5317.73411329432, 4330.783460826946, 5224.164133738622, 5263.394709608406, 5283.577313814885, 5285.365756109253, 6101.0848372744, 6101.664408457533, 6103.176071230371, 6116.816495505142, 6131.662758548278, 6386.10594516768, 6388.64862432566, 6392.872142557169, 6395.0, 6405.29541750407, 6437.935620643827, 6454.4513716333695, 6532.248791389004, 6543.967280163569, 6654.500912431587, 6288.717007734663, 6296.9370306296805, 6303.401176888188, 6310.0850376695125, 6312.021636646327, 6826.761063362758, 6835.14560679905, 6843.520953533205, 6843.8973415398805, 6851.314868513208, 6300.495960323163, 6376.489880809617, 6388.297287298394, 6419.743210271537, 6430.80707578764, 6626.768063117768, 6627.271000189975, 6633.86732265352, 6634.933650663528, 6637.834054148685, 6504.284528701839, 6513.088167656532, 6526.836829079311, 6543.607383556907, 6552.57408268461, 6308.684565771693, 6380.553361264667, 6381.776637817654, 6440.420362167428, 6541.803745887631, 5241.73791310433, 5257.78968841242, 5293.258943747878, 5330.200469929541, 5335.413104558495, 4267.4025636410925, 4477.686561940633, 4676.462206846189, 4681.485036645966, 4728.4562275338085, 5968.134620480006, 6108.410620383957, 6267.091271765568, 6325.240971083499, 6327.746890124436, 5729.684867332337, 6516.218053833571, 6536.281009088996, 6551.95168772994, 6577.967110164419, 6299.842503937347, 6300.873982520324, 6302.558820882494, 6307.684615769193, 6324.936750632435, 5813.94186058134, 5836.533077353802, 5839.270091238595, 5842.270663939707, 5862.472377486047, 6579.605223686594, 6667.366600172976, 6678.933210668026, 6713.731450742111, 6797.66011699423, 6368.554201205871, 6445.967770161119, 6634.701438435185, 6671.166104236971, 6675.365840245169, 6690.866182676416, 6733.898991515033, 6752.966235168793, 6837.726490940307, 6853.314668533107, 6467.417932386108, 6491.513136514686, 6508.837279068061, 6511.446527045254, 6518.57629254097, 6370.713317900798, 6424.775132870327, 6520.250171230369, 6524.869502610016, 6655.866882662416, 6306.716197771019, 6386.489080873612, 6398.13625160616, 6398.168238129014, 6408.519361048034, 6376.936230637634, 6386.61680299165, 6409.198850143732, 6412.422881940648, 6415.422611964946, 5564.443555644403, 5992.280926288873, 6288.65412402304, 6318.241810982711, 6327.557071004985, 6331.0819931110655, 6337.081123237197, 6339.683015849097, 6410.743570257324, 6414.069959855888, 6345.587536810092, 6347.555671103163, 6347.650879201688, 6349.587276826992, 6353.714082866374], "value": 5983.37534049252, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.4193782067614, 811.8701007838747, 1124.8931351521603, 1143.5997400909678, 1143.6454699043302, 38313.23373532527, 39022.024449388744, 39087.60912390882, 39093.60906390935, 39217.80391098041, 40989.59010409895, 41391.0, 41516.16967660645, 41517.0, 41547.58452415475, 6109.816705498843, 8578.914210857905, 8729.301655867515, 8730.86903696442, 8745.256653184455, 6178.382161783808, 8525.914740852606, 8685.392022558424, 8693.95653021734, 8717.346199035097, 8737.344699147558, 8751.693690720826, 8786.0, 8788.296936245117, 8803.119688031209, 8684.044755076937, 8751.606177722018, 8773.473591584525, 8774.868376974318, 8776.254018408443, 8485.236328730443, 8548.444351117158, 8549.615267312924, 8555.657773689107, 8558.743237702878, 38941.80529097365, 39099.41350879739, 39225.80387098046, 39231.80384098061, 39261.80369098165, 3492.703120234783, 3532.1699400640828, 3532.717382609385, 3534.9469757953652, 3547.0, 30974.845125774453, 30984.845075774476, 31029.8448507756, 31040.223994400094, 31054.91307804216, 3093.1184612385364, 3107.829069401182, 3157.8894738684035, 3159.636641786201, 3172.333809899924, 332264.0, 333691.0, 338224.0, 338926.0, 339177.3041134803, 41186.38220426696, 41732.79133604313, 41859.7907010463, 49834.75082624564, 49962.250566241535, 42900.78549607232, 43065.35401968973, 43094.353584696255, 43244.0, 52003.47996520062, 14144.222067786164, 14526.782098268535, 14534.200618965848, 14584.0, 14598.854011459962, 11793.0, 11795.764084718257, 11801.409929503663, 11813.40932953349, 11816.704582385337, 11895.226810257305, 12938.611841644766, 12950.546730864564, 12962.481500739865, 13062.34688265602, 5609.691466969356, 5621.4659607337235, 5659.405762394889, 5672.688002159921, 5672.943270567324, 962.4706411473801, 971.1745016735719, 973.8301827551276, 977.3158788847693, 987.2792861211356, 23959.864287885026, 24034.66470978786, 24041.0534933409, 24056.679190982464, 24100.638490422658, 1059.645018918671, 1070.277562645227, 1076.8169411200115, 1084.1976937066513, 1106.623747925709, 12003.399830008639, 12164.39178041112, 12168.209066410654, 12179.695507612381, 12227.0829687772, 448277.0, 448619.0, 449250.0, 449324.0, 450008.0, 53936.0, 54172.0, 54203.45796541984, 54261.914761704546, 54308.728456357465, 1439.5825210688993, 1448.478547722819, 1498.7901693762883, 1502.8797696184279, 1503.6992601479749, 19210.0, 23516.882415587814, 23549.411264718517, 23557.882210588836, 23630.527389452458, 38495.615043849204, 38571.614283856805, 38594.6140538591, 38630.22739545249, 38670.6132938667, 437760.0, 439442.0, 439844.0, 440571.0, 442327.0, 26530.071447499246, 26664.40251321071, 31504.842475787475, 33842.66157338395, 34208.315833683686, 41925.371119432624, 42451.51419700296, 42770.78614606907, 43043.7847810759, 43379.349309759746, 27854.58218126689, 27941.580876286465, 29413.411731765675, 29823.0, 29912.25219369533, 23855.642165367186, 26404.20787376382, 29515.0, 29725.702742972295, 30328.393432130793, 34548.82725586456, 34985.82507087448, 35593.64406355903, 35740.0, 35852.10369740777, 29157.125286241448, 29232.853835730686, 29935.850320749127, 30008.549871751504, 30258.0, 21919.0, 23841.52316953686, 23856.64215036741, 23954.161604343764, 23972.160974365812], "value": 17715.917109225706, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.96582095876505, 428.13730333378265, 432.76846886915496, 436.9191699535586, 449.438202247191, 20697.793022069793, 22055.0, 22693.77306226937, 22952.540949181028, 22994.310170694866, 17147.571310717238, 20515.97420128991, 21697.80662063586, 22840.428989275275, 23113.30660080196, 6016.127661489081, 6237.500999920018, 6245.562810603237, 6253.593516421441, 6291.36424529621, 5754.827355179351, 6116.388361163892, 6187.969060154693, 6197.287311959115, 6201.906971395432, 5886.558508111887, 6141.723622436979, 6166.568340216164, 6168.876622467548, 6172.135900973867, 5384.165454354581, 5961.046232602785, 6007.4893634041155, 6137.570370074097, 6147.415995480422, 5339.866503337409, 5346.572274218053, 5414.675119492804, 5417.566594672456, 5428.077226871442, 25874.22377328683, 25893.482130357286, 25947.481050378887, 25968.480630387476, 26129.346766330804, 2125.171183238538, 2199.285232299493, 2199.4061603367163, 2201.119552179125, 2209.8895055247335, 15500.379984800595, 16730.414435494822, 16776.83223167765, 16826.24281907305, 17244.879082859574, 2035.0740413111998, 2069.730934978443, 2114.7356580427445, 2118.195085867376, 2120.215520257499, 121052.39473802663, 152109.0, 152179.0, 154057.0, 175089.0, 9425.90574094264, 11737.237079589799, 11970.700732481757, 12037.277763334228, 12193.915038462037, 15995.360185592446, 16089.919550402174, 16105.597360066093, 16113.597160071093, 16135.354585816436, 8603.655853765904, 8925.285977121815, 8928.508932008803, 9095.727128186165, 9120.726378208665, 8602.268807151337, 8612.612432440555, 8656.0, 8800.735977920673, 8819.250363719155, 6065.939340606626, 6535.44448721864, 7356.301151390717, 7642.617869106522, 7682.504460470473, 2008.7689915659887, 2126.9468263293543, 2136.2523116908956, 2140.957180856374, 2296.1159953418055, 854.9957250213834, 889.8798662180652, 895.5701263393607, 902.8058967322057, 950.8621249918723, 18437.70936034465, 18456.0, 19681.11434985429, 20310.0, 20711.27510537154, 878.5870640798814, 900.4597241654955, 945.3004776465503, 961.6730311694058, 963.5712108111823, 8657.350698697623, 11877.940610297066, 13024.739505209844, 13253.07228493996, 13347.66630834237, 629809.0, 645592.0, 651104.0, 701418.0, 716499.0, 83318.0, 122934.0, 132508.67491325157, 133639.0, 158076.20961895346, 1103.3545375955157, 1202.7714734200474, 1244.6079484962208, 1250.0686988193854, 1254.7051442910908, 16013.439529616408, 20278.0, 20535.58928821445, 20546.383608491768, 20807.959602019535, 63060.0, 64861.72983945728, 65175.34824651883, 65703.67148164229, 65965.34034659591, 76221.0, 90930.54534727284, 93015.53492232495, 93740.53129734307, 94119.23523059001, 6195.380461953769, 8348.582570871553, 8404.159584041792, 8602.39783215169, 8778.648794207378, 10103.797924041331, 13556.661083472993, 13601.659958501117, 13874.791878121634, 14038.26176855024, 11628.011619012479, 11692.123090768397, 12097.455614497192, 12116.75766484683, 12167.148299618942, 9371.29715271371, 11156.944215279196, 11235.045021173291, 11261.324320540794, 11578.247413918069, 11863.644090677293, 11889.227200231957, 12187.573434929735, 12215.511379545074, 12257.958073563846, 7782.338501227459, 8302.875456868273, 8495.108013658724, 8715.782105447415, 8741.562921854009, 11494.195406321478, 11761.411929403666, 11813.586524471602, 11853.52585896589, 11859.16985810985], "value": 10366.545917099267, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1278.3735969375007, 1430.2920054572987, 1451.920144392058, 1455.3960106555783, 1459.6569806095572, 10428.687139385811, 10896.727581810448, 10917.454127293624, 10919.0, 10938.453077346121, 10558.366498010106, 11002.50488728007, 11012.449377531131, 11018.173636977239, 11019.118470522359, 2453.950920981579, 2498.9250322490266, 2499.9250022499355, 2501.7123030851503, 2507.2352932355625, 2052.620265250926, 2336.684547586071, 2404.2306461932103, 2455.9631605525933, 2457.8156638252112, 2121.4802373418534, 2142.7642959274467, 2381.785639292463, 2467.8025757939413, 2476.616124500696, 2223.710917580704, 2251.5046689728233, 2417.2385698504913, 2420.1408499982604, 2428.0, 2377.7978871795917, 2405.314485371678, 2441.7314095449487, 2445.963310550343, 2448.179859746987, 10884.346939183675, 10994.34033957965, 10998.560057597686, 11001.669949901516, 11011.559537618487, 6170.7531698732, 6412.93587064128, 6416.967915160394, 6421.0, 6432.163818703585, 3806.1816709407613, 3978.403239514096, 4172.332426811726, 4180.561041090703, 4187.581241875819, 1221.4564518789143, 1231.667449788561, 1246.7880460321767, 1257.5912828330738, 1272.274803362084, 15029.924850375788, 15189.696206075816, 15200.859935504881, 15238.923805381013, 15246.237688115662, 5119.180931051014, 5336.626436149432, 5395.7841686332895, 5397.0, 5440.591955603346, 11381.260218085798, 11387.715307117223, 11395.373254470918, 11399.316041037564, 11409.600663976888, 6200.3799620037635, 6303.905441418382, 6317.778877739257, 6319.6208227506495, 6321.8735625288155, 4679.181143299977, 5776.393478684761, 5827.912581311284, 5984.042553191469, 6001.639901605918, 4120.1553681495225, 4209.684273679486, 4214.093969796447, 4255.276602977439, 4274.893127671833, 1129.0797999630274, 1248.3134276148082, 1267.2776517385003, 1285.5629086110766, 1286.7104901397297, 580.4188159058618, 721.6572128239045, 745.8769303065042, 752.7177308509309, 791.1890312429806, 6366.904496432558, 6406.583572067801, 6449.3873082057125, 6599.47204223661, 6633.402993730491, 563.3605857351935, 591.1635036423413, 594.9405059494103, 598.850287428143, 694.9200841903246, 3766.472693822867, 3970.3647416413237, 4040.7979601019833, 4098.200850834059, 4112.876613701594, 13271.402786874614, 14088.859111408754, 14102.647433814236, 14115.153090814378, 14143.151410915378, 13995.230262335466, 14001.22993235382, 14018.719625607635, 14021.579352619437, 14033.368498417596, 9965.35225210359, 9995.150412214898, 10084.092431681183, 10104.595816167419, 10231.0, 6310.242770867525, 6377.489800816016, 6436.13112229843, 6468.773592924225, 6568.310327415553, 12304.384780761106, 12328.445219964944, 12339.50641974311, 12642.55751048709, 12667.113302069136, 11164.218504704593, 12589.307588082744, 12879.80680289815, 12890.677733056373, 12938.223706577259, 8888.511131887808, 8889.555522223993, 8988.50563219003, 9049.27605791522, 9109.134632209929, 9929.851052234368, 10026.24803139782, 10117.29178957466, 10133.543990520297, 10170.08469237772, 8612.526311052952, 8627.180417860292, 8673.522956237468, 8765.780855478664, 8767.167119123673, 8399.790005249917, 8450.915490845013, 8496.150384961455, 8498.53258070812, 8503.1921967413, 7655.88516172269, 7818.257265559762, 7823.295903368724, 7826.452148349788, 7827.686892524235, 5767.3367562729145, 5847.590668653153, 5949.553783466344, 6011.158437818796, 6042.0332746760305, 5806.0, 5903.321118071305, 5976.0, 6162.445379915829, 6202.162708034538], "value": 5113.15817870204, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.8885251145891, 385.79360042377334, 393.45899388341024, 395.1366264711605, 403.3647005965603, 5612.775488980441, 5938.910916336258, 5970.283565972079, 5988.341282458926, 6028.8794224115545, 5617.38208797032, 5739.397363276858, 5810.535157187425, 5874.76500939963, 5890.911636325459, 3714.9257014859686, 4477.955220447787, 4490.281554951209, 4499.257622492293, 4519.5932366087045, 4075.816588253536, 4089.161721847029, 4130.070734084822, 4506.166359223538, 4525.027119169378, 4330.826766929319, 4457.59881610655, 4470.798814053375, 4471.351654010166, 4487.304467807478, 3938.1139243670086, 4420.07178492517, 4436.268015777393, 4447.3773671685985, 4481.341903495711, 3922.9803850980848, 3940.6059394060653, 3940.8817735467983, 3976.16500534888, 3991.600839916014, 5467.17992301154, 5659.773609055633, 5755.971220143914, 5782.248307720011, 5782.45066718661, 4406.581374769392, 5274.4397531666355, 5299.5230429261555, 5362.946370536322, 5366.248725178478, 5175.896482070375, 6324.683765811691, 6329.132890813473, 6338.87322253557, 6350.618962862243, 4347.456567929009, 4444.0, 4495.505494395614, 4513.7066090704, 4531.750753708545, 6378.394052564999, 6548.312427195171, 6561.3438656134485, 6575.309592492815, 6598.307177746364, 3850.4801851749653, 4509.233430316854, 4581.198290299184, 4617.815287388534, 4621.491635920046, 4670.719756814602, 4682.297655351656, 4686.882827929261, 4687.484376718558, 4689.812407503661, 4371.715838470489, 4882.462024462283, 4962.9007419851405, 5111.386633603917, 5306.893862122736, 4878.390201224847, 5445.646033007842, 5626.7902400983985, 6285.654289014148, 6324.620522768557, 1279.7248591552861, 1520.9771853422208, 1542.2520077762501, 1597.297189236732, 1605.75913612959, 1096.2490693874722, 1098.8791232964368, 1114.7213196700825, 1124.100719424457, 1133.5465813674596, 5881.676507792027, 5882.294124705063, 5893.351731309553, 5901.527877769769, 5923.970380148072, 5127.897442051138, 5336.546393556591, 6369.0764839099, 6448.065030570541, 6454.870902581922, 4647.442306923191, 5855.788348369954, 5905.232319798399, 5949.702514874239, 5955.553333500005, 2791.0510426455103, 2879.985600071986, 3348.9330213395597, 3392.3384939936477, 3404.8253352593315, 5194.844154675366, 6666.0, 6667.3997141115005, 6683.7660681875905, 6729.49528785343, 5780.364159942403, 6734.090897728842, 6744.190697116377, 6744.696488658022, 6749.291324410964, 5916.911246331309, 6211.565190436626, 6757.493188010821, 6779.830504237335, 6781.728730850809, 6262.968685156545, 6498.350164983463, 6562.343765623399, 6579.572327798678, 6632.1709786276715, 5939.16851640762, 6339.080833279241, 6448.967755161194, 6494.545381823227, 6513.902291465726, 5043.653727049339, 5209.843704688866, 5478.643888147258, 5702.315722113373, 6663.800085997428, 3782.527184101987, 4279.496494550723, 4290.377895205115, 4308.827646894089, 4314.137172565437, 5722.4277572242445, 6293.82162008183, 6471.831936301392, 6514.54398192122, 6522.28254891971, 5684.431556844283, 6278.780242691484, 6282.083964390905, 6299.433051025429, 6306.211723534559, 5601.327840659147, 5603.495685388335, 5680.630759000652, 5868.882622347443, 6243.250809902841, 5016.648834581544, 5776.797812076557, 6075.453209211192, 6169.606716305924, 6171.6297022178815, 6288.245410550763, 6291.96854015727, 6293.653849038347, 6294.527910406646, 6305.337939516285, 5049.091163590589, 5927.140564618193, 6046.637201767908, 6112.297085835008, 6135.233095863017], "value": 4608.50926129933, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.7784109369925, 807.4751411582474, 822.6421506644614, 828.0063923292047, 834.8121672623661, 14275.143491390523, 15221.695566088672, 15693.529194124167, 15997.920010399961, 16075.0, 14493.5651930442, 14599.708005839877, 14764.704705905875, 14764.852351476482, 17236.310547578112, 986.6793292180053, 1010.7675234696025, 1011.357787804742, 1023.0894503891542, 1024.160188645311, 1020.275604320933, 1020.7703266764955, 1021.0096206679509, 1021.2748948246754, 1021.7496713305213, 1072.7908057928707, 1073.9140868730522, 1074.1406874500408, 1076.3272954403499, 1076.4779082145153, 1067.2849191042021, 1069.8395240713917, 1070.7911957168355, 1071.3304184884407, 1074.1567869222622, 787.9290863822283, 791.1811275330064, 791.2957467853615, 794.1700922535964, 794.3367288314207, 15185.620359490991, 15854.60363490922, 16279.83720162795, 17021.744673829904, 17075.57311067221, 14724.263786810723, 14726.558203253919, 14931.626709332246, 15010.399584016735, 15153.999999999889, 13165.0, 13634.181949083088, 13685.589432317047, 13712.725745485035, 13812.37844297009, 3362.3947689415895, 3416.385050690875, 3427.485877118427, 3430.1596108953217, 3433.484977253407, 106329.46835265851, 109105.0, 109687.0, 112280.43859780648, 112323.43838280838, 7005.859882802315, 11137.220394572465, 11143.387113708663, 11441.656750297505, 11841.28952262867, 22899.08403663868, 22906.198283060014, 23342.533149337258, 24238.757612424004, 25022.87488562582, 7844.725434609762, 7969.920300797034, 8029.959850200712, 8093.27160555541, 8254.876176857351, 4178.644815190682, 4239.59723826236, 4262.458803555428, 4302.569743025735, 4421.955780442218, 4136.131412403429, 4142.047329114261, 4225.19721252961, 4236.343366778093, 4239.915201696011, 874.2437791310471, 909.1045320359441, 922.4972390047441, 928.7770934975557, 929.8558723397884, 566.289306919813, 609.2780055634088, 634.7905191286865, 643.9259485159266, 648.419664400359, 9794.804103917882, 9903.5305818001, 9945.2187022654, 9967.040864371802, 10080.647177348757, 601.3205078261624, 601.9556070218168, 612.5834432585796, 646.2568046746186, 651.2900937977627, 4274.337477690963, 4407.823687052482, 4558.6581006423985, 4635.675502714777, 4645.488996210414, 79761.80357294645, 99206.50396748114, 99604.5019774911, 101105.49447252863, 103271.0, 64179.0, 83218.0, 85351.0, 85602.0, 87025.12974870297, 58780.0, 75498.0, 76295.0, 76422.61788691022, 78533.0, 8667.566621668766, 9121.0, 9309.120442131234, 9328.486933218737, 9548.18840398574, 35363.646363536034, 35461.82269088638, 36676.26647466982, 36805.0, 38606.80696596499, 63523.0, 63809.68095159494, 64493.0, 65335.543575117226, 67729.0, 12654.959959885533, 12689.302088385228, 12782.552610658582, 13019.088663793444, 13066.869331306565, 10794.622188223375, 13270.601881943558, 13315.600531984055, 13596.796048059485, 13611.523596674466, 10616.813173207875, 12609.558665446666, 12650.873491265338, 12787.808182877077, 12898.67753306175, 12834.55079072228, 12950.352482375654, 12987.0, 13201.735965280832, 13709.862901371258, 12251.53668076985, 12372.566960156351, 12498.937505312415, 12640.63074066342, 12820.23078615286, 7859.410544209093, 8513.702020429255, 9084.0, 9130.119699127712, 9342.912737614184, 10198.541065651914, 10577.259591828499, 10620.415877126832, 10645.201609879136, 10690.305130166515], "value": 7594.80641829028, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1616.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 122.0, 126.0, 179.0, 170.0, 124.0, 123.0, 122.0, 122.0, 123.0], "value": 132.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 122.0, 118.0, 163.0, 116.0, 125.0, 117.0, 114.0, 142.0, 118.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 216.0, 219.0, 298.0, 218.0, 217.0, 217.0, 210.0, 209.0, 214.0], "value": 223.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 62.0, 61.0, 97.0, 71.0, 64.0, 63.0, 65.0, 109.0, 88.0], "value": 76.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 75.0, 96.0, 75.0, 76.0, 76.0, 89.0, 72.0, 76.0, 90.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 91.0, 90.0, 90.0, 95.0, 91.0, 92.0, 96.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 99.0, 131.0, 144.0, 100.0, 99.0, 101.0, 100.0, 97.0, 100.0], "value": 107.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 134.0, 146.0, 101.0, 101.0, 105.0, 102.0, 101.0, 102.0, 95.0], "value": 108.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 51.0, 50.0, 51.0, 52.0, 51.0, 51.0, 52.0, 52.0, 52.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 67.0, 70.0, 71.0, 67.0, 66.0, 63.0, 66.0, 65.0, 65.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 117.0, 90.0, 92.0, 89.0, 92.0, 87.0, 90.0, 92.0], "value": 97.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 151.0, 150.0, 151.0, 158.0, 163.0, 166.0, 201.0, 200.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 210.0, 210.0, 206.0, 201.0, 200.0, 202.0, 199.0, 202.0, 201.0], "value": 203.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 63.0, 72.0, 110.0, 72.0, 80.0, 101.0, 96.0, 105.0], "value": 84.3, "unit": "score"}]}]} 16:11:52 INFO - Return code: 0 16:11:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:11:52 ERROR - # TBPL SUCCESS # 16:11:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:11:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:11:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:11:52 INFO - 2017-04-04 16:11:52.570 screenresolution[1920:20806] starting screenresolution argv=screenresolution get 16:11:52 INFO - 2017-04-04 16:11:52.572 screenresolution[1920:20806] Display 0: 1600x1200x32@60 16:11:52 INFO - 2017-04-04 16:11:52.578 screenresolution[1921:20809] starting screenresolution argv=screenresolution list 16:11:52 INFO - Available Modes on Display 0 16:11:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:11:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:11:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:11:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:11:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:11:52 INFO - Intel Iris: 16:11:52 INFO - Chipset Model: Intel Iris 16:11:52 INFO - Type: GPU 16:11:52 INFO - Bus: Built-In 16:11:52 INFO - VRAM (Dynamic, Max): 1536 MB 16:11:52 INFO - Vendor: Intel (0x8086) 16:11:52 INFO - Device ID: 0x0a2e 16:11:52 INFO - Revision ID: 0x0009 16:11:52 INFO - Displays: 16:11:52 INFO - Display: 16:11:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:11:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:11:52 INFO - Display Serial Number: 1600x1200 60 16:11:52 INFO - Main Display: Yes 16:11:52 INFO - Mirror: Off 16:11:52 INFO - Online: Yes 16:11:52 INFO - Rotation: Supported 16:11:52 INFO - Return code: 0 16:11:52 INFO - Running post-action listener: _resource_record_post_action 16:11:52 INFO - [mozharness: 2017-04-04 23:11:52.812688Z] Finished run-tests step (success) 16:11:52 INFO - Running post-run listener: _resource_record_post_run 16:11:52 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 86016; Write bytes: 517373952; Read time: 2; Write time: 1651 16:11:52 INFO - TinderboxPrint: CPU usage
53.4% 16:11:52 INFO - TinderboxPrint: I/O read bytes / time
86,016 / 2 16:11:52 INFO - TinderboxPrint: I/O write bytes / time
517,373,952 / 1,651 16:11:52 INFO - TinderboxPrint: CPU idle
2,244.4 (46.5%) 16:11:52 INFO - TinderboxPrint: CPU system
964.6 (20.0%) 16:11:52 INFO - TinderboxPrint: CPU user
1,612.7 (33.4%) 16:11:52 INFO - TinderboxPrint: Swap in / out
206,368,768 / 0 16:11:52 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 193278464; Write bytes: 191115264; Read time: 14874; Write time: 352 16:11:52 INFO - run-tests - Wall time: 1186s; CPU: 53.0%; Read bytes: 86016; Write bytes: 309415936; Read time: 2; Write time: 1228 16:11:53 INFO - Running post-run listener: _upload_blobber_files 16:11:53 INFO - Blob upload gear active. 16:11:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:11:53 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 16:11:53 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:11:53 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:11:53 INFO - (blobuploader) - INFO - Open directory for files ... 16:11:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:11:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:11:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:54 INFO - (blobuploader) - INFO - Done attempting. 16:11:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:11:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:11:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:55 INFO - (blobuploader) - INFO - Done attempting. 16:11:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:11:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:11:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:56 INFO - (blobuploader) - INFO - Done attempting. 16:11:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:11:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:11:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:58 INFO - (blobuploader) - INFO - Done attempting. 16:11:58 INFO - (blobuploader) - INFO - Iteration through files over. 16:11:58 INFO - Return code: 0 16:11:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:11:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:11:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b1c4f4bf70c8ed9426b454ee57f2c759d00d5433d908fb6bd1c9ca0baef0ee88494f601af0c305f6efe482599edc638e5a60c7be07d747e06c6f746e62001f15", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c2fcd376ca2184f3ff5de2ab85d9ecd6e77a11e5a2c1a2a803620a04d6a1332426ccff3bef646fabeb95d0631235e5f6eed7ee078fe70358b5feec2a4cef9fd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fdecd4b3e9ab2294fd3c3bc24c2528ee8050ef1c57191a0a4106b7bdd78734c1de35aa5373af7df92386c49efe4bb2df1b7d58ef7d98fe0986a8d3be996077a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e8ea33006d8cb73b9b885355430a95454d4d0d65055b4e9dd5c8acc108eeb10bf3b545a5d72214b5ceaded33ba430c5fd60c1b7f2c5d20d83c4b53680a861e5e"} 16:11:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:11:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:11:58 INFO - Contents: 16:11:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b1c4f4bf70c8ed9426b454ee57f2c759d00d5433d908fb6bd1c9ca0baef0ee88494f601af0c305f6efe482599edc638e5a60c7be07d747e06c6f746e62001f15", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c2fcd376ca2184f3ff5de2ab85d9ecd6e77a11e5a2c1a2a803620a04d6a1332426ccff3bef646fabeb95d0631235e5f6eed7ee078fe70358b5feec2a4cef9fd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fdecd4b3e9ab2294fd3c3bc24c2528ee8050ef1c57191a0a4106b7bdd78734c1de35aa5373af7df92386c49efe4bb2df1b7d58ef7d98fe0986a8d3be996077a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e8ea33006d8cb73b9b885355430a95454d4d0d65055b4e9dd5c8acc108eeb10bf3b545a5d72214b5ceaded33ba430c5fd60c1b7f2c5d20d83c4b53680a861e5e"} 16:11:58 INFO - Running post-run listener: copy_logs_to_upload_dir 16:11:58 INFO - Copying logs to upload dir... 16:11:58 INFO - mkdir: /builds/slave/test/build/upload/logs 16:11:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1291.289756 ========= master_lag: 0.64 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2017-04-04 16:11:59.634303) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 16:11:59.638506) ========= 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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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/Pine/sha512/b1c4f4bf70c8ed9426b454ee57f2c759d00d5433d908fb6bd1c9ca0baef0ee88494f601af0c305f6efe482599edc638e5a60c7be07d747e06c6f746e62001f15", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c2fcd376ca2184f3ff5de2ab85d9ecd6e77a11e5a2c1a2a803620a04d6a1332426ccff3bef646fabeb95d0631235e5f6eed7ee078fe70358b5feec2a4cef9fd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fdecd4b3e9ab2294fd3c3bc24c2528ee8050ef1c57191a0a4106b7bdd78734c1de35aa5373af7df92386c49efe4bb2df1b7d58ef7d98fe0986a8d3be996077a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e8ea33006d8cb73b9b885355430a95454d4d0d65055b4e9dd5c8acc108eeb10bf3b545a5d72214b5ceaded33ba430c5fd60c1b7f2c5d20d83c4b53680a861e5e"} build_url:https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011220 build_url: 'https://queue.taskcluster.net/v1/task/bOqQS1OdSbesNXfuUa5hPQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b1c4f4bf70c8ed9426b454ee57f2c759d00d5433d908fb6bd1c9ca0baef0ee88494f601af0c305f6efe482599edc638e5a60c7be07d747e06c6f746e62001f15", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c2fcd376ca2184f3ff5de2ab85d9ecd6e77a11e5a2c1a2a803620a04d6a1332426ccff3bef646fabeb95d0631235e5f6eed7ee078fe70358b5feec2a4cef9fd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fdecd4b3e9ab2294fd3c3bc24c2528ee8050ef1c57191a0a4106b7bdd78734c1de35aa5373af7df92386c49efe4bb2df1b7d58ef7d98fe0986a8d3be996077a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e8ea33006d8cb73b9b885355430a95454d4d0d65055b4e9dd5c8acc108eeb10bf3b545a5d72214b5ceaded33ba430c5fd60c1b7f2c5d20d83c4b53680a861e5e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 16:11:59.676371) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 16:11:59.676723) ========= 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.1Vp9hgr4uC/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.BnhCxRCijH/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.005422 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 16:11:59.725201) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 16:11:59.725533) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 16:11:59.725829) ========= ========= Total master_lag: 0.78 =========