builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0106 starttime: 1491323764.42 results: success (0) buildid: 20170404074827 builduid: 46f3a5248b1d458dbc4b46a1096c5219 revision: a997fcc0a07f8ceff0b70a850123b9c34ab0b390 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:04.418494) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:04.418948) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:04.419712) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:04.446635) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:04.447017) ========= 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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 09:36:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 40.6M=0s 2017-04-04 09:36:04 (40.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098962 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:04.571700) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:04.572016) ========= 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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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.028328 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:04.622716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-04 09:36:04.623094) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a997fcc0a07f8ceff0b70a850123b9c34ab0b390 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a997fcc0a07f8ceff0b70a850123b9c34ab0b390 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4y57mS1Lk4/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.lz8WWrYXjf/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 09:36:04,729 truncating revision to first 12 chars 2017-04-04 09:36:04,729 Setting DEBUG logging. 2017-04-04 09:36:04,729 attempt 1/10 2017-04-04 09:36:04,729 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a997fcc0a07f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-04 09:36:06,722 unpacking tar archive at: mozilla-aurora-a997fcc0a07f/testing/mozharness/ program finished with exit code 0 elapsedTime=2.454340 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-04 09:36:07.100805) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:07.101166) ========= script_repo_revision: a997fcc0a07f8ceff0b70a850123b9c34ab0b390 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:07.101610) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:07.101882) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 09:36:07.121481) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-04 09:36:07.121752) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4y57mS1Lk4/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.lz8WWrYXjf/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 09:36:07 INFO - MultiFileLogger online at 20170404 09:36:07 in /builds/slave/test 09:36:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:36:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:07 INFO - {'append_to_log': False, 09:36:07 INFO - 'base_work_dir': '/builds/slave/test', 09:36:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:36:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:07 INFO - 'branch': 'Mozilla-Aurora', 09:36:07 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:07 INFO - 'config_files': ('talos/mac_config.py',), 09:36:07 INFO - 'default_actions': ('clobber', 09:36:07 INFO - 'read-buildbot-config', 09:36:07 INFO - 'download-and-extract', 09:36:07 INFO - 'populate-webroot', 09:36:07 INFO - 'create-virtualenv', 09:36:07 INFO - 'install', 09:36:07 INFO - 'run-tests'), 09:36:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:07 INFO - 'download_minidump_stackwalk': True, 09:36:07 INFO - 'download_symbols': 'ondemand', 09:36:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:07 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:07 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:07 INFO - 'gecko_profile': False, 09:36:07 INFO - 'gecko_profile_interval': 0, 09:36:07 INFO - 'installer_path': 'installer.exe', 09:36:07 INFO - 'log_level': 'info', 09:36:07 INFO - 'log_name': 'talos', 09:36:07 INFO - 'log_to_console': True, 09:36:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:36:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:36:07 INFO - 'opt_config_files': (), 09:36:07 INFO - 'pip_index': False, 09:36:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:07 INFO - 'cmd': ('bash', 09:36:07 INFO - '-c', 09:36:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:07 INFO - 'enabled': True, 09:36:07 INFO - 'halt_on_failure': False, 09:36:07 INFO - 'name': 'check_screen_resolution'},), 09:36:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:07 INFO - 'cmd': ('bash', 09:36:07 INFO - '-c', 09:36:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:07 INFO - 'enabled': True, 09:36:07 INFO - 'halt_on_failure': False, 09:36:07 INFO - 'name': 'check_screen_resolution'},), 09:36:07 INFO - 'run_cmd_checks_enabled': True, 09:36:07 INFO - 'suite': 'dromaeojs-e10s', 09:36:07 INFO - 'system_bits': '32', 09:36:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:07 INFO - 'title': 't-yosemite-r7-0106', 09:36:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:07 INFO - 'use_talos_json': True, 09:36:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:07 INFO - 'work_dir': 'build'} 09:36:07 INFO - [mozharness: 2017-04-04 16:36:07.342600Z] Running clobber step. 09:36:07 INFO - Running pre-action listener: _resource_record_pre_action 09:36:07 INFO - Running main action method: clobber 09:36:07 INFO - rmtree: /builds/slave/test/build 09:36:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:09 INFO - Running post-action listener: _resource_record_post_action 09:36:09 INFO - [mozharness: 2017-04-04 16:36:09.886018Z] Finished clobber step (success) 09:36:09 INFO - [mozharness: 2017-04-04 16:36:09.886193Z] Running read-buildbot-config step. 09:36:09 INFO - Running pre-action listener: _resource_record_pre_action 09:36:09 INFO - Running main action method: read_buildbot_config 09:36:09 INFO - Using buildbot properties: 09:36:09 INFO - { 09:36:09 INFO - "project": "", 09:36:09 INFO - "product": "firefox", 09:36:09 INFO - "script_repo_revision": "production", 09:36:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:36:09 INFO - "repository": "", 09:36:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:36:09 INFO - "buildid": "20170404074827", 09:36:09 INFO - "pgo_build": "False", 09:36:09 INFO - "basedir": "/builds/slave/test", 09:36:09 INFO - "buildnumber": 39, 09:36:09 INFO - "slavename": "t-yosemite-r7-0106", 09:36:09 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:36:09 INFO - "platform": "yosemite_r7", 09:36:09 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:36:09 INFO - "branch": "mozilla-aurora", 09:36:09 INFO - "repo_path": "releases/mozilla-aurora", 09:36:09 INFO - "revision": "a997fcc0a07f8ceff0b70a850123b9c34ab0b390", 09:36:09 INFO - "stage_platform": "macosx64", 09:36:09 INFO - "builduid": "46f3a5248b1d458dbc4b46a1096c5219", 09:36:09 INFO - "slavebuilddir": "test" 09:36:09 INFO - } 09:36:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 09:36:09 INFO - Running post-action listener: _resource_record_post_action 09:36:09 INFO - [mozharness: 2017-04-04 16:36:09.889683Z] Finished read-buildbot-config step (success) 09:36:09 INFO - [mozharness: 2017-04-04 16:36:09.889784Z] Running download-and-extract step. 09:36:09 INFO - Running pre-action listener: _resource_record_pre_action 09:36:09 INFO - Running main action method: download_and_extract 09:36:09 INFO - mkdir: /builds/slave/test/build/tests 09:36:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:09 INFO - trying https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 09:36:09 INFO - Downloading https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:36:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 09:36:11 INFO - Downloaded 1199 bytes. 09:36:11 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:36:11 INFO - Using the following test package requirements: 09:36:11 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:36:11 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:11 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 09:36:11 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:11 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 09:36:11 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:11 INFO - u'jsshell-mac.zip'], 09:36:11 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:11 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 09:36:11 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:36:11 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:11 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 09:36:11 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:11 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 09:36:11 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:11 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 09:36:11 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:11 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 09:36:11 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:36:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:36:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:36:11 INFO - Fetch https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:36:12 INFO - Expected file size: 51791329 09:36:12 INFO - Obtained file size: 51791329 09:36:28 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:36:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:36:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:36:28 INFO - Fetch https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:36:30 INFO - Expected file size: 51791329 09:36:30 INFO - Obtained file size: 51791329 09:36:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 09:36:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:36:46 INFO - Fetch https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 09:36:47 INFO - Expected file size: 14404438 09:36:47 INFO - Obtained file size: 14404438 09:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:47 INFO - trying https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:36:47 INFO - Downloading https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 09:36:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 09:36:50 INFO - Downloaded 74673849 bytes. 09:36:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:36:50 INFO - mkdir: /builds/slave/test/properties 09:36:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:36:50 INFO - Writing to file /builds/slave/test/properties/build_url 09:36:50 INFO - Contents: 09:36:50 INFO - build_url:https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:36:50 INFO - Running post-action listener: _resource_record_post_action 09:36:50 INFO - Running post-action listener: set_extra_try_arguments 09:36:50 INFO - [mozharness: 2017-04-04 16:36:50.713999Z] Finished download-and-extract step (success) 09:36:50 INFO - [mozharness: 2017-04-04 16:36:50.714130Z] Running populate-webroot step. 09:36:50 INFO - Running pre-action listener: _resource_record_pre_action 09:36:50 INFO - Running main action method: populate_webroot 09:36:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:36:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:36:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:36:50 INFO - '--tppagecycles', 09:36:50 INFO - '7'], 09:36:50 INFO - 'tests': ['tsvgm']}}, 09:36:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:36:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:36:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:36:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:36:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:36:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:36:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:36:50 INFO - 'talos_options': ['--disable-e10s'], 09:36:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:36:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:36:50 INFO - 'talos_options': ['--disable-e10s'], 09:36:50 INFO - 'tests': ['damp', 'tps']}, 09:36:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:36:50 INFO - 'tests': ['damp', 'tps']}, 09:36:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:36:50 INFO - 'tests': ['dromaeo_dom']}, 09:36:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:36:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:36:50 INFO - 'tests': ['basic_compositor_video']}, 09:36:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:36:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:36:50 INFO - 'tests': ['a11yr', 09:36:50 INFO - 'ts_paint', 09:36:50 INFO - 'tpaint', 09:36:50 INFO - 'sessionrestore', 09:36:50 INFO - 'sessionrestore_no_auto_restore', 09:36:50 INFO - 'tabpaint']}, 09:36:50 INFO - 'other-e10s': {'tests': ['a11yr', 09:36:50 INFO - 'ts_paint', 09:36:50 INFO - 'tpaint', 09:36:50 INFO - 'sessionrestore', 09:36:50 INFO - 'sessionrestore_no_auto_restore', 09:36:50 INFO - 'tabpaint']}, 09:36:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:36:50 INFO - 'ts_paint', 09:36:50 INFO - 'tpaint', 09:36:50 INFO - 'sessionrestore', 09:36:50 INFO - 'sessionrestore_no_auto_restore', 09:36:50 INFO - 'tabpaint']}, 09:36:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:36:50 INFO - 'ts_paint', 09:36:50 INFO - 'tpaint', 09:36:50 INFO - 'sessionrestore', 09:36:50 INFO - 'sessionrestore_no_auto_restore', 09:36:50 INFO - 'tabpaint']}, 09:36:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:36:50 INFO - 'tests': ['a11yr', 09:36:50 INFO - 'ts_paint', 09:36:50 INFO - 'tpaint', 09:36:50 INFO - 'sessionrestore', 09:36:50 INFO - 'sessionrestore_no_auto_restore', 09:36:50 INFO - 'tabpaint']}, 09:36:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:36:50 INFO - 'tests': ['a11yr', 09:36:50 INFO - 'ts_paint', 09:36:50 INFO - 'tpaint', 09:36:50 INFO - 'sessionrestore', 09:36:50 INFO - 'sessionrestore_no_auto_restore', 09:36:50 INFO - 'tabpaint']}, 09:36:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:36:50 INFO - 'tests': ['tsvgx', 09:36:50 INFO - 'tsvgr_opacity', 09:36:50 INFO - 'tart', 09:36:50 INFO - 'tscrollx', 09:36:50 INFO - 'cart', 09:36:50 INFO - 'tsvg_static']}, 09:36:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:36:50 INFO - 'tsvgr_opacity', 09:36:50 INFO - 'tart', 09:36:50 INFO - 'tscrollx', 09:36:50 INFO - 'cart', 09:36:50 INFO - 'tsvg_static']}, 09:36:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:36:50 INFO - 'talos_options': ['--disable-e10s'], 09:36:50 INFO - 'tests': ['tp5o']}, 09:36:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:36:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:36:50 INFO - 'talos_options': ['--disable-e10s', 09:36:50 INFO - '--xperf_path', 09:36:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:50 INFO - 'tests': ['tp5n']}, 09:36:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:36:50 INFO - 'talos_options': ['--xperf_path', 09:36:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:50 INFO - 'tests': ['tp5n']}}, 09:36:50 INFO - 'talos.zip': {'path': '', 09:36:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:36:50 INFO - Running post-action listener: _resource_record_post_action 09:36:50 INFO - [mozharness: 2017-04-04 16:36:50.733332Z] Finished populate-webroot step (success) 09:36:50 INFO - [mozharness: 2017-04-04 16:36:50.733467Z] Running create-virtualenv step. 09:36:50 INFO - Running pre-action listener: _resource_record_pre_action 09:36:50 INFO - Running main action method: create_virtualenv 09:36:50 INFO - Creating virtualenv /builds/slave/test/build/venv 09:36:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:36:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:36:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:36:50 INFO - Using real prefix '/tools/python27' 09:36:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:36:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:36:54 INFO - Installing pip.................done. 09:36:54 INFO - Return code: 0 09:36:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:36:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:36:54 INFO - Reading from file tmpfile_stdout 09:36:54 INFO - Output received: 09:36:54 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:36:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:54 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 0x10cfdbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfde768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f951ad7d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdb9d30>, '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 0x10cce0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdaebe8>, '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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:36:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:36:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4y57mS1Lk4/Render', 09:36:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:54 INFO - 'HOME': '/Users/cltbld', 09:36:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:54 INFO - 'LOGNAME': 'cltbld', 09:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:54 INFO - 'MOZ_NO_REMOTE': '1', 09:36:54 INFO - 'NO_EM_RESTART': '1', 09:36:54 INFO - 'PAGER': '/bin/cat', 09:36:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:54 INFO - 'PWD': '/builds/slave/test', 09:36:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:54 INFO - 'SHELL': '/bin/bash', 09:36:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lz8WWrYXjf/Listeners', 09:36:54 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 09:36:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:54 INFO - 'USER': 'cltbld', 09:36:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:54 INFO - 'XPC_FLAGS': '0x0', 09:36:54 INFO - 'XPC_SERVICE_NAME': '0', 09:36:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:36:54 INFO - Cleaning up... 09:36:54 INFO - Return code: 0 09:36:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cfdbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfde768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f951ad7d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdb9d30>, '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 0x10cce0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdaebe8>, '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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:36:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:36:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4y57mS1Lk4/Render', 09:36:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:55 INFO - 'HOME': '/Users/cltbld', 09:36:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:55 INFO - 'LOGNAME': 'cltbld', 09:36:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:55 INFO - 'MOZ_NO_REMOTE': '1', 09:36:55 INFO - 'NO_EM_RESTART': '1', 09:36:55 INFO - 'PAGER': '/bin/cat', 09:36:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:55 INFO - 'PWD': '/builds/slave/test', 09:36:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:55 INFO - 'SHELL': '/bin/bash', 09:36:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lz8WWrYXjf/Listeners', 09:36:55 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 09:36:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:55 INFO - 'USER': 'cltbld', 09:36:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:55 INFO - 'XPC_FLAGS': '0x0', 09:36:55 INFO - 'XPC_SERVICE_NAME': '0', 09:36:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:55 INFO - Downloading/unpacking psutil>=3.1.1 09:36:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:36:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:58 INFO - Installing collected packages: psutil 09:36:58 INFO - Running setup.py install for psutil 09:36:58 INFO - building 'psutil._psutil_osx' extension 09:36:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:36:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:36:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:36:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:36:59 INFO - building 'psutil._psutil_posix' extension 09:36:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:36:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:36:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:36:59 INFO - ^ 09:36:59 INFO - 1 warning generated. 09:36:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:36:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:59 INFO - Successfully installed psutil 09:36:59 INFO - Cleaning up... 09:37:00 INFO - Return code: 0 09:37:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:00 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 0x10cfdbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfde768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f951ad7d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdb9d30>, '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 0x10cce0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdaebe8>, '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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:37:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4y57mS1Lk4/Render', 09:37:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:00 INFO - 'HOME': '/Users/cltbld', 09:37:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:00 INFO - 'LOGNAME': 'cltbld', 09:37:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:00 INFO - 'MOZ_NO_REMOTE': '1', 09:37:00 INFO - 'NO_EM_RESTART': '1', 09:37:00 INFO - 'PAGER': '/bin/cat', 09:37:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:00 INFO - 'PWD': '/builds/slave/test', 09:37:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:00 INFO - 'SHELL': '/bin/bash', 09:37:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lz8WWrYXjf/Listeners', 09:37:00 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 09:37:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:00 INFO - 'USER': 'cltbld', 09:37:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:00 INFO - 'XPC_FLAGS': '0x0', 09:37:00 INFO - 'XPC_SERVICE_NAME': '0', 09:37:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:37:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:37:04 INFO - Installing collected packages: mozsystemmonitor 09:37:04 INFO - Running setup.py install for mozsystemmonitor 09:37:04 INFO - Successfully installed mozsystemmonitor 09:37:04 INFO - Cleaning up... 09:37:04 INFO - Return code: 0 09:37:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cfdbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfde768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f951ad7d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdb9d30>, '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 0x10cce0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdaebe8>, '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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:37:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:37:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4y57mS1Lk4/Render', 09:37:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:04 INFO - 'HOME': '/Users/cltbld', 09:37:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:04 INFO - 'LOGNAME': 'cltbld', 09:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:04 INFO - 'MOZ_NO_REMOTE': '1', 09:37:04 INFO - 'NO_EM_RESTART': '1', 09:37:04 INFO - 'PAGER': '/bin/cat', 09:37:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:04 INFO - 'PWD': '/builds/slave/test', 09:37:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:04 INFO - 'SHELL': '/bin/bash', 09:37:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lz8WWrYXjf/Listeners', 09:37:04 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 09:37:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:04 INFO - 'USER': 'cltbld', 09:37:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:04 INFO - 'XPC_FLAGS': '0x0', 09:37:04 INFO - 'XPC_SERVICE_NAME': '0', 09:37:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:04 INFO - Downloading/unpacking jsonschema==2.5.1 09:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:07 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:07 INFO - warning: no files found matching '*.txt' 09:37:07 INFO - no previously-included directories found matching 'build' 09:37:07 INFO - no previously-included directories found matching 'dist' 09:37:07 INFO - no previously-included directories found matching '.git*' 09:37:07 INFO - Installing collected packages: jsonschema, functools32 09:37:08 INFO - Running setup.py install for functools32 09:37:08 INFO - warning: no files found matching '*.txt' 09:37:08 INFO - no previously-included directories found matching 'build' 09:37:08 INFO - no previously-included directories found matching 'dist' 09:37:08 INFO - no previously-included directories found matching '.git*' 09:37:08 INFO - Successfully installed jsonschema functools32 09:37:08 INFO - Cleaning up... 09:37:08 INFO - Return code: 0 09:37:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:08 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 0x10cfdbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfde768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f951ad7d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdb9d30>, '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 0x10cce0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdaebe8>, '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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:37:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4y57mS1Lk4/Render', 09:37:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:08 INFO - 'HOME': '/Users/cltbld', 09:37:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:08 INFO - 'LOGNAME': 'cltbld', 09:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:08 INFO - 'MOZ_NO_REMOTE': '1', 09:37:08 INFO - 'NO_EM_RESTART': '1', 09:37:08 INFO - 'PAGER': '/bin/cat', 09:37:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:08 INFO - 'PWD': '/builds/slave/test', 09:37:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:08 INFO - 'SHELL': '/bin/bash', 09:37:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lz8WWrYXjf/Listeners', 09:37:08 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 09:37:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:08 INFO - 'USER': 'cltbld', 09:37:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:08 INFO - 'XPC_FLAGS': '0x0', 09:37:08 INFO - 'XPC_SERVICE_NAME': '0', 09:37:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:37:08 INFO - Cleaning up... 09:37:08 INFO - Return code: 0 09:37:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:08 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 0x10cfdbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfde768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f951ad7d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdb9d30>, '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 0x10cce0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdaebe8>, '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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:37:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4y57mS1Lk4/Render', 09:37:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:08 INFO - 'HOME': '/Users/cltbld', 09:37:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:08 INFO - 'LOGNAME': 'cltbld', 09:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:08 INFO - 'MOZ_NO_REMOTE': '1', 09:37:08 INFO - 'NO_EM_RESTART': '1', 09:37:08 INFO - 'PAGER': '/bin/cat', 09:37:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:08 INFO - 'PWD': '/builds/slave/test', 09:37:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:08 INFO - 'SHELL': '/bin/bash', 09:37:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lz8WWrYXjf/Listeners', 09:37:08 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 09:37:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:08 INFO - 'USER': 'cltbld', 09:37:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:08 INFO - 'XPC_FLAGS': '0x0', 09:37:08 INFO - 'XPC_SERVICE_NAME': '0', 09:37:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:08 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:11 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:37:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:12 INFO - Downloading docopt-0.6.1.tar.gz 09:37:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:12 INFO - Running setup.py install for blobuploader 09:37:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:37:12 INFO - Running setup.py install for requests 09:37:13 INFO - Running setup.py install for docopt 09:37:13 INFO - Successfully installed blobuploader requests docopt 09:37:13 INFO - Cleaning up... 09:37:13 INFO - Return code: 0 09:37:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:13 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 0x10cfdbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfde768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f951ad7d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdb9d30>, '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 0x10cce0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdaebe8>, '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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:37:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:37:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4y57mS1Lk4/Render', 09:37:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:13 INFO - 'HOME': '/Users/cltbld', 09:37:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:13 INFO - 'LOGNAME': 'cltbld', 09:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:13 INFO - 'MOZ_NO_REMOTE': '1', 09:37:13 INFO - 'NO_EM_RESTART': '1', 09:37:13 INFO - 'PAGER': '/bin/cat', 09:37:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:13 INFO - 'PWD': '/builds/slave/test', 09:37:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:13 INFO - 'SHELL': '/bin/bash', 09:37:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lz8WWrYXjf/Listeners', 09:37:13 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 09:37:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:13 INFO - 'USER': 'cltbld', 09:37:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:13 INFO - 'XPC_FLAGS': '0x0', 09:37:13 INFO - 'XPC_SERVICE_NAME': '0', 09:37:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:13 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-mg_Dfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:14 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-Pi47DQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:14 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-Fnh1aX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:14 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-gDgrgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:14 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-SJBVWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:14 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-LJ_g9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:14 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-S1y49W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:14 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-RdmCp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:14 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-y_DZkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:14 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-Y_VAY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:14 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-TZ_LM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:15 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-AScWqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:15 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-TZ_xzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:15 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-4MPXfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:15 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-VovzcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:15 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-d9rWJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:15 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-o64dAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:15 INFO - Running setup.py install for manifestparser 09:37:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:15 INFO - Running setup.py install for mozcrash 09:37:15 INFO - Running setup.py install for mozdebug 09:37:16 INFO - Running setup.py install for mozdevice 09:37:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:37:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:16 INFO - Running setup.py install for mozfile 09:37:16 INFO - Running setup.py install for mozhttpd 09:37:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:16 INFO - Running setup.py install for mozinfo 09:37:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:16 INFO - Running setup.py install for mozInstall 09:37:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:16 INFO - Running setup.py install for mozleak 09:37:16 INFO - Running setup.py install for mozlog 09:37:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:17 INFO - Running setup.py install for moznetwork 09:37:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:37:17 INFO - Running setup.py install for mozprocess 09:37:17 INFO - Running setup.py install for mozprofile 09:37:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:37:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:37:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:37:17 INFO - Running setup.py install for mozrunner 09:37:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:37:17 INFO - Running setup.py install for mozscreenshot 09:37:17 INFO - Running setup.py install for moztest 09:37:18 INFO - Running setup.py install for mozversion 09:37:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:37:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:37:18 INFO - Cleaning up... 09:37:18 INFO - Return code: 0 09:37:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:18 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 0x10cfdbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfde768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f951ad7d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdb9d30>, '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 0x10cce0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdaebe8>, '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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:37:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:37:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4y57mS1Lk4/Render', 09:37:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:18 INFO - 'HOME': '/Users/cltbld', 09:37:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:18 INFO - 'LOGNAME': 'cltbld', 09:37:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:18 INFO - 'MOZ_NO_REMOTE': '1', 09:37:18 INFO - 'NO_EM_RESTART': '1', 09:37:18 INFO - 'PAGER': '/bin/cat', 09:37:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:18 INFO - 'PWD': '/builds/slave/test', 09:37:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:18 INFO - 'SHELL': '/bin/bash', 09:37:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lz8WWrYXjf/Listeners', 09:37:18 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 09:37:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:18 INFO - 'USER': 'cltbld', 09:37:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:18 INFO - 'XPC_FLAGS': '0x0', 09:37:18 INFO - 'XPC_SERVICE_NAME': '0', 09:37:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-0C5PiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-8WhRwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-nYjiu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-Y9zfik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-OTe8Qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-TBftRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-YykJBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-axErER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-x0c4Qx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-CM_UbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-sAp2nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-kJ4pMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-XGEl4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-GKw1Hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:20 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-HQvnBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:20 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-r8FfLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:20 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-ckw9EC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:37:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:37:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:37:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:37:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:22 INFO - Downloading blessings-1.6.tar.gz 09:37:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:37:23 INFO - Installing collected packages: blessings 09:37:23 INFO - Running setup.py install for blessings 09:37:23 INFO - Successfully installed blessings 09:37:23 INFO - Cleaning up... 09:37:23 INFO - Return code: 0 09:37:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:37:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:23 INFO - Reading from file tmpfile_stdout 09:37:23 INFO - Current package versions: 09:37:23 INFO - blessings == 1.6 09:37:23 INFO - blobuploader == 1.2.4 09:37:23 INFO - docopt == 0.6.1 09:37:23 INFO - functools32 == 3.2.3-2 09:37:23 INFO - jsonschema == 2.5.1 09:37:23 INFO - manifestparser == 1.1 09:37:23 INFO - mozInstall == 1.12 09:37:23 INFO - mozcrash == 1.0 09:37:23 INFO - mozdebug == 0.1 09:37:23 INFO - mozdevice == 0.49 09:37:23 INFO - mozfile == 1.2 09:37:23 INFO - mozhttpd == 0.7 09:37:23 INFO - mozinfo == 0.9 09:37:23 INFO - mozleak == 0.1 09:37:23 INFO - mozlog == 3.4 09:37:23 INFO - moznetwork == 0.27 09:37:23 INFO - mozprocess == 0.25 09:37:23 INFO - mozprofile == 0.28 09:37:23 INFO - mozrunner == 6.13 09:37:23 INFO - mozscreenshot == 0.1 09:37:23 INFO - mozsystemmonitor == 0.3 09:37:23 INFO - moztest == 0.8 09:37:23 INFO - mozversion == 1.4 09:37:23 INFO - psutil == 3.1.1 09:37:23 INFO - requests == 1.2.3 09:37:23 INFO - wsgiref == 0.1.2 09:37:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:23 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 0x10cfdbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfde768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f951ad7d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdb9d30>, '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 0x10cce0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdaebe8>, '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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:37:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:37:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4y57mS1Lk4/Render', 09:37:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:23 INFO - 'HOME': '/Users/cltbld', 09:37:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:23 INFO - 'LOGNAME': 'cltbld', 09:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:23 INFO - 'MOZ_NO_REMOTE': '1', 09:37:23 INFO - 'NO_EM_RESTART': '1', 09:37:23 INFO - 'PAGER': '/bin/cat', 09:37:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:23 INFO - 'PWD': '/builds/slave/test', 09:37:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:23 INFO - 'SHELL': '/bin/bash', 09:37:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lz8WWrYXjf/Listeners', 09:37:23 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 09:37:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:23 INFO - 'USER': 'cltbld', 09:37:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:23 INFO - 'XPC_FLAGS': '0x0', 09:37:23 INFO - 'XPC_SERVICE_NAME': '0', 09:37:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:37:23 INFO - Cleaning up... 09:37:23 INFO - Return code: 0 09:37:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:23 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 0x10cfdbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfde768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f951ad7d120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdb9d30>, '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 0x10cce0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdaebe8>, '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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:37:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:37:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4y57mS1Lk4/Render', 09:37:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:23 INFO - 'HOME': '/Users/cltbld', 09:37:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:23 INFO - 'LOGNAME': 'cltbld', 09:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:23 INFO - 'MOZ_NO_REMOTE': '1', 09:37:23 INFO - 'NO_EM_RESTART': '1', 09:37:23 INFO - 'PAGER': '/bin/cat', 09:37:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:23 INFO - 'PWD': '/builds/slave/test', 09:37:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:23 INFO - 'SHELL': '/bin/bash', 09:37:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lz8WWrYXjf/Listeners', 09:37:23 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 09:37:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:23 INFO - 'USER': 'cltbld', 09:37:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:23 INFO - 'XPC_FLAGS': '0x0', 09:37:23 INFO - 'XPC_SERVICE_NAME': '0', 09:37:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:37:24 INFO - Cleaning up... 09:37:24 INFO - Return code: 0 09:37:24 INFO - Running post-action listener: _resource_record_post_action 09:37:24 INFO - Running post-action listener: _start_resource_monitoring 09:37:24 INFO - Starting resource monitoring. 09:37:24 INFO - [mozharness: 2017-04-04 16:37:24.151410Z] Finished create-virtualenv step (success) 09:37:24 INFO - [mozharness: 2017-04-04 16:37:24.152409Z] Running install step. 09:37:24 INFO - Running pre-action listener: _resource_record_pre_action 09:37:24 INFO - Running main action method: install 09:37:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:24 INFO - Reading from file tmpfile_stdout 09:37:24 INFO - Detecting whether we're running mozinstall >=1.0... 09:37:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:37:24 INFO - Reading from file tmpfile_stdout 09:37:24 INFO - Output received: 09:37:24 INFO - Usage: mozinstall [options] installer 09:37:24 INFO - Options: 09:37:24 INFO - -h, --help show this help message and exit 09:37:24 INFO - -d DEST, --destination=DEST 09:37:24 INFO - Directory to install application into. [default: 09:37:24 INFO - "/builds/slave/test"] 09:37:24 INFO - --app=APP Application being installed. [default: firefox] 09:37:24 INFO - mkdir: /builds/slave/test/build/application 09:37:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:37:44 INFO - Reading from file tmpfile_stdout 09:37:44 INFO - Output received: 09:37:44 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:37:44 INFO - Running post-action listener: _resource_record_post_action 09:37:44 INFO - [mozharness: 2017-04-04 16:37:44.643898Z] Finished install step (success) 09:37:44 INFO - [mozharness: 2017-04-04 16:37:44.644069Z] Running run-tests step. 09:37:44 INFO - Running pre-action listener: _resource_record_pre_action 09:37:44 INFO - Running main action method: run_tests 09:37:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:44 INFO - 2017-04-04 09:37:44.659 screenresolution[1625:7348] starting screenresolution argv=screenresolution get 09:37:44 INFO - 2017-04-04 09:37:44.661 screenresolution[1625:7348] Display 0: 1600x1200x32@60 09:37:44 INFO - 2017-04-04 09:37:44.667 screenresolution[1626:7351] starting screenresolution argv=screenresolution list 09:37:44 INFO - Available Modes on Display 0 09:37:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:44 INFO - Intel Iris: 09:37:44 INFO - Chipset Model: Intel Iris 09:37:44 INFO - Type: GPU 09:37:44 INFO - Bus: Built-In 09:37:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:44 INFO - Vendor: Intel (0x8086) 09:37:44 INFO - Device ID: 0x0a2e 09:37:44 INFO - Revision ID: 0x0009 09:37:44 INFO - Displays: 09:37:44 INFO - Display: 09:37:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:44 INFO - Display Serial Number: 1600x1200 60 09:37:44 INFO - Main Display: Yes 09:37:44 INFO - Mirror: Off 09:37:44 INFO - Online: Yes 09:37:44 INFO - Rotation: Supported 09:37:44 INFO - Return code: 0 09:37:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:37:44 INFO - Python 2.7.3 09:37:44 INFO - Return code: 0 09:37:44 INFO - grabbing minidump binary from tooltool 09:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:44 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 0x10cdb9d30>, '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 0x10cce0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdaebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:37:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:37:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:37:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:37:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:37:45 INFO - Return code: 0 09:37:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:37:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:37:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:37:45 INFO - ENV: RUST_BACKTRACE is now 1 09:37:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:37:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:37:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0106', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:37:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0106 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:37:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4y57mS1Lk4/Render', 09:37:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:45 INFO - 'HOME': '/Users/cltbld', 09:37:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:45 INFO - 'LOGNAME': 'cltbld', 09:37:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:37:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:45 INFO - 'MOZ_NO_REMOTE': '1', 09:37:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:37:45 INFO - 'NO_EM_RESTART': '1', 09:37:45 INFO - 'PAGER': '/bin/cat', 09:37:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:45 INFO - 'PWD': '/builds/slave/test', 09:37:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:37:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:45 INFO - 'RUST_BACKTRACE': '1', 09:37:45 INFO - 'SHELL': '/bin/bash', 09:37:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lz8WWrYXjf/Listeners', 09:37:45 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 09:37:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:45 INFO - 'USER': 'cltbld', 09:37:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:45 INFO - 'XPC_FLAGS': '0x0', 09:37:45 INFO - 'XPC_SERVICE_NAME': '0', 09:37:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0106', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:37:45 INFO - mozversion application_buildid: 20170404074827 09:37:45 INFO - mozversion application_changeset: a997fcc0a07f8ceff0b70a850123b9c34ab0b390 09:37:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:37:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:37:45 INFO - mozversion application_name: Firefox 09:37:45 INFO - mozversion application_remotingname: firefox-dev 09:37:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:37:45 INFO - mozversion application_vendor: Mozilla 09:37:45 INFO - mozversion application_version: 54.0a2 09:37:45 INFO - mozversion platform_buildid: 20170404074827 09:37:45 INFO - mozversion platform_changeset: a997fcc0a07f8ceff0b70a850123b9c34ab0b390 09:37:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:37:45 INFO - mozversion platform_version: 54.0a2 09:37:45 INFO - using testdate: 1491323865 09:37:45 INFO - actual date: 1491323865 09:37:45 INFO - starting webserver on 'localhost:49242' 09:37:45 INFO - SUITE-START | Running 2 tests 09:37:45 INFO - TEST-START | dromaeo_css 09:37:45 INFO - operating with platform_type : mac_ 09:37:45 INFO - Initialising browser for dromaeo_css test... 09:37:45 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpbmNMDC/profile http://localhost:49242/getInfo.html) 09:37:47 INFO - PROCESS | 1636 | 2017-04-04 09:37:47.966 plugin-container[1638:7526] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c3b, name = 'com.apple.tsm.portname' 09:37:47 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:47 INFO - PROCESS | 1636 | 2017-04-04 09:37:47.969 plugin-container[1638:7526] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 09:37:47 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:47 INFO - PROCESS | 1636 | 2017-04-04 09:37:47.969 plugin-container[1638:7526] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:37:48 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 09:37:48 INFO - PROCESS | 1636 | colorDepth:24 09:37:48 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 09:37:48 INFO - PROCESS | 1636 | __metrics 09:37:48 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 09:37:48 INFO - TEST-INFO | 1636: exit 0 09:37:48 INFO - Browser initialized. 09:37:48 INFO - Running cycle 1/1 for dromaeo_css test... 09:37:48 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpbmNMDC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:51 INFO - PROCESS | 1639 | 2017-04-04 09:37:51.333 plugin-container[1640:7658] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4353, name = 'com.apple.tsm.portname' 09:37:51 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:51 INFO - PROCESS | 1639 | 2017-04-04 09:37:51.334 plugin-container[1640:7658] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 09:37:51 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:37:51 INFO - PROCESS | 1639 | 2017-04-04 09:37:51.334 plugin-container[1640:7658] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:01 INFO - PROCESS | 1639 | RSS: Main: 220827648 09:38:01 INFO - PROCESS | 1639 | 09:41:04 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:41:06 INFO - PROCESS | 1639 | RSS: Main: 238403584 09:41:06 INFO - PROCESS | 1639 | 09:42:49 INFO - PROCESS | 1639 | ************************* 09:42:49 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 09:42:49 INFO - PROCESS | 1639 | 09:42:49 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:42:49 INFO - PROCESS | 1639 | Full stack: 09:42:49 INFO - PROCESS | 1639 | ************************* 09:44:08 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:44:09 INFO - PROCESS | 1639 | RSS: Main: 246501376 09:44:09 INFO - PROCESS | 1639 | 09:47:11 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:47:11 INFO - PROCESS | 1639 | RSS: Main: 255016960 09:47:11 INFO - PROCESS | 1639 | 09:50:13 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:50:14 INFO - PROCESS | 1639 | RSS: Main: 251170816 09:50:14 INFO - PROCESS | 1639 | 09:53:17 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:53:18 INFO - PROCESS | 1639 | RSS: Main: 256344064 09:53:18 INFO - PROCESS | 1639 | 09:56:20 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:56:21 INFO - PROCESS | 1639 | RSS: Main: 253366272 09:56:21 INFO - PROCESS | 1639 | 09:56:21 INFO - PROCESS | 1639 | __start_tp_report 09:56:21 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 09:56:21 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 09:56:21 INFO - PROCESS | 1639 | |i|pagename|runs| 09:56:21 INFO - PROCESS | 1639 | |0;dojo.html;2756.875940582674;3495.632958539352;3507.175813683785;3510.280392519532;3543.521624580681;6052.57631965762;6087.208662873826;6110.419510146535;6113.816585502431;6137.601055931373;6069.180660610817;6112.816615501542;6125.816225513242;6180.69096545172;6209.316975132731;5450.754716037768;5513.448655134494;5524.502794748471;5538.418466061066;5616;5568.638038527482;5577.944220557783;5589.301337332833;5593.133064375008;5620.831375058734;5339.038972984861;5362.276092727471;5440.836774896739;5453.400125986138;5591.384947655754;4311.9784401078105;5544.085225937715;5588.580856435743;5624.353199382042;5643.6049476536655;5420.539254163361;5530.391656917735;5537;5539.307586551681;5576.804811831607;5859.267591551056;6095.329513753483;6187.257529096492;6238.594491358047;6239;6330.620162790247;6331.651759153245;6353.618782873043;6353.9682301588655;6375.362463753634;6501.349865013508;6507.186601674791;6525;6527.249366322839;6548.8035358939305;6193.163922870445;6196.473299769525;6197.504199664016;6242;6242.219722534684;6328.6519241442165;6384.7126879290545;6447.2263328400895;6593.538452308292;6674.699638516278;6337.081123237105;6372.522060845455;6372.776952806629;6374.043893416025;6386.425221730066;6363.872722545523;6451.645159516178;6477.4494167996245;6496.480281577557;6497.382748638872;6327.588706734047;6346.777862774781;6397.744090236339;6409.166808314889;6440.645764482999;6325.778597749057;6327.114204011442;6373.203349581302;6400.199975003125;6421.871562568723;5237.266782650432;5242.135047717084;5243.711595862264;5255.921161182585;5258.2112683097075;4569.451665800125;4626.282926146453;4646.279826626878;4658.557437043475;4702.905941881143;6090;6174.783882564088;6188.412100850412;6194.14175747274;6233.594816336924;6465.838354041092;6494.577852439576;6499.122618446544;6504.934950650528;6520.608763474207;5152.896942061137;5985.820425387245;6034.728437220336;6144.556029333103;6253.96809526423;5779.711014449261;5794.971025144847;5813;5814.186013958049;5814.592978491465;6778.152730908637;6805.285445028299;6807;6810.523263371517;6844.8973265402055;6254.061890716429;6257.53068519872;6465.547411681137;6493.090967264492;6521.608703477807;6659.167604049494;6675.132232809704;6718.6976586054725;6728.6972086255255;6734.865302693821;6380.425761681471;6387.680615969276;6394.904076438764;6401.231852177769;6418.261899881387;6288.025356069817;6370.108184854217;6418.0693799397895;6441.098246245436;6449.097126402401;6240.4071613196675;6279.215098112736;6309;6350.7777227796805;6382.297947225708;6345.587536810092;6355.872882542416;6357.8873697103745;6375.139356186855;6403.199600049994;5518.33779946409;6134.539909506894;6249.218847644044;6283.96858015707;6364.172657554674;6262.123302737711;6264.342244064308;6318.052292156213;6323.20959880015;6331.715072821642;6277.3408792078;6311.715972781328;6322.114903913363;6337.7148028337915;6345.809625711236 09:56:21 INFO - PROCESS | 1639 | |1;ext.html;709.5884387055509;722.382363079567;811.431997601679;850.4939560961226;884.5400391796267;39614;40590.99999999996;40595.79702101489;40688.38967415484;40751.98120047;34002.48996265058;41726;41869.7906510467;41953.79023104888;42010;7267.854642907138;7450.217727138654;7965.6813727450835;9084.182423581893;9282.675106371278;9972.850407243897;10641.946790266076;11195.384253866034;11226.887731122706;11384.71538211543;9755.707328780149;11060.723481912977;11248.26886252395;11253.156013298994;11303.26528775631;9841.606335746561;10801.811800701917;11272.492737826777;11273.928976747236;11277.492512836941;10432.956704329583;10843.67468975932;10890.782184356269;10958.342499450058;10991.120710343153;33513.497297540554;39017.41473877894;39108.80445597782;39172.412413813814;39272;3844.5386553613744;3915.490986171808;3923.8234279457492;3928;3928.3910993796007;24832;27470.45059098827;27528.862355688292;28769.28076798076;29176.708232917823;3078.615173103362;3142.685731426862;3145.370925814846;3172.2228054126654;3176.1900715317447;323770;326311;326910.7308926928;328634;329257.35371323227;43385.56614433879;43676;52811.73594132005;52986;53050.734746326794;55741.72129139328;56954.43045569574;57428.71285643545;57450.425495745345;57729.422705772406;14830.332635031235;14832.332545035499;14941.551753447415;14942.327595258239;14943.327550260265;10562.366258024544;12566.497340106476;12606.306653133983;12648.241105533698;12662.556810511587;12847;12854.421551030226;12879.905208057233;12929.741405171844;12969.675758106123;4598.793054312497;4834.661573689878;5145.716985565755;5422.43064478232;5431.755570999315;1064.685917654294;1106.3417266726285;1134.3721759275466;1135.3982389333667;1180.722530205401;22773.633581984803;23827.761722382555;27274.863625681744;27296.45407091847;27374.863125684245;1220.725336799213;1227.8281040654138;1231.6305108467602;1241.3793103448277;1261.6341261034197;12879.678008049874;14148.434062637585;14162.371327297287;14271.429142834171;14384.35270412834;459906.7004665022;461446;461714;462735;463317;57122.85754284891;57288.42711572831;57376.42623573711;57434.7128264356;57507.712461437426;1393.2754967416886;1441.7837324401423;1442.322108608952;1450.8766754825958;1453.7528620134603;26283.342916426478;26314.34214144585;26336.20991370262;26867.193984180507;27100.864495678183;48913;49015.26477102918;49098.509014909396;49224.261636076204;49312.50687493079;426431;427799;428793;429268;429748.8512557417;34616;35005;35138.77014304487;35168.47247291344;35185.824070879484;43218.919527012076;43381.78309108434;44061.559384405744;44193.55806441895;44423.777881110385;29359;29504;29851.402971940006;30322.545161823033;30399.480025999055;24139.758602414455;29665.851670741507;30357.696423035486;30401.847990759903;30858.228544285674;32939.01182964515;33313.333733325686;33537.664623353456;35122;35149.47275790814;24503.264902052968;25793.87103064473;27084.864575676995;28617.426041567913;29164.70835291705;22108.55782884301;23458.76541234566;23728.40678983039;23767.405814854767;23907.402314942265 09:56:21 INFO - PROCESS | 1639 | |2;jquery.html;341.24073935493544;412.5482596556771;415.8669225847728;432.4659046078094;439.95510662177327;20045.899770501146;20395.898020509914;20758.498761443698;20995.790042099576;21124.57750844982;20700.999999999964;20788.376348709564;21171.576568468623;21269.149234030658;21976.230831920886;6365.681715914209;6707.463402927754;6728.93271067288;6756.49326300527;6779.932200677979;6522.510811689119;6630.900536491957;6910.861782764342;7228.963855180743;7371.152317483477;7116.857662846766;7312.232215617337;7323.084614423197;7352.338289553939;7352.448566357518;7334.779956601311;7379.704811807521;7386.15059268183;7434.070741157356;7451.478396512246;6951.965240173818;7162.964185179041;7182.569045857213;7230.276972302728;7231.638418079127;24650.50698986028;24813.875930620412;25218.747812521822;25761.61357579638;25817.225483235532;2777.1807316841378;2883.956740648892;2889.0321742216383;2897.6377952755906;2898.3913378190605;13604.591862244148;15024.624384390369;15096.773548396783;15238.161901095435;15249.313780879887;1999.540105775665;2069.7619773726065;2071.2854065347433;2072.7305450291515;2079.6984437256665;119805;144884.55115448817;149097;152234.47765522313;152423.23788381097;15648.278689344163;19853.900730496254;20158.596828063357;20280.985950702405;20412.387628371172;24550.386240344134;30865.382692346477;31202.219944501114;31257.8437107813;31343.529847052312;9914.256430767577;10027.247956403298;10229.897701023043;10275.177985761122;10405.791884162274;8485.490870547786;8501.787455313543;8681.826363472695;8687.478751274944;8691.826163476695;9183.403078799856;9482.38364506305;9497.865704317559;9553.474558899188;9582.856257156149;3965.147493288957;4041.656459201001;4059.2693315202955;4076.164386300801;4083.6932181701573;815.229608020415;938.8920274168421;998.1814911772423;1027.337367398039;1049.192122066005;15711;21215.787842121383;21324;21375.57248855014;21480.57038859214;728.9781306560811;822.321584692631;898.9730308090768;917.3945196170629;1081.4808891731855;5967.224260846062;6228.314885362606;6292.528060395489;6353.396427339396;6529.151210342625;761503;766499;772501;780091;791023;154117;156502;157280;158615.41384586005;160547;1257.4215860704178;1277.5592420614873;1301.8633043530483;1310.2531557012412;1311.698309388846;11495.425228738362;11517.654470365902;11667.883321166679;11792.764144716886;11881;59478.40521594902;59931;61230.38769612247;61727.38272617216;61749.45626359377;69977.65011174911;90588;91113;92142.53928730314;92876.53561732148;10270.897291026995;11127.387993660426;11347.092232621206;11548;11649.621609733422;11793.351365674971;12572.748545028866;12687.80968285495;12961.287129207984;13111.868881311446;8649.524276164871;9516.85724714116;11053.83419248728;11098.167637427065;11215;8789.560521974003;9762.316637835283;10252.64115755945;10366.378017318984;10713.089387402159;11358.375289359165;11483;11491;11548.653540393801;11600.6519804406;11207.271527350696;11442.199046067028;11522.366269855238;11731.824022639837;11758.764824703287;10728;10952.616658416917;11067.059299959594;11170;11209 09:56:21 INFO - PROCESS | 1639 | |3;mootools.html;1334.1461464662616;1474.3660226102772;1480.8889333300006;1505.638646724786;1511.5540915429947;10671.573137074518;11073.446327683603;11222.719432014193;11281.041111505521;11301.265417747862;11071.612493562727;11254;11277.774444511106;11299.774004519904;11305.321680699144;2405.8797060146967;2447.3269850791085;2449.1428000199967;2451.4116612013077;2451.9754802451926;2312.537492501498;2382.2376839411395;2384.1655420602738;2387.0809738250723;2416.8791560421996;1820.6176702892408;2309.3764683535437;2317.9304620861403;2335.6730057791924;2428.1501474483966;2354.9058037678474;2396.664466974625;2403.158894386968;2403.6995375578053;2413.553492603865;2371.885213949441;2391.318474234835;2397.2328854766483;2398.400399900025;2412.987935060331;10992.120630349633;11147;11172.994430501294;11217.326960382321;11218.326900386002;5842.795502157404;5931.288245410536;6016.96991515044;6025.397460253984;6032.185654936571;3579.1231148368556;3659.670629643345;3664.963350366489;3666.7249956253386;3721.0325315418186;1034.9793004139874;1096.3038470571164;1106.164845541611;1216.4343580235234;1250.1311588446033;11698.356590387526;12035.27788332694;13652.180869147884;15492.612684682861;15712.292946817306;4335.176316499855;4426.690131690751;4821.276808478756;4832.371791667131;4858.58702010326;10857.891421085687;11752.88347606976;11868.050555955502;11877.465514051888;11904.404779760976;5057.848264552069;5766.250387449605;5893.14549390336;5897.262842144732;5951.732172052268;4500.594946454769;5141.331626888556;5254.791397978486;5350.250964864922;5385.5960802939935;3324.451465508164;3365.915852103717;3656.469811877263;3682.742208045465;3844.8654297099465;1062.2935747727674;1066.0299127793671;1068.6900798768424;1075.8708954925303;1079.80023695615;773.6015951784723;883.3154305413356;898.2409863665192;922.6770630279349;929.163752622645;5793.159991801165;5975;6572.11276477679;6667.833304167435;6862.313768623198;977.3891317926297;1019.1541020952592;1019.6940917724653;1027.291169093338;1058.5183741397645;3825.617438256152;4237.745735255895;4247.5964783345535;4368.694191406635;4371.256886329332;13696.383662735201;14340.282985850667;14340.641483962985;14386.35261413239;14454.277286135652;12291.385430728427;12406.441710123067;13015.739685206243;14296;14308.856911430961;9808;10129.64546240878;10131.898681013243;10403.5838566458;10420.895791041992;6709.664516774044;6719.832004199934;6763.7632682855865;6910.516263861481;6919.93080069213;12186.025117990717;12416.068794840243;12494.937525312314;12607.747845042864;12613.117081804186;9513.33406661527;9521;11867.881321186678;12682.809757853454;12993.155444896052;9134.725958221265;9328.39365441244;9398.577064031999;9449.811003780023;9455.101765332283;8534.402591818513;9640.650308956892;10294.639687610897;10341.534630941776;10454.895451045391;8829.514376709343;8856.512891791013;8914.242289405473;8934.86597701022;8961.417507861968;7403.370713489414;8540.231379175904;8636.697715580176;8718.17177368149;8739.91260087391;7822;7854.607269636381;7880.975473188449;7922.405819563441;7956.045274567089;5622.381538030895;5726;5835.8832823342445;5872.0898260769645;6023.608465449732;5742;5771.8557036074435;5793.565482588739;5925;6043.063325184604 09:56:21 INFO - PROCESS | 1639 | |4;prototype.html;315.3787039532121;373.21624588364455;384.17594260311625;389.13611781844315;392.73490393984054;5067.390293998594;5176.456472070435;5197.856471576257;5212;5226.529612334889;4559.794809233585;4758.4542269885615;5135.7688903999315;5164.457731938149;5188.448360254529;3195.4407978603767;3735.8505659773577;3860.8417474757603;3911.3350730375764;4278.165757677262;3825.445310429986;3995.420664003718;4109.363048727452;4277.465316835395;4325.113351762897;3691.53855768029;3832.5400951885813;4299.871003869889;4318.244307246219;4324.891877703051;3312.337532493511;4284.464441944757;4303.26844436445;4320.783960801948;4327.394164816928;3070.539419087155;3662.34077865984;3733.1600389912323;3933.606639336072;3961.4057891316534;4560.817567297304;4683.929741053887;4900.672180714168;5032.037505372908;5049.444561098277;4719.413047789021;4976.402831660188;5000.499950005007;5002.349694539686;5015.598752099823;4719.174144524729;5235.371755389339;5284.709340986245;5481.698506582136;5488.670679759227;4402.974631669404;4407.581279778416;4421.248387774054;4422.71252368595;4444.933326000112;5099.954509325579;5195.324607801;5653.462921022497;5835.620684655484;5880.8235752927485;4274.786260687016;4421.292593185075;4422.783734473046;4440.267355886307;4466.4640243171025;4256.871928938834;4317.525072242051;4451.017818061906;4543.227651299221;4561.2245918193985;4132.91734165315;4254.957450425518;4270.423492828429;4650.906981860344;4920.286558449005;4964.627652926045;5209.895802083937;5301.920471192935;5452.345718513804;5507.072524616352;1356.6433566433566;1451.992740036293;1527.1753253243346;1557.9143147127018;1604.374294025331;1016.9694909152737;1055.875492600378;1136.85177970249;1176.1061593189067;1206.817319027358;4654.95263565695;5176.559992400613;5193.584513238932;5222.451642577475;5223.425423203445;5032.421271553745;5256.476891092297;5738.810668212145;5781.82654520365;5880.794172204038;4870.4171144378215;4912.975435122801;5022.924656130161;5157.922631160535;5190.37715474138;2614.163467690359;2774.8057635965692;2840.6648875028704;2978.4087843576226;3003.759699224057;5539;5585.916211256835;5859.974504461702;6036.486898613667;6092.0453580013655;5143.887947791607;5171;5357.696190638063;5582.455140985503;5872.122659896705;5018.096742586369;5230.633855630145;5746.770129194785;5864.794732184353;6091.380706296247;5143.871403214949;5148.819791307286;5231.59346700522;5581.776728930798;5827.79602713903;4956.950430495649;5252.1596544552685;5371.892562148813;5615;5702.942970570241;5826.592138550261;5978.551608629457;5996.580239383201;6036.366181550874;6067.6324639825325;3934.6655534279907;3939.1727737175515;4309.439772829637;4435.600795928382;4542.7955741992355;4990.6506544541535;5188.792448302026;5452.700101494456;5597.857585386866;5859.882802343844;4524.457065152203;5287.471252874681;5450.285503039921;5519.2824932758485;5540.722963851872;4869.245266983623;5243.68537887728;5548.250986116823;5579.3304803422225;5660.433956604306;4579.542045795394;4911.705297682008;5280.76236569363;5348.438413966477;5450.291462109901;4331.870043898688;4934.383202099738;5211.4527974562125;5393.651587103224;5591.8881622368135;4056.350983842571;4661.930071048869;4913.48408417111;5290.894182116259;5536.833894983157 09:56:21 INFO - PROCESS | 1639 | |5;yui.html;634.0299342006729;664.5613894829407;770.556929765385;824.9010118785744;836.9455985360951;14964.925175374121;15686.274509803909;16138.677226455464;16151.03093814372;16255;14855.92572037141;15167.544973650809;15233.466828661;15341.232938353094;15376.923115384436;924.2652091587171;962.5427921737154;962.8074385122968;965.3387429610704;968.7190714692731;868.9739307820744;930.7068273493861;954.3367359685021;963.0081016552962;963.6001059560274;967.9128878400943;968.5496244246424;973.5181085362756;974.1865542272211;978.0122076702546;955.1022039283051;963.9566219520103;964.4068897627943;966.6906589891238;968.293146003418;713.3116542536426;724.478375569589;724.6775185042658;725.4559080689482;726.1540305544088;15805.367785288574;16064.27710753019;16101.838981610152;16292;16480.917595412066;13827.377768000464;14126.293685315693;14148.646283842883;14193.78709319361;14203.360848761831;11690.824637630443;11857.94071029648;11882.702932426671;11923.523059077626;11947.223430476992;3547.94678079829;3595.1910820065536;3624.0939765058733;3775.4336849472797;3780.924381512382;95303;108710.45644771804;110360;112088.87911120948;112627.3105903412;10534.525946332433;10764.838527422095;10951.780964380669;11010.284331518425;11052.889471105185;22762.317130486113;22939.42651433694;23035.654465183037;23036.424089397566;23060.76939230586;7566.924330756622;7569.318761311399;7595.772126836204;7611.809704757426;7709.267619576131;4180.247555439989;4182.226288136704;4250.744955302692;4277.572242775697;4292.871213863589;3475.2528206435736;3506.1935754776546;3527.8588856445967;3563.7505374624047;3576.6065732769375;639.0414378432351;641.1504756198057;646.12127506591;654.780648482754;655.5968079630993;575.453319346614;629.1223742878659;678.4775722693597;683.3747121383878;711.2958171410266;8855.247303979106;10165.745856353651;10552.841707374395;10633.255672102878;10656.680299591024;658.4107224034462;704.7427688893517;707.2374965185268;714.3963350968527;714.6498215874288;5059.645824792303;5076.238564215398;5077.720725360066;5078.365204349457;5093.1087059764395;82247;90131;100351.99648003427;100508.99491005142;100607.49696251472;85469.71795423074;86778.13221867826;88002;88734.55632721924;88863;59082.11376829265;62669.37330626727;66937.6653116741;67299.32700673028;69548.6522567384;9187.678431254873;9831.9016809831;11261.98642122213;11318.377489237842;11338.659840204808;48830.75584622054;49274;49662.50337496579;51751.48248517467;52011;62771.686141569;63484.778032768634;63751.3624863764;65303.69392612216;65736;10636.09593184557;10970.670879873616;11791.292522448337;12649.683757906127;13076.411561479947;13530;15015.774763378777;15019;15034.711997724533;15059.548213553611;13151.60545183646;13286.734265314446;13896;13972.720545589234;14039.227842468763;13105.01712371595;13681.589552313446;13742;14038.649033774238;14426;11784.057275418118;12702.174358666656;13228.66928326761;13489.932550337186;13561.79657305161;9010;9163.946146193006;9286.767830804283;9325.393849399767;9532.475713835805;10104.49477526106;11320.773584528099;11523.827142593036;11551.653450396501;11566.248193867372 09:56:21 INFO - PROCESS | 1639 | __end_tp_report 09:56:21 INFO - PROCESS | 1639 | __start_cc_report 09:56:21 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,428 09:56:21 INFO - PROCESS | 1639 | __end_cc_report 09:56:21 INFO - PROCESS | 1639 | __startTimestamp1491324981199__endTimestamp 09:56:21 INFO - PROCESS | 1639 | 09:56:21 INFO - PROCESS | 1639 | ------- Summary: start ------- 09:56:21 INFO - PROCESS | 1639 | Number of tests: 6 09:56:21 INFO - PROCESS | 1639 | 09:56:21 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:6020.76 Median:6264.34 stddev:679.61 (10.8%) stddev-sans-first:631.62 09:56:21 INFO - PROCESS | 1639 | Values: 2756.9 3495.6 3507.2 3510.3 3543.5 6052.6 6087.2 6110.4 6113.8 6137.6 6069.2 6112.8 6125.8 6180.7 6209.3 5450.8 5513.4 5524.5 5538.4 5616.0 5568.6 5577.9 5589.3 5593.1 5620.8 5339.0 5362.3 5440.8 5453.4 5591.4 4312.0 5544.1 5588.6 5624.4 5643.6 5420.5 5530.4 5537.0 5539.3 5576.8 5859.3 6095.3 6187.3 6238.6 6239.0 6330.6 6331.7 6353.6 6354.0 6375.4 6501.3 6507.2 6525.0 6527.2 6548.8 6193.2 6196.5 6197.5 6242.0 6242.2 6328.7 6384.7 6447.2 6593.5 6674.7 6337.1 6372.5 6372.8 6374.0 6386.4 6363.9 6451.6 6477.4 6496.5 6497.4 6327.6 6346.8 6397.7 6409.2 6440.6 6325.8 6327.1 6373.2 6400.2 6421.9 5237.3 5242.1 5243.7 5255.9 5258.2 4569.5 4626.3 4646.3 4658.6 4702.9 6090.0 6174.8 6188.4 6194.1 6233.6 6465.8 6494.6 6499.1 6504.9 6520.6 5152.9 5985.8 6034.7 6144.6 6254.0 5779.7 5795.0 5813.0 5814.2 5814.6 6778.2 6805.3 6807.0 6810.5 6844.9 6254.1 6257.5 6465.5 6493.1 6521.6 6659.2 6675.1 6718.7 6728.7 6734.9 6380.4 6387.7 6394.9 6401.2 6418.3 6288.0 6370.1 6418.1 6441.1 6449.1 6240.4 6279.2 6309.0 6350.8 6382.3 6345.6 6355.9 6357.9 6375.1 6403.2 5518.3 6134.5 6249.2 6284.0 6364.2 6262.1 6264.3 6318.1 6323.2 6331.7 6277.3 6311.7 6322.1 6337.7 6345.8 09:56:21 INFO - PROCESS | 1639 | 09:56:21 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:54812.82 Median:3174.21 stddev:108634.83 (3422.4%) stddev-sans-first:108863.63 09:56:21 INFO - PROCESS | 1639 | Values: 709.6 722.4 811.4 850.5 884.5 39614.0 40591.0 40595.8 40688.4 40752.0 34002.5 41726.0 41869.8 41953.8 42010.0 7267.9 7450.2 7965.7 9084.2 9282.7 9972.9 10641.9 11195.4 11226.9 11384.7 9755.7 11060.7 11248.3 11253.2 11303.3 9841.6 10801.8 11272.5 11273.9 11277.5 10433.0 10843.7 10890.8 10958.3 10991.1 33513.5 39017.4 39108.8 39172.4 39272.0 3844.5 3915.5 3923.8 3928.0 3928.4 24832.0 27470.5 27528.9 28769.3 29176.7 3078.6 3142.7 3145.4 3172.2 3176.2 323770.0 326311.0 326910.7 328634.0 329257.4 43385.6 43676.0 52811.7 52986.0 53050.7 55741.7 56954.4 57428.7 57450.4 57729.4 14830.3 14832.3 14941.6 14942.3 14943.3 10562.4 12566.5 12606.3 12648.2 12662.6 12847.0 12854.4 12879.9 12929.7 12969.7 4598.8 4834.7 5145.7 5422.4 5431.8 1064.7 1106.3 1134.4 1135.4 1180.7 22773.6 23827.8 27274.9 27296.5 27374.9 1220.7 1227.8 1231.6 1241.4 1261.6 12879.7 14148.4 14162.4 14271.4 14384.4 459906.7 461446.0 461714.0 462735.0 463317.0 57122.9 57288.4 57376.4 57434.7 57507.7 1393.3 1441.8 1442.3 1450.9 1453.8 26283.3 26314.3 26336.2 26867.2 27100.9 48913.0 49015.3 49098.5 49224.3 49312.5 426431.0 427799.0 428793.0 429268.0 429748.9 34616.0 35005.0 35138.8 35168.5 35185.8 43218.9 43381.8 44061.6 44193.6 44423.8 29359.0 29504.0 29851.4 30322.5 30399.5 24139.8 29665.9 30357.7 30401.8 30858.2 32939.0 33313.3 33537.7 35122.0 35149.5 24503.3 25793.9 27084.9 28617.4 29164.7 22108.6 23458.8 23728.4 23767.4 23907.4 09:56:21 INFO - PROCESS | 1639 | 09:56:21 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:42976.96 Median:25016.31 stddev:128941.06 (515.4%) stddev-sans-first:129263.03 09:56:21 INFO - PROCESS | 1639 | Values: 341.2 412.5 415.9 432.5 440.0 20045.9 20395.9 20758.5 20995.8 21124.6 20701.0 20788.4 21171.6 21269.1 21976.2 6365.7 6707.5 6728.9 6756.5 6779.9 6522.5 6630.9 6910.9 7229.0 7371.2 7116.9 7312.2 7323.1 7352.3 7352.4 7334.8 7379.7 7386.2 7434.1 7451.5 6952.0 7163.0 7182.6 7230.3 7231.6 24650.5 24813.9 25218.7 25761.6 25817.2 2777.2 2884.0 2889.0 2897.6 2898.4 13604.6 15024.6 15096.8 15238.2 15249.3 1999.5 2069.8 2071.3 2072.7 2079.7 119805.0 144884.6 149097.0 152234.5 152423.2 15648.3 19853.9 20158.6 20281.0 20412.4 24550.4 30865.4 31202.2 31257.8 31343.5 9914.3 10027.2 10229.9 10275.2 10405.8 8485.5 8501.8 8681.8 8687.5 8691.8 9183.4 9482.4 9497.9 9553.5 9582.9 3965.1 4041.7 4059.3 4076.2 4083.7 815.2 938.9 998.2 1027.3 1049.2 15711.0 21215.8 21324.0 21375.6 21480.6 729.0 822.3 899.0 917.4 1081.5 5967.2 6228.3 6292.5 6353.4 6529.2 761503.0 766499.0 772501.0 780091.0 791023.0 154117.0 156502.0 157280.0 158615.4 160547.0 1257.4 1277.6 1301.9 1310.3 1311.7 11495.4 11517.7 11667.9 11792.8 11881.0 59478.4 59931.0 61230.4 61727.4 61749.5 69977.7 90588.0 91113.0 92142.5 92876.5 10270.9 11127.4 11347.1 11548.0 11649.6 11793.4 12572.7 12687.8 12961.3 13111.9 8649.5 9516.9 11053.8 11098.2 11215.0 8789.6 9762.3 10252.6 10366.4 10713.1 11358.4 11483.0 11491.0 11548.7 11600.7 11207.3 11442.2 11522.4 11731.8 11758.8 10728.0 10952.6 11067.1 11170.0 11209.0 09:56:21 INFO - PROCESS | 1639 | 09:56:21 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6664.40 Median:2448.23 stddev:4158.09 (169.8%) stddev-sans-first:4150.46 09:56:21 INFO - PROCESS | 1639 | Values: 1334.1 1474.4 1480.9 1505.6 1511.6 10671.6 11073.4 11222.7 11281.0 11301.3 11071.6 11254.0 11277.8 11299.8 11305.3 2405.9 2447.3 2449.1 2451.4 2452.0 2312.5 2382.2 2384.2 2387.1 2416.9 1820.6 2309.4 2317.9 2335.7 2428.2 2354.9 2396.7 2403.2 2403.7 2413.6 2371.9 2391.3 2397.2 2398.4 2413.0 10992.1 11147.0 11173.0 11217.3 11218.3 5842.8 5931.3 6017.0 6025.4 6032.2 3579.1 3659.7 3665.0 3666.7 3721.0 1035.0 1096.3 1106.2 1216.4 1250.1 11698.4 12035.3 13652.2 15492.6 15712.3 4335.2 4426.7 4821.3 4832.4 4858.6 10857.9 11752.9 11868.1 11877.5 11904.4 5057.8 5766.3 5893.1 5897.3 5951.7 4500.6 5141.3 5254.8 5350.3 5385.6 3324.5 3365.9 3656.5 3682.7 3844.9 1062.3 1066.0 1068.7 1075.9 1079.8 773.6 883.3 898.2 922.7 929.2 5793.2 5975.0 6572.1 6667.8 6862.3 977.4 1019.2 1019.7 1027.3 1058.5 3825.6 4237.7 4247.6 4368.7 4371.3 13696.4 14340.3 14340.6 14386.4 14454.3 12291.4 12406.4 13015.7 14296.0 14308.9 9808.0 10129.6 10131.9 10403.6 10420.9 6709.7 6719.8 6763.8 6910.5 6919.9 12186.0 12416.1 12494.9 12607.7 12613.1 9513.3 9521.0 11867.9 12682.8 12993.2 9134.7 9328.4 9398.6 9449.8 9455.1 8534.4 9640.7 10294.6 10341.5 10454.9 8829.5 8856.5 8914.2 8934.9 8961.4 7403.4 8540.2 8636.7 8718.2 8739.9 7822.0 7854.6 7881.0 7922.4 7956.0 5622.4 5726.0 5835.9 5872.1 6023.6 5742.0 5771.9 5793.6 5925.0 6043.1 09:56:21 INFO - PROCESS | 1639 | 09:56:21 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4553.81 Median:4970.52 stddev:1294.40 (26.0%) stddev-sans-first:1258.34 09:56:21 INFO - PROCESS | 1639 | Values: 315.4 373.2 384.2 389.1 392.7 5067.4 5176.5 5197.9 5212.0 5226.5 4559.8 4758.5 5135.8 5164.5 5188.4 3195.4 3735.9 3860.8 3911.3 4278.2 3825.4 3995.4 4109.4 4277.5 4325.1 3691.5 3832.5 4299.9 4318.2 4324.9 3312.3 4284.5 4303.3 4320.8 4327.4 3070.5 3662.3 3733.2 3933.6 3961.4 4560.8 4683.9 4900.7 5032.0 5049.4 4719.4 4976.4 5000.5 5002.3 5015.6 4719.2 5235.4 5284.7 5481.7 5488.7 4403.0 4407.6 4421.2 4422.7 4444.9 5100.0 5195.3 5653.5 5835.6 5880.8 4274.8 4421.3 4422.8 4440.3 4466.5 4256.9 4317.5 4451.0 4543.2 4561.2 4132.9 4255.0 4270.4 4650.9 4920.3 4964.6 5209.9 5301.9 5452.3 5507.1 1356.6 1452.0 1527.2 1557.9 1604.4 1017.0 1055.9 1136.9 1176.1 1206.8 4655.0 5176.6 5193.6 5222.5 5223.4 5032.4 5256.5 5738.8 5781.8 5880.8 4870.4 4913.0 5022.9 5157.9 5190.4 2614.2 2774.8 2840.7 2978.4 3003.8 5539.0 5585.9 5860.0 6036.5 6092.0 5143.9 5171.0 5357.7 5582.5 5872.1 5018.1 5230.6 5746.8 5864.8 6091.4 5143.9 5148.8 5231.6 5581.8 5827.8 4957.0 5252.2 5371.9 5615.0 5702.9 5826.6 5978.6 5996.6 6036.4 6067.6 3934.7 3939.2 4309.4 4435.6 4542.8 4990.7 5188.8 5452.7 5597.9 5859.9 4524.5 5287.5 5450.3 5519.3 5540.7 4869.2 5243.7 5548.3 5579.3 5660.4 4579.5 4911.7 5280.8 5348.4 5450.3 4331.9 4934.4 5211.5 5393.7 5591.9 4056.4 4661.9 4913.5 5290.9 5536.8 09:56:21 INFO - PROCESS | 1639 | 09:56:21 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:19899.67 Median:4216.49 stddev:28076.23 (665.9%) stddev-sans-first:28117.72 09:56:21 INFO - PROCESS | 1639 | Values: 634.0 664.6 770.6 824.9 836.9 14964.9 15686.3 16138.7 16151.0 16255.0 14855.9 15167.5 15233.5 15341.2 15376.9 924.3 962.5 962.8 965.3 968.7 869.0 930.7 954.3 963.0 963.6 967.9 968.5 973.5 974.2 978.0 955.1 964.0 964.4 966.7 968.3 713.3 724.5 724.7 725.5 726.2 15805.4 16064.3 16101.8 16292.0 16480.9 13827.4 14126.3 14148.6 14193.8 14203.4 11690.8 11857.9 11882.7 11923.5 11947.2 3547.9 3595.2 3624.1 3775.4 3780.9 95303.0 108710.5 110360.0 112088.9 112627.3 10534.5 10764.8 10951.8 11010.3 11052.9 22762.3 22939.4 23035.7 23036.4 23060.8 7566.9 7569.3 7595.8 7611.8 7709.3 4180.2 4182.2 4250.7 4277.6 4292.9 3475.3 3506.2 3527.9 3563.8 3576.6 639.0 641.2 646.1 654.8 655.6 575.5 629.1 678.5 683.4 711.3 8855.2 10165.7 10552.8 10633.3 10656.7 658.4 704.7 707.2 714.4 714.6 5059.6 5076.2 5077.7 5078.4 5093.1 82247.0 90131.0 100352.0 100509.0 100607.5 85469.7 86778.1 88002.0 88734.6 88863.0 59082.1 62669.4 66937.7 67299.3 69548.7 9187.7 9831.9 11262.0 11318.4 11338.7 48830.8 49274.0 49662.5 51751.5 52011.0 62771.7 63484.8 63751.4 65303.7 65736.0 10636.1 10970.7 11791.3 12649.7 13076.4 13530.0 15015.8 15019.0 15034.7 15059.5 13151.6 13286.7 13896.0 13972.7 14039.2 13105.0 13681.6 13742.0 14038.6 14426.0 11784.1 12702.2 13228.7 13489.9 13561.8 9010.0 9163.9 9286.8 9325.4 9532.5 10104.5 11320.8 11523.8 11551.7 11566.2 09:56:21 INFO - PROCESS | 1639 | -------- Summary: end -------- 09:56:21 INFO - PROCESS | 1639 | 09:56:21 INFO - TEST-INFO | 1639: exit 0 09:56:21 INFO - TEST-OK | dromaeo_css | took 1116647ms 09:56:21 INFO - TEST-START | kraken 09:56:21 INFO - operating with platform_type : mac_ 09:56:21 INFO - Initialising browser for kraken test... 09:56:21 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpeOzzQD/profile http://localhost:49242/getInfo.html) 09:56:24 INFO - PROCESS | 1651 | 2017-04-04 09:56:24.698 plugin-container[1652:10126] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 09:56:24 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:24 INFO - PROCESS | 1651 | 2017-04-04 09:56:24.700 plugin-container[1652:10126] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 09:56:24 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:24 INFO - PROCESS | 1651 | 2017-04-04 09:56:24.701 plugin-container[1652:10126] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:56:24 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 09:56:24 INFO - PROCESS | 1651 | colorDepth:24 09:56:24 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 09:56:24 INFO - PROCESS | 1651 | __metrics 09:56:24 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 09:56:25 INFO - TEST-INFO | 1651: exit 0 09:56:25 INFO - Browser initialized. 09:56:25 INFO - Running cycle 1/1 for kraken test... 09:56:25 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpeOzzQD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:27 INFO - PROCESS | 1653 | 2017-04-04 09:56:27.992 plugin-container[1654:10251] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 09:56:27 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:27 INFO - PROCESS | 1653 | 2017-04-04 09:56:27.992 plugin-container[1654:10251] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.CFPasteboardClient' 09:56:27 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:27 INFO - PROCESS | 1653 | 2017-04-04 09:56:27.992 plugin-container[1654:10251] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:56:37 INFO - PROCESS | 1653 | RSS: Main: 220872704 09:56:37 INFO - PROCESS | 1653 | 09:56:40 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:56:41 INFO - PROCESS | 1653 | RSS: Main: 235352064 09:56:41 INFO - PROCESS | 1653 | 09:56:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:56:44 INFO - PROCESS | 1653 | RSS: Main: 243367936 09:56:44 INFO - PROCESS | 1653 | 09:56:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:56:48 INFO - PROCESS | 1653 | RSS: Main: 247005184 09:56:48 INFO - PROCESS | 1653 | 09:56:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:56:51 INFO - PROCESS | 1653 | RSS: Main: 247443456 09:56:51 INFO - PROCESS | 1653 | 09:56:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:56:54 INFO - PROCESS | 1653 | RSS: Main: 247504896 09:56:54 INFO - PROCESS | 1653 | 09:56:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:56:57 INFO - PROCESS | 1653 | RSS: Main: 252760064 09:56:57 INFO - PROCESS | 1653 | 09:57:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:01 INFO - PROCESS | 1653 | RSS: Main: 252502016 09:57:01 INFO - PROCESS | 1653 | 09:57:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:57:05 INFO - PROCESS | 1653 | RSS: Main: 252305408 09:57:05 INFO - PROCESS | 1653 | 09:57:07 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:08 INFO - PROCESS | 1653 | RSS: Main: 252080128 09:57:08 INFO - PROCESS | 1653 | 09:57:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:10 INFO - PROCESS | 1653 | RSS: Main: 252026880 09:57:10 INFO - PROCESS | 1653 | 09:57:12 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:13 INFO - PROCESS | 1653 | RSS: Main: 252084224 09:57:13 INFO - PROCESS | 1653 | 09:57:16 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:17 INFO - PROCESS | 1653 | RSS: Main: 252497920 09:57:17 INFO - PROCESS | 1653 | 09:57:20 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:20 INFO - PROCESS | 1653 | RSS: Main: 252239872 09:57:20 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:57:22 INFO - PROCESS | 1653 | RSS: Main: 254201856 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | __start_tp_report 09:57:22 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 09:57:22 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 09:57:22 INFO - PROCESS | 1653 | |i|pagename|runs| 09:57:22 INFO - PROCESS | 1653 | |0;ai-astar;141;181;151;124;120;121;125;120;122;122 09:57:22 INFO - PROCESS | 1653 | |1;audio-beat-detection;174;142;141;148;113;112;121;116;132;114 09:57:22 INFO - PROCESS | 1653 | |2;audio-dft;195;210;201;202;198;201;196;195;196;195 09:57:22 INFO - PROCESS | 1653 | |3;audio-fft;86;69;75;77;54;54;61;85;99;84 09:57:22 INFO - PROCESS | 1653 | |4;audio-oscillator;92;78;119;105;104;57;96;104;62;116 09:57:22 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;137;138;90;91;91;90;92;91;91;91 09:57:22 INFO - PROCESS | 1653 | |6;imaging-darkroom;130;155;118;97;97;128;125;107;90;152 09:57:22 INFO - PROCESS | 1653 | |7;imaging-desaturate;111;88;141;116;91;91;92;92;92;91 09:57:22 INFO - PROCESS | 1653 | |8;json-parse-financial;64;64;62;76;104;87;99;99;94;66 09:57:22 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;63;58;70;66;58;58;59;57;59;60 09:57:22 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;87;81;84;86;88;86;87;83;86;104 09:57:22 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;140;138;199;152;160;141;147;180;212;158 09:57:22 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;181;196;173;171;174;173;168;174;169;169 09:57:22 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;67;66;60;72;94;87;74;62;61;68 09:57:22 INFO - PROCESS | 1653 | __end_tp_report 09:57:22 INFO - PROCESS | 1653 | __start_cc_report 09:57:22 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1017 09:57:22 INFO - PROCESS | 1653 | __end_cc_report 09:57:22 INFO - PROCESS | 1653 | __startTimestamp1491325042676__endTimestamp 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | ------- Summary: start ------- 09:57:22 INFO - PROCESS | 1653 | Number of tests: 14 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:132.70 Median:124.50 stddev:19.89 (16.0%) stddev-sans-first:20.87 09:57:22 INFO - PROCESS | 1653 | Values: 141.0 181.0 151.0 124.0 120.0 121.0 125.0 120.0 122.0 122.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:136.50 stddev:20.16 (14.8%) stddev-sans-first:14.28 09:57:22 INFO - PROCESS | 1653 | Values: 174.0 142.0 141.0 148.0 113.0 112.0 121.0 116.0 132.0 114.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:198.90 Median:199.50 stddev:4.77 (2.4%) stddev-sans-first:4.85 09:57:22 INFO - PROCESS | 1653 | Values: 195.0 210.0 201.0 202.0 198.0 201.0 196.0 195.0 196.0 195.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:74.40 Median:80.50 stddev:14.88 (18.5%) stddev-sans-first:15.18 09:57:22 INFO - PROCESS | 1653 | Values: 86.0 69.0 75.0 77.0 54.0 54.0 61.0 85.0 99.0 84.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:59.50 stddev:21.30 (35.8%) stddev-sans-first:22.58 09:57:22 INFO - PROCESS | 1653 | Values: 92.0 78.0 119.0 105.0 104.0 57.0 96.0 104.0 62.0 116.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:100.20 Median:91.00 stddev:19.67 (21.6%) stddev-sans-first:15.72 09:57:22 INFO - PROCESS | 1653 | Values: 137.0 138.0 90.0 91.0 91.0 90.0 92.0 91.0 91.0 91.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:119.90 Median:153.50 stddev:22.53 (14.7%) stddev-sans-first:23.60 09:57:22 INFO - PROCESS | 1653 | Values: 130.0 155.0 118.0 97.0 97.0 128.0 125.0 107.0 90.0 152.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:100.50 Median:91.00 stddev:17.11 (18.8%) stddev-sans-first:17.72 09:57:22 INFO - PROCESS | 1653 | Values: 111.0 88.0 141.0 116.0 91.0 91.0 92.0 92.0 92.0 91.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:81.50 Median:81.50 stddev:16.89 (20.7%) stddev-sans-first:16.69 09:57:22 INFO - PROCESS | 1653 | Values: 64.0 64.0 62.0 76.0 104.0 87.0 99.0 99.0 94.0 66.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:59.50 stddev:4.24 (7.1%) stddev-sans-first:4.42 09:57:22 INFO - PROCESS | 1653 | Values: 63.0 58.0 70.0 66.0 58.0 58.0 59.0 57.0 59.0 60.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:86.00 stddev:6.27 (7.3%) stddev-sans-first:6.65 09:57:22 INFO - PROCESS | 1653 | Values: 87.0 81.0 84.0 86.0 88.0 86.0 87.0 83.0 86.0 104.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:162.70 Median:159.00 stddev:25.89 (16.3%) stddev-sans-first:26.13 09:57:22 INFO - PROCESS | 1653 | Values: 140.0 138.0 199.0 152.0 160.0 141.0 147.0 180.0 212.0 158.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.80 Median:173.50 stddev:8.32 (4.8%) stddev-sans-first:8.52 09:57:22 INFO - PROCESS | 1653 | Values: 181.0 196.0 173.0 171.0 174.0 173.0 168.0 174.0 169.0 169.0 09:57:22 INFO - PROCESS | 1653 | 09:57:22 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:70.00 stddev:11.29 (16.1%) stddev-sans-first:11.88 09:57:22 INFO - PROCESS | 1653 | Values: 67.0 66.0 60.0 72.0 94.0 87.0 74.0 62.0 61.0 68.0 09:57:22 INFO - PROCESS | 1653 | -------- Summary: end -------- 09:57:22 INFO - PROCESS | 1653 | 09:57:23 INFO - TEST-INFO | 1653: exit 0 09:57:23 INFO - TEST-OK | kraken | took 61818ms 09:57:23 INFO - SUITE-END | took 1178s 09:57:23 INFO - Completed test suite (00:19:38) 09:57:23 INFO - javascript benchmark 09:57:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7643.528458702949, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2756.875940582674, 3495.632958539352, 3507.175813683785, 3510.280392519532, 3543.521624580681, 6052.57631965762, 6087.208662873826, 6110.419510146535, 6113.816585502431, 6137.601055931373, 6069.180660610817, 6112.816615501542, 6125.816225513242, 6180.69096545172, 6209.316975132731, 5450.754716037768, 5513.448655134494, 5524.502794748471, 5538.418466061066, 5616.0, 5568.638038527482, 5577.944220557783, 5589.301337332833, 5593.133064375008, 5620.831375058734, 5339.038972984861, 5362.276092727471, 5440.836774896739, 5453.400125986138, 5591.384947655754, 4311.9784401078105, 5544.085225937715, 5588.580856435743, 5624.353199382042, 5643.6049476536655, 5420.539254163361, 5530.391656917735, 5537.0, 5539.307586551681, 5576.804811831607, 5859.267591551056, 6095.329513753483, 6187.257529096492, 6238.594491358047, 6239.0, 6330.620162790247, 6331.651759153245, 6353.618782873043, 6353.9682301588655, 6375.362463753634, 6501.349865013508, 6507.186601674791, 6525.0, 6527.249366322839, 6548.8035358939305, 6193.163922870445, 6196.473299769525, 6197.504199664016, 6242.0, 6242.219722534684, 6328.6519241442165, 6384.7126879290545, 6447.2263328400895, 6593.538452308292, 6674.699638516278, 6337.081123237105, 6372.522060845455, 6372.776952806629, 6374.043893416025, 6386.425221730066, 6363.872722545523, 6451.645159516178, 6477.4494167996245, 6496.480281577557, 6497.382748638872, 6327.588706734047, 6346.777862774781, 6397.744090236339, 6409.166808314889, 6440.645764482999, 6325.778597749057, 6327.114204011442, 6373.203349581302, 6400.199975003125, 6421.871562568723, 5237.266782650432, 5242.135047717084, 5243.711595862264, 5255.921161182585, 5258.2112683097075, 4569.451665800125, 4626.282926146453, 4646.279826626878, 4658.557437043475, 4702.905941881143, 6090.0, 6174.783882564088, 6188.412100850412, 6194.14175747274, 6233.594816336924, 6465.838354041092, 6494.577852439576, 6499.122618446544, 6504.934950650528, 6520.608763474207, 5152.896942061137, 5985.820425387245, 6034.728437220336, 6144.556029333103, 6253.96809526423, 5779.711014449261, 5794.971025144847, 5813.0, 5814.186013958049, 5814.592978491465, 6778.152730908637, 6805.285445028299, 6807.0, 6810.523263371517, 6844.8973265402055, 6254.061890716429, 6257.53068519872, 6465.547411681137, 6493.090967264492, 6521.608703477807, 6659.167604049494, 6675.132232809704, 6718.6976586054725, 6728.6972086255255, 6734.865302693821, 6380.425761681471, 6387.680615969276, 6394.904076438764, 6401.231852177769, 6418.261899881387, 6288.025356069817, 6370.108184854217, 6418.0693799397895, 6441.098246245436, 6449.097126402401, 6240.4071613196675, 6279.215098112736, 6309.0, 6350.7777227796805, 6382.297947225708, 6345.587536810092, 6355.872882542416, 6357.8873697103745, 6375.139356186855, 6403.199600049994, 5518.33779946409, 6134.539909506894, 6249.218847644044, 6283.96858015707, 6364.172657554674, 6262.123302737711, 6264.342244064308, 6318.052292156213, 6323.20959880015, 6331.715072821642, 6277.3408792078, 6311.715972781328, 6322.114903913363, 6337.7148028337915, 6345.809625711236], "value": 5975.776504509436, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.5884387055509, 722.382363079567, 811.431997601679, 850.4939560961226, 884.5400391796267, 39614.0, 40590.99999999996, 40595.79702101489, 40688.38967415484, 40751.98120047, 34002.48996265058, 41726.0, 41869.7906510467, 41953.79023104888, 42010.0, 7267.854642907138, 7450.217727138654, 7965.6813727450835, 9084.182423581893, 9282.675106371278, 9972.850407243897, 10641.946790266076, 11195.384253866034, 11226.887731122706, 11384.71538211543, 9755.707328780149, 11060.723481912977, 11248.26886252395, 11253.156013298994, 11303.26528775631, 9841.606335746561, 10801.811800701917, 11272.492737826777, 11273.928976747236, 11277.492512836941, 10432.956704329583, 10843.67468975932, 10890.782184356269, 10958.342499450058, 10991.120710343153, 33513.497297540554, 39017.41473877894, 39108.80445597782, 39172.412413813814, 39272.0, 3844.5386553613744, 3915.490986171808, 3923.8234279457492, 3928.0, 3928.3910993796007, 24832.0, 27470.45059098827, 27528.862355688292, 28769.28076798076, 29176.708232917823, 3078.615173103362, 3142.685731426862, 3145.370925814846, 3172.2228054126654, 3176.1900715317447, 323770.0, 326311.0, 326910.7308926928, 328634.0, 329257.35371323227, 43385.56614433879, 43676.0, 52811.73594132005, 52986.0, 53050.734746326794, 55741.72129139328, 56954.43045569574, 57428.71285643545, 57450.425495745345, 57729.422705772406, 14830.332635031235, 14832.332545035499, 14941.551753447415, 14942.327595258239, 14943.327550260265, 10562.366258024544, 12566.497340106476, 12606.306653133983, 12648.241105533698, 12662.556810511587, 12847.0, 12854.421551030226, 12879.905208057233, 12929.741405171844, 12969.675758106123, 4598.793054312497, 4834.661573689878, 5145.716985565755, 5422.43064478232, 5431.755570999315, 1064.685917654294, 1106.3417266726285, 1134.3721759275466, 1135.3982389333667, 1180.722530205401, 22773.633581984803, 23827.761722382555, 27274.863625681744, 27296.45407091847, 27374.863125684245, 1220.725336799213, 1227.8281040654138, 1231.6305108467602, 1241.3793103448277, 1261.6341261034197, 12879.678008049874, 14148.434062637585, 14162.371327297287, 14271.429142834171, 14384.35270412834, 459906.7004665022, 461446.0, 461714.0, 462735.0, 463317.0, 57122.85754284891, 57288.42711572831, 57376.42623573711, 57434.7128264356, 57507.712461437426, 1393.2754967416886, 1441.7837324401423, 1442.322108608952, 1450.8766754825958, 1453.7528620134603, 26283.342916426478, 26314.34214144585, 26336.20991370262, 26867.193984180507, 27100.864495678183, 48913.0, 49015.26477102918, 49098.509014909396, 49224.261636076204, 49312.50687493079, 426431.0, 427799.0, 428793.0, 429268.0, 429748.8512557417, 34616.0, 35005.0, 35138.77014304487, 35168.47247291344, 35185.824070879484, 43218.919527012076, 43381.78309108434, 44061.559384405744, 44193.55806441895, 44423.777881110385, 29359.0, 29504.0, 29851.402971940006, 30322.545161823033, 30399.480025999055, 24139.758602414455, 29665.851670741507, 30357.696423035486, 30401.847990759903, 30858.228544285674, 32939.01182964515, 33313.333733325686, 33537.664623353456, 35122.0, 35149.47275790814, 24503.264902052968, 25793.87103064473, 27084.864575676995, 28617.426041567913, 29164.70835291705, 22108.55782884301, 23458.76541234566, 23728.40678983039, 23767.405814854767, 23907.402314942265], "value": 18727.27229197197, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.24073935493544, 412.5482596556771, 415.8669225847728, 432.4659046078094, 439.95510662177327, 20045.899770501146, 20395.898020509914, 20758.498761443698, 20995.790042099576, 21124.57750844982, 20700.999999999964, 20788.376348709564, 21171.576568468623, 21269.149234030658, 21976.230831920886, 6365.681715914209, 6707.463402927754, 6728.93271067288, 6756.49326300527, 6779.932200677979, 6522.510811689119, 6630.900536491957, 6910.861782764342, 7228.963855180743, 7371.152317483477, 7116.857662846766, 7312.232215617337, 7323.084614423197, 7352.338289553939, 7352.448566357518, 7334.779956601311, 7379.704811807521, 7386.15059268183, 7434.070741157356, 7451.478396512246, 6951.965240173818, 7162.964185179041, 7182.569045857213, 7230.276972302728, 7231.638418079127, 24650.50698986028, 24813.875930620412, 25218.747812521822, 25761.61357579638, 25817.225483235532, 2777.1807316841378, 2883.956740648892, 2889.0321742216383, 2897.6377952755906, 2898.3913378190605, 13604.591862244148, 15024.624384390369, 15096.773548396783, 15238.161901095435, 15249.313780879887, 1999.540105775665, 2069.7619773726065, 2071.2854065347433, 2072.7305450291515, 2079.6984437256665, 119805.0, 144884.55115448817, 149097.0, 152234.47765522313, 152423.23788381097, 15648.278689344163, 19853.900730496254, 20158.596828063357, 20280.985950702405, 20412.387628371172, 24550.386240344134, 30865.382692346477, 31202.219944501114, 31257.8437107813, 31343.529847052312, 9914.256430767577, 10027.247956403298, 10229.897701023043, 10275.177985761122, 10405.791884162274, 8485.490870547786, 8501.787455313543, 8681.826363472695, 8687.478751274944, 8691.826163476695, 9183.403078799856, 9482.38364506305, 9497.865704317559, 9553.474558899188, 9582.856257156149, 3965.147493288957, 4041.656459201001, 4059.2693315202955, 4076.164386300801, 4083.6932181701573, 815.229608020415, 938.8920274168421, 998.1814911772423, 1027.337367398039, 1049.192122066005, 15711.0, 21215.787842121383, 21324.0, 21375.57248855014, 21480.57038859214, 728.9781306560811, 822.321584692631, 898.9730308090768, 917.3945196170629, 1081.4808891731855, 5967.224260846062, 6228.314885362606, 6292.528060395489, 6353.396427339396, 6529.151210342625, 761503.0, 766499.0, 772501.0, 780091.0, 791023.0, 154117.0, 156502.0, 157280.0, 158615.41384586005, 160547.0, 1257.4215860704178, 1277.5592420614873, 1301.8633043530483, 1310.2531557012412, 1311.698309388846, 11495.425228738362, 11517.654470365902, 11667.883321166679, 11792.764144716886, 11881.0, 59478.40521594902, 59931.0, 61230.38769612247, 61727.38272617216, 61749.45626359377, 69977.65011174911, 90588.0, 91113.0, 92142.53928730314, 92876.53561732148, 10270.897291026995, 11127.387993660426, 11347.092232621206, 11548.0, 11649.621609733422, 11793.351365674971, 12572.748545028866, 12687.80968285495, 12961.287129207984, 13111.868881311446, 8649.524276164871, 9516.85724714116, 11053.83419248728, 11098.167637427065, 11215.0, 8789.560521974003, 9762.316637835283, 10252.64115755945, 10366.378017318984, 10713.089387402159, 11358.375289359165, 11483.0, 11491.0, 11548.653540393801, 11600.6519804406, 11207.271527350696, 11442.199046067028, 11522.366269855238, 11731.824022639837, 11758.764824703287, 10728.0, 10952.616658416917, 11067.059299959594, 11170.0, 11209.0], "value": 11111.217430807837, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.1461464662616, 1474.3660226102772, 1480.8889333300006, 1505.638646724786, 1511.5540915429947, 10671.573137074518, 11073.446327683603, 11222.719432014193, 11281.041111505521, 11301.265417747862, 11071.612493562727, 11254.0, 11277.774444511106, 11299.774004519904, 11305.321680699144, 2405.8797060146967, 2447.3269850791085, 2449.1428000199967, 2451.4116612013077, 2451.9754802451926, 2312.537492501498, 2382.2376839411395, 2384.1655420602738, 2387.0809738250723, 2416.8791560421996, 1820.6176702892408, 2309.3764683535437, 2317.9304620861403, 2335.6730057791924, 2428.1501474483966, 2354.9058037678474, 2396.664466974625, 2403.158894386968, 2403.6995375578053, 2413.553492603865, 2371.885213949441, 2391.318474234835, 2397.2328854766483, 2398.400399900025, 2412.987935060331, 10992.120630349633, 11147.0, 11172.994430501294, 11217.326960382321, 11218.326900386002, 5842.795502157404, 5931.288245410536, 6016.96991515044, 6025.397460253984, 6032.185654936571, 3579.1231148368556, 3659.670629643345, 3664.963350366489, 3666.7249956253386, 3721.0325315418186, 1034.9793004139874, 1096.3038470571164, 1106.164845541611, 1216.4343580235234, 1250.1311588446033, 11698.356590387526, 12035.27788332694, 13652.180869147884, 15492.612684682861, 15712.292946817306, 4335.176316499855, 4426.690131690751, 4821.276808478756, 4832.371791667131, 4858.58702010326, 10857.891421085687, 11752.88347606976, 11868.050555955502, 11877.465514051888, 11904.404779760976, 5057.848264552069, 5766.250387449605, 5893.14549390336, 5897.262842144732, 5951.732172052268, 4500.594946454769, 5141.331626888556, 5254.791397978486, 5350.250964864922, 5385.5960802939935, 3324.451465508164, 3365.915852103717, 3656.469811877263, 3682.742208045465, 3844.8654297099465, 1062.2935747727674, 1066.0299127793671, 1068.6900798768424, 1075.8708954925303, 1079.80023695615, 773.6015951784723, 883.3154305413356, 898.2409863665192, 922.6770630279349, 929.163752622645, 5793.159991801165, 5975.0, 6572.11276477679, 6667.833304167435, 6862.313768623198, 977.3891317926297, 1019.1541020952592, 1019.6940917724653, 1027.291169093338, 1058.5183741397645, 3825.617438256152, 4237.745735255895, 4247.5964783345535, 4368.694191406635, 4371.256886329332, 13696.383662735201, 14340.282985850667, 14340.641483962985, 14386.35261413239, 14454.277286135652, 12291.385430728427, 12406.441710123067, 13015.739685206243, 14296.0, 14308.856911430961, 9808.0, 10129.64546240878, 10131.898681013243, 10403.5838566458, 10420.895791041992, 6709.664516774044, 6719.832004199934, 6763.7632682855865, 6910.516263861481, 6919.93080069213, 12186.025117990717, 12416.068794840243, 12494.937525312314, 12607.747845042864, 12613.117081804186, 9513.33406661527, 9521.0, 11867.881321186678, 12682.809757853454, 12993.155444896052, 9134.725958221265, 9328.39365441244, 9398.577064031999, 9449.811003780023, 9455.101765332283, 8534.402591818513, 9640.650308956892, 10294.639687610897, 10341.534630941776, 10454.895451045391, 8829.514376709343, 8856.512891791013, 8914.242289405473, 8934.86597701022, 8961.417507861968, 7403.370713489414, 8540.231379175904, 8636.697715580176, 8718.17177368149, 8739.91260087391, 7822.0, 7854.607269636381, 7880.975473188449, 7922.405819563441, 7956.045274567089, 5622.381538030895, 5726.0, 5835.8832823342445, 5872.0898260769645, 6023.608465449732, 5742.0, 5771.8557036074435, 5793.565482588739, 5925.0, 6043.063325184604], "value": 5081.803617498653, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.3787039532121, 373.21624588364455, 384.17594260311625, 389.13611781844315, 392.73490393984054, 5067.390293998594, 5176.456472070435, 5197.856471576257, 5212.0, 5226.529612334889, 4559.794809233585, 4758.4542269885615, 5135.7688903999315, 5164.457731938149, 5188.448360254529, 3195.4407978603767, 3735.8505659773577, 3860.8417474757603, 3911.3350730375764, 4278.165757677262, 3825.445310429986, 3995.420664003718, 4109.363048727452, 4277.465316835395, 4325.113351762897, 3691.53855768029, 3832.5400951885813, 4299.871003869889, 4318.244307246219, 4324.891877703051, 3312.337532493511, 4284.464441944757, 4303.26844436445, 4320.783960801948, 4327.394164816928, 3070.539419087155, 3662.34077865984, 3733.1600389912323, 3933.606639336072, 3961.4057891316534, 4560.817567297304, 4683.929741053887, 4900.672180714168, 5032.037505372908, 5049.444561098277, 4719.413047789021, 4976.402831660188, 5000.499950005007, 5002.349694539686, 5015.598752099823, 4719.174144524729, 5235.371755389339, 5284.709340986245, 5481.698506582136, 5488.670679759227, 4402.974631669404, 4407.581279778416, 4421.248387774054, 4422.71252368595, 4444.933326000112, 5099.954509325579, 5195.324607801, 5653.462921022497, 5835.620684655484, 5880.8235752927485, 4274.786260687016, 4421.292593185075, 4422.783734473046, 4440.267355886307, 4466.4640243171025, 4256.871928938834, 4317.525072242051, 4451.017818061906, 4543.227651299221, 4561.2245918193985, 4132.91734165315, 4254.957450425518, 4270.423492828429, 4650.906981860344, 4920.286558449005, 4964.627652926045, 5209.895802083937, 5301.920471192935, 5452.345718513804, 5507.072524616352, 1356.6433566433566, 1451.992740036293, 1527.1753253243346, 1557.9143147127018, 1604.374294025331, 1016.9694909152737, 1055.875492600378, 1136.85177970249, 1176.1061593189067, 1206.817319027358, 4654.95263565695, 5176.559992400613, 5193.584513238932, 5222.451642577475, 5223.425423203445, 5032.421271553745, 5256.476891092297, 5738.810668212145, 5781.82654520365, 5880.794172204038, 4870.4171144378215, 4912.975435122801, 5022.924656130161, 5157.922631160535, 5190.37715474138, 2614.163467690359, 2774.8057635965692, 2840.6648875028704, 2978.4087843576226, 3003.759699224057, 5539.0, 5585.916211256835, 5859.974504461702, 6036.486898613667, 6092.0453580013655, 5143.887947791607, 5171.0, 5357.696190638063, 5582.455140985503, 5872.122659896705, 5018.096742586369, 5230.633855630145, 5746.770129194785, 5864.794732184353, 6091.380706296247, 5143.871403214949, 5148.819791307286, 5231.59346700522, 5581.776728930798, 5827.79602713903, 4956.950430495649, 5252.1596544552685, 5371.892562148813, 5615.0, 5702.942970570241, 5826.592138550261, 5978.551608629457, 5996.580239383201, 6036.366181550874, 6067.6324639825325, 3934.6655534279907, 3939.1727737175515, 4309.439772829637, 4435.600795928382, 4542.7955741992355, 4990.6506544541535, 5188.792448302026, 5452.700101494456, 5597.857585386866, 5859.882802343844, 4524.457065152203, 5287.471252874681, 5450.285503039921, 5519.2824932758485, 5540.722963851872, 4869.245266983623, 5243.68537887728, 5548.250986116823, 5579.3304803422225, 5660.433956604306, 4579.542045795394, 4911.705297682008, 5280.76236569363, 5348.438413966477, 5450.291462109901, 4331.870043898688, 4934.383202099738, 5211.4527974562125, 5393.651587103224, 5591.8881622368135, 4056.350983842571, 4661.930071048869, 4913.48408417111, 5290.894182116259, 5536.833894983157], "value": 4175.633839673593, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.0299342006729, 664.5613894829407, 770.556929765385, 824.9010118785744, 836.9455985360951, 14964.925175374121, 15686.274509803909, 16138.677226455464, 16151.03093814372, 16255.0, 14855.92572037141, 15167.544973650809, 15233.466828661, 15341.232938353094, 15376.923115384436, 924.2652091587171, 962.5427921737154, 962.8074385122968, 965.3387429610704, 968.7190714692731, 868.9739307820744, 930.7068273493861, 954.3367359685021, 963.0081016552962, 963.6001059560274, 967.9128878400943, 968.5496244246424, 973.5181085362756, 974.1865542272211, 978.0122076702546, 955.1022039283051, 963.9566219520103, 964.4068897627943, 966.6906589891238, 968.293146003418, 713.3116542536426, 724.478375569589, 724.6775185042658, 725.4559080689482, 726.1540305544088, 15805.367785288574, 16064.27710753019, 16101.838981610152, 16292.0, 16480.917595412066, 13827.377768000464, 14126.293685315693, 14148.646283842883, 14193.78709319361, 14203.360848761831, 11690.824637630443, 11857.94071029648, 11882.702932426671, 11923.523059077626, 11947.223430476992, 3547.94678079829, 3595.1910820065536, 3624.0939765058733, 3775.4336849472797, 3780.924381512382, 95303.0, 108710.45644771804, 110360.0, 112088.87911120948, 112627.3105903412, 10534.525946332433, 10764.838527422095, 10951.780964380669, 11010.284331518425, 11052.889471105185, 22762.317130486113, 22939.42651433694, 23035.654465183037, 23036.424089397566, 23060.76939230586, 7566.924330756622, 7569.318761311399, 7595.772126836204, 7611.809704757426, 7709.267619576131, 4180.247555439989, 4182.226288136704, 4250.744955302692, 4277.572242775697, 4292.871213863589, 3475.2528206435736, 3506.1935754776546, 3527.8588856445967, 3563.7505374624047, 3576.6065732769375, 639.0414378432351, 641.1504756198057, 646.12127506591, 654.780648482754, 655.5968079630993, 575.453319346614, 629.1223742878659, 678.4775722693597, 683.3747121383878, 711.2958171410266, 8855.247303979106, 10165.745856353651, 10552.841707374395, 10633.255672102878, 10656.680299591024, 658.4107224034462, 704.7427688893517, 707.2374965185268, 714.3963350968527, 714.6498215874288, 5059.645824792303, 5076.238564215398, 5077.720725360066, 5078.365204349457, 5093.1087059764395, 82247.0, 90131.0, 100351.99648003427, 100508.99491005142, 100607.49696251472, 85469.71795423074, 86778.13221867826, 88002.0, 88734.55632721924, 88863.0, 59082.11376829265, 62669.37330626727, 66937.6653116741, 67299.32700673028, 69548.6522567384, 9187.678431254873, 9831.9016809831, 11261.98642122213, 11318.377489237842, 11338.659840204808, 48830.75584622054, 49274.0, 49662.50337496579, 51751.48248517467, 52011.0, 62771.686141569, 63484.778032768634, 63751.3624863764, 65303.69392612216, 65736.0, 10636.09593184557, 10970.670879873616, 11791.292522448337, 12649.683757906127, 13076.411561479947, 13530.0, 15015.774763378777, 15019.0, 15034.711997724533, 15059.548213553611, 13151.60545183646, 13286.734265314446, 13896.0, 13972.720545589234, 14039.227842468763, 13105.01712371595, 13681.589552313446, 13742.0, 14038.649033774238, 14426.0, 11784.057275418118, 12702.174358666656, 13228.66928326761, 13489.932550337186, 13561.79657305161, 9010.0, 9163.946146193006, 9286.767830804283, 9325.393849399767, 9532.475713835805, 10104.49477526106, 11320.773584528099, 11523.827142593036, 11551.653450396501, 11566.248193867372], "value": 7557.037035742397, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 181.0, 151.0, 124.0, 120.0, 121.0, 125.0, 120.0, 122.0, 122.0], "value": 132.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 142.0, 141.0, 148.0, 113.0, 112.0, 121.0, 116.0, 132.0, 114.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 210.0, 201.0, 202.0, 198.0, 201.0, 196.0, 195.0, 196.0, 195.0], "value": 198.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 75.0, 77.0, 54.0, 54.0, 61.0, 85.0, 99.0, 84.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 78.0, 119.0, 105.0, 104.0, 57.0, 96.0, 104.0, 62.0, 116.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 90.0, 91.0, 91.0, 90.0, 92.0, 91.0, 91.0, 91.0], "value": 100.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 155.0, 118.0, 97.0, 97.0, 128.0, 125.0, 107.0, 90.0, 152.0], "value": 119.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 88.0, 141.0, 116.0, 91.0, 91.0, 92.0, 92.0, 92.0, 91.0], "value": 100.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 62.0, 76.0, 104.0, 87.0, 99.0, 99.0, 94.0, 66.0], "value": 81.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 58.0, 70.0, 66.0, 58.0, 58.0, 59.0, 57.0, 59.0, 60.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 81.0, 84.0, 86.0, 88.0, 86.0, 87.0, 83.0, 86.0, 104.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 199.0, 152.0, 160.0, 141.0, 147.0, 180.0, 212.0, 158.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 196.0, 173.0, 171.0, 174.0, 173.0, 168.0, 174.0, 169.0, 169.0], "value": 174.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 60.0, 72.0, 94.0, 87.0, 74.0, 62.0, 61.0, 68.0], "value": 71.1, "unit": "score"}]}]} 09:57:23 INFO - Return code: 0 09:57:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:57:23 ERROR - # TBPL SUCCESS # 09:57:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:57:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:57:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:57:24 INFO - 2017-04-04 09:57:24.008 screenresolution[1658:10639] starting screenresolution argv=screenresolution get 09:57:24 INFO - 2017-04-04 09:57:24.011 screenresolution[1658:10639] Display 0: 1600x1200x32@60 09:57:24 INFO - 2017-04-04 09:57:24.018 screenresolution[1659:10642] starting screenresolution argv=screenresolution list 09:57:24 INFO - Available Modes on Display 0 09:57:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:57:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:57:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:57:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:57:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:57:24 INFO - Intel Iris: 09:57:24 INFO - Chipset Model: Intel Iris 09:57:24 INFO - Type: GPU 09:57:24 INFO - Bus: Built-In 09:57:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:57:24 INFO - Vendor: Intel (0x8086) 09:57:24 INFO - Device ID: 0x0a2e 09:57:24 INFO - Revision ID: 0x0009 09:57:24 INFO - Displays: 09:57:24 INFO - Display: 09:57:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:57:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:57:24 INFO - Display Serial Number: 1600x1200 60 09:57:24 INFO - Main Display: Yes 09:57:24 INFO - Mirror: Off 09:57:24 INFO - Online: Yes 09:57:24 INFO - Rotation: Supported 09:57:24 INFO - Return code: 0 09:57:24 INFO - Running post-action listener: _resource_record_post_action 09:57:24 INFO - [mozharness: 2017-04-04 16:57:24.264414Z] Finished run-tests step (success) 09:57:24 INFO - Running post-run listener: _resource_record_post_run 09:57:24 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 40147456; Write bytes: 514717184; Read time: 436; Write time: 2895 09:57:24 INFO - TinderboxPrint: CPU usage
54.7% 09:57:24 INFO - TinderboxPrint: I/O read bytes / time
40,147,456 / 436 09:57:24 INFO - TinderboxPrint: I/O write bytes / time
514,717,184 / 2,895 09:57:24 INFO - TinderboxPrint: CPU idle
2,174.2 (45.3%) 09:57:24 INFO - TinderboxPrint: CPU system
970.4 (20.2%) 09:57:24 INFO - TinderboxPrint: CPU user
1,653.1 (34.5%) 09:57:24 INFO - TinderboxPrint: Swap in / out
248,274,944 / 0 09:57:24 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203837952; Write bytes: 201029632; Read time: 15675; Write time: 437 09:57:24 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 38132224; Write bytes: 302894592; Read time: 364; Write time: 2421 09:57:24 INFO - Running post-run listener: _upload_blobber_files 09:57:24 INFO - Blob upload gear active. 09:57:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:57:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:57:24 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:57:24 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:57:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:57:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:57:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:57:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:26 INFO - (blobuploader) - INFO - Done attempting. 09:57:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:57:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:57:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:27 INFO - (blobuploader) - INFO - Done attempting. 09:57:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:57:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:57:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:28 INFO - (blobuploader) - INFO - Done attempting. 09:57:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:57:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:57:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:30 INFO - (blobuploader) - INFO - Done attempting. 09:57:30 INFO - (blobuploader) - INFO - Iteration through files over. 09:57:30 INFO - Return code: 0 09:57:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:57:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:57:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d99916f40177169ec4287fc6d648ed717498edbe399f15dffcedb7ecd43fa76f4fe822e835925e0b085169e1468e3a54422a412e496262c391f75cf6d6af1015", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/63eddaceebc06de7187060370a003ad94448b3d8fa32a5bd75c33068eb07eee4644977d9b4c6e3474f082560330744e07a0afa0a7ec2a0a952e89682c26f63ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/063c2fbfc61e36adf061e44b793450b8e00268072d9dab09b424017c6ea4160516bbfc4d126dc21665684b2790d26afb6e873ba363fe771880655e4c118bd91f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88f79392ae27b9a94271f9149650d1313f2f6be94f1e463101e065207fbd4279ba191046ac07e709597e13eb65fd426e94edef1578e5e5c97d1626e711ca12fd"} 09:57:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:57:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:57:30 INFO - Contents: 09:57:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d99916f40177169ec4287fc6d648ed717498edbe399f15dffcedb7ecd43fa76f4fe822e835925e0b085169e1468e3a54422a412e496262c391f75cf6d6af1015", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/63eddaceebc06de7187060370a003ad94448b3d8fa32a5bd75c33068eb07eee4644977d9b4c6e3474f082560330744e07a0afa0a7ec2a0a952e89682c26f63ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/063c2fbfc61e36adf061e44b793450b8e00268072d9dab09b424017c6ea4160516bbfc4d126dc21665684b2790d26afb6e873ba363fe771880655e4c118bd91f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88f79392ae27b9a94271f9149650d1313f2f6be94f1e463101e065207fbd4279ba191046ac07e709597e13eb65fd426e94edef1578e5e5c97d1626e711ca12fd"} 09:57:30 INFO - Running post-run listener: copy_logs_to_upload_dir 09:57:30 INFO - Copying logs to upload dir... 09:57:30 INFO - mkdir: /builds/slave/test/build/upload/logs 09:57:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.846189 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-04 09:57:31.018587) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 09:57:31.022607) ========= 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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d99916f40177169ec4287fc6d648ed717498edbe399f15dffcedb7ecd43fa76f4fe822e835925e0b085169e1468e3a54422a412e496262c391f75cf6d6af1015", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/63eddaceebc06de7187060370a003ad94448b3d8fa32a5bd75c33068eb07eee4644977d9b4c6e3474f082560330744e07a0afa0a7ec2a0a952e89682c26f63ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/063c2fbfc61e36adf061e44b793450b8e00268072d9dab09b424017c6ea4160516bbfc4d126dc21665684b2790d26afb6e873ba363fe771880655e4c118bd91f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88f79392ae27b9a94271f9149650d1313f2f6be94f1e463101e065207fbd4279ba191046ac07e709597e13eb65fd426e94edef1578e5e5c97d1626e711ca12fd"} build_url:https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011535 build_url: 'https://queue.taskcluster.net/v1/task/OHj4_yNQSlGfgBnktd57Fw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d99916f40177169ec4287fc6d648ed717498edbe399f15dffcedb7ecd43fa76f4fe822e835925e0b085169e1468e3a54422a412e496262c391f75cf6d6af1015", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/63eddaceebc06de7187060370a003ad94448b3d8fa32a5bd75c33068eb07eee4644977d9b4c6e3474f082560330744e07a0afa0a7ec2a0a952e89682c26f63ee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/063c2fbfc61e36adf061e44b793450b8e00268072d9dab09b424017c6ea4160516bbfc4d126dc21665684b2790d26afb6e873ba363fe771880655e4c118bd91f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88f79392ae27b9a94271f9149650d1313f2f6be94f1e463101e065207fbd4279ba191046ac07e709597e13eb65fd426e94edef1578e5e5c97d1626e711ca12fd"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 09:57:31.062217) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 09:57:31.062565) ========= 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.4y57mS1Lk4/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.lz8WWrYXjf/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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.005103 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 09:57:31.112054) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 09:57:31.115493) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 09:57:31.115840) ========= ========= Total master_lag: 0.19 =========