builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0196 starttime: 1492654096.45 results: success (0) buildid: 20170419174827 builduid: 9086fc883d884cfc98b049c40e8b2481 revision: 584df356a66b1f5d48c73625dd08589a11029845 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:16.448275) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:16.448883) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:16.449198) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:16.476915) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:16.477368) ========= 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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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-19 19:08:16-- 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% 15.4M=0.001s 2017-04-19 19:08:16 (15.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138957 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:16.640906) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:16.641267) ========= 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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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.035882 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:16.699657) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-19 19:08:16.700054) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 584df356a66b1f5d48c73625dd08589a11029845 --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-beta --rev 584df356a66b1f5d48c73625dd08589a11029845 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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-19 19:08:16,971 truncating revision to first 12 chars 2017-04-19 19:08:16,971 Setting DEBUG logging. 2017-04-19 19:08:16,971 attempt 1/10 2017-04-19 19:08:16,971 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/584df356a66b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-19 19:08:18,599 unpacking tar archive at: mozilla-beta-584df356a66b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.904947 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-19 19:08:18.630687) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:18.631071) ========= script_repo_revision: 584df356a66b1f5d48c73625dd08589a11029845 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:18.631529) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:18.631842) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-19 19:08:18.651773) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-04-19 19:08:18.652191) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 19:08:19 INFO - MultiFileLogger online at 20170419 19:08:19 in /builds/slave/test 19:08:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 19:08:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:08:19 INFO - {'append_to_log': False, 19:08:19 INFO - 'base_work_dir': '/builds/slave/test', 19:08:19 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:08:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:08:19 INFO - 'branch': 'Mozilla-Beta', 19:08:19 INFO - 'buildbot_json_path': 'buildprops.json', 19:08:19 INFO - 'config_files': ('talos/mac_config.py',), 19:08:19 INFO - 'default_actions': ('clobber', 19:08:19 INFO - 'read-buildbot-config', 19:08:19 INFO - 'download-and-extract', 19:08:19 INFO - 'populate-webroot', 19:08:19 INFO - 'create-virtualenv', 19:08:19 INFO - 'install', 19:08:19 INFO - 'run-tests'), 19:08:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:08:19 INFO - 'download_minidump_stackwalk': True, 19:08:19 INFO - 'download_symbols': 'ondemand', 19:08:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:08:19 INFO - 'tooltool.py': '/tools/tooltool.py', 19:08:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:08:19 INFO - '/tools/misc-python/virtualenv.py')}, 19:08:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:08:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:08:19 INFO - 'gecko_profile': False, 19:08:19 INFO - 'gecko_profile_interval': 0, 19:08:19 INFO - 'installer_path': 'installer.exe', 19:08:19 INFO - 'log_level': 'info', 19:08:19 INFO - 'log_name': 'talos', 19:08:19 INFO - 'log_to_console': True, 19:08:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:08:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:08:19 INFO - 'opt_config_files': (), 19:08:19 INFO - 'pip_index': False, 19:08:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:08:19 INFO - 'cmd': ('bash', 19:08:19 INFO - '-c', 19:08:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:08:19 INFO - 'enabled': True, 19:08:19 INFO - 'halt_on_failure': False, 19:08:19 INFO - 'name': 'check_screen_resolution'},), 19:08:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:08:19 INFO - 'cmd': ('bash', 19:08:19 INFO - '-c', 19:08:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:08:19 INFO - 'enabled': True, 19:08:19 INFO - 'halt_on_failure': False, 19:08:19 INFO - 'name': 'check_screen_resolution'},), 19:08:19 INFO - 'run_cmd_checks_enabled': True, 19:08:19 INFO - 'suite': 'dromaeojs-e10s', 19:08:19 INFO - 'system_bits': '32', 19:08:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:08:19 INFO - 'title': 't-yosemite-r7-0196', 19:08:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:08:19 INFO - 'use_talos_json': True, 19:08:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:08:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:08:19 INFO - 'work_dir': 'build'} 19:08:19 INFO - [mozharness: 2017-04-20 02:08:19.034388Z] Running clobber step. 19:08:19 INFO - Running pre-action listener: _resource_record_pre_action 19:08:19 INFO - Running main action method: clobber 19:08:19 INFO - rmtree: /builds/slave/test/build 19:08:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:08:21 INFO - Running post-action listener: _resource_record_post_action 19:08:21 INFO - [mozharness: 2017-04-20 02:08:21.549299Z] Finished clobber step (success) 19:08:21 INFO - [mozharness: 2017-04-20 02:08:21.549434Z] Running read-buildbot-config step. 19:08:21 INFO - Running pre-action listener: _resource_record_pre_action 19:08:21 INFO - Running main action method: read_buildbot_config 19:08:21 INFO - Using buildbot properties: 19:08:21 INFO - { 19:08:21 INFO - "project": "", 19:08:21 INFO - "product": "firefox", 19:08:21 INFO - "script_repo_revision": "production", 19:08:21 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 19:08:21 INFO - "repository": "", 19:08:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 19:08:21 INFO - "buildid": "20170419174827", 19:08:21 INFO - "pgo_build": "False", 19:08:21 INFO - "basedir": "/builds/slave/test", 19:08:21 INFO - "buildnumber": 20, 19:08:21 INFO - "slavename": "t-yosemite-r7-0196", 19:08:21 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:08:21 INFO - "platform": "yosemite_r7", 19:08:21 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 19:08:21 INFO - "branch": "mozilla-beta", 19:08:21 INFO - "repo_path": "releases/mozilla-beta", 19:08:21 INFO - "revision": "584df356a66b1f5d48c73625dd08589a11029845", 19:08:21 INFO - "stage_platform": "macosx64", 19:08:21 INFO - "builduid": "9086fc883d884cfc98b049c40e8b2481", 19:08:21 INFO - "slavebuilddir": "test" 19:08:21 INFO - } 19:08:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 19:08:21 INFO - Running post-action listener: _resource_record_post_action 19:08:21 INFO - [mozharness: 2017-04-20 02:08:21.552825Z] Finished read-buildbot-config step (success) 19:08:21 INFO - [mozharness: 2017-04-20 02:08:21.552936Z] Running download-and-extract step. 19:08:21 INFO - Running pre-action listener: _resource_record_pre_action 19:08:21 INFO - Running main action method: download_and_extract 19:08:21 INFO - mkdir: /builds/slave/test/build/tests 19:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:21 INFO - trying https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 19:08:21 INFO - Downloading https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 19:08:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 19:08:22 INFO - Downloaded 1165 bytes. 19:08:22 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 19:08:22 INFO - Using the following test package requirements: 19:08:22 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:08:22 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:08:22 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 19:08:22 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:08:22 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 19:08:22 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:08:22 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:08:22 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 19:08:22 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:08:22 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:08:22 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 19:08:22 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:08:22 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 19:08:22 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:08:22 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 19:08:22 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:08:22 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 19:08:22 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 19:08:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:08:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:08:22 INFO - Fetch https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:08:24 INFO - Expected file size: 51220716 19:08:24 INFO - Obtained file size: 51220716 19:08:39 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 19:08:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:08:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:08:39 INFO - Fetch https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:08:41 INFO - Expected file size: 51220716 19:08:41 INFO - Obtained file size: 51220716 19:08:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 19:08:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 19:08:56 INFO - Fetch https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 19:08:57 INFO - Expected file size: 14404424 19:08:57 INFO - Obtained file size: 14404424 19:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:58 INFO - trying https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:08:58 INFO - Downloading https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 19:08:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 19:09:00 INFO - Downloaded 59217981 bytes. 19:09:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:09:00 INFO - mkdir: /builds/slave/test/properties 19:09:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:09:00 INFO - Writing to file /builds/slave/test/properties/build_url 19:09:00 INFO - Contents: 19:09:00 INFO - build_url:https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:09:01 INFO - Running post-action listener: _resource_record_post_action 19:09:01 INFO - Running post-action listener: set_extra_try_arguments 19:09:01 INFO - [mozharness: 2017-04-20 02:09:01.123970Z] Finished download-and-extract step (success) 19:09:01 INFO - [mozharness: 2017-04-20 02:09:01.124122Z] Running populate-webroot step. 19:09:01 INFO - Running pre-action listener: _resource_record_pre_action 19:09:01 INFO - Running main action method: populate_webroot 19:09:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:09:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:09:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:09:01 INFO - '--tppagecycles', 19:09:01 INFO - '7'], 19:09:01 INFO - 'tests': ['tsvgm']}}, 19:09:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:09:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:09:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:09:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:09:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:09:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:09:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:09:01 INFO - 'talos_options': ['--disable-e10s'], 19:09:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:09:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:09:01 INFO - 'talos_options': ['--disable-e10s'], 19:09:01 INFO - 'tests': ['damp', 'tps']}, 19:09:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:09:01 INFO - 'tests': ['damp', 'tps']}, 19:09:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:09:01 INFO - 'tests': ['dromaeo_dom']}, 19:09:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:09:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:09:01 INFO - 'tests': ['basic_compositor_video']}, 19:09:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:09:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:09:01 INFO - 'tests': ['a11yr', 19:09:01 INFO - 'ts_paint', 19:09:01 INFO - 'tpaint', 19:09:01 INFO - 'sessionrestore', 19:09:01 INFO - 'sessionrestore_no_auto_restore', 19:09:01 INFO - 'tabpaint']}, 19:09:01 INFO - 'other-e10s': {'tests': ['a11yr', 19:09:01 INFO - 'ts_paint', 19:09:01 INFO - 'tpaint', 19:09:01 INFO - 'sessionrestore', 19:09:01 INFO - 'sessionrestore_no_auto_restore', 19:09:01 INFO - 'tabpaint']}, 19:09:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:09:01 INFO - 'ts_paint', 19:09:01 INFO - 'tpaint', 19:09:01 INFO - 'sessionrestore', 19:09:01 INFO - 'sessionrestore_no_auto_restore', 19:09:01 INFO - 'tabpaint']}, 19:09:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:09:01 INFO - 'ts_paint', 19:09:01 INFO - 'tpaint', 19:09:01 INFO - 'sessionrestore', 19:09:01 INFO - 'sessionrestore_no_auto_restore', 19:09:01 INFO - 'tabpaint']}, 19:09:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:09:01 INFO - 'tests': ['a11yr', 19:09:01 INFO - 'ts_paint', 19:09:01 INFO - 'tpaint', 19:09:01 INFO - 'sessionrestore', 19:09:01 INFO - 'sessionrestore_no_auto_restore', 19:09:01 INFO - 'tabpaint']}, 19:09:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:09:01 INFO - 'tests': ['a11yr', 19:09:01 INFO - 'ts_paint', 19:09:01 INFO - 'tpaint', 19:09:01 INFO - 'sessionrestore', 19:09:01 INFO - 'sessionrestore_no_auto_restore', 19:09:01 INFO - 'tabpaint']}, 19:09:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:09:01 INFO - 'tests': ['tsvgx', 19:09:01 INFO - 'tsvgr_opacity', 19:09:01 INFO - 'tart', 19:09:01 INFO - 'tscrollx', 19:09:01 INFO - 'cart', 19:09:01 INFO - 'tsvg_static']}, 19:09:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:09:01 INFO - 'tsvgr_opacity', 19:09:01 INFO - 'tart', 19:09:01 INFO - 'tscrollx', 19:09:01 INFO - 'cart', 19:09:01 INFO - 'tsvg_static']}, 19:09:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:09:01 INFO - 'talos_options': ['--disable-e10s'], 19:09:01 INFO - 'tests': ['tp5o']}, 19:09:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:09:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:09:01 INFO - 'talos_options': ['--disable-e10s', 19:09:01 INFO - '--xperf_path', 19:09:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:01 INFO - 'tests': ['tp5n']}, 19:09:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:09:01 INFO - 'talos_options': ['--xperf_path', 19:09:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:01 INFO - 'tests': ['tp5n']}}, 19:09:01 INFO - 'talos.zip': {'path': '', 19:09:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:09:01 INFO - Running post-action listener: _resource_record_post_action 19:09:01 INFO - [mozharness: 2017-04-20 02:09:01.142284Z] Finished populate-webroot step (success) 19:09:01 INFO - [mozharness: 2017-04-20 02:09:01.142415Z] Running create-virtualenv step. 19:09:01 INFO - Running pre-action listener: _resource_record_pre_action 19:09:01 INFO - Running main action method: create_virtualenv 19:09:01 INFO - Creating virtualenv /builds/slave/test/build/venv 19:09:01 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 19:09:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:09:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:09:01 INFO - Using real prefix '/tools/python27' 19:09:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:09:01 INFO - Installing distribute.............................................................................................................................................................................................done. 19:09:04 INFO - Installing pip.................done. 19:09:04 INFO - Return code: 0 19:09:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:09:05 INFO - Reading from file tmpfile_stdout 19:09:05 INFO - Output received: 19:09:05 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) 19:09:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11026db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110270768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624c1e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11004bd30>, '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 0x10ff72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110040be8>, '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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 19:09:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:09:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xs4bR4d8YJ/Render', 19:09:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:05 INFO - 'HOME': '/Users/cltbld', 19:09:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:05 INFO - 'LOGNAME': 'cltbld', 19:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:05 INFO - 'MOZ_NO_REMOTE': '1', 19:09:05 INFO - 'NO_EM_RESTART': '1', 19:09:05 INFO - 'PAGER': '/bin/cat', 19:09:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:05 INFO - 'PWD': '/builds/slave/test', 19:09:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:05 INFO - 'SHELL': '/bin/bash', 19:09:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b58VDVuQbf/Listeners', 19:09:05 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 19:09:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:05 INFO - 'USER': 'cltbld', 19:09:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:05 INFO - 'XPC_FLAGS': '0x0', 19:09:05 INFO - 'XPC_SERVICE_NAME': '0', 19:09:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:05 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 19:09:05 INFO - Cleaning up... 19:09:05 INFO - Return code: 0 19:09:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11026db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110270768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624c1e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11004bd30>, '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 0x10ff72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110040be8>, '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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 19:09:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:09:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xs4bR4d8YJ/Render', 19:09:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:05 INFO - 'HOME': '/Users/cltbld', 19:09:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:05 INFO - 'LOGNAME': 'cltbld', 19:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:05 INFO - 'MOZ_NO_REMOTE': '1', 19:09:05 INFO - 'NO_EM_RESTART': '1', 19:09:05 INFO - 'PAGER': '/bin/cat', 19:09:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:05 INFO - 'PWD': '/builds/slave/test', 19:09:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:05 INFO - 'SHELL': '/bin/bash', 19:09:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b58VDVuQbf/Listeners', 19:09:05 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 19:09:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:05 INFO - 'USER': 'cltbld', 19:09:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:05 INFO - 'XPC_FLAGS': '0x0', 19:09:05 INFO - 'XPC_SERVICE_NAME': '0', 19:09:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:05 INFO - Downloading/unpacking psutil>=3.1.1 19:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:09:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:09 INFO - Installing collected packages: psutil 19:09:09 INFO - Running setup.py install for psutil 19:09:09 INFO - building 'psutil._psutil_osx' extension 19:09:09 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 19:09:09 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 19:09:09 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 19:09:10 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 19:09:10 INFO - building 'psutil._psutil_posix' extension 19:09:10 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 19:09:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:09:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:09:10 INFO - ^ 19:09:10 INFO - 1 warning generated. 19:09:10 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 19:09:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:10 INFO - Successfully installed psutil 19:09:10 INFO - Cleaning up... 19:09:10 INFO - Return code: 0 19:09:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:10 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 0x11026db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110270768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624c1e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11004bd30>, '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 0x10ff72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110040be8>, '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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 19:09:10 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 19:09:10 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 19:09:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xs4bR4d8YJ/Render', 19:09:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:10 INFO - 'HOME': '/Users/cltbld', 19:09:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:10 INFO - 'LOGNAME': 'cltbld', 19:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:10 INFO - 'MOZ_NO_REMOTE': '1', 19:09:10 INFO - 'NO_EM_RESTART': '1', 19:09:10 INFO - 'PAGER': '/bin/cat', 19:09:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:10 INFO - 'PWD': '/builds/slave/test', 19:09:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:10 INFO - 'SHELL': '/bin/bash', 19:09:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b58VDVuQbf/Listeners', 19:09:10 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 19:09:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:10 INFO - 'USER': 'cltbld', 19:09:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:10 INFO - 'XPC_FLAGS': '0x0', 19:09:10 INFO - 'XPC_SERVICE_NAME': '0', 19:09:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:09:10 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 19:09:10 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 19:09:10 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 19:09:10 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 19:09:10 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 19:09:10 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 19:09:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:09:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:09:13 INFO - Installing collected packages: mozsystemmonitor 19:09:13 INFO - Running setup.py install for mozsystemmonitor 19:09:13 INFO - Successfully installed mozsystemmonitor 19:09:13 INFO - Cleaning up... 19:09:13 INFO - Return code: 0 19:09:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:14 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 0x11026db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110270768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624c1e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11004bd30>, '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 0x10ff72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110040be8>, '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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 19:09:14 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 19:09:14 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 19:09:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xs4bR4d8YJ/Render', 19:09:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:14 INFO - 'HOME': '/Users/cltbld', 19:09:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:14 INFO - 'LOGNAME': 'cltbld', 19:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:14 INFO - 'MOZ_NO_REMOTE': '1', 19:09:14 INFO - 'NO_EM_RESTART': '1', 19:09:14 INFO - 'PAGER': '/bin/cat', 19:09:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:14 INFO - 'PWD': '/builds/slave/test', 19:09:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:14 INFO - 'SHELL': '/bin/bash', 19:09:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b58VDVuQbf/Listeners', 19:09:14 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 19:09:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:14 INFO - 'USER': 'cltbld', 19:09:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:14 INFO - 'XPC_FLAGS': '0x0', 19:09:14 INFO - 'XPC_SERVICE_NAME': '0', 19:09:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:14 INFO - Downloading/unpacking jsonschema==2.5.1 19:09:14 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 19:09:14 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 19:09:14 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 19:09:14 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 19:09:14 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 19:09:14 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 19:09:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:09:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:18 INFO - Downloading functools32-3.2.3-2.tar.gz 19:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:09:18 INFO - warning: no files found matching '*.txt' 19:09:18 INFO - no previously-included directories found matching 'build' 19:09:18 INFO - no previously-included directories found matching 'dist' 19:09:18 INFO - no previously-included directories found matching '.git*' 19:09:18 INFO - Installing collected packages: jsonschema, functools32 19:09:18 INFO - Running setup.py install for functools32 19:09:18 INFO - warning: no files found matching '*.txt' 19:09:18 INFO - no previously-included directories found matching 'build' 19:09:18 INFO - no previously-included directories found matching 'dist' 19:09:18 INFO - no previously-included directories found matching '.git*' 19:09:18 INFO - Successfully installed jsonschema functools32 19:09:18 INFO - Cleaning up... 19:09:18 INFO - Return code: 0 19:09:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:18 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 0x11026db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110270768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624c1e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11004bd30>, '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 0x10ff72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110040be8>, '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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 19:09:18 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 19:09:18 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 19:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xs4bR4d8YJ/Render', 19:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:18 INFO - 'HOME': '/Users/cltbld', 19:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:18 INFO - 'LOGNAME': 'cltbld', 19:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:18 INFO - 'MOZ_NO_REMOTE': '1', 19:09:18 INFO - 'NO_EM_RESTART': '1', 19:09:18 INFO - 'PAGER': '/bin/cat', 19:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:18 INFO - 'PWD': '/builds/slave/test', 19:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:18 INFO - 'SHELL': '/bin/bash', 19:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b58VDVuQbf/Listeners', 19:09:18 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 19:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:18 INFO - 'USER': 'cltbld', 19:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:18 INFO - 'XPC_FLAGS': '0x0', 19:09:18 INFO - 'XPC_SERVICE_NAME': '0', 19:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:09:18 INFO - Cleaning up... 19:09:18 INFO - Return code: 0 19:09:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:18 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 0x11026db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110270768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624c1e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11004bd30>, '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 0x10ff72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110040be8>, '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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 19:09:18 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 19:09:18 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 19:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xs4bR4d8YJ/Render', 19:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:18 INFO - 'HOME': '/Users/cltbld', 19:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:18 INFO - 'LOGNAME': 'cltbld', 19:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:18 INFO - 'MOZ_NO_REMOTE': '1', 19:09:18 INFO - 'NO_EM_RESTART': '1', 19:09:18 INFO - 'PAGER': '/bin/cat', 19:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:18 INFO - 'PWD': '/builds/slave/test', 19:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:18 INFO - 'SHELL': '/bin/bash', 19:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b58VDVuQbf/Listeners', 19:09:18 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 19:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:18 INFO - 'USER': 'cltbld', 19:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:18 INFO - 'XPC_FLAGS': '0x0', 19:09:18 INFO - 'XPC_SERVICE_NAME': '0', 19:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:19 INFO - Downloading/unpacking blobuploader==1.2.4 19:09:19 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 19:09:19 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 19:09:19 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 19:09:19 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 19:09:19 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 19:09:19 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 19:09:21 INFO - Downloading blobuploader-1.2.4.tar.gz 19:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:09:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:09:21 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 19:09:21 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 19:09:21 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 19:09:21 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 19:09:21 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 19:09:21 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 19:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:09:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:22 INFO - Downloading docopt-0.6.1.tar.gz 19:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:09:22 INFO - Installing collected packages: blobuploader, requests, docopt 19:09:22 INFO - Running setup.py install for blobuploader 19:09:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:09:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:09:23 INFO - Running setup.py install for requests 19:09:23 INFO - Running setup.py install for docopt 19:09:23 INFO - Successfully installed blobuploader requests docopt 19:09:23 INFO - Cleaning up... 19:09:23 INFO - Return code: 0 19:09:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:23 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 0x11026db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110270768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624c1e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11004bd30>, '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 0x10ff72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110040be8>, '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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 19:09:23 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 19:09:23 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 19:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xs4bR4d8YJ/Render', 19:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:23 INFO - 'HOME': '/Users/cltbld', 19:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:23 INFO - 'LOGNAME': 'cltbld', 19:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:23 INFO - 'MOZ_NO_REMOTE': '1', 19:09:23 INFO - 'NO_EM_RESTART': '1', 19:09:23 INFO - 'PAGER': '/bin/cat', 19:09:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:23 INFO - 'PWD': '/builds/slave/test', 19:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:23 INFO - 'SHELL': '/bin/bash', 19:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b58VDVuQbf/Listeners', 19:09:23 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 19:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:23 INFO - 'USER': 'cltbld', 19:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:23 INFO - 'XPC_FLAGS': '0x0', 19:09:23 INFO - 'XPC_SERVICE_NAME': '0', 19:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-rwEf_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-iKfdVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-U1heZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-cFrs4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-gZWFR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-sDvdr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-9Y79BT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-wZYsDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-5rvJvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-fmQzer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-qIUsxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-d2SqpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-CgDIwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-lKVSvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-S0frDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-D8jtru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-le4Fnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:09:25 INFO - Running setup.py install for manifestparser 19:09:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:09:26 INFO - Running setup.py install for mozcrash 19:09:26 INFO - Running setup.py install for mozdebug 19:09:26 INFO - Running setup.py install for mozdevice 19:09:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:09:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:09:26 INFO - Running setup.py install for mozfile 19:09:26 INFO - Running setup.py install for mozhttpd 19:09:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:09:26 INFO - Running setup.py install for mozinfo 19:09:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:09:26 INFO - Running setup.py install for mozInstall 19:09:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:09:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:09:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:09:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:09:27 INFO - Running setup.py install for mozleak 19:09:27 INFO - Running setup.py install for mozlog 19:09:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:09:27 INFO - Running setup.py install for moznetwork 19:09:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:09:27 INFO - Running setup.py install for mozprocess 19:09:27 INFO - Running setup.py install for mozprofile 19:09:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:09:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:09:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:09:27 INFO - Running setup.py install for mozrunner 19:09:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:09:28 INFO - Running setup.py install for mozscreenshot 19:09:28 INFO - Running setup.py install for moztest 19:09:28 INFO - Running setup.py install for mozversion 19:09:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:09:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:09:28 INFO - Cleaning up... 19:09:28 INFO - Return code: 0 19:09:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:28 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')]} 19:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:28 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')]} 19:09:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:28 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 0x11026db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110270768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624c1e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11004bd30>, '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 0x10ff72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110040be8>, '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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 19:09:28 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 19:09:28 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 19:09:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xs4bR4d8YJ/Render', 19:09:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:28 INFO - 'HOME': '/Users/cltbld', 19:09:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:28 INFO - 'LOGNAME': 'cltbld', 19:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:28 INFO - 'MOZ_NO_REMOTE': '1', 19:09:28 INFO - 'NO_EM_RESTART': '1', 19:09:28 INFO - 'PAGER': '/bin/cat', 19:09:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:28 INFO - 'PWD': '/builds/slave/test', 19:09:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:28 INFO - 'SHELL': '/bin/bash', 19:09:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b58VDVuQbf/Listeners', 19:09:28 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 19:09:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:28 INFO - 'USER': 'cltbld', 19:09:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:28 INFO - 'XPC_FLAGS': '0x0', 19:09:28 INFO - 'XPC_SERVICE_NAME': '0', 19:09:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:28 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-B13ELV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:28 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)) 19:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:28 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-95WxdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:29 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)) 19:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:29 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-e1qr4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:29 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)) 19:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:29 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-FyRQHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:29 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)) 19:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:29 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-8Qnhz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:29 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)) 19:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:29 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-tJj9gf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:29 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)) 19:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:29 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-MdLgTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:29 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)) 19:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:29 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-WpjnKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:29 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)) 19:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:29 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-ixppKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:29 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)) 19:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:29 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-H4UD1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:29 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)) 19:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:29 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-Sh5oI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:29 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)) 19:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:29 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-CdCZ4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:30 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)) 19:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-mduC4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:30 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)) 19:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-kSPFrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:30 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)) 19:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-nf8akw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:30 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)) 19:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-rX0rQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:30 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)) 19:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:30 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-_IGMR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:30 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)) 19:09:30 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)) 19:09:30 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)) 19:09:30 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)) 19:09:30 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)) 19:09:30 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)) 19:09:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:33 INFO - Downloading blessings-1.6.tar.gz 19:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:09:33 INFO - Installing collected packages: blessings 19:09:33 INFO - Running setup.py install for blessings 19:09:33 INFO - Successfully installed blessings 19:09:33 INFO - Cleaning up... 19:09:33 INFO - Return code: 0 19:09:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:09:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:33 INFO - Reading from file tmpfile_stdout 19:09:33 INFO - Current package versions: 19:09:33 INFO - blessings == 1.6 19:09:33 INFO - blobuploader == 1.2.4 19:09:33 INFO - docopt == 0.6.1 19:09:33 INFO - functools32 == 3.2.3-2 19:09:33 INFO - jsonschema == 2.5.1 19:09:33 INFO - manifestparser == 1.1 19:09:33 INFO - mozInstall == 1.12 19:09:33 INFO - mozcrash == 1.0 19:09:33 INFO - mozdebug == 0.1 19:09:33 INFO - mozdevice == 0.49 19:09:33 INFO - mozfile == 1.2 19:09:33 INFO - mozhttpd == 0.7 19:09:33 INFO - mozinfo == 0.9 19:09:33 INFO - mozleak == 0.1 19:09:33 INFO - mozlog == 3.4 19:09:33 INFO - moznetwork == 0.27 19:09:33 INFO - mozprocess == 0.25 19:09:33 INFO - mozprofile == 0.28 19:09:33 INFO - mozrunner == 6.13 19:09:33 INFO - mozscreenshot == 0.1 19:09:33 INFO - mozsystemmonitor == 0.3 19:09:33 INFO - moztest == 0.8 19:09:33 INFO - mozversion == 1.4 19:09:33 INFO - psutil == 3.1.1 19:09:33 INFO - requests == 1.2.3 19:09:33 INFO - wsgiref == 0.1.2 19:09:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:33 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')]} 19:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:33 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')]} 19:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:33 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 0x11026db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110270768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624c1e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11004bd30>, '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 0x10ff72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110040be8>, '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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 19:09:33 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 19:09:33 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 19:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xs4bR4d8YJ/Render', 19:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:33 INFO - 'HOME': '/Users/cltbld', 19:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:33 INFO - 'LOGNAME': 'cltbld', 19:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:33 INFO - 'MOZ_NO_REMOTE': '1', 19:09:33 INFO - 'NO_EM_RESTART': '1', 19:09:33 INFO - 'PAGER': '/bin/cat', 19:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:33 INFO - 'PWD': '/builds/slave/test', 19:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:33 INFO - 'SHELL': '/bin/bash', 19:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b58VDVuQbf/Listeners', 19:09:33 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 19:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:33 INFO - 'USER': 'cltbld', 19:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:33 INFO - 'XPC_FLAGS': '0x0', 19:09:33 INFO - 'XPC_SERVICE_NAME': '0', 19:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:34 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)) 19:09:34 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)) 19:09:34 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)) 19:09:34 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)) 19:09:34 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)) 19:09:34 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)) 19:09:34 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)) 19:09:34 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)) 19:09:34 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)) 19:09:34 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)) 19:09:34 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)) 19:09:34 INFO - Cleaning up... 19:09:34 INFO - Return code: 0 19:09:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:34 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 0x11026db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110270768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624c1e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11004bd30>, '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 0x10ff72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110040be8>, '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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 19:09:34 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 19:09:34 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 19:09:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xs4bR4d8YJ/Render', 19:09:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:34 INFO - 'HOME': '/Users/cltbld', 19:09:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:34 INFO - 'LOGNAME': 'cltbld', 19:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:34 INFO - 'MOZ_NO_REMOTE': '1', 19:09:34 INFO - 'NO_EM_RESTART': '1', 19:09:34 INFO - 'PAGER': '/bin/cat', 19:09:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:34 INFO - 'PWD': '/builds/slave/test', 19:09:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:34 INFO - 'SHELL': '/bin/bash', 19:09:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b58VDVuQbf/Listeners', 19:09:34 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 19:09:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:34 INFO - 'USER': 'cltbld', 19:09:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:34 INFO - 'XPC_FLAGS': '0x0', 19:09:34 INFO - 'XPC_SERVICE_NAME': '0', 19:09:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:09:34 INFO - Cleaning up... 19:09:34 INFO - Return code: 0 19:09:34 INFO - Running post-action listener: _resource_record_post_action 19:09:34 INFO - Running post-action listener: _start_resource_monitoring 19:09:34 INFO - Starting resource monitoring. 19:09:34 INFO - [mozharness: 2017-04-20 02:09:34.492648Z] Finished create-virtualenv step (success) 19:09:34 INFO - [mozharness: 2017-04-20 02:09:34.493684Z] Running install step. 19:09:34 INFO - Running pre-action listener: _resource_record_pre_action 19:09:34 INFO - Running main action method: install 19:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:34 INFO - Reading from file tmpfile_stdout 19:09:34 INFO - Detecting whether we're running mozinstall >=1.0... 19:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:09:34 INFO - Reading from file tmpfile_stdout 19:09:34 INFO - Output received: 19:09:34 INFO - Usage: mozinstall [options] installer 19:09:34 INFO - Options: 19:09:34 INFO - -h, --help show this help message and exit 19:09:34 INFO - -d DEST, --destination=DEST 19:09:34 INFO - Directory to install application into. [default: 19:09:34 INFO - "/builds/slave/test"] 19:09:34 INFO - --app=APP Application being installed. [default: firefox] 19:09:34 INFO - mkdir: /builds/slave/test/build/application 19:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 19:09:49 INFO - Reading from file tmpfile_stdout 19:09:49 INFO - Output received: 19:09:49 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 19:09:49 INFO - Running post-action listener: _resource_record_post_action 19:09:49 INFO - [mozharness: 2017-04-20 02:09:49.416178Z] Finished install step (success) 19:09:49 INFO - [mozharness: 2017-04-20 02:09:49.416318Z] Running run-tests step. 19:09:49 INFO - Running pre-action listener: _resource_record_pre_action 19:09:49 INFO - Running main action method: run_tests 19:09:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:09:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:09:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:09:49 INFO - 2017-04-19 19:09:49.430 screenresolution[1627:7446] starting screenresolution argv=screenresolution get 19:09:49 INFO - 2017-04-19 19:09:49.433 screenresolution[1627:7446] Display 0: 1600x1200x32@60 19:09:49 INFO - 2017-04-19 19:09:49.439 screenresolution[1628:7448] starting screenresolution argv=screenresolution list 19:09:49 INFO - Available Modes on Display 0 19:09:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:09:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:09:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:09:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:09:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:09:49 INFO - Intel Iris: 19:09:49 INFO - Chipset Model: Intel Iris 19:09:49 INFO - Type: GPU 19:09:49 INFO - Bus: Built-In 19:09:49 INFO - VRAM (Dynamic, Max): 1536 MB 19:09:49 INFO - Vendor: Intel (0x8086) 19:09:49 INFO - Device ID: 0x0a2e 19:09:49 INFO - Revision ID: 0x0009 19:09:49 INFO - Displays: 19:09:49 INFO - Display: 19:09:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:09:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:09:49 INFO - Display Serial Number: 1600x1200 60 19:09:49 INFO - Main Display: Yes 19:09:49 INFO - Mirror: Off 19:09:49 INFO - Online: Yes 19:09:49 INFO - Rotation: Supported 19:09:49 INFO - Return code: 0 19:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:09:49 INFO - Python 2.7.3 19:09:49 INFO - Return code: 0 19:09:49 INFO - grabbing minidump binary from tooltool 19:09:49 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')]} 19:09:49 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 0x11004bd30>, '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 0x10ff72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110040be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:09:49 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 19:09:49 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 19:09:49 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 19:09:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:09:49 INFO - Return code: 0 19:09:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:09:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:09:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:09:49 INFO - ENV: RUST_BACKTRACE is now 1 19:09:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:09:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--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 19:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0196 --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 19:09:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xs4bR4d8YJ/Render', 19:09:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:49 INFO - 'HOME': '/Users/cltbld', 19:09:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:49 INFO - 'LOGNAME': 'cltbld', 19:09:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:09:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:49 INFO - 'MOZ_NO_REMOTE': '1', 19:09:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:09:49 INFO - 'NO_EM_RESTART': '1', 19:09:49 INFO - 'PAGER': '/bin/cat', 19:09:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:49 INFO - 'PWD': '/builds/slave/test', 19:09:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:09:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:49 INFO - 'RUST_BACKTRACE': '1', 19:09:49 INFO - 'SHELL': '/bin/bash', 19:09:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b58VDVuQbf/Listeners', 19:09:49 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 19:09:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:49 INFO - 'USER': 'cltbld', 19:09:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:49 INFO - 'XPC_FLAGS': '0x0', 19:09:49 INFO - 'XPC_SERVICE_NAME': '0', 19:09:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--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 19:09:50 INFO - mozversion application_buildid: 20170419174827 19:09:50 INFO - mozversion application_changeset: 584df356a66b1f5d48c73625dd08589a11029845 19:09:50 INFO - mozversion application_display_name: Firefox 19:09:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:09:50 INFO - mozversion application_name: Firefox 19:09:50 INFO - mozversion application_remotingname: firefox 19:09:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:09:50 INFO - mozversion application_vendor: Mozilla 19:09:50 INFO - mozversion application_version: 54.0 19:09:50 INFO - mozversion platform_buildid: 20170419174827 19:09:50 INFO - mozversion platform_changeset: 584df356a66b1f5d48c73625dd08589a11029845 19:09:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:09:50 INFO - mozversion platform_version: 54.0 19:09:50 INFO - using testdate: 1492654190 19:09:50 INFO - actual date: 1492654190 19:09:50 INFO - starting webserver on 'localhost:49246' 19:09:50 INFO - SUITE-START | Running 2 tests 19:09:50 INFO - TEST-START | dromaeo_css 19:09:50 INFO - operating with platform_type : mac_ 19:09:50 INFO - Initialising browser for dromaeo_css test... 19:09:50 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmptOYCzd/profile http://localhost:49246/getInfo.html) 19:09:52 INFO - PROCESS | 1638 | 2017-04-19 19:09:52.976 plugin-container[1642:7622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d4f, name = 'com.apple.tsm.portname' 19:09:52 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:09:52 INFO - PROCESS | 1638 | 2017-04-19 19:09:52.977 plugin-container[1642:7622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a2f, name = 'com.apple.CFPasteboardClient' 19:09:52 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:09:52 INFO - PROCESS | 1638 | 2017-04-19 19:09:52.977 plugin-container[1642:7622] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:09:53 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 19:09:53 INFO - PROCESS | 1638 | colorDepth:24 19:09:53 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 19:09:53 INFO - PROCESS | 1638 | __metrics 19:09:53 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 19:09:53 INFO - TEST-INFO | 1638: exit 0 19:09:53 INFO - Browser initialized. 19:09:53 INFO - Running cycle 1/1 for dromaeo_css test... 19:09:53 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmptOYCzd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:09:56 INFO - PROCESS | 1643 | 2017-04-19 19:09:56.054 plugin-container[1644:7754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854b, name = 'com.apple.tsm.portname' 19:09:56 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:09:56 INFO - PROCESS | 1643 | 2017-04-19 19:09:56.056 plugin-container[1644:7754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 19:09:56 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:09:56 INFO - PROCESS | 1643 | 2017-04-19 19:09:56.057 plugin-container[1644:7754] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:10:05 INFO - PROCESS | 1643 | RSS: Main: 174530560 19:10:05 INFO - PROCESS | 1643 | 19:13:08 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 19:13:09 INFO - PROCESS | 1643 | RSS: Main: 193593344 19:13:09 INFO - PROCESS | 1643 | 19:16:11 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 19:16:12 INFO - PROCESS | 1643 | RSS: Main: 202100736 19:16:12 INFO - PROCESS | 1643 | 19:19:14 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 19:19:15 INFO - PROCESS | 1643 | RSS: Main: 204480512 19:19:15 INFO - PROCESS | 1643 | 19:22:17 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 19:22:17 INFO - PROCESS | 1643 | RSS: Main: 207806464 19:22:17 INFO - PROCESS | 1643 | 19:25:20 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 19:25:21 INFO - PROCESS | 1643 | RSS: Main: 210231296 19:25:21 INFO - PROCESS | 1643 | 19:28:23 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 19:28:24 INFO - PROCESS | 1643 | RSS: Main: 209080320 19:28:24 INFO - PROCESS | 1643 | 19:28:24 INFO - PROCESS | 1643 | __start_tp_report 19:28:24 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 19:28:24 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 19:28:24 INFO - PROCESS | 1643 | |i|pagename|runs| 19:28:24 INFO - PROCESS | 1643 | |0;dojo.html;3402.336544373845;3564.1624218308693;3571.4107172316567;3583.229605634788;3598.5321908151936;5189.195674670423;5718.7998420055255;6014.939850601492;6033.818985430438;6053.2433445819215;5333.679979201251;5569.944300556993;5954.880902381949;6016.398360163981;6027.5479339049525;4456.866294011169;5407.864803379907;5441.13248928323;5456.345238571357;5498.80754173602;5506.201600767887;5507.118860982244;5510.421405752379;5526.06056970314;5545.750441230134;5324.467553244684;5331.520163185312;5507.228987941691;5515.889682206353;5527.778888844441;5457.481539253765;5476.58925580579;5509.889802203934;5547.973624879411;5548.944510554883;5371.4897084776885;5457.836264912059;5460.863478413032;5472.2338872557875;5474.589405794542;5706.343770466367;5967.134765459027;6021.939780602182;6111.083337499366;6156.6613836238985;6304.464120549713;6330.588511746721;6389.201349831271;6395.968020159869;6400.7119679614525;6402.615843049385;6410.903836442457;6411.8397040073905;6413.518986076063;6429.935700643027;5249.527542521153;5990.790322338741;6147.44672979434;6174.228221472316;6190.9999999999545;6320.020396838498;6355.332690067568;6488.967555162194;6530.216374035146;6544.901826472606;6379;6405.711742971577;6411.743530258737;6412.583182093149;6415.358464153547;6402.679866006774;6417.518686098468;6423.935760642427;6435.839104022437;6447.4197322241225;6063.666498342545;6248.500119990481;6266.436020758062;6279.874402511924;6347.143135676717;6282.340354262828;6297.464715499143;6327.114204011442;6329.873402531924;6336.936630633727;5015.448300686921;5056.974715126401;5086.109930762176;5134.306868572695;5157.226416037624;4574.45106587205;4578.656600754956;4608.746518941423;4620.075984802986;4635.797995951896;6032.336442991267;6253.437190652863;6322.0200868865395;6338.271098823602;6351.2696039956;6288.748450061947;6313.715882785285;6317.46301564363;6340.30256671775;6446.323136070739;6284.780032698834;6307.211598550181;6312.305646378894;6323.20959880015;6333.334999825044;5650.011248031578;5662.433756624387;5675.517581005578;5675.659460432387;5707.3721890592005;6339;6387.840303992251;6610.4711623071;6654.26803051664;6655.700493477708;5745.885082298247;6137.7851775187655;6393.6163830170335;6419.133416988647;6423.389777971085;6594.208694956636;6634.237062737847;6678.666066696548;6689.966550167218;6701.497387695845;5101;6261.530385221035;6324.7153878076515;6367.586106903036;6423.614583125028;5142.614303927296;6373.840653983688;6379.93620063812;6422.775202867877;6430.967845160744;6257.155284036597;6326.6836658165985;6352.968235158794;6369.267534233437;6374.681265936591;5357.276767636475;6151.98492248774;6221.595596286227;6250.499960003098;6353.5870168438905;4967.279744437109;5178.482151784942;6133.509319254538;6169.722362493789;6170.5372097092;6289.779857704958;6290.21372328459;6290.339514351111;6306.779262725782;6310.936890631035;6050.818475445743;6117.938820611737;6162.445379915829;6195.597286176384;6219.595726277777 19:28:24 INFO - PROCESS | 1643 | |1;ext.html;743.9814004649883;785.1363500149832;852.8166444214495;998.5606333213381;1000.009990109791;31108.84445577772;34140;40126;41983.79008104963;42062;40647;40828.77513674594;40897.59102408975;40919.59080409195;40977;8329.416940814146;8475.025372082227;11195.664130076111;11479.942600286988;11688.181827272094;11212.831807522894;11301.717457063556;11322.886771132307;11336.603218887341;11337.886621133766;11293.322400655947;11326.244432113035;11336.659900202965;11337;11341.489632966537;11272.774544509104;11284.774304513905;11293.887061129366;11301.604443844508;11313.264637798558;10951.561937522489;10990.395528245943;11020.118390528738;11025.724356891142;11036.061934735557;35023.64976350255;35205;35240.295194095976;35248.766293179615;35254.82372588146;3809.085819403351;3820.178661587772;3824.9235015299537;3848.3457812171996;3870.322693528621;26084.608730868862;26129.477410451873;26509.73490265092;26710.33224169392;27424.90300387982;3181.5704879841155;3183.3474137801927;3236.6763323667446;3238.1257060593744;3314.66853314669;387316;387375;427119;434022;440976.79511602555;43821.34267985983;44366;44394.55605443969;44416.777916110215;44421.77789111034;46964.29553556699;46971.06057878823;47054.29418558724;47061.76469117632;47253.29120063202;13814.654633634038;13830.585082447542;13834.515791947233;13841.653958651113;13884.652883677787;12060.879391206152;12067.913887750145;12077.51689932393;12091.455884485043;12122.39388030595;12010.03919686423;12028.578999734968;12032.278063316055;12068.456919438646;12149.088818338483;4722.52774722525;5221.738913054332;5820.097884827858;5884.176215329857;5933.851653708692;964.4068897628049;984.1437948984429;1048.7745134796055;1077.498962982217;1087.7443800706828;29374.26564335866;29649.555256671167;30350.54474182889;30650.54024189639;30676.38647227043;948.374073111743;1060.2101434431397;1070.8875568065396;1078.8597482327248;1096.550414330121;11481.540738370371;13062.150960187557;14680.706385872436;15333.539993800205;15469.922650386676;459378;460450;461619;462266;463957;43070.78464607657;56515.43484565184;56868.43131568714;57087.714561426925;57163;1468.2438544149772;1483.124956275824;1511.5389806109022;1518.8784897208197;1519.1568679382829;27574.862125689244;28535.28661783472;28994;29070.854645727482;29108.7089129106;44597.77701111474;45653.77173114113;47987.280190797865;48084;48234.27648585204;467774;469558;471175;471896;472177;26908.192754217405;27423.765930533573;31302.530462042632;32422.67577324291;32690.182745431553;38216;38248.27882745339;38737.03157421087;38745.61254387533;39043.609563904;27292.454150916474;27465.901363945217;27945;28053.43893122085;28563.714362856106;27233.59149612797;27242.72757272402;27810;28165.15504534867;28365.723542440228;31194.220144495663;31844;32289.677103229606;32449.51325730069;32531;25150.874245628656;26053.0881419157;27761.305967350978;28432.715672843005;28688;18225.362112326005;21426.785732142478;22832.31503054911;23043.308700739006;23157.305280841603 19:28:24 INFO - PROCESS | 1643 | |2;jquery.html;411.7154478028551;417.84957415330473;422.1514755341762;441.9094085712428;453.77311344327836;20097.799022009774;21033.57932841344;22324.776752232472;22509.54980900381;22596.88701556492;22096.779032209673;23006.309810705665;23477.530449391;23744.762552374472;23996.76003239967;6896.413804826596;7067.151941766995;7157.033800436952;7159.964200178992;7509.849803003936;7840.529568225925;7865.4887432317;7889.211078892123;7920.603969801516;7924.564148971805;6636.369544893228;7622.428317876182;7920.762377128695;7923.4057445691515;7923.484973476734;7645.46481746278;7844.568548729818;7865.764027079168;7867.370610351215;7901.091374491892;7520.473566850323;7595.708729515995;7676.7313144040245;7687.962125113094;7718.961405192994;26756.795944182362;27000.594991075148;27101.322466938484;27330.453390932267;27534.311642209104;2730.604062410959;2887.740103390684;3024.3044099857157;3025.697430256979;3033.272014716474;15893.966892151973;16800.99194048361;17185.312587496486;17208.56978575533;17574;2071.5028393185676;2097.9055942482623;2119.1527357507875;2148.8066074053254;2149.5485947950947;143893.28053359667;169007;170293;172571.27428725804;176253;18127.818721812877;19504.219831206876;21118.049687764087;21907;22033.77966220317;32311.838440808115;32436;32777.01668949935;32778.508322375186;32950.50574241388;10775;11019.228653994143;11078.169137314733;11132.276402034004;11223.270487418455;9225.584848681825;9247.352685312098;9254.676086336945;9433.90566094344;9449.527523623792;8045.9597702011115;8409;9291.628334866666;9824.361416507903;9960.900390995997;3671.4492826076303;4329.567043295645;4336.544662810423;4374.387585737999;4399.252127138394;851.1828644501225;895.5566994337845;908.7864351877304;944.9196818270524;960.6493629825063;19836.404907852786;21222.78777212239;22741.772582274298;22781.430464238194;22829.88585057064;841.1420351241699;946.5078159357233;952.8761261036021;965.6089283840056;973.2651847854825;9606.807863842683;10696.893031069745;11710.473028713728;11883.049356051495;11886.227395219283;634416;639954;644610;647583;652943;101032.49483752681;110803;143758;149228;158999;1323.6426164935413;1351.9526816561372;1385.5427708856053;1389.1109689798536;1393.881520070785;20273.59452810965;20387.898060509604;20823.271185509042;20994.47513812106;21076.683849742574;62179.689101554206;62744.686276568325;63898;64129.67935160294;64517.67741161264;90918;90942.87783799827;91182.54408727914;92742;93951;10095.495225238561;11539.596114135966;11922.642320730392;11947.820782688093;12204.511819527119;13405.463781448632;13517.256550889795;13625.182489050689;13823.377947992163;13852.307384630527;9670.516474176404;10065.194784417083;11769.117316201147;11990.880091198977;12049.879501205227;9328.486933218737;10607.522661480407;10657.307275027099;11000.944995274973;11108.389038602954;10032.598696052077;11835.644930652094;11857.16999810005;11912.880871191177;12025.278483291031;10283.742906427398;11664.94167529157;11860.347680877634;11931;12220.45007974661;9637.807243855224;11177.770445250853;11687.707807304885;11701.122415818678;11989.280643161439 19:28:24 INFO - PROCESS | 1643 | |3;mootools.html;1333.1667707682698;1535.7926679898214;1620.2303905644812;1656.3705791799114;1657.4447560067385;11012.614558490455;11431.371274579895;11665.825012624817;11694.824577631332;11790.882091179086;9664.75838104046;11423.428828558559;11599.188056836025;11606.883931160686;11671.941640291787;2429.8663573503495;2481.7642323979194;2482.044412901028;2485.639582260571;2487.751224877507;2434.1358817619757;2454.5581795276844;2499.175272160183;2500.7996161842325;2503.6494890715317;2439.4511234972165;2443.2914454808174;2460.5694003549306;2496.8252222344445;2506.235598142566;2321.292005938188;2431.963520547193;2444.584420648494;2451.8774061296954;2483.3295010347288;2441.206607852437;2449.6080627099755;2462.495188486356;2472.678551788274;2475.5667758142254;11566.016888564482;11719.12106592009;11772.941135294268;11808.291502509877;11824.586139485076;5976.7908123215475;6114.2357205349335;6129.049997250433;6135.938640613582;6145;3657.4330978698345;3758.774473531597;3806.371948628473;3816.751911125768;3862.555806082308;1272.4083301264864;1901.3630433804692;1959.3632069577445;1977.940661780149;2015.2745011795741;14634.195119268436;14969.251537423193;15672.29474673631;15816.841831581653;16021.439249626206;3985.6811455083525;4669.41632295963;4736.550027747358;4917.704937703749;4922.138625740553;12122.27266364021;12261;12389.380530973416;12394.008479321632;12419.944304734017;6080.665563394055;6090.23872015998;6092.573519853568;6093.268807743099;6093.542984276197;5247;5500.28496295479;5535.501804837584;5542.91685624716;5594.86012849682;4065.207284579538;4102.692298077656;4103.5691252418665;4176.394422808676;4188.937165942513;1088.0751361342955;1088.6679562733443;1091.5470079916904;1104.7900898829196;1105.1766434006556;854.2098558833054;861.6510313323114;873.8339715454043;897.3090720145451;900.3517467423445;6133.773245350948;6860.794176174722;6916;7010.662339343128;7079.858402831915;1037.190991027;1040.0966231795796;1062.8671416072991;1078.8543546621106;1083.5286650307141;4079.286124928126;4167.2082304362075;4203.117345357509;4344.087741574242;4481;13619.523316683868;14134;14170.362333695008;14179.78730319046;14205.431782728576;13071.869281307256;13641.931790341183;13893.652658683613;14091.295435228403;14124.435022599186;9008.234300084436;10553.894461055292;10835.729106772236;10860.728481788019;10881.183911206692;5707.429257074259;6004.249468816398;6712.093867327848;6798.524103312919;6973.965130174317;11986.340751258764;12270.693232669255;12284.815727763911;12295.385230738248;12818.102732808615;10666.466676665981;11635.825462617899;12076.75846483083;12882.871171288167;13403;7748.690052397841;9717.368371055858;9718.854217186607;9741.074597913186;9757.12185903272;10524.842127368249;10655.25413221067;10671.786564268827;10702.090322322378;10804.891951080388;9047.411918225294;9069;9175.862362064441;9179.173874351074;9314.25485961135;8878.644854205759;8920.553972301488;8937.419067760575;8957.820843582962;8988.32587555949;6021;7726.7681969541;7763.301302882767;7768.456208065607;7769.223077692412;5349.063913815145;5395.541378982673;5397.649152805055;5518.034343989866;5644.83065508019;5980.581359304807;5998.640081595118;6055.2430946131735;6080.391960803884;6217.4715149211015 19:28:24 INFO - PROCESS | 1643 | |4;prototype.html;323.9584735076729;332.68754762462083;402.2819267607322;405.58427611698005;410.8767369789064;4647.884507718149;5118.488151184884;5134.2812006319145;5165.431802501722;5325.227841962923;4627;4629.513901040393;4778.283257511366;4802.567768900798;5170.4312525622245;3738.8504459821643;3909.550401703798;4328.610425061744;4335.631471324926;4579.740571342891;4185.832566697329;4388.210122178007;4398.098389830077;4604.861854144364;4607.56736231508;3901.6878649707955;4544.5682660147395;4548.749093999144;4591.104734576759;4641.628669706415;4228.703990720651;4529.841455549073;4562.634989200889;4621.191291523986;4661.906761864778;3586.372384832644;3979.243943650697;3998.460592671813;4188.476440444944;4296.63478604319;4473.977630111829;4720.59874910633;4978.228374601942;5108.284840122349;5158.303629010111;4451.666125040602;4965.535167125707;5056.6713163644245;5238.895222095575;5264.368045905743;5517.724113794295;5643.802466913638;5704.372519022904;5715.457031582035;5765.4811067004175;4262.914741705148;4275.135659108405;4575;4941.481144479814;4950.455449900508;5685.744180996203;5854.648721076706;5930.1994230779155;5968.045112781934;6051.33893994136;4266.935995960063;4383.9342409863875;4707.411573553306;5068.366454193226;5088.618353623493;5188.92216616751;5222.529972302511;5224.555912747459;5241.200129905864;5256.6057545684225;4485.923378389163;4859.2954021667365;4866.099771542276;4932.21084626458;5302.958361030954;5479.917801232985;5483.424240454695;5526.834194974157;5599.860003499945;5627.314109258442;1463.4121977654365;1533.4402942925974;1687.8565321947526;1768.9292428302724;1776.3693888669595;1243.6890777305673;1301.9153755005893;1341.3360386608497;1422.822147231596;1481.7925490431348;5005.199168133154;5264.499872512105;5267.209918512254;5295.761690723926;5348.91976620351;4871.585915197248;5127.6154288428515;5170.948290517122;5440;5836.941630583639;4881.926771098437;4962.131626965267;5076.720780357042;5320.893582128336;5378.542823859937;2706.754892749357;2987.83566903818;3034.332446861687;3041.406925649477;3072.4623190941497;5191.792328306826;5332.520073193354;5809.24479817621;6012.218411606551;6067.696615169224;5047.621428392885;5074.594032477393;5715.913122117706;5904.7933322334375;5907.616004959664;5613.494785469327;5651.773929042874;5948.762049518057;6139.84650383744;6145.784897528565;5004.599632029502;5507.697076660662;5631.605787594829;5705.972924873397;5797.391273916178;4769.666123371469;5005.5745261653165;5250.684958902325;5370.570354371563;5772.422757724194;5169.793208271777;5988.850278743066;6033.668148251888;6066.481440764434;6153.753849845956;4206.20082184384;4349.456317960255;4456.771614192903;4534.569215924482;4605.654575906888;4421.557844215553;5060.721660308718;5613.354464236501;5733;5755.446029571908;5433.962264150943;5562.91655625174;5571.470710282517;5597.888042239214;5613.719314034201;4887.90224195507;5409.810656626999;5506.944930550643;5582.385937546947;5600.69196194197;5481.479259470344;5500.80747173863;5518.696471694096;5525.4474552544425;5538.418466061166;4412.249917514095;5044.949550504448;5539.639923404966;5542.972285138549;5569.610127291051;4911.386076740408;5345.438728933406;5375.946240537701;5446.727663616883;5461.481159289861 19:28:24 INFO - PROCESS | 1643 | |5;yui.html;669.6517810738413;720.5784615999637;794.5749024272014;814.0069115678873;885.4598694796175;15496.845031549681;17194.57013574658;17203.311867525284;17220.13899305036;17228.82771172288;15789.210539473037;15939.027007601642;15996.040237585754;16116.919415402906;16288.348466061372;1000.7598176437657;1015.1624909449697;1023.534291897185;1028.984565231522;1029.5984566019222;1021.5505177721816;1022.161827301613;1025.676911772793;1025.784585237101;1026.379040680389;1016.1515134862399;1068.8076146293663;1073.726199819045;1074.8763892152385;1079.1151255970108;1050.8476270940748;1056.0917610854658;1057.1120258982414;1058.4125810175349;1061.1139698351885;792.5918152151589;801.4670244287541;803.8111043904678;805.8871757953891;806.4475834053692;17357.7396339055;17750.55623609407;17751.822481775147;18139.18373673188;18216.63566728671;14686.853131468655;14736.410543578244;14864.033837800616;14970.47603333878;15192.46826361072;11866.584669536525;11983.085287495163;12152.331621760799;12245.93877030618;12686.746265074738;3860.942085868714;3869.1487872667967;3933.9606603934167;3942.231264903345;4075.7554546727292;122320;122924;125090;125357.37321313335;127643.36178319142;8906.910930890608;10603.469826508644;10673.679789606324;11184.993350598485;11448.828267575993;20238.49403764888;23858.16496422617;24261.27216183517;24329.661868597042;24479.755202447748;8100.959495202486;8110.5539195343645;8118.14759450261;8148.185181481923;8260.628271727786;4057.837686492507;4590.770461476913;4604.378408914754;4659.6505262105475;4721.740304283228;3537.7877327359924;3606.85572577094;3617.3127105849803;3645.9453108203397;3653.5615726112505;659.8878190707495;660.8083655739878;662.0400419391901;676.3439463720183;676.9627670478171;573.498189084551;704.8202708309349;705.8270723672733;721.1706537481833;727.9745208917662;10369;10795.892041079489;11066.004059634672;11068.723281917855;11143.944280278547;724.8477819657826;727.6761840980819;732.9083864516936;734.9853002939911;741.8071301461658;4354.518371400199;4744.644151688637;4953.424657534247;4956.791292473591;4963.933607887183;99839;101190.49404753077;101226;102182;106698.3995240072;76575;80042;83388;84665.73001404984;99157;53676;68943.65528172327;76175;78581;81689.9577510567;10713.678589642323;11291.604793832177;11423.257488263236;11533.82699259495;11556.653300401002;48019.519804802905;49118;49671;50061.24908126308;50529.74735126301;61877.74167951616;62653.43366415876;63415.682921585096;63684;64575;12678.746425071262;12799.29603871796;12894.41975111141;13106.148100373826;13111.475540978636;14581;14619.92690036543;14875.702485950003;15610.9219453902;15784.447544335893;13529;13603.523876664269;13899.513517026855;14380.5101418819;14453.49412770548;13506.379234491924;13955;13979.930100349433;14104.193771902228;14281.42874285017;13303.268320242092;13746.450141994208;13792.724145516833;13804.240766757925;13979.161250325013;9728.952074570434;9945.751356216153;10348.637797677045;10377.896221037994;10717.839232411365;10079.395236285847;10191.235657325878;11424.371659558805;11699.00558452541;11726.237794543327 19:28:24 INFO - PROCESS | 1643 | __end_tp_report 19:28:24 INFO - PROCESS | 1643 | __start_cc_report 19:28:24 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,399 19:28:24 INFO - PROCESS | 1643 | __end_cc_report 19:28:24 INFO - PROCESS | 1643 | __startTimestamp1492655304107__endTimestamp 19:28:24 INFO - PROCESS | 1643 | 19:28:24 INFO - PROCESS | 1643 | ------- Summary: start ------- 19:28:24 INFO - PROCESS | 1643 | Number of tests: 6 19:28:24 INFO - PROCESS | 1643 | 19:28:24 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5920.11 Median:6219.60 stddev:662.78 (10.7%) stddev-sans-first:634.70 19:28:24 INFO - PROCESS | 1643 | Values: 3402.3 3564.2 3571.4 3583.2 3598.5 5189.2 5718.8 6014.9 6033.8 6053.2 5333.7 5569.9 5954.9 6016.4 6027.5 4456.9 5407.9 5441.1 5456.3 5498.8 5506.2 5507.1 5510.4 5526.1 5545.8 5324.5 5331.5 5507.2 5515.9 5527.8 5457.5 5476.6 5509.9 5548.0 5548.9 5371.5 5457.8 5460.9 5472.2 5474.6 5706.3 5967.1 6021.9 6111.1 6156.7 6304.5 6330.6 6389.2 6396.0 6400.7 6402.6 6410.9 6411.8 6413.5 6429.9 5249.5 5990.8 6147.4 6174.2 6191.0 6320.0 6355.3 6489.0 6530.2 6544.9 6379.0 6405.7 6411.7 6412.6 6415.4 6402.7 6417.5 6423.9 6435.8 6447.4 6063.7 6248.5 6266.4 6279.9 6347.1 6282.3 6297.5 6327.1 6329.9 6336.9 5015.4 5057.0 5086.1 5134.3 5157.2 4574.5 4578.7 4608.7 4620.1 4635.8 6032.3 6253.4 6322.0 6338.3 6351.3 6288.7 6313.7 6317.5 6340.3 6446.3 6284.8 6307.2 6312.3 6323.2 6333.3 5650.0 5662.4 5675.5 5675.7 5707.4 6339.0 6387.8 6610.5 6654.3 6655.7 5745.9 6137.8 6393.6 6419.1 6423.4 6594.2 6634.2 6678.7 6690.0 6701.5 5101.0 6261.5 6324.7 6367.6 6423.6 5142.6 6373.8 6379.9 6422.8 6431.0 6257.2 6326.7 6353.0 6369.3 6374.7 5357.3 6152.0 6221.6 6250.5 6353.6 4967.3 5178.5 6133.5 6169.7 6170.5 6289.8 6290.2 6290.3 6306.8 6310.9 6050.8 6117.9 6162.4 6195.6 6219.6 19:28:24 INFO - PROCESS | 1643 | 19:28:24 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:57463.69 Median:30500.54 stddev:119623.60 (392.2%) stddev-sans-first:119883.37 19:28:24 INFO - PROCESS | 1643 | Values: 744.0 785.1 852.8 998.6 1000.0 31108.8 34140.0 40126.0 41983.8 42062.0 40647.0 40828.8 40897.6 40919.6 40977.0 8329.4 8475.0 11195.7 11479.9 11688.2 11212.8 11301.7 11322.9 11336.6 11337.9 11293.3 11326.2 11336.7 11337.0 11341.5 11272.8 11284.8 11293.9 11301.6 11313.3 10951.6 10990.4 11020.1 11025.7 11036.1 35023.6 35205.0 35240.3 35248.8 35254.8 3809.1 3820.2 3824.9 3848.3 3870.3 26084.6 26129.5 26509.7 26710.3 27424.9 3181.6 3183.3 3236.7 3238.1 3314.7 387316.0 387375.0 427119.0 434022.0 440976.8 43821.3 44366.0 44394.6 44416.8 44421.8 46964.3 46971.1 47054.3 47061.8 47253.3 13814.7 13830.6 13834.5 13841.7 13884.7 12060.9 12067.9 12077.5 12091.5 12122.4 12010.0 12028.6 12032.3 12068.5 12149.1 4722.5 5221.7 5820.1 5884.2 5933.9 964.4 984.1 1048.8 1077.5 1087.7 29374.3 29649.6 30350.5 30650.5 30676.4 948.4 1060.2 1070.9 1078.9 1096.6 11481.5 13062.2 14680.7 15333.5 15469.9 459378.0 460450.0 461619.0 462266.0 463957.0 43070.8 56515.4 56868.4 57087.7 57163.0 1468.2 1483.1 1511.5 1518.9 1519.2 27574.9 28535.3 28994.0 29070.9 29108.7 44597.8 45653.8 47987.3 48084.0 48234.3 467774.0 469558.0 471175.0 471896.0 472177.0 26908.2 27423.8 31302.5 32422.7 32690.2 38216.0 38248.3 38737.0 38745.6 39043.6 27292.5 27465.9 27945.0 28053.4 28563.7 27233.6 27242.7 27810.0 28165.2 28365.7 31194.2 31844.0 32289.7 32449.5 32531.0 25150.9 26053.1 27761.3 28432.7 28688.0 18225.4 21426.8 22832.3 23043.3 23157.3 19:28:24 INFO - PROCESS | 1643 | 19:28:24 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:40450.56 Median:25376.78 stddev:108355.90 (427.0%) stddev-sans-first:108618.16 19:28:24 INFO - PROCESS | 1643 | Values: 411.7 417.8 422.2 441.9 453.8 20097.8 21033.6 22324.8 22509.5 22596.9 22096.8 23006.3 23477.5 23744.8 23996.8 6896.4 7067.2 7157.0 7160.0 7509.8 7840.5 7865.5 7889.2 7920.6 7924.6 6636.4 7622.4 7920.8 7923.4 7923.5 7645.5 7844.6 7865.8 7867.4 7901.1 7520.5 7595.7 7676.7 7688.0 7719.0 26756.8 27000.6 27101.3 27330.5 27534.3 2730.6 2887.7 3024.3 3025.7 3033.3 15894.0 16801.0 17185.3 17208.6 17574.0 2071.5 2097.9 2119.2 2148.8 2149.5 143893.3 169007.0 170293.0 172571.3 176253.0 18127.8 19504.2 21118.0 21907.0 22033.8 32311.8 32436.0 32777.0 32778.5 32950.5 10775.0 11019.2 11078.2 11132.3 11223.3 9225.6 9247.4 9254.7 9433.9 9449.5 8046.0 8409.0 9291.6 9824.4 9960.9 3671.4 4329.6 4336.5 4374.4 4399.3 851.2 895.6 908.8 944.9 960.6 19836.4 21222.8 22741.8 22781.4 22829.9 841.1 946.5 952.9 965.6 973.3 9606.8 10696.9 11710.5 11883.0 11886.2 634416.0 639954.0 644610.0 647583.0 652943.0 101032.5 110803.0 143758.0 149228.0 158999.0 1323.6 1352.0 1385.5 1389.1 1393.9 20273.6 20387.9 20823.3 20994.5 21076.7 62179.7 62744.7 63898.0 64129.7 64517.7 90918.0 90942.9 91182.5 92742.0 93951.0 10095.5 11539.6 11922.6 11947.8 12204.5 13405.5 13517.3 13625.2 13823.4 13852.3 9670.5 10065.2 11769.1 11990.9 12049.9 9328.5 10607.5 10657.3 11000.9 11108.4 10032.6 11835.6 11857.2 11912.9 12025.3 10283.7 11664.9 11860.3 11931.0 12220.5 9637.8 11177.8 11687.7 11701.1 11989.3 19:28:24 INFO - PROCESS | 1643 | 19:28:24 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:6899.97 Median:2484.48 stddev:4292.46 (172.8%) stddev-sans-first:4284.12 19:28:24 INFO - PROCESS | 1643 | Values: 1333.2 1535.8 1620.2 1656.4 1657.4 11012.6 11431.4 11665.8 11694.8 11790.9 9664.8 11423.4 11599.2 11606.9 11671.9 2429.9 2481.8 2482.0 2485.6 2487.8 2434.1 2454.6 2499.2 2500.8 2503.6 2439.5 2443.3 2460.6 2496.8 2506.2 2321.3 2432.0 2444.6 2451.9 2483.3 2441.2 2449.6 2462.5 2472.7 2475.6 11566.0 11719.1 11772.9 11808.3 11824.6 5976.8 6114.2 6129.0 6135.9 6145.0 3657.4 3758.8 3806.4 3816.8 3862.6 1272.4 1901.4 1959.4 1977.9 2015.3 14634.2 14969.3 15672.3 15816.8 16021.4 3985.7 4669.4 4736.6 4917.7 4922.1 12122.3 12261.0 12389.4 12394.0 12419.9 6080.7 6090.2 6092.6 6093.3 6093.5 5247.0 5500.3 5535.5 5542.9 5594.9 4065.2 4102.7 4103.6 4176.4 4188.9 1088.1 1088.7 1091.5 1104.8 1105.2 854.2 861.7 873.8 897.3 900.4 6133.8 6860.8 6916.0 7010.7 7079.9 1037.2 1040.1 1062.9 1078.9 1083.5 4079.3 4167.2 4203.1 4344.1 4481.0 13619.5 14134.0 14170.4 14179.8 14205.4 13071.9 13641.9 13893.7 14091.3 14124.4 9008.2 10553.9 10835.7 10860.7 10881.2 5707.4 6004.2 6712.1 6798.5 6974.0 11986.3 12270.7 12284.8 12295.4 12818.1 10666.5 11635.8 12076.8 12882.9 13403.0 7748.7 9717.4 9718.9 9741.1 9757.1 10524.8 10655.3 10671.8 10702.1 10804.9 9047.4 9069.0 9175.9 9179.2 9314.3 8878.6 8920.6 8937.4 8957.8 8988.3 6021.0 7726.8 7763.3 7768.5 7769.2 5349.1 5395.5 5397.6 5518.0 5644.8 5980.6 5998.6 6055.2 6080.4 6217.5 19:28:24 INFO - PROCESS | 1643 | 19:28:24 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4732.94 Median:5098.45 stddev:1276.05 (25.0%) stddev-sans-first:1235.97 19:28:24 INFO - PROCESS | 1643 | Values: 324.0 332.7 402.3 405.6 410.9 4647.9 5118.5 5134.3 5165.4 5325.2 4627.0 4629.5 4778.3 4802.6 5170.4 3738.9 3909.6 4328.6 4335.6 4579.7 4185.8 4388.2 4398.1 4604.9 4607.6 3901.7 4544.6 4548.7 4591.1 4641.6 4228.7 4529.8 4562.6 4621.2 4661.9 3586.4 3979.2 3998.5 4188.5 4296.6 4474.0 4720.6 4978.2 5108.3 5158.3 4451.7 4965.5 5056.7 5238.9 5264.4 5517.7 5643.8 5704.4 5715.5 5765.5 4262.9 4275.1 4575.0 4941.5 4950.5 5685.7 5854.6 5930.2 5968.0 6051.3 4266.9 4383.9 4707.4 5068.4 5088.6 5188.9 5222.5 5224.6 5241.2 5256.6 4485.9 4859.3 4866.1 4932.2 5303.0 5479.9 5483.4 5526.8 5599.9 5627.3 1463.4 1533.4 1687.9 1768.9 1776.4 1243.7 1301.9 1341.3 1422.8 1481.8 5005.2 5264.5 5267.2 5295.8 5348.9 4871.6 5127.6 5170.9 5440.0 5836.9 4881.9 4962.1 5076.7 5320.9 5378.5 2706.8 2987.8 3034.3 3041.4 3072.5 5191.8 5332.5 5809.2 6012.2 6067.7 5047.6 5074.6 5715.9 5904.8 5907.6 5613.5 5651.8 5948.8 6139.8 6145.8 5004.6 5507.7 5631.6 5706.0 5797.4 4769.7 5005.6 5250.7 5370.6 5772.4 5169.8 5988.9 6033.7 6066.5 6153.8 4206.2 4349.5 4456.8 4534.6 4605.7 4421.6 5060.7 5613.4 5733.0 5755.4 5434.0 5562.9 5571.5 5597.9 5613.7 4887.9 5409.8 5506.9 5582.4 5600.7 5481.5 5500.8 5518.7 5525.4 5538.4 4412.2 5044.9 5539.6 5543.0 5569.6 4911.4 5345.4 5375.9 5446.7 5461.5 19:28:24 INFO - PROCESS | 1643 | 19:28:24 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20943.57 Median:16202.63 stddev:30199.23 (186.4%) stddev-sans-first:30245.58 19:28:24 INFO - PROCESS | 1643 | Values: 669.7 720.6 794.6 814.0 885.5 15496.8 17194.6 17203.3 17220.1 17228.8 15789.2 15939.0 15996.0 16116.9 16288.3 1000.8 1015.2 1023.5 1029.0 1029.6 1021.6 1022.2 1025.7 1025.8 1026.4 1016.2 1068.8 1073.7 1074.9 1079.1 1050.8 1056.1 1057.1 1058.4 1061.1 792.6 801.5 803.8 805.9 806.4 17357.7 17750.6 17751.8 18139.2 18216.6 14686.9 14736.4 14864.0 14970.5 15192.5 11866.6 11983.1 12152.3 12245.9 12686.7 3860.9 3869.1 3934.0 3942.2 4075.8 122320.0 122924.0 125090.0 125357.4 127643.4 8906.9 10603.5 10673.7 11185.0 11448.8 20238.5 23858.2 24261.3 24329.7 24479.8 8101.0 8110.6 8118.1 8148.2 8260.6 4057.8 4590.8 4604.4 4659.7 4721.7 3537.8 3606.9 3617.3 3645.9 3653.6 659.9 660.8 662.0 676.3 677.0 573.5 704.8 705.8 721.2 728.0 10369.0 10795.9 11066.0 11068.7 11143.9 724.8 727.7 732.9 735.0 741.8 4354.5 4744.6 4953.4 4956.8 4963.9 99839.0 101190.5 101226.0 102182.0 106698.4 76575.0 80042.0 83388.0 84665.7 99157.0 53676.0 68943.7 76175.0 78581.0 81690.0 10713.7 11291.6 11423.3 11533.8 11556.7 48019.5 49118.0 49671.0 50061.2 50529.7 61877.7 62653.4 63415.7 63684.0 64575.0 12678.7 12799.3 12894.4 13106.1 13111.5 14581.0 14619.9 14875.7 15610.9 15784.4 13529.0 13603.5 13899.5 14380.5 14453.5 13506.4 13955.0 13979.9 14104.2 14281.4 13303.3 13746.5 13792.7 13804.2 13979.2 9729.0 9945.8 10348.6 10377.9 10717.8 10079.4 10191.2 11424.4 11699.0 11726.2 19:28:24 INFO - PROCESS | 1643 | -------- Summary: end -------- 19:28:24 INFO - PROCESS | 1643 | 19:28:24 INFO - TEST-INFO | 1643: exit 0 19:28:24 INFO - TEST-OK | dromaeo_css | took 1114606ms 19:28:24 INFO - TEST-START | kraken 19:28:24 INFO - operating with platform_type : mac_ 19:28:24 INFO - Initialising browser for kraken test... 19:28:24 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpxGI4VQ/profile http://localhost:49246/getInfo.html) 19:28:27 INFO - PROCESS | 1655 | 2017-04-19 19:28:27.190 plugin-container[1657:10164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 19:28:27 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:28:27 INFO - PROCESS | 1655 | 2017-04-19 19:28:27.190 plugin-container[1657:10164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 19:28:27 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:28:27 INFO - PROCESS | 1655 | 2017-04-19 19:28:27.190 plugin-container[1657:10164] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:28:27 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 19:28:27 INFO - PROCESS | 1655 | colorDepth:24 19:28:27 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 19:28:27 INFO - PROCESS | 1655 | __metrics 19:28:27 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 19:28:27 INFO - TEST-INFO | 1655: exit 0 19:28:27 INFO - Browser initialized. 19:28:27 INFO - Running cycle 1/1 for kraken test... 19:28:28 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpxGI4VQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:30 INFO - PROCESS | 1658 | 2017-04-19 19:28:30.171 plugin-container[1659:10287] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 19:28:30 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:28:30 INFO - PROCESS | 1658 | 2017-04-19 19:28:30.172 plugin-container[1659:10287] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8917, name = 'com.apple.CFPasteboardClient' 19:28:30 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:28:30 INFO - PROCESS | 1658 | 2017-04-19 19:28:30.172 plugin-container[1659:10287] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:28:40 INFO - PROCESS | 1658 | RSS: Main: 174505984 19:28:40 INFO - PROCESS | 1658 | 19:28:42 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 19:28:43 INFO - PROCESS | 1658 | RSS: Main: 187678720 19:28:43 INFO - PROCESS | 1658 | 19:28:46 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 19:28:47 INFO - PROCESS | 1658 | RSS: Main: 195604480 19:28:47 INFO - PROCESS | 1658 | 19:28:50 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 19:28:51 INFO - PROCESS | 1658 | RSS: Main: 197324800 19:28:51 INFO - PROCESS | 1658 | 19:28:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 19:28:53 INFO - PROCESS | 1658 | RSS: Main: 200044544 19:28:53 INFO - PROCESS | 1658 | 19:28:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:28:56 INFO - PROCESS | 1658 | RSS: Main: 200695808 19:28:56 INFO - PROCESS | 1658 | 19:28:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 19:29:00 INFO - PROCESS | 1658 | RSS: Main: 204886016 19:29:00 INFO - PROCESS | 1658 | 19:29:03 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 19:29:03 INFO - PROCESS | 1658 | RSS: Main: 204120064 19:29:03 INFO - PROCESS | 1658 | 19:29:06 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 19:29:07 INFO - PROCESS | 1658 | RSS: Main: 204267520 19:29:07 INFO - PROCESS | 1658 | 19:29:09 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:29:10 INFO - PROCESS | 1658 | RSS: Main: 203665408 19:29:10 INFO - PROCESS | 1658 | 19:29:11 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:29:12 INFO - PROCESS | 1658 | RSS: Main: 203640832 19:29:12 INFO - PROCESS | 1658 | 19:29:14 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:29:15 INFO - PROCESS | 1658 | RSS: Main: 203718656 19:29:15 INFO - PROCESS | 1658 | 19:29:18 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:29:19 INFO - PROCESS | 1658 | RSS: Main: 204066816 19:29:19 INFO - PROCESS | 1658 | 19:29:21 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:29:22 INFO - PROCESS | 1658 | RSS: Main: 203816960 19:29:22 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 19:29:24 INFO - PROCESS | 1658 | RSS: Main: 205574144 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | __start_tp_report 19:29:24 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 19:29:24 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 19:29:24 INFO - PROCESS | 1658 | |i|pagename|runs| 19:29:24 INFO - PROCESS | 1658 | |0;ai-astar;109;125;126;173;131;178;163;122;121;120 19:29:24 INFO - PROCESS | 1658 | |1;audio-beat-detection;149;143;94;181;132;120;142;123;145;139 19:29:24 INFO - PROCESS | 1658 | |2;audio-dft;200;194;253;188;277;201;281;283;213;200 19:29:24 INFO - PROCESS | 1658 | |3;audio-fft;79;57;58;85;59;62;61;60;72;58 19:29:24 INFO - PROCESS | 1658 | |4;audio-oscillator;85;70;82;70;70;68;79;71;69;83 19:29:24 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;138;137;88;90;88;88;89;89;91;89 19:29:24 INFO - PROCESS | 1658 | |6;imaging-darkroom;95;95;95;107;154;153;116;95;96;116 19:29:24 INFO - PROCESS | 1658 | |7;imaging-desaturate;112;75;145;145;117;89;89;89;91;121 19:29:24 INFO - PROCESS | 1658 | |8;json-parse-financial;64;63;65;67;71;96;98;99;103;100 19:29:24 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;59;65;58;66;65;62;55;58;51;65 19:29:24 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;83;87;85;86;82;87;100;73;100;105 19:29:24 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;123;136;139;182;154;148;150;148;204;175 19:29:24 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;165;169;164;163;168;165;165;164;162;167 19:29:24 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;67;69;70;67;64;65;65;65;65;63 19:29:24 INFO - PROCESS | 1658 | __end_tp_report 19:29:24 INFO - PROCESS | 1658 | __start_cc_report 19:29:24 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,986 19:29:24 INFO - PROCESS | 1658 | __end_cc_report 19:29:24 INFO - PROCESS | 1658 | __startTimestamp1492655364412__endTimestamp 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | ------- Summary: start ------- 19:29:24 INFO - PROCESS | 1658 | Number of tests: 14 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:136.80 Median:128.50 stddev:24.74 (19.3%) stddev-sans-first:24.11 19:29:24 INFO - PROCESS | 1658 | Values: 109.0 125.0 126.0 173.0 131.0 178.0 163.0 122.0 121.0 120.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:144.00 stddev:22.53 (15.6%) stddev-sans-first:23.46 19:29:24 INFO - PROCESS | 1658 | Values: 149.0 143.0 94.0 181.0 132.0 120.0 142.0 123.0 145.0 139.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:229.00 Median:233.00 stddev:39.62 (17.0%) stddev-sans-first:40.61 19:29:24 INFO - PROCESS | 1658 | Values: 200.0 194.0 253.0 188.0 277.0 201.0 281.0 283.0 213.0 200.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:65.10 Median:61.50 stddev:9.96 (16.2%) stddev-sans-first:9.21 19:29:24 INFO - PROCESS | 1658 | Values: 79.0 57.0 58.0 85.0 59.0 62.0 61.0 60.0 72.0 58.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:74.70 Median:75.00 stddev:6.70 (8.9%) stddev-sans-first:5.98 19:29:24 INFO - PROCESS | 1658 | Values: 85.0 70.0 82.0 70.0 70.0 68.0 79.0 71.0 69.0 83.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:98.70 Median:89.00 stddev:20.47 (23.0%) stddev-sans-first:16.03 19:29:24 INFO - PROCESS | 1658 | Values: 138.0 137.0 88.0 90.0 88.0 88.0 89.0 89.0 91.0 89.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:112.20 Median:95.00 stddev:23.37 (24.6%) stddev-sans-first:23.94 19:29:24 INFO - PROCESS | 1658 | Values: 95.0 95.0 95.0 107.0 154.0 153.0 116.0 95.0 96.0 116.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:107.30 Median:82.00 stddev:24.63 (30.0%) stddev-sans-first:26.07 19:29:24 INFO - PROCESS | 1658 | Values: 112.0 75.0 145.0 145.0 117.0 89.0 89.0 89.0 91.0 121.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:82.60 Median:69.00 stddev:17.71 (25.7%) stddev-sans-first:17.46 19:29:24 INFO - PROCESS | 1658 | Values: 64.0 63.0 65.0 67.0 71.0 96.0 98.0 99.0 103.0 100.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:63.50 stddev:5.04 (7.9%) stddev-sans-first:5.32 19:29:24 INFO - PROCESS | 1658 | Values: 59.0 65.0 58.0 66.0 65.0 62.0 55.0 58.0 51.0 65.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:84.00 stddev:9.84 (11.7%) stddev-sans-first:10.21 19:29:24 INFO - PROCESS | 1658 | Values: 83.0 87.0 85.0 86.0 82.0 87.0 100.0 73.0 100.0 105.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:155.90 Median:152.00 stddev:24.24 (15.9%) stddev-sans-first:22.59 19:29:24 INFO - PROCESS | 1658 | Values: 123.0 136.0 139.0 182.0 154.0 148.0 150.0 148.0 204.0 175.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:165.00 stddev:2.20 (1.3%) stddev-sans-first:2.33 19:29:24 INFO - PROCESS | 1658 | Values: 165.0 169.0 164.0 163.0 168.0 165.0 165.0 164.0 162.0 167.0 19:29:24 INFO - PROCESS | 1658 | 19:29:24 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:2.21 (3.4%) stddev-sans-first:2.32 19:29:24 INFO - PROCESS | 1658 | Values: 67.0 69.0 70.0 67.0 64.0 65.0 65.0 65.0 65.0 63.0 19:29:24 INFO - PROCESS | 1658 | -------- Summary: end -------- 19:29:24 INFO - PROCESS | 1658 | 19:29:25 INFO - TEST-INFO | 1658: exit 0 19:29:25 INFO - TEST-OK | kraken | took 60577ms 19:29:25 INFO - SUITE-END | took 1175s 19:29:25 INFO - Completed test suite (00:19:35) 19:29:25 INFO - javascript benchmark 19:29:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7879.327333664695, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3402.336544373845, 3564.1624218308693, 3571.4107172316567, 3583.229605634788, 3598.5321908151936, 5189.195674670423, 5718.7998420055255, 6014.939850601492, 6033.818985430438, 6053.2433445819215, 5333.679979201251, 5569.944300556993, 5954.880902381949, 6016.398360163981, 6027.5479339049525, 4456.866294011169, 5407.864803379907, 5441.13248928323, 5456.345238571357, 5498.80754173602, 5506.201600767887, 5507.118860982244, 5510.421405752379, 5526.06056970314, 5545.750441230134, 5324.467553244684, 5331.520163185312, 5507.228987941691, 5515.889682206353, 5527.778888844441, 5457.481539253765, 5476.58925580579, 5509.889802203934, 5547.973624879411, 5548.944510554883, 5371.4897084776885, 5457.836264912059, 5460.863478413032, 5472.2338872557875, 5474.589405794542, 5706.343770466367, 5967.134765459027, 6021.939780602182, 6111.083337499366, 6156.6613836238985, 6304.464120549713, 6330.588511746721, 6389.201349831271, 6395.968020159869, 6400.7119679614525, 6402.615843049385, 6410.903836442457, 6411.8397040073905, 6413.518986076063, 6429.935700643027, 5249.527542521153, 5990.790322338741, 6147.44672979434, 6174.228221472316, 6190.9999999999545, 6320.020396838498, 6355.332690067568, 6488.967555162194, 6530.216374035146, 6544.901826472606, 6379.0, 6405.711742971577, 6411.743530258737, 6412.583182093149, 6415.358464153547, 6402.679866006774, 6417.518686098468, 6423.935760642427, 6435.839104022437, 6447.4197322241225, 6063.666498342545, 6248.500119990481, 6266.436020758062, 6279.874402511924, 6347.143135676717, 6282.340354262828, 6297.464715499143, 6327.114204011442, 6329.873402531924, 6336.936630633727, 5015.448300686921, 5056.974715126401, 5086.109930762176, 5134.306868572695, 5157.226416037624, 4574.45106587205, 4578.656600754956, 4608.746518941423, 4620.075984802986, 4635.797995951896, 6032.336442991267, 6253.437190652863, 6322.0200868865395, 6338.271098823602, 6351.2696039956, 6288.748450061947, 6313.715882785285, 6317.46301564363, 6340.30256671775, 6446.323136070739, 6284.780032698834, 6307.211598550181, 6312.305646378894, 6323.20959880015, 6333.334999825044, 5650.011248031578, 5662.433756624387, 5675.517581005578, 5675.659460432387, 5707.3721890592005, 6339.0, 6387.840303992251, 6610.4711623071, 6654.26803051664, 6655.700493477708, 5745.885082298247, 6137.7851775187655, 6393.6163830170335, 6419.133416988647, 6423.389777971085, 6594.208694956636, 6634.237062737847, 6678.666066696548, 6689.966550167218, 6701.497387695845, 5101.0, 6261.530385221035, 6324.7153878076515, 6367.586106903036, 6423.614583125028, 5142.614303927296, 6373.840653983688, 6379.93620063812, 6422.775202867877, 6430.967845160744, 6257.155284036597, 6326.6836658165985, 6352.968235158794, 6369.267534233437, 6374.681265936591, 5357.276767636475, 6151.98492248774, 6221.595596286227, 6250.499960003098, 6353.5870168438905, 4967.279744437109, 5178.482151784942, 6133.509319254538, 6169.722362493789, 6170.5372097092, 6289.779857704958, 6290.21372328459, 6290.339514351111, 6306.779262725782, 6310.936890631035, 6050.818475445743, 6117.938820611737, 6162.445379915829, 6195.597286176384, 6219.595726277777], "value": 5881.067175769848, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.9814004649883, 785.1363500149832, 852.8166444214495, 998.5606333213381, 1000.009990109791, 31108.84445577772, 34140.0, 40126.0, 41983.79008104963, 42062.0, 40647.0, 40828.77513674594, 40897.59102408975, 40919.59080409195, 40977.0, 8329.416940814146, 8475.025372082227, 11195.664130076111, 11479.942600286988, 11688.181827272094, 11212.831807522894, 11301.717457063556, 11322.886771132307, 11336.603218887341, 11337.886621133766, 11293.322400655947, 11326.244432113035, 11336.659900202965, 11337.0, 11341.489632966537, 11272.774544509104, 11284.774304513905, 11293.887061129366, 11301.604443844508, 11313.264637798558, 10951.561937522489, 10990.395528245943, 11020.118390528738, 11025.724356891142, 11036.061934735557, 35023.64976350255, 35205.0, 35240.295194095976, 35248.766293179615, 35254.82372588146, 3809.085819403351, 3820.178661587772, 3824.9235015299537, 3848.3457812171996, 3870.322693528621, 26084.608730868862, 26129.477410451873, 26509.73490265092, 26710.33224169392, 27424.90300387982, 3181.5704879841155, 3183.3474137801927, 3236.6763323667446, 3238.1257060593744, 3314.66853314669, 387316.0, 387375.0, 427119.0, 434022.0, 440976.79511602555, 43821.34267985983, 44366.0, 44394.55605443969, 44416.777916110215, 44421.77789111034, 46964.29553556699, 46971.06057878823, 47054.29418558724, 47061.76469117632, 47253.29120063202, 13814.654633634038, 13830.585082447542, 13834.515791947233, 13841.653958651113, 13884.652883677787, 12060.879391206152, 12067.913887750145, 12077.51689932393, 12091.455884485043, 12122.39388030595, 12010.03919686423, 12028.578999734968, 12032.278063316055, 12068.456919438646, 12149.088818338483, 4722.52774722525, 5221.738913054332, 5820.097884827858, 5884.176215329857, 5933.851653708692, 964.4068897628049, 984.1437948984429, 1048.7745134796055, 1077.498962982217, 1087.7443800706828, 29374.26564335866, 29649.555256671167, 30350.54474182889, 30650.54024189639, 30676.38647227043, 948.374073111743, 1060.2101434431397, 1070.8875568065396, 1078.8597482327248, 1096.550414330121, 11481.540738370371, 13062.150960187557, 14680.706385872436, 15333.539993800205, 15469.922650386676, 459378.0, 460450.0, 461619.0, 462266.0, 463957.0, 43070.78464607657, 56515.43484565184, 56868.43131568714, 57087.714561426925, 57163.0, 1468.2438544149772, 1483.124956275824, 1511.5389806109022, 1518.8784897208197, 1519.1568679382829, 27574.862125689244, 28535.28661783472, 28994.0, 29070.854645727482, 29108.7089129106, 44597.77701111474, 45653.77173114113, 47987.280190797865, 48084.0, 48234.27648585204, 467774.0, 469558.0, 471175.0, 471896.0, 472177.0, 26908.192754217405, 27423.765930533573, 31302.530462042632, 32422.67577324291, 32690.182745431553, 38216.0, 38248.27882745339, 38737.03157421087, 38745.61254387533, 39043.609563904, 27292.454150916474, 27465.901363945217, 27945.0, 28053.43893122085, 28563.714362856106, 27233.59149612797, 27242.72757272402, 27810.0, 28165.15504534867, 28365.723542440228, 31194.220144495663, 31844.0, 32289.677103229606, 32449.51325730069, 32531.0, 25150.874245628656, 26053.0881419157, 27761.305967350978, 28432.715672843005, 28688.0, 18225.362112326005, 21426.785732142478, 22832.31503054911, 23043.308700739006, 23157.305280841603], "value": 18551.310673688367, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.7154478028551, 417.84957415330473, 422.1514755341762, 441.9094085712428, 453.77311344327836, 20097.799022009774, 21033.57932841344, 22324.776752232472, 22509.54980900381, 22596.88701556492, 22096.779032209673, 23006.309810705665, 23477.530449391, 23744.762552374472, 23996.76003239967, 6896.413804826596, 7067.151941766995, 7157.033800436952, 7159.964200178992, 7509.849803003936, 7840.529568225925, 7865.4887432317, 7889.211078892123, 7920.603969801516, 7924.564148971805, 6636.369544893228, 7622.428317876182, 7920.762377128695, 7923.4057445691515, 7923.484973476734, 7645.46481746278, 7844.568548729818, 7865.764027079168, 7867.370610351215, 7901.091374491892, 7520.473566850323, 7595.708729515995, 7676.7313144040245, 7687.962125113094, 7718.961405192994, 26756.795944182362, 27000.594991075148, 27101.322466938484, 27330.453390932267, 27534.311642209104, 2730.604062410959, 2887.740103390684, 3024.3044099857157, 3025.697430256979, 3033.272014716474, 15893.966892151973, 16800.99194048361, 17185.312587496486, 17208.56978575533, 17574.0, 2071.5028393185676, 2097.9055942482623, 2119.1527357507875, 2148.8066074053254, 2149.5485947950947, 143893.28053359667, 169007.0, 170293.0, 172571.27428725804, 176253.0, 18127.818721812877, 19504.219831206876, 21118.049687764087, 21907.0, 22033.77966220317, 32311.838440808115, 32436.0, 32777.01668949935, 32778.508322375186, 32950.50574241388, 10775.0, 11019.228653994143, 11078.169137314733, 11132.276402034004, 11223.270487418455, 9225.584848681825, 9247.352685312098, 9254.676086336945, 9433.90566094344, 9449.527523623792, 8045.9597702011115, 8409.0, 9291.628334866666, 9824.361416507903, 9960.900390995997, 3671.4492826076303, 4329.567043295645, 4336.544662810423, 4374.387585737999, 4399.252127138394, 851.1828644501225, 895.5566994337845, 908.7864351877304, 944.9196818270524, 960.6493629825063, 19836.404907852786, 21222.78777212239, 22741.772582274298, 22781.430464238194, 22829.88585057064, 841.1420351241699, 946.5078159357233, 952.8761261036021, 965.6089283840056, 973.2651847854825, 9606.807863842683, 10696.893031069745, 11710.473028713728, 11883.049356051495, 11886.227395219283, 634416.0, 639954.0, 644610.0, 647583.0, 652943.0, 101032.49483752681, 110803.0, 143758.0, 149228.0, 158999.0, 1323.6426164935413, 1351.9526816561372, 1385.5427708856053, 1389.1109689798536, 1393.881520070785, 20273.59452810965, 20387.898060509604, 20823.271185509042, 20994.47513812106, 21076.683849742574, 62179.689101554206, 62744.686276568325, 63898.0, 64129.67935160294, 64517.67741161264, 90918.0, 90942.87783799827, 91182.54408727914, 92742.0, 93951.0, 10095.495225238561, 11539.596114135966, 11922.642320730392, 11947.820782688093, 12204.511819527119, 13405.463781448632, 13517.256550889795, 13625.182489050689, 13823.377947992163, 13852.307384630527, 9670.516474176404, 10065.194784417083, 11769.117316201147, 11990.880091198977, 12049.879501205227, 9328.486933218737, 10607.522661480407, 10657.307275027099, 11000.944995274973, 11108.389038602954, 10032.598696052077, 11835.644930652094, 11857.16999810005, 11912.880871191177, 12025.278483291031, 10283.742906427398, 11664.94167529157, 11860.347680877634, 11931.0, 12220.45007974661, 9637.807243855224, 11177.770445250853, 11687.707807304885, 11701.122415818678, 11989.280643161439], "value": 11932.39490760719, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.1667707682698, 1535.7926679898214, 1620.2303905644812, 1656.3705791799114, 1657.4447560067385, 11012.614558490455, 11431.371274579895, 11665.825012624817, 11694.824577631332, 11790.882091179086, 9664.75838104046, 11423.428828558559, 11599.188056836025, 11606.883931160686, 11671.941640291787, 2429.8663573503495, 2481.7642323979194, 2482.044412901028, 2485.639582260571, 2487.751224877507, 2434.1358817619757, 2454.5581795276844, 2499.175272160183, 2500.7996161842325, 2503.6494890715317, 2439.4511234972165, 2443.2914454808174, 2460.5694003549306, 2496.8252222344445, 2506.235598142566, 2321.292005938188, 2431.963520547193, 2444.584420648494, 2451.8774061296954, 2483.3295010347288, 2441.206607852437, 2449.6080627099755, 2462.495188486356, 2472.678551788274, 2475.5667758142254, 11566.016888564482, 11719.12106592009, 11772.941135294268, 11808.291502509877, 11824.586139485076, 5976.7908123215475, 6114.2357205349335, 6129.049997250433, 6135.938640613582, 6145.0, 3657.4330978698345, 3758.774473531597, 3806.371948628473, 3816.751911125768, 3862.555806082308, 1272.4083301264864, 1901.3630433804692, 1959.3632069577445, 1977.940661780149, 2015.2745011795741, 14634.195119268436, 14969.251537423193, 15672.29474673631, 15816.841831581653, 16021.439249626206, 3985.6811455083525, 4669.41632295963, 4736.550027747358, 4917.704937703749, 4922.138625740553, 12122.27266364021, 12261.0, 12389.380530973416, 12394.008479321632, 12419.944304734017, 6080.665563394055, 6090.23872015998, 6092.573519853568, 6093.268807743099, 6093.542984276197, 5247.0, 5500.28496295479, 5535.501804837584, 5542.91685624716, 5594.86012849682, 4065.207284579538, 4102.692298077656, 4103.5691252418665, 4176.394422808676, 4188.937165942513, 1088.0751361342955, 1088.6679562733443, 1091.5470079916904, 1104.7900898829196, 1105.1766434006556, 854.2098558833054, 861.6510313323114, 873.8339715454043, 897.3090720145451, 900.3517467423445, 6133.773245350948, 6860.794176174722, 6916.0, 7010.662339343128, 7079.858402831915, 1037.190991027, 1040.0966231795796, 1062.8671416072991, 1078.8543546621106, 1083.5286650307141, 4079.286124928126, 4167.2082304362075, 4203.117345357509, 4344.087741574242, 4481.0, 13619.523316683868, 14134.0, 14170.362333695008, 14179.78730319046, 14205.431782728576, 13071.869281307256, 13641.931790341183, 13893.652658683613, 14091.295435228403, 14124.435022599186, 9008.234300084436, 10553.894461055292, 10835.729106772236, 10860.728481788019, 10881.183911206692, 5707.429257074259, 6004.249468816398, 6712.093867327848, 6798.524103312919, 6973.965130174317, 11986.340751258764, 12270.693232669255, 12284.815727763911, 12295.385230738248, 12818.102732808615, 10666.466676665981, 11635.825462617899, 12076.75846483083, 12882.871171288167, 13403.0, 7748.690052397841, 9717.368371055858, 9718.854217186607, 9741.074597913186, 9757.12185903272, 10524.842127368249, 10655.25413221067, 10671.786564268827, 10702.090322322378, 10804.891951080388, 9047.411918225294, 9069.0, 9175.862362064441, 9179.173874351074, 9314.25485961135, 8878.644854205759, 8920.553972301488, 8937.419067760575, 8957.820843582962, 8988.32587555949, 6021.0, 7726.7681969541, 7763.301302882767, 7768.456208065607, 7769.223077692412, 5349.063913815145, 5395.541378982673, 5397.649152805055, 5518.034343989866, 5644.83065508019, 5980.581359304807, 5998.640081595118, 6055.2430946131735, 6080.391960803884, 6217.4715149211015], "value": 5308.8378372549705, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.9584735076729, 332.68754762462083, 402.2819267607322, 405.58427611698005, 410.8767369789064, 4647.884507718149, 5118.488151184884, 5134.2812006319145, 5165.431802501722, 5325.227841962923, 4627.0, 4629.513901040393, 4778.283257511366, 4802.567768900798, 5170.4312525622245, 3738.8504459821643, 3909.550401703798, 4328.610425061744, 4335.631471324926, 4579.740571342891, 4185.832566697329, 4388.210122178007, 4398.098389830077, 4604.861854144364, 4607.56736231508, 3901.6878649707955, 4544.5682660147395, 4548.749093999144, 4591.104734576759, 4641.628669706415, 4228.703990720651, 4529.841455549073, 4562.634989200889, 4621.191291523986, 4661.906761864778, 3586.372384832644, 3979.243943650697, 3998.460592671813, 4188.476440444944, 4296.63478604319, 4473.977630111829, 4720.59874910633, 4978.228374601942, 5108.284840122349, 5158.303629010111, 4451.666125040602, 4965.535167125707, 5056.6713163644245, 5238.895222095575, 5264.368045905743, 5517.724113794295, 5643.802466913638, 5704.372519022904, 5715.457031582035, 5765.4811067004175, 4262.914741705148, 4275.135659108405, 4575.0, 4941.481144479814, 4950.455449900508, 5685.744180996203, 5854.648721076706, 5930.1994230779155, 5968.045112781934, 6051.33893994136, 4266.935995960063, 4383.9342409863875, 4707.411573553306, 5068.366454193226, 5088.618353623493, 5188.92216616751, 5222.529972302511, 5224.555912747459, 5241.200129905864, 5256.6057545684225, 4485.923378389163, 4859.2954021667365, 4866.099771542276, 4932.21084626458, 5302.958361030954, 5479.917801232985, 5483.424240454695, 5526.834194974157, 5599.860003499945, 5627.314109258442, 1463.4121977654365, 1533.4402942925974, 1687.8565321947526, 1768.9292428302724, 1776.3693888669595, 1243.6890777305673, 1301.9153755005893, 1341.3360386608497, 1422.822147231596, 1481.7925490431348, 5005.199168133154, 5264.499872512105, 5267.209918512254, 5295.761690723926, 5348.91976620351, 4871.585915197248, 5127.6154288428515, 5170.948290517122, 5440.0, 5836.941630583639, 4881.926771098437, 4962.131626965267, 5076.720780357042, 5320.893582128336, 5378.542823859937, 2706.754892749357, 2987.83566903818, 3034.332446861687, 3041.406925649477, 3072.4623190941497, 5191.792328306826, 5332.520073193354, 5809.24479817621, 6012.218411606551, 6067.696615169224, 5047.621428392885, 5074.594032477393, 5715.913122117706, 5904.7933322334375, 5907.616004959664, 5613.494785469327, 5651.773929042874, 5948.762049518057, 6139.84650383744, 6145.784897528565, 5004.599632029502, 5507.697076660662, 5631.605787594829, 5705.972924873397, 5797.391273916178, 4769.666123371469, 5005.5745261653165, 5250.684958902325, 5370.570354371563, 5772.422757724194, 5169.793208271777, 5988.850278743066, 6033.668148251888, 6066.481440764434, 6153.753849845956, 4206.20082184384, 4349.456317960255, 4456.771614192903, 4534.569215924482, 4605.654575906888, 4421.557844215553, 5060.721660308718, 5613.354464236501, 5733.0, 5755.446029571908, 5433.962264150943, 5562.91655625174, 5571.470710282517, 5597.888042239214, 5613.719314034201, 4887.90224195507, 5409.810656626999, 5506.944930550643, 5582.385937546947, 5600.69196194197, 5481.479259470344, 5500.80747173863, 5518.696471694096, 5525.4474552544425, 5538.418466061166, 4412.249917514095, 5044.949550504448, 5539.639923404966, 5542.972285138549, 5569.610127291051, 4911.386076740408, 5345.438728933406, 5375.946240537701, 5446.727663616883, 5461.481159289861], "value": 4365.577531939785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.6517810738413, 720.5784615999637, 794.5749024272014, 814.0069115678873, 885.4598694796175, 15496.845031549681, 17194.57013574658, 17203.311867525284, 17220.13899305036, 17228.82771172288, 15789.210539473037, 15939.027007601642, 15996.040237585754, 16116.919415402906, 16288.348466061372, 1000.7598176437657, 1015.1624909449697, 1023.534291897185, 1028.984565231522, 1029.5984566019222, 1021.5505177721816, 1022.161827301613, 1025.676911772793, 1025.784585237101, 1026.379040680389, 1016.1515134862399, 1068.8076146293663, 1073.726199819045, 1074.8763892152385, 1079.1151255970108, 1050.8476270940748, 1056.0917610854658, 1057.1120258982414, 1058.4125810175349, 1061.1139698351885, 792.5918152151589, 801.4670244287541, 803.8111043904678, 805.8871757953891, 806.4475834053692, 17357.7396339055, 17750.55623609407, 17751.822481775147, 18139.18373673188, 18216.63566728671, 14686.853131468655, 14736.410543578244, 14864.033837800616, 14970.47603333878, 15192.46826361072, 11866.584669536525, 11983.085287495163, 12152.331621760799, 12245.93877030618, 12686.746265074738, 3860.942085868714, 3869.1487872667967, 3933.9606603934167, 3942.231264903345, 4075.7554546727292, 122320.0, 122924.0, 125090.0, 125357.37321313335, 127643.36178319142, 8906.910930890608, 10603.469826508644, 10673.679789606324, 11184.993350598485, 11448.828267575993, 20238.49403764888, 23858.16496422617, 24261.27216183517, 24329.661868597042, 24479.755202447748, 8100.959495202486, 8110.5539195343645, 8118.14759450261, 8148.185181481923, 8260.628271727786, 4057.837686492507, 4590.770461476913, 4604.378408914754, 4659.6505262105475, 4721.740304283228, 3537.7877327359924, 3606.85572577094, 3617.3127105849803, 3645.9453108203397, 3653.5615726112505, 659.8878190707495, 660.8083655739878, 662.0400419391901, 676.3439463720183, 676.9627670478171, 573.498189084551, 704.8202708309349, 705.8270723672733, 721.1706537481833, 727.9745208917662, 10369.0, 10795.892041079489, 11066.004059634672, 11068.723281917855, 11143.944280278547, 724.8477819657826, 727.6761840980819, 732.9083864516936, 734.9853002939911, 741.8071301461658, 4354.518371400199, 4744.644151688637, 4953.424657534247, 4956.791292473591, 4963.933607887183, 99839.0, 101190.49404753077, 101226.0, 102182.0, 106698.3995240072, 76575.0, 80042.0, 83388.0, 84665.73001404984, 99157.0, 53676.0, 68943.65528172327, 76175.0, 78581.0, 81689.9577510567, 10713.678589642323, 11291.604793832177, 11423.257488263236, 11533.82699259495, 11556.653300401002, 48019.519804802905, 49118.0, 49671.0, 50061.24908126308, 50529.74735126301, 61877.74167951616, 62653.43366415876, 63415.682921585096, 63684.0, 64575.0, 12678.746425071262, 12799.29603871796, 12894.41975111141, 13106.148100373826, 13111.475540978636, 14581.0, 14619.92690036543, 14875.702485950003, 15610.9219453902, 15784.447544335893, 13529.0, 13603.523876664269, 13899.513517026855, 14380.5101418819, 14453.49412770548, 13506.379234491924, 13955.0, 13979.930100349433, 14104.193771902228, 14281.42874285017, 13303.268320242092, 13746.450141994208, 13792.724145516833, 13804.240766757925, 13979.161250325013, 9728.952074570434, 9945.751356216153, 10348.637797677045, 10377.896221037994, 10717.839232411365, 10079.395236285847, 10191.235657325878, 11424.371659558805, 11699.00558452541, 11726.237794543327], "value": 7930.422972948366, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 125.0, 126.0, 173.0, 131.0, 178.0, 163.0, 122.0, 121.0, 120.0], "value": 136.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 143.0, 94.0, 181.0, 132.0, 120.0, 142.0, 123.0, 145.0, 139.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 194.0, 253.0, 188.0, 277.0, 201.0, 281.0, 283.0, 213.0, 200.0], "value": 229.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 58.0, 85.0, 59.0, 62.0, 61.0, 60.0, 72.0, 58.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 82.0, 70.0, 70.0, 68.0, 79.0, 71.0, 69.0, 83.0], "value": 74.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 88.0, 90.0, 88.0, 88.0, 89.0, 89.0, 91.0, 89.0], "value": 98.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 107.0, 154.0, 153.0, 116.0, 95.0, 96.0, 116.0], "value": 112.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 75.0, 145.0, 145.0, 117.0, 89.0, 89.0, 89.0, 91.0, 121.0], "value": 107.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 67.0, 71.0, 96.0, 98.0, 99.0, 103.0, 100.0], "value": 82.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 65.0, 58.0, 66.0, 65.0, 62.0, 55.0, 58.0, 51.0, 65.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 85.0, 86.0, 82.0, 87.0, 100.0, 73.0, 100.0, 105.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 136.0, 139.0, 182.0, 154.0, 148.0, 150.0, 148.0, 204.0, 175.0], "value": 155.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 169.0, 164.0, 163.0, 168.0, 165.0, 165.0, 164.0, 162.0, 167.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 70.0, 67.0, 64.0, 65.0, 65.0, 65.0, 65.0, 63.0], "value": 66.0, "unit": "score"}]}]} 19:29:25 INFO - Return code: 0 19:29:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:29:25 ERROR - # TBPL SUCCESS # 19:29:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:29:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:29:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:29:25 INFO - 2017-04-19 19:29:25.736 screenresolution[1663:10657] starting screenresolution argv=screenresolution get 19:29:25 INFO - 2017-04-19 19:29:25.738 screenresolution[1663:10657] Display 0: 1600x1200x32@60 19:29:25 INFO - 2017-04-19 19:29:25.744 screenresolution[1664:10660] starting screenresolution argv=screenresolution list 19:29:25 INFO - Available Modes on Display 0 19:29:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:29:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:29:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:29:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:29:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:29:25 INFO - Intel Iris: 19:29:25 INFO - Chipset Model: Intel Iris 19:29:25 INFO - Type: GPU 19:29:25 INFO - Bus: Built-In 19:29:25 INFO - VRAM (Dynamic, Max): 1536 MB 19:29:25 INFO - Vendor: Intel (0x8086) 19:29:25 INFO - Device ID: 0x0a2e 19:29:25 INFO - Revision ID: 0x0009 19:29:25 INFO - Displays: 19:29:25 INFO - Display: 19:29:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:29:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:29:25 INFO - Display Serial Number: 1600x1200 60 19:29:25 INFO - Main Display: Yes 19:29:25 INFO - Mirror: Off 19:29:25 INFO - Online: Yes 19:29:25 INFO - Rotation: Supported 19:29:25 INFO - Return code: 0 19:29:25 INFO - Running post-action listener: _resource_record_post_action 19:29:25 INFO - [mozharness: 2017-04-20 02:29:25.980971Z] Finished run-tests step (success) 19:29:25 INFO - Running post-run listener: _resource_record_post_run 19:29:26 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 40413184; Write bytes: 425091072; Read time: 398; Write time: 1698 19:29:26 INFO - TinderboxPrint: CPU usage
54.4% 19:29:26 INFO - TinderboxPrint: I/O read bytes / time
40,413,184 / 398 19:29:26 INFO - TinderboxPrint: I/O write bytes / time
425,091,072 / 1,698 19:29:26 INFO - TinderboxPrint: CPU idle
2,174.4 (45.6%) 19:29:26 INFO - TinderboxPrint: CPU system
954.1 (20.0%) 19:29:26 INFO - TinderboxPrint: CPU user
1,637.2 (34.4%) 19:29:26 INFO - TinderboxPrint: Swap in / out
162,484,224 / 0 19:29:26 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 112462336; Write bytes: 106821632; Read time: 9945; Write time: 317 19:29:26 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 39450624; Write bytes: 300554240; Read time: 383; Write time: 1333 19:29:26 INFO - Running post-run listener: _upload_blobber_files 19:29:26 INFO - Blob upload gear active. 19:29:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:29:26 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 19:29:26 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:29:26 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:29:26 INFO - (blobuploader) - INFO - Open directory for files ... 19:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:29:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:28 INFO - (blobuploader) - INFO - Done attempting. 19:29:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:29:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:29:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:29 INFO - (blobuploader) - INFO - Done attempting. 19:29:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:29:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:29:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:30 INFO - (blobuploader) - INFO - Done attempting. 19:29:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:29:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:29:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:32 INFO - (blobuploader) - INFO - Done attempting. 19:29:32 INFO - (blobuploader) - INFO - Iteration through files over. 19:29:32 INFO - Return code: 0 19:29:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:29:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:29:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e5c0223e410a1346fee3fdb0dedb2ef063d9a9986b2396b24304ff4f2e1e703f96a871aed9c7054eee06202825dc2fe76098655cf18a48c7d87d4cdfe84bdc17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/025284348dc4b6ffa71ffdd96c85cf1d56c6ce93a242b906d00b301bb1146d6891fd30bca2a33cb70e1511c75c777069e0363cd411ad734465afba0a9f8befdf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/002d0ee253e0c0b1336c3c1a9b11657c1fd4fd17fa9c98b256b015ec966bd35c4a30a8c94829ffae6ceb0703e5a4bf1cdff5585f08d83a5028470af377ead1a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5da1842a9627e85123a2a89c79df378914c5019331220bdc8842f7b39963867208e5af4197d0e272895524b129372c40e0eab758b05c87f93f1f06d5bd846557"} 19:29:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:29:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:29:32 INFO - Contents: 19:29:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e5c0223e410a1346fee3fdb0dedb2ef063d9a9986b2396b24304ff4f2e1e703f96a871aed9c7054eee06202825dc2fe76098655cf18a48c7d87d4cdfe84bdc17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/025284348dc4b6ffa71ffdd96c85cf1d56c6ce93a242b906d00b301bb1146d6891fd30bca2a33cb70e1511c75c777069e0363cd411ad734465afba0a9f8befdf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/002d0ee253e0c0b1336c3c1a9b11657c1fd4fd17fa9c98b256b015ec966bd35c4a30a8c94829ffae6ceb0703e5a4bf1cdff5585f08d83a5028470af377ead1a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5da1842a9627e85123a2a89c79df378914c5019331220bdc8842f7b39963867208e5af4197d0e272895524b129372c40e0eab758b05c87f93f1f06d5bd846557"} 19:29:32 INFO - Running post-run listener: copy_logs_to_upload_dir 19:29:32 INFO - Copying logs to upload dir... 19:29:32 INFO - mkdir: /builds/slave/test/build/upload/logs 19:29:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.636095 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-04-19 19:29:32.316986) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-19 19:29:32.320653) ========= 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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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-Beta/sha512/e5c0223e410a1346fee3fdb0dedb2ef063d9a9986b2396b24304ff4f2e1e703f96a871aed9c7054eee06202825dc2fe76098655cf18a48c7d87d4cdfe84bdc17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/025284348dc4b6ffa71ffdd96c85cf1d56c6ce93a242b906d00b301bb1146d6891fd30bca2a33cb70e1511c75c777069e0363cd411ad734465afba0a9f8befdf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/002d0ee253e0c0b1336c3c1a9b11657c1fd4fd17fa9c98b256b015ec966bd35c4a30a8c94829ffae6ceb0703e5a4bf1cdff5585f08d83a5028470af377ead1a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5da1842a9627e85123a2a89c79df378914c5019331220bdc8842f7b39963867208e5af4197d0e272895524b129372c40e0eab758b05c87f93f1f06d5bd846557"} build_url:https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011109 build_url: 'https://queue.taskcluster.net/v1/task/KynrzFL-QUeRgmfBseZKwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e5c0223e410a1346fee3fdb0dedb2ef063d9a9986b2396b24304ff4f2e1e703f96a871aed9c7054eee06202825dc2fe76098655cf18a48c7d87d4cdfe84bdc17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/025284348dc4b6ffa71ffdd96c85cf1d56c6ce93a242b906d00b301bb1146d6891fd30bca2a33cb70e1511c75c777069e0363cd411ad734465afba0a9f8befdf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/002d0ee253e0c0b1336c3c1a9b11657c1fd4fd17fa9c98b256b015ec966bd35c4a30a8c94829ffae6ceb0703e5a4bf1cdff5585f08d83a5028470af377ead1a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5da1842a9627e85123a2a89c79df378914c5019331220bdc8842f7b39963867208e5af4197d0e272895524b129372c40e0eab758b05c87f93f1f06d5bd846557"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-19 19:29:32.355289) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 19:29:32.355680) ========= 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.Xs4bR4d8YJ/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.b58VDVuQbf/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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.005136 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 19:29:32.404205) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-19 19:29:32.404534) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-19 19:29:32.405136) ========= ========= Total master_lag: 0.17 =========