builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0199 starttime: 1485883010.94 results: success (0) buildid: 20170131071808 builduid: f8b941798fed43998e3a5f97e7f0b413 revision: 927ab26019d8fb81fe497548fc7f7d0c33383412 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:50.943061) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:50.943510) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:50.943784) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:50.970075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:50.970484) ========= 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.EukExdqBo9/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.ArGSAcfLQ2/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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-01-31 09:16:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.13M=0.001s 2017-01-31 09:16:51 (8.13 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.079188 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:51.073085) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:51.076477) ========= 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.EukExdqBo9/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.ArGSAcfLQ2/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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.019780 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:51.125042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 09:16:51.128471) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 927ab26019d8fb81fe497548fc7f7d0c33383412 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 927ab26019d8fb81fe497548fc7f7d0c33383412 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EukExdqBo9/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.ArGSAcfLQ2/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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-01-31 09:16:51,212 truncating revision to first 12 chars 2017-01-31 09:16:51,212 Setting DEBUG logging. 2017-01-31 09:16:51,212 attempt 1/10 2017-01-31 09:16:51,212 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/927ab26019d8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-31 09:16:52,860 unpacking tar archive at: mozilla-aurora-927ab26019d8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.903228 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 09:16:53.058375) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:53.058719) ========= script_repo_revision: 927ab26019d8fb81fe497548fc7f7d0c33383412 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:53.059107) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:53.059387) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 09:16:53.080305) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-01-31 09:16:53.080664) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EukExdqBo9/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.ArGSAcfLQ2/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:16:53 INFO - MultiFileLogger online at 20170131 09:16:53 in /builds/slave/test 09:16:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:16:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:16:53 INFO - {'append_to_log': False, 09:16:53 INFO - 'base_work_dir': '/builds/slave/test', 09:16:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:16:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:16:53 INFO - 'branch': 'Mozilla-Aurora', 09:16:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:16:53 INFO - 'config_files': ('talos/mac_config.py',), 09:16:53 INFO - 'default_actions': ('clobber', 09:16:53 INFO - 'read-buildbot-config', 09:16:53 INFO - 'download-and-extract', 09:16:53 INFO - 'populate-webroot', 09:16:53 INFO - 'create-virtualenv', 09:16:53 INFO - 'install', 09:16:53 INFO - 'run-tests'), 09:16:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:16:53 INFO - 'download_minidump_stackwalk': True, 09:16:53 INFO - 'download_symbols': 'ondemand', 09:16:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:16:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:16:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:16:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:16:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:16:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:16:53 INFO - 'installer_path': 'installer.exe', 09:16:53 INFO - 'log_level': 'info', 09:16:53 INFO - 'log_name': 'talos', 09:16:53 INFO - 'log_to_console': True, 09:16:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:16:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:16:53 INFO - 'opt_config_files': (), 09:16:53 INFO - 'pip_index': False, 09:16:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:53 INFO - 'cmd': ('bash', 09:16:53 INFO - '-c', 09:16:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:53 INFO - 'enabled': True, 09:16:53 INFO - 'halt_on_failure': False, 09:16:53 INFO - 'name': 'check_screen_resolution'},), 09:16:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:53 INFO - 'cmd': ('bash', 09:16:53 INFO - '-c', 09:16:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:53 INFO - 'enabled': True, 09:16:53 INFO - 'halt_on_failure': False, 09:16:53 INFO - 'name': 'check_screen_resolution'},), 09:16:53 INFO - 'run_cmd_checks_enabled': True, 09:16:53 INFO - 'sps_profile': False, 09:16:53 INFO - 'sps_profile_interval': 0, 09:16:53 INFO - 'suite': 'dromaeojs-e10s', 09:16:53 INFO - 'system_bits': '32', 09:16:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:16:53 INFO - 'title': 't-yosemite-r7-0199', 09:16:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:16:53 INFO - 'use_talos_json': True, 09:16:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:16:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:16:53 INFO - 'work_dir': 'build'} 09:16:53 INFO - [mozharness: 2017-01-31 17:16:53.294339Z] Running clobber step. 09:16:53 INFO - Running pre-action listener: _resource_record_pre_action 09:16:53 INFO - Running main action method: clobber 09:16:53 INFO - rmtree: /builds/slave/test/build 09:16:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:16:53 INFO - Running post-action listener: _resource_record_post_action 09:16:53 INFO - [mozharness: 2017-01-31 17:16:53.449931Z] Finished clobber step (success) 09:16:53 INFO - [mozharness: 2017-01-31 17:16:53.450071Z] Running read-buildbot-config step. 09:16:53 INFO - Running pre-action listener: _resource_record_pre_action 09:16:53 INFO - Running main action method: read_buildbot_config 09:16:53 INFO - Using buildbot properties: 09:16:53 INFO - { 09:16:53 INFO - "project": "", 09:16:53 INFO - "product": "firefox", 09:16:53 INFO - "script_repo_revision": "production", 09:16:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:16:53 INFO - "repository": "", 09:16:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:16:53 INFO - "buildid": "20170131071808", 09:16:53 INFO - "pgo_build": "False", 09:16:53 INFO - "basedir": "/builds/slave/test", 09:16:53 INFO - "buildnumber": 39, 09:16:53 INFO - "slavename": "t-yosemite-r7-0199", 09:16:53 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:16:53 INFO - "platform": "yosemite_r7", 09:16:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:16:53 INFO - "branch": "mozilla-aurora", 09:16:53 INFO - "repo_path": "releases/mozilla-aurora", 09:16:53 INFO - "revision": "927ab26019d8fb81fe497548fc7f7d0c33383412", 09:16:53 INFO - "stage_platform": "macosx64", 09:16:53 INFO - "builduid": "f8b941798fed43998e3a5f97e7f0b413", 09:16:53 INFO - "slavebuilddir": "test" 09:16:53 INFO - } 09:16:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 09:16:53 INFO - Running post-action listener: _resource_record_post_action 09:16:53 INFO - [mozharness: 2017-01-31 17:16:53.453413Z] Finished read-buildbot-config step (success) 09:16:53 INFO - [mozharness: 2017-01-31 17:16:53.453555Z] Running download-and-extract step. 09:16:53 INFO - Running pre-action listener: _resource_record_pre_action 09:16:53 INFO - Running main action method: download_and_extract 09:16:53 INFO - mkdir: /builds/slave/test/build/tests 09:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:53 INFO - trying https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 09:16:53 INFO - Downloading https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 09:16:54 INFO - Downloaded 1199 bytes. 09:16:54 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:16:54 INFO - Using the following test package requirements: 09:16:54 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:16:54 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:54 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 09:16:54 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:54 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 09:16:54 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:54 INFO - u'jsshell-mac.zip'], 09:16:54 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:54 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 09:16:54 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:16:54 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:54 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 09:16:54 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:54 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 09:16:54 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:54 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 09:16:54 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:54 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 09:16:54 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:16:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:16:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:16:54 INFO - Fetch https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:16:55 INFO - Expected file size: 16353269 09:16:55 INFO - Obtained file size: 16353269 09:17:02 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:17:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:17:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:17:02 INFO - Fetch https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:17:02 INFO - Expected file size: 16353269 09:17:02 INFO - Obtained file size: 16353269 09:17:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 09:17:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:17:09 INFO - Fetch https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 09:17:10 INFO - Expected file size: 14403485 09:17:10 INFO - Obtained file size: 14403485 09:17: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')]} 09:17:10 INFO - trying https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:17:10 INFO - Downloading https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 09:17:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 09:17:12 INFO - Downloaded 73856173 bytes. 09:17:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:17:12 INFO - mkdir: /builds/slave/test/properties 09:17:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:17:12 INFO - Writing to file /builds/slave/test/properties/build_url 09:17:12 INFO - Contents: 09:17:12 INFO - build_url:https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:17:13 INFO - Running post-action listener: _resource_record_post_action 09:17:13 INFO - Running post-action listener: set_extra_try_arguments 09:17:13 INFO - [mozharness: 2017-01-31 17:17:13.627791Z] Finished download-and-extract step (success) 09:17:13 INFO - [mozharness: 2017-01-31 17:17:13.627927Z] Running populate-webroot step. 09:17:13 INFO - Running pre-action listener: _resource_record_pre_action 09:17:13 INFO - Running main action method: populate_webroot 09:17:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:17:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:17:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:17:13 INFO - '--tppagecycles', 09:17:13 INFO - '7'], 09:17:13 INFO - 'tests': ['tsvgm']}}, 09:17:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:17:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:17:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:17:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:17:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:17:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:17:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:17:13 INFO - 'talos_options': ['--disable-e10s'], 09:17:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:17:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:17:13 INFO - 'talos_options': ['--disable-e10s'], 09:17:13 INFO - 'tests': ['damp', 'tps']}, 09:17:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:17:13 INFO - 'tests': ['damp', 'tps']}, 09:17:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:17:13 INFO - 'tests': ['dromaeo_dom']}, 09:17:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:17:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:17:13 INFO - 'tests': ['basic_compositor_video']}, 09:17:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:17:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:17:13 INFO - 'tests': ['a11yr', 09:17:13 INFO - 'ts_paint', 09:17:13 INFO - 'tpaint', 09:17:13 INFO - 'sessionrestore', 09:17:13 INFO - 'sessionrestore_no_auto_restore', 09:17:13 INFO - 'tabpaint']}, 09:17:13 INFO - 'other-e10s': {'tests': ['a11yr', 09:17:13 INFO - 'ts_paint', 09:17:13 INFO - 'tpaint', 09:17:13 INFO - 'sessionrestore', 09:17:13 INFO - 'sessionrestore_no_auto_restore', 09:17:13 INFO - 'tabpaint']}, 09:17:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:17:13 INFO - 'ts_paint', 09:17:13 INFO - 'tpaint', 09:17:13 INFO - 'sessionrestore', 09:17:13 INFO - 'sessionrestore_no_auto_restore', 09:17:13 INFO - 'tabpaint']}, 09:17:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:17:13 INFO - 'ts_paint', 09:17:13 INFO - 'tpaint', 09:17:13 INFO - 'sessionrestore', 09:17:13 INFO - 'sessionrestore_no_auto_restore', 09:17:13 INFO - 'tabpaint']}, 09:17:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:17:13 INFO - 'tests': ['a11yr', 09:17:13 INFO - 'ts_paint', 09:17:13 INFO - 'tpaint', 09:17:13 INFO - 'sessionrestore', 09:17:13 INFO - 'sessionrestore_no_auto_restore', 09:17:13 INFO - 'tabpaint']}, 09:17:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:17:13 INFO - 'tests': ['a11yr', 09:17:13 INFO - 'ts_paint', 09:17:13 INFO - 'tpaint', 09:17:13 INFO - 'sessionrestore', 09:17:13 INFO - 'sessionrestore_no_auto_restore', 09:17:13 INFO - 'tabpaint']}, 09:17:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:17:13 INFO - 'tests': ['tsvgx', 09:17:13 INFO - 'tsvgr_opacity', 09:17:13 INFO - 'tart', 09:17:13 INFO - 'tscrollx', 09:17:13 INFO - 'cart', 09:17:13 INFO - 'tsvg_static']}, 09:17:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:17:13 INFO - 'tsvgr_opacity', 09:17:13 INFO - 'tart', 09:17:13 INFO - 'tscrollx', 09:17:13 INFO - 'cart', 09:17:13 INFO - 'tsvg_static']}, 09:17:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:17:13 INFO - 'talos_options': ['--disable-e10s'], 09:17:13 INFO - 'tests': ['tp5o']}, 09:17:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:17:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:17:13 INFO - 'talos_options': ['--disable-e10s', 09:17:13 INFO - '--xperf_path', 09:17:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:13 INFO - 'tests': ['tp5n']}, 09:17:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:17:13 INFO - 'talos_options': ['--xperf_path', 09:17:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:13 INFO - 'tests': ['tp5n']}}, 09:17:13 INFO - 'talos.zip': {'path': '', 09:17:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:17:13 INFO - Running post-action listener: _resource_record_post_action 09:17:13 INFO - [mozharness: 2017-01-31 17:17:13.647013Z] Finished populate-webroot step (success) 09:17:13 INFO - [mozharness: 2017-01-31 17:17:13.647146Z] Running create-virtualenv step. 09:17:13 INFO - Running pre-action listener: _resource_record_pre_action 09:17:13 INFO - Running main action method: create_virtualenv 09:17:13 INFO - Creating virtualenv /builds/slave/test/build/venv 09:17:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:17:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:17:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:17:13 INFO - Using real prefix '/tools/python27' 09:17:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:17:14 INFO - Installing distribute.............................................................................................................................................................................................done. 09:17:17 INFO - Installing pip.................done. 09:17:17 INFO - Return code: 0 09:17:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:17:17 INFO - Reading from file tmpfile_stdout 09:17:17 INFO - Output received: 09:17:17 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:17:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10940db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97314d8260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091ebd30>, '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 0x109112db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091e0be8>, '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.EukExdqBo9/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.ArGSAcfLQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:17:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EukExdqBo9/Render', 09:17:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:17 INFO - 'HOME': '/Users/cltbld', 09:17:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:17 INFO - 'LOGNAME': 'cltbld', 09:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:17 INFO - 'MOZ_NO_REMOTE': '1', 09:17:17 INFO - 'NO_EM_RESTART': '1', 09:17:17 INFO - 'PAGER': '/bin/cat', 09:17:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:17 INFO - 'PWD': '/builds/slave/test', 09:17:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:17 INFO - 'SHELL': '/bin/bash', 09:17:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArGSAcfLQ2/Listeners', 09:17:17 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:17:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:17 INFO - 'USER': 'cltbld', 09:17:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:17 INFO - 'XPC_FLAGS': '0x0', 09:17:17 INFO - 'XPC_SERVICE_NAME': '0', 09:17:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:17:17 INFO - Cleaning up... 09:17:17 INFO - Return code: 0 09:17:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10940db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97314d8260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091ebd30>, '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 0x109112db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091e0be8>, '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.EukExdqBo9/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.ArGSAcfLQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:17:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EukExdqBo9/Render', 09:17:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:17 INFO - 'HOME': '/Users/cltbld', 09:17:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:17 INFO - 'LOGNAME': 'cltbld', 09:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:17 INFO - 'MOZ_NO_REMOTE': '1', 09:17:17 INFO - 'NO_EM_RESTART': '1', 09:17:17 INFO - 'PAGER': '/bin/cat', 09:17:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:17 INFO - 'PWD': '/builds/slave/test', 09:17:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:17 INFO - 'SHELL': '/bin/bash', 09:17:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArGSAcfLQ2/Listeners', 09:17:17 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:17:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:17 INFO - 'USER': 'cltbld', 09:17:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:17 INFO - 'XPC_FLAGS': '0x0', 09:17:17 INFO - 'XPC_SERVICE_NAME': '0', 09:17:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:18 INFO - Downloading/unpacking psutil>=3.1.1 09:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:17:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:21 INFO - Installing collected packages: psutil 09:17:21 INFO - Running setup.py install for psutil 09:17:21 INFO - building 'psutil._psutil_osx' extension 09:17:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:17:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:17:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:17:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:17:22 INFO - building 'psutil._psutil_posix' extension 09:17:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:17:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:17:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:17:22 INFO - ^ 09:17:22 INFO - 1 warning generated. 09:17:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:17:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:22 INFO - Successfully installed psutil 09:17:22 INFO - Cleaning up... 09:17:22 INFO - Return code: 0 09:17:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10940db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97314d8260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091ebd30>, '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 0x109112db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091e0be8>, '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.EukExdqBo9/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.ArGSAcfLQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:17:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EukExdqBo9/Render', 09:17:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:22 INFO - 'HOME': '/Users/cltbld', 09:17:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:22 INFO - 'LOGNAME': 'cltbld', 09:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:22 INFO - 'MOZ_NO_REMOTE': '1', 09:17:22 INFO - 'NO_EM_RESTART': '1', 09:17:22 INFO - 'PAGER': '/bin/cat', 09:17:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:22 INFO - 'PWD': '/builds/slave/test', 09:17:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:22 INFO - 'SHELL': '/bin/bash', 09:17:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArGSAcfLQ2/Listeners', 09:17:22 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:17:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:22 INFO - 'USER': 'cltbld', 09:17:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:22 INFO - 'XPC_FLAGS': '0x0', 09:17:22 INFO - 'XPC_SERVICE_NAME': '0', 09:17:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:17: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 09:17: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 09:17: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 09:17: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 09:17: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 09:17: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 09:17:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:17:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:17:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:17:25 INFO - Installing collected packages: mozsystemmonitor 09:17:25 INFO - Running setup.py install for mozsystemmonitor 09:17:25 INFO - Successfully installed mozsystemmonitor 09:17:25 INFO - Cleaning up... 09:17:25 INFO - Return code: 0 09:17:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:25 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 0x10940db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97314d8260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091ebd30>, '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 0x109112db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091e0be8>, '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.EukExdqBo9/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.ArGSAcfLQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EukExdqBo9/Render', 09:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:25 INFO - 'HOME': '/Users/cltbld', 09:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:25 INFO - 'LOGNAME': 'cltbld', 09:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:25 INFO - 'MOZ_NO_REMOTE': '1', 09:17:25 INFO - 'NO_EM_RESTART': '1', 09:17:25 INFO - 'PAGER': '/bin/cat', 09:17:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:25 INFO - 'PWD': '/builds/slave/test', 09:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:25 INFO - 'SHELL': '/bin/bash', 09:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArGSAcfLQ2/Listeners', 09:17:25 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:25 INFO - 'USER': 'cltbld', 09:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:25 INFO - 'XPC_FLAGS': '0x0', 09:17:25 INFO - 'XPC_SERVICE_NAME': '0', 09:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:25 INFO - Downloading/unpacking jsonschema==2.5.1 09:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:17:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:28 INFO - Downloading functools32-3.2.3-2.tar.gz 09:17:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:17:28 INFO - warning: no files found matching '*.txt' 09:17:28 INFO - no previously-included directories found matching 'build' 09:17:28 INFO - no previously-included directories found matching 'dist' 09:17:28 INFO - no previously-included directories found matching '.git*' 09:17:28 INFO - Installing collected packages: jsonschema, functools32 09:17:29 INFO - Running setup.py install for functools32 09:17:29 INFO - warning: no files found matching '*.txt' 09:17:29 INFO - no previously-included directories found matching 'build' 09:17:29 INFO - no previously-included directories found matching 'dist' 09:17:29 INFO - no previously-included directories found matching '.git*' 09:17:29 INFO - Successfully installed jsonschema functools32 09:17:29 INFO - Cleaning up... 09:17:29 INFO - Return code: 0 09:17:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:29 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 0x10940db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97314d8260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091ebd30>, '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 0x109112db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091e0be8>, '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.EukExdqBo9/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.ArGSAcfLQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:17:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EukExdqBo9/Render', 09:17:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:29 INFO - 'HOME': '/Users/cltbld', 09:17:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:29 INFO - 'LOGNAME': 'cltbld', 09:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:29 INFO - 'MOZ_NO_REMOTE': '1', 09:17:29 INFO - 'NO_EM_RESTART': '1', 09:17:29 INFO - 'PAGER': '/bin/cat', 09:17:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:29 INFO - 'PWD': '/builds/slave/test', 09:17:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:29 INFO - 'SHELL': '/bin/bash', 09:17:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArGSAcfLQ2/Listeners', 09:17:29 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:17:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:29 INFO - 'USER': 'cltbld', 09:17:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:29 INFO - 'XPC_FLAGS': '0x0', 09:17:29 INFO - 'XPC_SERVICE_NAME': '0', 09:17:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:17:29 INFO - Cleaning up... 09:17:29 INFO - Return code: 0 09:17:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:29 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 0x10940db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97314d8260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091ebd30>, '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 0x109112db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091e0be8>, '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.EukExdqBo9/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.ArGSAcfLQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:17:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EukExdqBo9/Render', 09:17:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:29 INFO - 'HOME': '/Users/cltbld', 09:17:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:29 INFO - 'LOGNAME': 'cltbld', 09:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:29 INFO - 'MOZ_NO_REMOTE': '1', 09:17:29 INFO - 'NO_EM_RESTART': '1', 09:17:29 INFO - 'PAGER': '/bin/cat', 09:17:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:29 INFO - 'PWD': '/builds/slave/test', 09:17:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:29 INFO - 'SHELL': '/bin/bash', 09:17:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArGSAcfLQ2/Listeners', 09:17:29 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:17:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:29 INFO - 'USER': 'cltbld', 09:17:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:29 INFO - 'XPC_FLAGS': '0x0', 09:17:29 INFO - 'XPC_SERVICE_NAME': '0', 09:17:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:29 INFO - Downloading/unpacking blobuploader==1.2.4 09:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:32 INFO - Downloading blobuploader-1.2.4.tar.gz 09:17:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:17:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:17:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:33 INFO - Downloading docopt-0.6.1.tar.gz 09:17:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:17:33 INFO - Installing collected packages: blobuploader, requests, docopt 09:17:33 INFO - Running setup.py install for blobuploader 09:17:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:17:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:17:33 INFO - Running setup.py install for requests 09:17:34 INFO - Running setup.py install for docopt 09:17:34 INFO - Successfully installed blobuploader requests docopt 09:17:34 INFO - Cleaning up... 09:17:34 INFO - Return code: 0 09:17:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:34 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 0x10940db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97314d8260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091ebd30>, '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 0x109112db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091e0be8>, '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.EukExdqBo9/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.ArGSAcfLQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:17:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EukExdqBo9/Render', 09:17:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:34 INFO - 'HOME': '/Users/cltbld', 09:17:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:34 INFO - 'LOGNAME': 'cltbld', 09:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:34 INFO - 'MOZ_NO_REMOTE': '1', 09:17:34 INFO - 'NO_EM_RESTART': '1', 09:17:34 INFO - 'PAGER': '/bin/cat', 09:17:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:34 INFO - 'PWD': '/builds/slave/test', 09:17:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:34 INFO - 'SHELL': '/bin/bash', 09:17:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArGSAcfLQ2/Listeners', 09:17:34 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:17:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:34 INFO - 'USER': 'cltbld', 09:17:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:34 INFO - 'XPC_FLAGS': '0x0', 09:17:34 INFO - 'XPC_SERVICE_NAME': '0', 09:17:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:34 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-LxRx9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:35 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-1IfeF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:35 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-rpRkte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:35 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-2dmH41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:35 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-qUmW0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:35 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-AUWcTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:35 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-BudzMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:35 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-LonYs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:35 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-R7GfTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:35 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-4lwHWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:35 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-C4fP93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:36 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-QnfpsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:36 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-39YFyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:36 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-nJWBZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:36 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-eqibL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:36 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-nud_od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:36 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-nKoRCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:17:36 INFO - Running setup.py install for manifestparser 09:17:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:17:36 INFO - Running setup.py install for mozcrash 09:17:36 INFO - Running setup.py install for mozdebug 09:17:37 INFO - Running setup.py install for mozdevice 09:17:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:17:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:17:37 INFO - Running setup.py install for mozfile 09:17:37 INFO - Running setup.py install for mozhttpd 09:17:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:17:37 INFO - Running setup.py install for mozinfo 09:17:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:17:37 INFO - Running setup.py install for mozInstall 09:17:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:17:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:17:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:17:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:17:37 INFO - Running setup.py install for mozleak 09:17:37 INFO - Running setup.py install for mozlog 09:17:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:17:38 INFO - Running setup.py install for moznetwork 09:17:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:17:38 INFO - Running setup.py install for mozprocess 09:17:38 INFO - Running setup.py install for mozprofile 09:17:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:17:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:17:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:17:38 INFO - Running setup.py install for mozrunner 09:17:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:17:38 INFO - Running setup.py install for mozscreenshot 09:17:38 INFO - Running setup.py install for moztest 09:17:39 INFO - Running setup.py install for mozversion 09:17:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:17:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:17:39 INFO - Cleaning up... 09:17:39 INFO - Return code: 0 09:17:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:39 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 0x10940db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97314d8260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091ebd30>, '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 0x109112db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091e0be8>, '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.EukExdqBo9/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.ArGSAcfLQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:17:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EukExdqBo9/Render', 09:17:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:39 INFO - 'HOME': '/Users/cltbld', 09:17:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:39 INFO - 'LOGNAME': 'cltbld', 09:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:39 INFO - 'MOZ_NO_REMOTE': '1', 09:17:39 INFO - 'NO_EM_RESTART': '1', 09:17:39 INFO - 'PAGER': '/bin/cat', 09:17:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:39 INFO - 'PWD': '/builds/slave/test', 09:17:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:39 INFO - 'SHELL': '/bin/bash', 09:17:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArGSAcfLQ2/Listeners', 09:17:39 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:17:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:39 INFO - 'USER': 'cltbld', 09:17:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:39 INFO - 'XPC_FLAGS': '0x0', 09:17:39 INFO - 'XPC_SERVICE_NAME': '0', 09:17:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:39 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-oO48XE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:39 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-R5VzeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:39 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-gOsDcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:39 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-atHQtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:39 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-r_29Z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-TV0Hzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-lH3RvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-43mK95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-xGhI6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-ETC0Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-ojlsl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-3eqZmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-YgZB6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-STStoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-S50ri4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-mVtaRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-OEbhzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:17:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:17:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:17:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:17:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:43 INFO - Downloading blessings-1.6.tar.gz 09:17:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:17:44 INFO - Installing collected packages: blessings 09:17:44 INFO - Running setup.py install for blessings 09:17:44 INFO - Successfully installed blessings 09:17:44 INFO - Cleaning up... 09:17:44 INFO - Return code: 0 09:17:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:17:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:44 INFO - Reading from file tmpfile_stdout 09:17:44 INFO - Current package versions: 09:17:44 INFO - blessings == 1.6 09:17:44 INFO - blobuploader == 1.2.4 09:17:44 INFO - docopt == 0.6.1 09:17:44 INFO - functools32 == 3.2.3-2 09:17:44 INFO - jsonschema == 2.5.1 09:17:44 INFO - manifestparser == 1.1 09:17:44 INFO - mozInstall == 1.12 09:17:44 INFO - mozcrash == 1.0 09:17:44 INFO - mozdebug == 0.1 09:17:44 INFO - mozdevice == 0.49 09:17:44 INFO - mozfile == 1.2 09:17:44 INFO - mozhttpd == 0.7 09:17:44 INFO - mozinfo == 0.9 09:17:44 INFO - mozleak == 0.1 09:17:44 INFO - mozlog == 3.4 09:17:44 INFO - moznetwork == 0.27 09:17:44 INFO - mozprocess == 0.24 09:17:44 INFO - mozprofile == 0.28 09:17:44 INFO - mozrunner == 6.13 09:17:44 INFO - mozscreenshot == 0.1 09:17:44 INFO - mozsystemmonitor == 0.3 09:17:44 INFO - moztest == 0.8 09:17:44 INFO - mozversion == 1.4 09:17:44 INFO - psutil == 3.1.1 09:17:44 INFO - requests == 1.2.3 09:17:44 INFO - wsgiref == 0.1.2 09:17:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:44 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 0x10940db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97314d8260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091ebd30>, '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 0x109112db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091e0be8>, '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.EukExdqBo9/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.ArGSAcfLQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EukExdqBo9/Render', 09:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:44 INFO - 'HOME': '/Users/cltbld', 09:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:44 INFO - 'LOGNAME': 'cltbld', 09:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:44 INFO - 'MOZ_NO_REMOTE': '1', 09:17:44 INFO - 'NO_EM_RESTART': '1', 09:17:44 INFO - 'PAGER': '/bin/cat', 09:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:44 INFO - 'PWD': '/builds/slave/test', 09:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:44 INFO - 'SHELL': '/bin/bash', 09:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArGSAcfLQ2/Listeners', 09:17:44 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:44 INFO - 'USER': 'cltbld', 09:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:44 INFO - 'XPC_FLAGS': '0x0', 09:17:44 INFO - 'XPC_SERVICE_NAME': '0', 09:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:17:44 INFO - Cleaning up... 09:17:44 INFO - Return code: 0 09:17:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:44 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 0x10940db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97314d8260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091ebd30>, '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 0x109112db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091e0be8>, '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.EukExdqBo9/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.ArGSAcfLQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EukExdqBo9/Render', 09:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:44 INFO - 'HOME': '/Users/cltbld', 09:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:44 INFO - 'LOGNAME': 'cltbld', 09:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:44 INFO - 'MOZ_NO_REMOTE': '1', 09:17:44 INFO - 'NO_EM_RESTART': '1', 09:17:44 INFO - 'PAGER': '/bin/cat', 09:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:44 INFO - 'PWD': '/builds/slave/test', 09:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:44 INFO - 'SHELL': '/bin/bash', 09:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArGSAcfLQ2/Listeners', 09:17:44 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:44 INFO - 'USER': 'cltbld', 09:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:44 INFO - 'XPC_FLAGS': '0x0', 09:17:44 INFO - 'XPC_SERVICE_NAME': '0', 09:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:17:45 INFO - Cleaning up... 09:17:45 INFO - Return code: 0 09:17:45 INFO - Running post-action listener: _resource_record_post_action 09:17:45 INFO - Running post-action listener: _start_resource_monitoring 09:17:45 INFO - Starting resource monitoring. 09:17:45 INFO - [mozharness: 2017-01-31 17:17:45.107055Z] Finished create-virtualenv step (success) 09:17:45 INFO - [mozharness: 2017-01-31 17:17:45.108015Z] Running install step. 09:17:45 INFO - Running pre-action listener: _resource_record_pre_action 09:17:45 INFO - Running main action method: install 09:17:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:45 INFO - Reading from file tmpfile_stdout 09:17:45 INFO - Detecting whether we're running mozinstall >=1.0... 09:17:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:17:45 INFO - Reading from file tmpfile_stdout 09:17:45 INFO - Output received: 09:17:45 INFO - Usage: mozinstall [options] installer 09:17:45 INFO - Options: 09:17:45 INFO - -h, --help show this help message and exit 09:17:45 INFO - -d DEST, --destination=DEST 09:17:45 INFO - Directory to install application into. [default: 09:17:45 INFO - "/builds/slave/test"] 09:17:45 INFO - --app=APP Application being installed. [default: firefox] 09:17:45 INFO - mkdir: /builds/slave/test/build/application 09:17:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:18:05 INFO - Reading from file tmpfile_stdout 09:18:05 INFO - Output received: 09:18:05 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:18:05 INFO - Running post-action listener: _resource_record_post_action 09:18:05 INFO - [mozharness: 2017-01-31 17:18:05.272594Z] Finished install step (success) 09:18:05 INFO - [mozharness: 2017-01-31 17:18:05.272744Z] Running run-tests step. 09:18:05 INFO - Running pre-action listener: _resource_record_pre_action 09:18:05 INFO - Running main action method: run_tests 09:18:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:05 INFO - 2017-01-31 09:18:05.287 screenresolution[1899:9737] starting screenresolution argv=screenresolution get 09:18:05 INFO - 2017-01-31 09:18:05.290 screenresolution[1899:9737] Display 0: 1600x1200x32@60 09:18:05 INFO - 2017-01-31 09:18:05.296 screenresolution[1900:9740] starting screenresolution argv=screenresolution list 09:18:05 INFO - Available Modes on Display 0 09:18:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:05 INFO - Intel Iris: 09:18:05 INFO - Chipset Model: Intel Iris 09:18:05 INFO - Type: GPU 09:18:05 INFO - Bus: Built-In 09:18:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:05 INFO - Vendor: Intel (0x8086) 09:18:05 INFO - Device ID: 0x0a2e 09:18:05 INFO - Revision ID: 0x0009 09:18:05 INFO - Displays: 09:18:05 INFO - Display: 09:18:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:05 INFO - Display Serial Number: 1600x1200 60 09:18:05 INFO - Main Display: Yes 09:18:05 INFO - Mirror: Off 09:18:05 INFO - Online: Yes 09:18:05 INFO - Rotation: Supported 09:18:05 INFO - Return code: 0 09:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:18:05 INFO - Python 2.7.3 09:18:05 INFO - Return code: 0 09:18:05 INFO - grabbing minidump binary from tooltool 09:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:05 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 0x1091ebd30>, '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 0x109112db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091e0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:18:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:18:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:18:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:18:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:05 INFO - Return code: 0 09:18:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:18:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:18:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:18:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:18:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0199', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0199 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:18:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EukExdqBo9/Render', 09:18:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:05 INFO - 'HOME': '/Users/cltbld', 09:18:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:05 INFO - 'LOGNAME': 'cltbld', 09:18:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:18:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:05 INFO - 'MOZ_NO_REMOTE': '1', 09:18:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:18:05 INFO - 'NO_EM_RESTART': '1', 09:18:05 INFO - 'PAGER': '/bin/cat', 09:18:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:05 INFO - 'PWD': '/builds/slave/test', 09:18:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:18:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:05 INFO - 'SHELL': '/bin/bash', 09:18:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ArGSAcfLQ2/Listeners', 09:18:05 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:18:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:05 INFO - 'USER': 'cltbld', 09:18:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:05 INFO - 'XPC_FLAGS': '0x0', 09:18:05 INFO - 'XPC_SERVICE_NAME': '0', 09:18:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0199', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:18:05 INFO - mozversion application_buildid: 20170131071808 09:18:05 INFO - mozversion application_changeset: 927ab26019d8fb81fe497548fc7f7d0c33383412 09:18:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:18:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:05 INFO - mozversion application_name: Firefox 09:18:05 INFO - mozversion application_remotingname: firefox-dev 09:18:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:18:05 INFO - mozversion application_vendor: Mozilla 09:18:05 INFO - mozversion application_version: 53.0a2 09:18:05 INFO - mozversion platform_buildid: 20170131071808 09:18:05 INFO - mozversion platform_changeset: 927ab26019d8fb81fe497548fc7f7d0c33383412 09:18:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:18:05 INFO - mozversion platform_version: 53.0a2 09:18:05 INFO - using testdate: 1485883085 09:18:05 INFO - actual date: 1485883085 09:18:05 INFO - starting webserver on 'localhost:49376' 09:18:05 INFO - SUITE-START | Running 2 tests 09:18:05 INFO - TEST-START | dromaeo_css 09:18:05 INFO - operating with platform_type : mac_ 09:18:05 INFO - Initialising browser for dromaeo_css test... 09:18:05 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpC3ENfl/profile http://localhost:49376/getInfo.html) 09:18:08 INFO - PROCESS | 1910 | 2017-01-31 09:18:08.819 plugin-container[1912:9898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 09:18:08 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:18:08 INFO - PROCESS | 1910 | 2017-01-31 09:18:08.822 plugin-container[1912:9898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.CFPasteboardClient' 09:18:08 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:18:08 INFO - PROCESS | 1910 | 2017-01-31 09:18:08.822 plugin-container[1912:9898] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:18:08 INFO - PROCESS | 1910 | __metrics Screen width/height:1600/1200 09:18:08 INFO - PROCESS | 1910 | colorDepth:24 09:18:08 INFO - PROCESS | 1910 | Browser inner width/height: 1024/689 09:18:08 INFO - PROCESS | 1910 | __metrics 09:18:08 ERROR - PROCESS | 1910 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 09:18:09 INFO - TEST-INFO | 1910: exit 0 09:18:09 INFO - Browser initialized. 09:18:09 INFO - Running cycle 1/1 for dromaeo_css test... 09:18:09 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpC3ENfl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:18:12 INFO - PROCESS | 1913 | 2017-01-31 09:18:12.122 plugin-container[1914:10030] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 09:18:12 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:18:12 INFO - PROCESS | 1913 | 2017-01-31 09:18:12.122 plugin-container[1914:10030] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a23, name = 'com.apple.CFPasteboardClient' 09:18:12 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:18:12 INFO - PROCESS | 1913 | 2017-01-31 09:18:12.122 plugin-container[1914:10030] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:18:21 INFO - PROCESS | 1913 | RSS: Main: 218841088 09:18:21 INFO - PROCESS | 1913 | 09:21:24 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49376/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49376/tests/dromaeo/cssquery-ext.html) 09:21:25 INFO - PROCESS | 1913 | RSS: Main: 239116288 09:21:25 INFO - PROCESS | 1913 | 09:23:10 INFO - PROCESS | 1913 | ************************* 09:23:10 INFO - PROCESS | 1913 | A coding exception was thrown and uncaught in a Task. 09:23:10 INFO - PROCESS | 1913 | 09:23:10 ERROR - PROCESS | 1913 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:23:10 INFO - PROCESS | 1913 | Full stack: 09:23:10 INFO - PROCESS | 1913 | ************************* 09:24:28 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49376/tests/dromaeo/cssquery-ext.html (next: http://localhost:49376/tests/dromaeo/cssquery-jquery.html) 09:24:28 INFO - PROCESS | 1913 | RSS: Main: 251240448 09:24:28 INFO - PROCESS | 1913 | 09:27:30 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49376/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49376/tests/dromaeo/cssquery-mootools.html) 09:27:31 INFO - PROCESS | 1913 | RSS: Main: 257687552 09:27:31 INFO - PROCESS | 1913 | 09:30:33 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49376/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49376/tests/dromaeo/cssquery-prototype.html) 09:30:33 INFO - PROCESS | 1913 | RSS: Main: 254148608 09:30:33 INFO - PROCESS | 1913 | 09:33:36 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49376/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49376/tests/dromaeo/cssquery-yui.html) 09:33:37 INFO - PROCESS | 1913 | RSS: Main: 259080192 09:33:37 INFO - PROCESS | 1913 | 09:36:39 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:49376/tests/dromaeo/cssquery-yui.html (next: http://localhost:49376/tests/dromaeo/cssquery-dojo.html) 09:36:40 INFO - PROCESS | 1913 | RSS: Main: 260386816 09:36:40 INFO - PROCESS | 1913 | 09:36:40 INFO - PROCESS | 1913 | __start_tp_report 09:36:40 INFO - PROCESS | 1913 | _x_x_mozilla_page_load 09:36:40 INFO - PROCESS | 1913 | _x_x_mozilla_page_load_details 09:36:40 INFO - PROCESS | 1913 | |i|pagename|runs| 09:36:40 INFO - PROCESS | 1913 | |0;dojo.html;3381.814000229987;3400.251944572193;3425.0752296879837;3427.708644765195;3428.0915557377284;5859.384764599719;5950.166976623265;5958.31479379872;5986.2517185351835;5991.850203744909;5900.380460051686;5932.37710040446;5966.850828729284;5980.940190598093;5992.49063829575;5301.58777756122;5358.115910874701;5371.462853714636;5393.649412788156;5425.159100339434;5425.430329815372;5431.456854314557;5432.076546987002;5441.401445840955;5459.645123067008;5240.554552862992;5274.551663108621;5368.865778355553;5418.376886658046;5464.4262352453015;4391.604755572014;5321.068812957737;5382.0581398255545;5422.91865622016;5473.343198816127;5263.710495922723;5360.892782144374;5383.353997520284;5396.91904621431;5407.594430417695;5708.80019199326;5940.2871655401195;5943.197668314765;6013.308469526017;6070.696465176724;6185.907211391833;6193.5354848386105;6222.968885155546;6244.46922011625;6338.778142765027;6255.96872015637;6260.937390626081;6265.060240963846;6267.749290028393;6279.277883043417;5896.380880007623;5940.049592065248;5949.762009519614;5957.046872500379;5971.044632858721;6189.6286222826775;6213.906791398133;6279.309275979638;6296.748130074838;6305.873882522415;6248.562600617913;6261.436470717657;6285.371462853769;6293.433590976742;6297.748090076346;6277.027060805583;6282.340354262828;6293.402126797947;6296.464800491918;6316.115743795872;6184.443400094013;6196.845078873064;6236.594621349598;6255.186825712628;6270.996640537492;6232.3144454110015;6254.6247225166635;6254.843628909313;6255.437010669061;6270;5052.191649336089;5052.444231134572;5057.822976195889;5071.41678706946;5074.746262686851;4408.206522825923;4417.491988421309;4476.41806565151;4511.300748384006;4534.115847409724;5940.821775346746;5960.37416071315;5979.6711180884595;5999.610025348338;6051.909221361683;5668.008098582732;5984.940150598525;6166.4758495527485;6314.336994615591;6315.810525684236;5845.327787304429;5908.497777688859;5958.165856780054;5967.224260846149;5972.40275972408;5601.495865372136;5607.775688972395;5608.242887210256;5615.663060216319;5630.774769009194;6451.838704032437;6492;6496.480281577368;6497.317781632861;6504.967475162689;6154.368922335843;6181.598196117238;6202.224721909762;6271.404216599416;6345.206849143857;6419.839504012437;6449.064885591657;6451.3548645135115;6466.224053113657;6478.611283323015;6258.874822503433;6278.089676996901;6283.308836027942;6300.306966233619;6306.337834527309;6206.906896396467;6223.253209614875;6230.43926046658;6253.874922501615;6279.780207692709;6216.378362163929;6247.875042499216;6263.530235232284;6274.247090349188;6279.748810047547;6286.245650521967;6295.276043255084;6297.937020629735;6299;6336.873262534816;5918.408159184047;6018.097285407049;6070.271567411939;6072.969635151972;6214.067889816563;6166.660833654192;6170.6914654268;6188.938110618837;6256.061590761422;6284.120223168668;6089.604175728564;6118.847028824315;6123.479504242189;6124.203853499195;6133.785317513865 09:36:40 INFO - PROCESS | 1913 | |1;ext.html;447.1995128719592;453.322283186636;495.2472242191868;526.4866754913958;536.9624126311155;38386.61613383865;41186.38220426692;41338;41340.75977720666;41407.17185656285;31994.880179193733;37854;41655;41687.16625667485;41756;8422.368322375816;8636.17956294151;9145.222656074242;9391.81216375672;9421.15209631133;11122.332660040383;11463.36951467669;11491.885081149165;11605.767884642302;11608.59369922053;11258.268212566198;11264.549418023269;11308.660740177807;11325.603603873866;11331.546738130506;11254.437278136102;11292;11314;11320.207585469061;11321.094312454983;9459.52702364886;11303.208775385727;11313.208075434723;11320.377379244139;11331.320120792696;41215;41303.38044929328;41358.759237222934;41614.375784363256;41648.95877603054;3995.820188091543;4088.9795551022353;4090;4143.316352801784;4249.213895429355;30016.24959376033;31070;31090.68909310923;31434.21414464634;31726.682733172605;3225.548423220751;3245.2211469247445;3323.3353329333986;3329.1011426914597;3345.631980482145;329515;332598;332805;332874.33562832273;333320;64188.679056604415;64482;64566.677166614805;64580.35419645837;64775.67612161909;53870.46129538654;67197.66401167963;67756.98364524536;68108;68472;13681.657958550917;14621.78067328991;16495.587610309598;17096.82903170977;17104.14479276031;8895.421797583136;11743.354115523558;12020.398980050963;13042.282674452807;13067.281299528617;17801.842880212746;17827.197776100107;17859.73210401845;17889.12164222738;17996.820031799514;7593.544387336778;7657.310842024244;7659.4255430841795;7664.118626357928;7689.269519395649;879.3009557401881;886.2201262888617;888.440282621944;904.8099899021148;925.7084018534275;30419.543706844415;30668.07995760131;30756.384872302875;30767;30841.69158308433;975.5512464266517;1045.2265323660436;1085.6363118355282;1131.3833960491552;1223.9204451710789;15443;15823.367065317258;15841.762373564407;15958.521244362688;16045.59886002836;646514;648383;649351;649516;650161;47500;61339;61451.6927415369;61499.38500615026;61677.69161154165;1343.1068339554283;1375;1377.252381498916;1378.1386633354155;1409.6757745718542;28988.565171522023;29533.85233073821;30002.549961750155;30143.698563014088;30422.239444014078;44548.331775024046;44768.55231447644;45090.77454612706;45142.09715805731;45197;501284;566540;570151;570398.1480092573;573443;23811.285661430185;24454.021839126235;24506.63240051365;24551.38621534476;41220.587794121675;43160.35259471173;43363.78318108389;43715;44032.779836101894;44147.558524414344;35766.821165894005;36205.81897090498;36206.27587448289;36365.45451818278;36385.818070910536;35460.113497162776;35567.46648800218;35578.644213557534;35616.46575301321;35779;34524.82737586396;35173;35198.648013520564;35211;35597;29700;30184.09447716572;31753.04740857778;32144.874929377358;32311.35377292394;22739.63562186274;24123.87938060369;24395.51208975846;24558.63162052535;24562.263132106065 09:36:40 INFO - PROCESS | 1913 | |2;jquery.html;355.3160166679142;362.9473726309685;391.6377350950372;397.2650596396667;404.98987525311844;15826;15841.683166336667;20361.490962725922;21465;21721.348359549218;17479.650406991852;20930;21274.936253187356;21364.679529807065;21900.45248868779;4922.630802689795;5581.469760372758;5757.913631295513;5801.419858014207;5808.622439541437;5710.600257981943;5805.216295800055;5808.448197421239;5808.535317174637;5809.6804675742815;5837;6074.453299203071;6111.052786818051;6138.693065346737;6150.7232174552255;6076.726547305382;6094.1772860663905;6118.694065296761;6252.781152659681;6310.368963103653;4672.462666793328;5083.74581270935;5980;6011.969940150315;6045.576809623328;13289.3355332233;19597.706034409497;23961.520769584684;25252;25285.620715689278;2654.32314759737;2691.5693489041855;2714.877830497632;2725.1552018874213;2753.0501976817964;10102.343347682377;10490.580376784996;10519.053285204369;13456.798148027787;13882.681145291283;1851.463075708043;1919.8560107991816;1919.89440580768;1920.0207893974034;1922.5193701574606;81257;118557.81442185554;131498;136972.26055478933;139748;10668.89331106679;11238.10095192399;12022.819657705142;12032.699182520508;12288;15614.687706245812;15617.297221624827;15639.765403518957;15651.060936343856;15668.92165539165;8647.394682372174;8833.69082082124;8869.201771840566;8930.106989301017;8942;7372.852542949218;7594.278543538355;8073.071596766447;8280.50316980983;8754.47473151613;4716.905661886812;4794.707644017539;4836.733979631154;4876.463589005207;4942.20924652054;2033.5526184239739;2107.7154584130944;2120.5546835164787;2121.6711409731515;2122.80894719476;788.2078511096312;824.4023083264705;829.8547754143121;836.1346006882846;840.8948881389827;20660.690089648666;21460.141594336365;21562.275017998523;21676.783232167476;21728.913554322222;803.1606970715616;837.7696133563319;849.4223927729204;873.5195642396657;879.6305551668314;10677.092447141924;11417.257878237888;11445.71385715347;11465.910738479832;11507.424628768527;630181;704257.4361384583;732397;732740;741932;155593;157007.21496392446;160651.19674401553;161762;163471;957.1385752822376;966.8114717630135;996.4021587047858;996.5151923633879;998.4408731110601;18705.81294187095;19030.143643535797;19283.035848207815;19287.324943626907;19335.903320483307;53508.929821404134;58872.35074421576;58907.70546147242;59996.10005850003;60115.39884601273;85407.30259835768;86966.56516717376;87015.56492217498;87190.69213961669;89079.55460222658;8081.111077781557;8150.5924703763385;8223.465474744122;8494.490330579938;8541.95729021351;8824.779380515538;9122.680706175252;9172.082791720775;9237.845269341333;9243.445393276425;7793.571353575608;7856.37149028065;7885.921140788519;8247.505149691038;8388.161183881562;7740.535567865946;7817.491863028885;7910.7231246906085;8020.639071241691;8191.590420479071;7893.763187104396;8001.879971800544;8121.715739949073;8354.498730076215;8481.151884811716;12665.683357916127;13240;13241.867581324064;13275.137116087424;13573.660658483617;11807.763844722886;11824;12079.57721479744;12140.15018948665;12200.633980980585 09:36:40 INFO - PROCESS | 1913 | |3;mootools.html;1243.6455610151106;1428.9356978935946;1443.747344214762;1456.5193486149565;1459.2557795524278;7584.938108664777;7811.2579304966075;8144.307733842623;8595.785105372377;8613.138686131384;8033.879491807627;8296.460730052542;8316.750497485085;8404.62179201936;8422.620982055807;2282.1784157703214;2325.1745630301252;2330.650402439631;2334.9766502335015;2338.2985104468676;2071.585682863426;2154.719886414764;2292.64464008079;2341.906323747048;2344.038944032947;2242.3273018094587;2304.44693273614;2313.6298192289237;2315.0392587076344;2322.593546129429;2292.083166733312;2298.230092918874;2314.9421264468356;2317.0963324303534;2325.3256555598855;2300.367398965281;2301.160076572039;2335.0309621507054;2348.43637526994;2360.280114565057;8446.366522510836;8508.404411691185;8513.914860851373;8527.445716028438;8550.230479256834;5149.0474262261605;5292.629515933886;5294.205869119624;5299.867503312409;5320.76056577455;3401.6598340166033;3580.606133325332;3611.602723700391;3638.7089032877307;3653.780773153619;1043.2957753516423;1126.7859106769688;1126.881677423875;1202.8857258560422;1269.156011252516;9659.46872921989;10201.38791672502;10864.78270434587;10930.01629853317;10948.124150067975;3981;4099.487564054493;4465.218586747306;4715.151272770866;4758.119747846659;9515.905670847802;9535.713928582154;9570.042995700374;9594.42433453995;9602.519874006412;4482.7758612069265;5289.497497737709;5303.7348132593215;5311.575073994071;5352.678839269656;4607.378003969421;4724.102420540086;4761.952370478489;4790.161721698689;4819.566239038433;3886.7084968627464;3891.610838916086;3952.209558088394;3967.7222594418113;4014.4781178446615;1161.8780028096928;1179.941002949849;1204.1450570095155;1298.1561984709824;1300.765862144823;759.5784339691414;782.3545574900729;900.9819803604022;911.6171208092617;916.3768637326682;5554.1391084381985;5564.527015203672;5799.3620701722775;5836.066229403275;5849.385814489502;775.3835700618022;973.9707808765748;986.3982970388057;995.721198064543;1024.4621573673733;3412.897613071612;3662.487251784752;3694.5935947045805;3716.7026637868908;3818.694504439638;9780.315377923476;10005.649802256887;10072.244581656258;10123.898761012442;10154.187664986783;8200.220979006985;8314.334853211849;9841.311108222355;9984.951580084133;10035.046670566284;6795.694193761293;7460.925390746132;7692.499987500794;7797.142314345418;7803.68785248595;4792.688475249098;5566.638168519034;5636.774529018958;5742.19609254713;5808.564357673107;7220.422366210795;7281.5631062136445;7595.620218989139;9257.953710231406;9318.72043838686;9111.817763644558;9139.680111196076;9398.906010939803;9496.952515237379;9505.28710346713;6106.419890110433;7059.435245180476;7261.346478816932;7283.016792733125;7308.634568271672;7778.88331675014;7829.686812527435;7887.84224315522;7896.210378962288;7963;5801.680907550125;6974.68613912365;7121.216666166821;7123.715051398093;7136.179339376038;6883.965580172067;6971.6862741175755;7021.087258656342;7151;7202.3877970373105;6207.379262073757;6238.532110091852;6411.102445657519;6414.069959855888;6427.935720642734;4782.043591281688;4873;4914.582260507754;5012.799488020439;5096.592272618256;3871.8644847430205;4937.8271760488215;4959.950400495949;5014.749262536786;5159.974200128975 09:36:40 INFO - PROCESS | 1913 | |4;prototype.html;337.5122947341092;354.7658545360063;355.1937102776697;358.6711234874789;372.2034845430779;4170.812313445895;4937.679050861691;5123.846284611458;5303.575713942875;5511.476409741073;3943.4676318696966;5200.635955483118;5378;5407.188921661753;5407.567394608432;3734.3464893643504;3885.630865067814;4097.344424892018;4285.4214681017975;4304.24675681756;3169.51033014484;3435.4368762213135;3867.323218436762;3880.8939452256113;4239.894002649928;3520.802927004818;3903.687704983609;4162;4217.810198541058;4225.281702110633;3398.320335932823;4173.914782156661;4195.964956995855;4206.747595144301;4207.454490033531;3037.9088627341216;3062.739667128297;3549.538559987185;3769.378052621314;3831.5210598675167;4571.748553829538;4973.452920178777;5205.973970130125;5240.004399164147;5257.663471576353;3924.5682974872743;4166.541680415152;4674.719516829001;4871.585915197177;4885.682430642032;4762.357081793948;5329.626926115174;5728.283964504437;5778.566607504412;5790.305163380422;3717.5353080864893;3803.7637767725323;4127.607877251657;4141.7100802943805;4145.958540414587;5607.803551975376;5712.1431785232135;6023.193791016168;6047.606905551125;6053.243344581927;4100.6877799104595;4241.936370954438;4255.212785634668;4255.957440425618;4265.829366825292;3706.09200745819;3784.5647750509042;3985.4619626350072;4281.486221653359;4328.11273688893;4870.9269360959615;4902.779374928065;4908.312836202935;4916.459189489154;4991.950080499221;5757.021306377842;5792.507636850831;5809.738561764731;5819.447152520504;5854;1149.8965093141658;1212.3635091576816;1398.6083896508994;1406.5006922542555;1427.6437384484782;976.7460460280379;989.302541708094;998.5157198758596;1005.949702514886;1007.3351587951917;4293.763842988603;4854.203944540472;5039.017391608601;5048.974755126201;5082.695038297714;5332.000999250562;5400.515762749179;5643.35101463337;5677.574181936371;5686.2039314496005;4206.57934206583;4376.354562265124;4699.859004229878;4973.850784476472;5073.061483625541;2708.6976070340447;2835.3195233143897;2958.896438624638;2964.644242690848;2978.5681076243823;4620.53794620542;4829.8024398898415;5766;5809;5838.941610583925;5346.518813306821;5421.566274698014;5646.265985421951;6039.094135879654;6059.273107164437;5096.643234973516;5811.27359080115;6065.5754097212775;6091.451769340781;6105.419985101586;5597.069011191133;5718.94118117469;5726.198332233407;5942.553319075013;5990.7304171311525;4679.766011699469;4869.293952376815;5846.707664616837;5862.765489380377;5873.882522349443;5153.634286914045;5194.74026298676;5381.973090134525;6055.1522786810765;6111.419415155553;3833.4824798651825;3902.765834049966;3953.6639385652543;3959.742616729903;3991.840326386912;4914.950850491449;5282.735863206747;5935.605132793877;5944.840756052528;5970.731317090828;5338.798770276812;5416.264786282902;5598.776048957996;5760.127958413616;5768.827145070279;4831.855044348676;5296.894062118813;5519.0959119103845;5785.971070144622;5791.333996590446;4706.0823139487475;5088.592912566912;5403.973509933775;5562.638428502134;5626.493615574618;5250.422453530032;5421.3765416975975;5739.938111449395;5748.109043098327;5772.497451987457;5158.948410515847;5642.971785141048;5651.463111004448;5669.6314739541795;5688 09:36:40 INFO - PROCESS | 1913 | |5;yui.html;678.8778019956409;720.8990741296222;773.0182668011626;809.2554849538424;816.738643634037;10975.670729878106;13974.441022359106;14150;14198.858011419883;14227.004109712325;14305.856941430582;14464;14481.855181448209;14496.565103046925;14555.490557830479;679.5650783498564;696.4846013949666;710.5239489542006;846.8094678697274;872.4634349874844;879.810840669256;879.9956000219989;880.5773228850156;881.1408876345575;882.686646240585;924.6393906376485;925.2089463508704;926.2775035472342;928.3408779766374;930.144267274106;905.4069584422214;910.9817803643956;918.3892711346946;920.9769755756092;922.4327038871113;674.9325067493261;679.6160169504215;682.0655701688669;682.5972676121063;684.4524380495584;14625.0493717908;14981.850181498154;14992.400303987828;15636.843631563766;15891.682166356608;14265.572032838927;14501.202433866134;14649.022381978402;14661.900357473232;14785.852141478555;11422.657320280405;11517.25137866036;11668.94165529167;11961.880381196164;12077.758444831054;3356.5972083350152;3709.684676802475;3793.9241215175543;3805.219929914361;3892.5134358205223;68807.9999999995;104844;107293.46353268262;108155.2961175969;111196.88803112027;8572.228499435081;9425.245980321557;9524.714258572254;10374.118199952938;10389.84415233772;19338.806611933982;20457.874816885214;21880.78119218819;22981.425464363525;23432.297031089096;7587.393008559302;7590.278923502258;7616.885746713804;7688.769336919901;7783.494072885244;4010.6189911958318;4063.674906007512;4068.7762173080173;4084.118868828877;4102.364133559303;3591.6049234584175;3675.5956844747056;3676.613955534684;3695.519582454264;3722.4230244312175;627.237462729731;660.6564586415134;665.2645396100859;667.0594461808827;673;659.2945548263313;672.3041651890368;675.1391975231521;691.8962155676688;708.7802781137864;7998.760037198894;9124.49815260167;9556.900956389965;10267.579649824263;10627.309224900357;703.6200451756097;715.1882613234017;716.9103862017248;722.4473277942378;725.3616817200935;4536.432945881765;4893.4617192108835;4901.926471102936;4901.995091006406;4902.607791376681;78013;92610.53694731483;98990.5050474743;99550.10347620088;101388;86544;88117.67823482653;89253.10746892578;90712.54643726871;91101.54449227711;78922.6053869727;79247.20752792398;80572;80866.59566702247;81178.59410702909;7974.960125199337;9399.34204605671;9488.87234053189;9589.472579008221;9591.232701384011;39790.801045995744;52831;53009;53746;54036.91926161533;68133.65933170302;68362.97455538072;69204.30795691979;71056.64471677608;71338.92991605234;10826.296290741077;11287.871212878646;11557.901999310052;11905.642830715093;12020.699482513008;11417.200795944203;13168.736625267633;13171.47314107425;13186.934065329995;13801.102928309629;11968.820467693165;12005.579804706793;12239.082068844695;12282.447289872156;12302.200356976768;12008.759824803281;12075.818862716891;12150.57472988441;12189.268643881396;12249.87750122523;11326.716832079264;11614.825777613174;12071.336076515876;12487.937560312505;13484.460621575026;9503;11138.443077846237;11591;11727.706807329543;11755.588554400554;11092.833607495733;11284.887151128383;11560.595379161688;11671.941640291745;12033.278003319829 09:36:40 INFO - PROCESS | 1913 | __end_tp_report 09:36:40 INFO - PROCESS | 1913 | __start_cc_report 09:36:40 INFO - PROCESS | 1913 | _x_x_mozilla_cycle_collect,381 09:36:40 INFO - PROCESS | 1913 | __end_cc_report 09:36:40 INFO - PROCESS | 1913 | __startTimestamp1485884200159__endTimestamp 09:36:40 INFO - PROCESS | 1913 | 09:36:40 INFO - PROCESS | 1913 | ------- Summary: start ------- 09:36:40 INFO - PROCESS | 1913 | Number of tests: 6 09:36:40 INFO - PROCESS | 1913 | 09:36:40 INFO - PROCESS | 1913 | [#0] dojo.html Cycles:165 Average:5864.43 Median:6118.85 stddev:636.37 (10.4%) stddev-sans-first:607.79 09:36:40 INFO - PROCESS | 1913 | Values: 3381.8 3400.3 3425.1 3427.7 3428.1 5859.4 5950.2 5958.3 5986.3 5991.9 5900.4 5932.4 5966.9 5980.9 5992.5 5301.6 5358.1 5371.5 5393.6 5425.2 5425.4 5431.5 5432.1 5441.4 5459.6 5240.6 5274.6 5368.9 5418.4 5464.4 4391.6 5321.1 5382.1 5422.9 5473.3 5263.7 5360.9 5383.4 5396.9 5407.6 5708.8 5940.3 5943.2 6013.3 6070.7 6185.9 6193.5 6223.0 6244.5 6338.8 6256.0 6260.9 6265.1 6267.7 6279.3 5896.4 5940.0 5949.8 5957.0 5971.0 6189.6 6213.9 6279.3 6296.7 6305.9 6248.6 6261.4 6285.4 6293.4 6297.7 6277.0 6282.3 6293.4 6296.5 6316.1 6184.4 6196.8 6236.6 6255.2 6271.0 6232.3 6254.6 6254.8 6255.4 6270.0 5052.2 5052.4 5057.8 5071.4 5074.7 4408.2 4417.5 4476.4 4511.3 4534.1 5940.8 5960.4 5979.7 5999.6 6051.9 5668.0 5984.9 6166.5 6314.3 6315.8 5845.3 5908.5 5958.2 5967.2 5972.4 5601.5 5607.8 5608.2 5615.7 5630.8 6451.8 6492.0 6496.5 6497.3 6505.0 6154.4 6181.6 6202.2 6271.4 6345.2 6419.8 6449.1 6451.4 6466.2 6478.6 6258.9 6278.1 6283.3 6300.3 6306.3 6206.9 6223.3 6230.4 6253.9 6279.8 6216.4 6247.9 6263.5 6274.2 6279.7 6286.2 6295.3 6297.9 6299.0 6336.9 5918.4 6018.1 6070.3 6073.0 6214.1 6166.7 6170.7 6188.9 6256.1 6284.1 6089.6 6118.8 6123.5 6124.2 6133.8 09:36:40 INFO - PROCESS | 1913 | 09:36:40 INFO - PROCESS | 1913 | [#1] ext.html Cycles:180 Average:65309.10 Median:35335.56 stddev:141758.28 (401.2%) stddev-sans-first:142072.30 09:36:40 INFO - PROCESS | 1913 | Values: 447.2 453.3 495.2 526.5 537.0 38386.6 41186.4 41338.0 41340.8 41407.2 31994.9 37854.0 41655.0 41687.2 41756.0 8422.4 8636.2 9145.2 9391.8 9421.2 11122.3 11463.4 11491.9 11605.8 11608.6 11258.3 11264.5 11308.7 11325.6 11331.5 11254.4 11292.0 11314.0 11320.2 11321.1 9459.5 11303.2 11313.2 11320.4 11331.3 41215.0 41303.4 41358.8 41614.4 41649.0 3995.8 4089.0 4090.0 4143.3 4249.2 30016.2 31070.0 31090.7 31434.2 31726.7 3225.5 3245.2 3323.3 3329.1 3345.6 329515.0 332598.0 332805.0 332874.3 333320.0 64188.7 64482.0 64566.7 64580.4 64775.7 53870.5 67197.7 67757.0 68108.0 68472.0 13681.7 14621.8 16495.6 17096.8 17104.1 8895.4 11743.4 12020.4 13042.3 13067.3 17801.8 17827.2 17859.7 17889.1 17996.8 7593.5 7657.3 7659.4 7664.1 7689.3 879.3 886.2 888.4 904.8 925.7 30419.5 30668.1 30756.4 30767.0 30841.7 975.6 1045.2 1085.6 1131.4 1223.9 15443.0 15823.4 15841.8 15958.5 16045.6 646514.0 648383.0 649351.0 649516.0 650161.0 47500.0 61339.0 61451.7 61499.4 61677.7 1343.1 1375.0 1377.3 1378.1 1409.7 28988.6 29533.9 30002.5 30143.7 30422.2 44548.3 44768.6 45090.8 45142.1 45197.0 501284.0 566540.0 570151.0 570398.1 573443.0 23811.3 24454.0 24506.6 24551.4 41220.6 43160.4 43363.8 43715.0 44032.8 44147.6 35766.8 36205.8 36206.3 36365.5 36385.8 35460.1 35567.5 35578.6 35616.5 35779.0 34524.8 35173.0 35198.6 35211.0 35597.0 29700.0 30184.1 31753.0 32144.9 32311.4 22739.6 24123.9 24395.5 24558.6 24562.3 09:36:40 INFO - PROCESS | 1913 | 09:36:40 INFO - PROCESS | 1913 | [#2] jquery.html Cycles:180 Average:39286.82 Median:5012.98 stddev:118573.84 (2365.3%) stddev-sans-first:118870.43 09:36:40 INFO - PROCESS | 1913 | Values: 355.3 362.9 391.6 397.3 405.0 15826.0 15841.7 20361.5 21465.0 21721.3 17479.7 20930.0 21274.9 21364.7 21900.5 4922.6 5581.5 5757.9 5801.4 5808.6 5710.6 5805.2 5808.4 5808.5 5809.7 5837.0 6074.5 6111.1 6138.7 6150.7 6076.7 6094.2 6118.7 6252.8 6310.4 4672.5 5083.7 5980.0 6012.0 6045.6 13289.3 19597.7 23961.5 25252.0 25285.6 2654.3 2691.6 2714.9 2725.2 2753.1 10102.3 10490.6 10519.1 13456.8 13882.7 1851.5 1919.9 1919.9 1920.0 1922.5 81257.0 118557.8 131498.0 136972.3 139748.0 10668.9 11238.1 12022.8 12032.7 12288.0 15614.7 15617.3 15639.8 15651.1 15668.9 8647.4 8833.7 8869.2 8930.1 8942.0 7372.9 7594.3 8073.1 8280.5 8754.5 4716.9 4794.7 4836.7 4876.5 4942.2 2033.6 2107.7 2120.6 2121.7 2122.8 788.2 824.4 829.9 836.1 840.9 20660.7 21460.1 21562.3 21676.8 21728.9 803.2 837.8 849.4 873.5 879.6 10677.1 11417.3 11445.7 11465.9 11507.4 630181.0 704257.4 732397.0 732740.0 741932.0 155593.0 157007.2 160651.2 161762.0 163471.0 957.1 966.8 996.4 996.5 998.4 18705.8 19030.1 19283.0 19287.3 19335.9 53508.9 58872.4 58907.7 59996.1 60115.4 85407.3 86966.6 87015.6 87190.7 89079.6 8081.1 8150.6 8223.5 8494.5 8542.0 8824.8 9122.7 9172.1 9237.8 9243.4 7793.6 7856.4 7885.9 8247.5 8388.2 7740.5 7817.5 7910.7 8020.6 8191.6 7893.8 8001.9 8121.7 8354.5 8481.2 12665.7 13240.0 13241.9 13275.1 13573.7 11807.8 11824.0 12079.6 12140.2 12200.6 09:36:40 INFO - PROCESS | 1913 | 09:36:40 INFO - PROCESS | 1913 | [#3] mootools.html Cycles:180 Average:5366.78 Median:5297.04 stddev:2900.77 (54.8%) stddev-sans-first:2892.36 09:36:40 INFO - PROCESS | 1913 | Values: 1243.6 1428.9 1443.7 1456.5 1459.3 7584.9 7811.3 8144.3 8595.8 8613.1 8033.9 8296.5 8316.8 8404.6 8422.6 2282.2 2325.2 2330.7 2335.0 2338.3 2071.6 2154.7 2292.6 2341.9 2344.0 2242.3 2304.4 2313.6 2315.0 2322.6 2292.1 2298.2 2314.9 2317.1 2325.3 2300.4 2301.2 2335.0 2348.4 2360.3 8446.4 8508.4 8513.9 8527.4 8550.2 5149.0 5292.6 5294.2 5299.9 5320.8 3401.7 3580.6 3611.6 3638.7 3653.8 1043.3 1126.8 1126.9 1202.9 1269.2 9659.5 10201.4 10864.8 10930.0 10948.1 3981.0 4099.5 4465.2 4715.2 4758.1 9515.9 9535.7 9570.0 9594.4 9602.5 4482.8 5289.5 5303.7 5311.6 5352.7 4607.4 4724.1 4762.0 4790.2 4819.6 3886.7 3891.6 3952.2 3967.7 4014.5 1161.9 1179.9 1204.1 1298.2 1300.8 759.6 782.4 901.0 911.6 916.4 5554.1 5564.5 5799.4 5836.1 5849.4 775.4 974.0 986.4 995.7 1024.5 3412.9 3662.5 3694.6 3716.7 3818.7 9780.3 10005.6 10072.2 10123.9 10154.2 8200.2 8314.3 9841.3 9985.0 10035.0 6795.7 7460.9 7692.5 7797.1 7803.7 4792.7 5566.6 5636.8 5742.2 5808.6 7220.4 7281.6 7595.6 9258.0 9318.7 9111.8 9139.7 9398.9 9497.0 9505.3 6106.4 7059.4 7261.3 7283.0 7308.6 7778.9 7829.7 7887.8 7896.2 7963.0 5801.7 6974.7 7121.2 7123.7 7136.2 6884.0 6971.7 7021.1 7151.0 7202.4 6207.4 6238.5 6411.1 6414.1 6427.9 4782.0 4873.0 4914.6 5012.8 5096.6 3871.9 4937.8 4960.0 5014.7 5160.0 09:36:40 INFO - PROCESS | 1913 | 09:36:40 INFO - PROCESS | 1913 | [#4] prototype.html Cycles:180 Average:4571.02 Median:5015.48 stddev:1392.12 (27.8%) stddev-sans-first:1359.28 09:36:40 INFO - PROCESS | 1913 | Values: 337.5 354.8 355.2 358.7 372.2 4170.8 4937.7 5123.8 5303.6 5511.5 3943.5 5200.6 5378.0 5407.2 5407.6 3734.3 3885.6 4097.3 4285.4 4304.2 3169.5 3435.4 3867.3 3880.9 4239.9 3520.8 3903.7 4162.0 4217.8 4225.3 3398.3 4173.9 4196.0 4206.7 4207.5 3037.9 3062.7 3549.5 3769.4 3831.5 4571.7 4973.5 5206.0 5240.0 5257.7 3924.6 4166.5 4674.7 4871.6 4885.7 4762.4 5329.6 5728.3 5778.6 5790.3 3717.5 3803.8 4127.6 4141.7 4146.0 5607.8 5712.1 6023.2 6047.6 6053.2 4100.7 4241.9 4255.2 4256.0 4265.8 3706.1 3784.6 3985.5 4281.5 4328.1 4870.9 4902.8 4908.3 4916.5 4992.0 5757.0 5792.5 5809.7 5819.4 5854.0 1149.9 1212.4 1398.6 1406.5 1427.6 976.7 989.3 998.5 1005.9 1007.3 4293.8 4854.2 5039.0 5049.0 5082.7 5332.0 5400.5 5643.4 5677.6 5686.2 4206.6 4376.4 4699.9 4973.9 5073.1 2708.7 2835.3 2958.9 2964.6 2978.6 4620.5 4829.8 5766.0 5809.0 5838.9 5346.5 5421.6 5646.3 6039.1 6059.3 5096.6 5811.3 6065.6 6091.5 6105.4 5597.1 5718.9 5726.2 5942.6 5990.7 4679.8 4869.3 5846.7 5862.8 5873.9 5153.6 5194.7 5382.0 6055.2 6111.4 3833.5 3902.8 3953.7 3959.7 3991.8 4915.0 5282.7 5935.6 5944.8 5970.7 5338.8 5416.3 5598.8 5760.1 5768.8 4831.9 5296.9 5519.1 5786.0 5791.3 4706.1 5088.6 5404.0 5562.6 5626.5 5250.4 5421.4 5739.9 5748.1 5772.5 5158.9 5643.0 5651.5 5669.6 5688.0 09:36:40 INFO - PROCESS | 1913 | 09:36:40 INFO - PROCESS | 1913 | [#5] yui.html Cycles:180 Average:19939.25 Median:4897.69 stddev:28730.58 (586.6%) stddev-sans-first:28774.78 09:36:40 INFO - PROCESS | 1913 | Values: 678.9 720.9 773.0 809.3 816.7 10975.7 13974.4 14150.0 14198.9 14227.0 14305.9 14464.0 14481.9 14496.6 14555.5 679.6 696.5 710.5 846.8 872.5 879.8 880.0 880.6 881.1 882.7 924.6 925.2 926.3 928.3 930.1 905.4 911.0 918.4 921.0 922.4 674.9 679.6 682.1 682.6 684.5 14625.0 14981.9 14992.4 15636.8 15891.7 14265.6 14501.2 14649.0 14661.9 14785.9 11422.7 11517.3 11668.9 11961.9 12077.8 3356.6 3709.7 3793.9 3805.2 3892.5 68808.0 104844.0 107293.5 108155.3 111196.9 8572.2 9425.2 9524.7 10374.1 10389.8 19338.8 20457.9 21880.8 22981.4 23432.3 7587.4 7590.3 7616.9 7688.8 7783.5 4010.6 4063.7 4068.8 4084.1 4102.4 3591.6 3675.6 3676.6 3695.5 3722.4 627.2 660.7 665.3 667.1 673.0 659.3 672.3 675.1 691.9 708.8 7998.8 9124.5 9556.9 10267.6 10627.3 703.6 715.2 716.9 722.4 725.4 4536.4 4893.5 4901.9 4902.0 4902.6 78013.0 92610.5 98990.5 99550.1 101388.0 86544.0 88117.7 89253.1 90712.5 91101.5 78922.6 79247.2 80572.0 80866.6 81178.6 7975.0 9399.3 9488.9 9589.5 9591.2 39790.8 52831.0 53009.0 53746.0 54036.9 68133.7 68363.0 69204.3 71056.6 71338.9 10826.3 11287.9 11557.9 11905.6 12020.7 11417.2 13168.7 13171.5 13186.9 13801.1 11968.8 12005.6 12239.1 12282.4 12302.2 12008.8 12075.8 12150.6 12189.3 12249.9 11326.7 11614.8 12071.3 12487.9 13484.5 9503.0 11138.4 11591.0 11727.7 11755.6 11092.8 11284.9 11560.6 11671.9 12033.3 09:36:40 INFO - PROCESS | 1913 | -------- Summary: end -------- 09:36:40 INFO - PROCESS | 1913 | 09:36:40 INFO - TEST-INFO | 1913: exit 0 09:36:40 INFO - TEST-OK | dromaeo_css | took 1114897ms 09:36:40 INFO - TEST-START | kraken 09:36:40 INFO - operating with platform_type : mac_ 09:36:40 INFO - Initialising browser for kraken test... 09:36:40 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpsMAyeE/profile http://localhost:49376/getInfo.html) 09:36:43 INFO - PROCESS | 1922 | 2017-01-31 09:36:43.586 plugin-container[1923:12075] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 09:36:43 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:43 INFO - PROCESS | 1922 | 2017-01-31 09:36:43.589 plugin-container[1923:12075] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 09:36:43 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:43 INFO - PROCESS | 1922 | 2017-01-31 09:36:43.589 plugin-container[1923:12075] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:36:43 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 09:36:43 INFO - PROCESS | 1922 | colorDepth:24 09:36:43 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 09:36:43 INFO - PROCESS | 1922 | __metrics 09:36:43 ERROR - PROCESS | 1922 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 09:36:44 INFO - TEST-INFO | 1922: exit 0 09:36:44 INFO - Browser initialized. 09:36:44 INFO - Running cycle 1/1 for kraken test... 09:36:44 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpsMAyeE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:46 INFO - PROCESS | 1924 | 2017-01-31 09:36:46.907 plugin-container[1925:12204] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 09:36:46 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:46 INFO - PROCESS | 1924 | 2017-01-31 09:36:46.907 plugin-container[1925:12204] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 09:36:46 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:46 INFO - PROCESS | 1924 | 2017-01-31 09:36:46.907 plugin-container[1925:12204] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:36:56 INFO - PROCESS | 1924 | RSS: Main: 218652672 09:36:56 INFO - PROCESS | 1924 | 09:36:59 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49376/tests/kraken/driver.html?testName=audio-beat-detection) 09:37:00 INFO - PROCESS | 1924 | RSS: Main: 232636416 09:37:00 INFO - PROCESS | 1924 | 09:37:03 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49376/tests/kraken/driver.html?testName=audio-dft) 09:37:04 INFO - PROCESS | 1924 | RSS: Main: 240644096 09:37:04 INFO - PROCESS | 1924 | 09:37:07 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49376/tests/kraken/driver.html?testName=audio-fft) 09:37:07 INFO - PROCESS | 1924 | RSS: Main: 244412416 09:37:07 INFO - PROCESS | 1924 | 09:37:09 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49376/tests/kraken/driver.html?testName=audio-oscillator) 09:37:10 INFO - PROCESS | 1924 | RSS: Main: 245723136 09:37:10 INFO - PROCESS | 1924 | 09:37:12 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49376/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:37:13 INFO - PROCESS | 1924 | RSS: Main: 246079488 09:37:13 INFO - PROCESS | 1924 | 09:37:16 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49376/tests/kraken/driver.html?testName=imaging-darkroom) 09:37:16 INFO - PROCESS | 1924 | RSS: Main: 249794560 09:37:16 INFO - PROCESS | 1924 | 09:37:19 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49376/tests/kraken/driver.html?testName=imaging-desaturate) 09:37:20 INFO - PROCESS | 1924 | RSS: Main: 248868864 09:37:20 INFO - PROCESS | 1924 | 09:37:23 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49376/tests/kraken/driver.html?testName=json-parse-financial) 09:37:24 INFO - PROCESS | 1924 | RSS: Main: 249077760 09:37:24 INFO - PROCESS | 1924 | 09:37:25 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49376/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:37:26 INFO - PROCESS | 1924 | RSS: Main: 248541184 09:37:26 INFO - PROCESS | 1924 | 09:37:28 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49376/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:37:28 INFO - PROCESS | 1924 | RSS: Main: 248578048 09:37:28 INFO - PROCESS | 1924 | 09:37:31 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49376/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:37:31 INFO - PROCESS | 1924 | RSS: Main: 248537088 09:37:31 INFO - PROCESS | 1924 | 09:37:34 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49376/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:37:35 INFO - PROCESS | 1924 | RSS: Main: 248926208 09:37:35 INFO - PROCESS | 1924 | 09:37:38 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49376/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:37:39 INFO - PROCESS | 1924 | RSS: Main: 248991744 09:37:39 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49376/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49376/tests/kraken/driver.html?testName=ai-astar) 09:37:40 INFO - PROCESS | 1924 | RSS: Main: 250748928 09:37:40 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | __start_tp_report 09:37:40 INFO - PROCESS | 1924 | _x_x_mozilla_page_load 09:37:40 INFO - PROCESS | 1924 | _x_x_mozilla_page_load_details 09:37:40 INFO - PROCESS | 1924 | |i|pagename|runs| 09:37:40 INFO - PROCESS | 1924 | |0;ai-astar;105;133;103;131;177;174;126;140;174;119 09:37:40 INFO - PROCESS | 1924 | |1;audio-beat-detection;138;115;111;158;147;121;116;113;163;146 09:37:40 INFO - PROCESS | 1924 | |2;audio-dft;196;200;197;198;189;194;193;194;197;200 09:37:40 INFO - PROCESS | 1924 | |3;audio-fft;81;58;59;88;65;51;74;84;65;74 09:37:40 INFO - PROCESS | 1924 | |4;audio-oscillator;83;59;84;99;100;58;102;103;104;115 09:37:40 INFO - PROCESS | 1924 | |5;imaging-gaussian-blur;91;87;89;87;100;87;128;87;89;96 09:37:40 INFO - PROCESS | 1924 | |6;imaging-darkroom;98;96;96;99;98;97;157;95;98;130 09:37:40 INFO - PROCESS | 1924 | |7;imaging-desaturate;114;110;92;92;94;92;97;138;148;91 09:37:40 INFO - PROCESS | 1924 | |8;json-parse-financial;60;61;60;61;63;63;62;63;63;63 09:37:40 INFO - PROCESS | 1924 | |9;json-stringify-tinderbox;70;74;81;81;75;69;93;109;111;86 09:37:40 INFO - PROCESS | 1924 | |10;stanford-crypto-aes;87;84;86;85;85;83;85;92;83;84 09:37:40 INFO - PROCESS | 1924 | |11;stanford-crypto-ccm;139;140;138;176;172;158;159;143;182;153 09:37:40 INFO - PROCESS | 1924 | |12;stanford-crypto-pbkdf2;169;170;171;170;172;178;246;201;172;173 09:37:40 INFO - PROCESS | 1924 | |13;stanford-crypto-sha256-iterative;66;72;82;68;64;64;67;67;67;75 09:37:40 INFO - PROCESS | 1924 | __end_tp_report 09:37:40 INFO - PROCESS | 1924 | __start_cc_report 09:37:40 INFO - PROCESS | 1924 | _x_x_mozilla_cycle_collect,945 09:37:40 INFO - PROCESS | 1924 | __end_cc_report 09:37:40 INFO - PROCESS | 1924 | __startTimestamp1485884260984__endTimestamp 09:37:40 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | ------- Summary: start ------- 09:37:40 INFO - PROCESS | 1924 | Number of tests: 14 09:37:40 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | [#0] ai-astar Cycles:10 Average:138.20 Median:136.50 stddev:27.91 (20.4%) stddev-sans-first:26.89 09:37:40 INFO - PROCESS | 1924 | Values: 105.0 133.0 103.0 131.0 177.0 174.0 126.0 140.0 174.0 119.0 09:37:40 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:142.00 stddev:19.88 (14.0%) stddev-sans-first:20.99 09:37:40 INFO - PROCESS | 1924 | Values: 138.0 115.0 111.0 158.0 147.0 121.0 116.0 113.0 163.0 146.0 09:37:40 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | [#2] audio-dft Cycles:10 Average:195.80 Median:197.00 stddev:3.39 (1.7%) stddev-sans-first:3.60 09:37:40 INFO - PROCESS | 1924 | Values: 196.0 200.0 197.0 198.0 189.0 194.0 193.0 194.0 197.0 200.0 09:37:40 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | [#3] audio-fft Cycles:10 Average:69.90 Median:74.00 stddev:12.24 (16.5%) stddev-sans-first:12.31 09:37:40 INFO - PROCESS | 1924 | Values: 81.0 58.0 59.0 88.0 65.0 51.0 74.0 84.0 65.0 74.0 09:37:40 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:58.50 stddev:19.38 (33.1%) stddev-sans-first:20.35 09:37:40 INFO - PROCESS | 1924 | Values: 83.0 59.0 84.0 99.0 100.0 58.0 102.0 103.0 104.0 115.0 09:37:40 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | [#5] imaging-gaussian-blur Cycles:10 Average:94.10 Median:88.00 stddev:12.70 (14.4%) stddev-sans-first:13.42 09:37:40 INFO - PROCESS | 1924 | Values: 91.0 87.0 89.0 87.0 100.0 87.0 128.0 87.0 89.0 96.0 09:37:40 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | [#6] imaging-darkroom Cycles:10 Average:106.40 Median:97.50 stddev:20.60 (21.1%) stddev-sans-first:21.62 09:37:40 INFO - PROCESS | 1924 | Values: 98.0 96.0 96.0 99.0 98.0 97.0 157.0 95.0 98.0 130.0 09:37:40 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | [#7] imaging-desaturate Cycles:10 Average:106.80 Median:92.00 stddev:20.82 (22.6%) stddev-sans-first:21.91 09:37:40 INFO - PROCESS | 1924 | Values: 114.0 110.0 92.0 92.0 94.0 92.0 97.0 138.0 148.0 91.0 09:37:40 INFO - PROCESS | 1924 | 09:37:40 INFO - PROCESS | 1924 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:63.00 stddev:1.29 (2.0%) stddev-sans-first:1.17 09:37:40 INFO - PROCESS | 1924 | Values: 60.0 61.0 60.0 61.0 63.0 63.0 62.0 63.0 63.0 63.0 09:37:40 INFO - PROCESS | 1924 | 09:37:41 INFO - PROCESS | 1924 | [#9] json-stringify-tinderbox Cycles:10 Average:84.90 Median:78.00 stddev:15.10 (19.4%) stddev-sans-first:15.02 09:37:41 INFO - PROCESS | 1924 | Values: 70.0 74.0 81.0 81.0 75.0 69.0 93.0 109.0 111.0 86.0 09:37:41 INFO - PROCESS | 1924 | 09:37:41 INFO - PROCESS | 1924 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:85.00 stddev:2.63 (3.1%) stddev-sans-first:2.73 09:37:41 INFO - PROCESS | 1924 | Values: 87.0 84.0 86.0 85.0 85.0 83.0 85.0 92.0 83.0 84.0 09:37:41 INFO - PROCESS | 1924 | 09:37:41 INFO - PROCESS | 1924 | [#11] stanford-crypto-ccm Cycles:10 Average:156.00 Median:158.50 stddev:16.30 (10.3%) stddev-sans-first:16.09 09:37:41 INFO - PROCESS | 1924 | Values: 139.0 140.0 138.0 176.0 172.0 158.0 159.0 143.0 182.0 153.0 09:37:41 INFO - PROCESS | 1924 | 09:37:41 INFO - PROCESS | 1924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.20 Median:172.50 stddev:24.34 (14.1%) stddev-sans-first:25.34 09:37:41 INFO - PROCESS | 1924 | Values: 169.0 170.0 171.0 170.0 172.0 178.0 246.0 201.0 172.0 173.0 09:37:41 INFO - PROCESS | 1924 | 09:37:41 INFO - PROCESS | 1924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:67.50 stddev:5.63 (8.3%) stddev-sans-first:5.85 09:37:41 INFO - PROCESS | 1924 | Values: 66.0 72.0 82.0 68.0 64.0 64.0 67.0 67.0 67.0 75.0 09:37:41 INFO - PROCESS | 1924 | -------- Summary: end -------- 09:37:41 INFO - PROCESS | 1924 | 09:37:41 INFO - TEST-INFO | 1924: exit 0 09:37:41 INFO - TEST-OK | kraken | took 61064ms 09:37:41 INFO - SUITE-END | took 1175s 09:37:42 INFO - Completed test suite (00:19:36) 09:37:42 INFO - javascript benchmark 09:37:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7274.53755324108, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3381.814000229987, 3400.251944572193, 3425.0752296879837, 3427.708644765195, 3428.0915557377284, 5859.384764599719, 5950.166976623265, 5958.31479379872, 5986.2517185351835, 5991.850203744909, 5900.380460051686, 5932.37710040446, 5966.850828729284, 5980.940190598093, 5992.49063829575, 5301.58777756122, 5358.115910874701, 5371.462853714636, 5393.649412788156, 5425.159100339434, 5425.430329815372, 5431.456854314557, 5432.076546987002, 5441.401445840955, 5459.645123067008, 5240.554552862992, 5274.551663108621, 5368.865778355553, 5418.376886658046, 5464.4262352453015, 4391.604755572014, 5321.068812957737, 5382.0581398255545, 5422.91865622016, 5473.343198816127, 5263.710495922723, 5360.892782144374, 5383.353997520284, 5396.91904621431, 5407.594430417695, 5708.80019199326, 5940.2871655401195, 5943.197668314765, 6013.308469526017, 6070.696465176724, 6185.907211391833, 6193.5354848386105, 6222.968885155546, 6244.46922011625, 6338.778142765027, 6255.96872015637, 6260.937390626081, 6265.060240963846, 6267.749290028393, 6279.277883043417, 5896.380880007623, 5940.049592065248, 5949.762009519614, 5957.046872500379, 5971.044632858721, 6189.6286222826775, 6213.906791398133, 6279.309275979638, 6296.748130074838, 6305.873882522415, 6248.562600617913, 6261.436470717657, 6285.371462853769, 6293.433590976742, 6297.748090076346, 6277.027060805583, 6282.340354262828, 6293.402126797947, 6296.464800491918, 6316.115743795872, 6184.443400094013, 6196.845078873064, 6236.594621349598, 6255.186825712628, 6270.996640537492, 6232.3144454110015, 6254.6247225166635, 6254.843628909313, 6255.437010669061, 6270.0, 5052.191649336089, 5052.444231134572, 5057.822976195889, 5071.41678706946, 5074.746262686851, 4408.206522825923, 4417.491988421309, 4476.41806565151, 4511.300748384006, 4534.115847409724, 5940.821775346746, 5960.37416071315, 5979.6711180884595, 5999.610025348338, 6051.909221361683, 5668.008098582732, 5984.940150598525, 6166.4758495527485, 6314.336994615591, 6315.810525684236, 5845.327787304429, 5908.497777688859, 5958.165856780054, 5967.224260846149, 5972.40275972408, 5601.495865372136, 5607.775688972395, 5608.242887210256, 5615.663060216319, 5630.774769009194, 6451.838704032437, 6492.0, 6496.480281577368, 6497.317781632861, 6504.967475162689, 6154.368922335843, 6181.598196117238, 6202.224721909762, 6271.404216599416, 6345.206849143857, 6419.839504012437, 6449.064885591657, 6451.3548645135115, 6466.224053113657, 6478.611283323015, 6258.874822503433, 6278.089676996901, 6283.308836027942, 6300.306966233619, 6306.337834527309, 6206.906896396467, 6223.253209614875, 6230.43926046658, 6253.874922501615, 6279.780207692709, 6216.378362163929, 6247.875042499216, 6263.530235232284, 6274.247090349188, 6279.748810047547, 6286.245650521967, 6295.276043255084, 6297.937020629735, 6299.0, 6336.873262534816, 5918.408159184047, 6018.097285407049, 6070.271567411939, 6072.969635151972, 6214.067889816563, 6166.660833654192, 6170.6914654268, 6188.938110618837, 6256.061590761422, 6284.120223168668, 6089.604175728564, 6118.847028824315, 6123.479504242189, 6124.203853499195, 6133.785317513865], "value": 5823.587814945937, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.1995128719592, 453.322283186636, 495.2472242191868, 526.4866754913958, 536.9624126311155, 38386.61613383865, 41186.38220426692, 41338.0, 41340.75977720666, 41407.17185656285, 31994.880179193733, 37854.0, 41655.0, 41687.16625667485, 41756.0, 8422.368322375816, 8636.17956294151, 9145.222656074242, 9391.81216375672, 9421.15209631133, 11122.332660040383, 11463.36951467669, 11491.885081149165, 11605.767884642302, 11608.59369922053, 11258.268212566198, 11264.549418023269, 11308.660740177807, 11325.603603873866, 11331.546738130506, 11254.437278136102, 11292.0, 11314.0, 11320.207585469061, 11321.094312454983, 9459.52702364886, 11303.208775385727, 11313.208075434723, 11320.377379244139, 11331.320120792696, 41215.0, 41303.38044929328, 41358.759237222934, 41614.375784363256, 41648.95877603054, 3995.820188091543, 4088.9795551022353, 4090.0, 4143.316352801784, 4249.213895429355, 30016.24959376033, 31070.0, 31090.68909310923, 31434.21414464634, 31726.682733172605, 3225.548423220751, 3245.2211469247445, 3323.3353329333986, 3329.1011426914597, 3345.631980482145, 329515.0, 332598.0, 332805.0, 332874.33562832273, 333320.0, 64188.679056604415, 64482.0, 64566.677166614805, 64580.35419645837, 64775.67612161909, 53870.46129538654, 67197.66401167963, 67756.98364524536, 68108.0, 68472.0, 13681.657958550917, 14621.78067328991, 16495.587610309598, 17096.82903170977, 17104.14479276031, 8895.421797583136, 11743.354115523558, 12020.398980050963, 13042.282674452807, 13067.281299528617, 17801.842880212746, 17827.197776100107, 17859.73210401845, 17889.12164222738, 17996.820031799514, 7593.544387336778, 7657.310842024244, 7659.4255430841795, 7664.118626357928, 7689.269519395649, 879.3009557401881, 886.2201262888617, 888.440282621944, 904.8099899021148, 925.7084018534275, 30419.543706844415, 30668.07995760131, 30756.384872302875, 30767.0, 30841.69158308433, 975.5512464266517, 1045.2265323660436, 1085.6363118355282, 1131.3833960491552, 1223.9204451710789, 15443.0, 15823.367065317258, 15841.762373564407, 15958.521244362688, 16045.59886002836, 646514.0, 648383.0, 649351.0, 649516.0, 650161.0, 47500.0, 61339.0, 61451.6927415369, 61499.38500615026, 61677.69161154165, 1343.1068339554283, 1375.0, 1377.252381498916, 1378.1386633354155, 1409.6757745718542, 28988.565171522023, 29533.85233073821, 30002.549961750155, 30143.698563014088, 30422.239444014078, 44548.331775024046, 44768.55231447644, 45090.77454612706, 45142.09715805731, 45197.0, 501284.0, 566540.0, 570151.0, 570398.1480092573, 573443.0, 23811.285661430185, 24454.021839126235, 24506.63240051365, 24551.38621534476, 41220.587794121675, 43160.35259471173, 43363.78318108389, 43715.0, 44032.779836101894, 44147.558524414344, 35766.821165894005, 36205.81897090498, 36206.27587448289, 36365.45451818278, 36385.818070910536, 35460.113497162776, 35567.46648800218, 35578.644213557534, 35616.46575301321, 35779.0, 34524.82737586396, 35173.0, 35198.648013520564, 35211.0, 35597.0, 29700.0, 30184.09447716572, 31753.04740857778, 32144.874929377358, 32311.35377292394, 22739.63562186274, 24123.87938060369, 24395.51208975846, 24558.63162052535, 24562.263132106065], "value": 19861.998091644877, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.3160166679142, 362.9473726309685, 391.6377350950372, 397.2650596396667, 404.98987525311844, 15826.0, 15841.683166336667, 20361.490962725922, 21465.0, 21721.348359549218, 17479.650406991852, 20930.0, 21274.936253187356, 21364.679529807065, 21900.45248868779, 4922.630802689795, 5581.469760372758, 5757.913631295513, 5801.419858014207, 5808.622439541437, 5710.600257981943, 5805.216295800055, 5808.448197421239, 5808.535317174637, 5809.6804675742815, 5837.0, 6074.453299203071, 6111.052786818051, 6138.693065346737, 6150.7232174552255, 6076.726547305382, 6094.1772860663905, 6118.694065296761, 6252.781152659681, 6310.368963103653, 4672.462666793328, 5083.74581270935, 5980.0, 6011.969940150315, 6045.576809623328, 13289.3355332233, 19597.706034409497, 23961.520769584684, 25252.0, 25285.620715689278, 2654.32314759737, 2691.5693489041855, 2714.877830497632, 2725.1552018874213, 2753.0501976817964, 10102.343347682377, 10490.580376784996, 10519.053285204369, 13456.798148027787, 13882.681145291283, 1851.463075708043, 1919.8560107991816, 1919.89440580768, 1920.0207893974034, 1922.5193701574606, 81257.0, 118557.81442185554, 131498.0, 136972.26055478933, 139748.0, 10668.89331106679, 11238.10095192399, 12022.819657705142, 12032.699182520508, 12288.0, 15614.687706245812, 15617.297221624827, 15639.765403518957, 15651.060936343856, 15668.92165539165, 8647.394682372174, 8833.69082082124, 8869.201771840566, 8930.106989301017, 8942.0, 7372.852542949218, 7594.278543538355, 8073.071596766447, 8280.50316980983, 8754.47473151613, 4716.905661886812, 4794.707644017539, 4836.733979631154, 4876.463589005207, 4942.20924652054, 2033.5526184239739, 2107.7154584130944, 2120.5546835164787, 2121.6711409731515, 2122.80894719476, 788.2078511096312, 824.4023083264705, 829.8547754143121, 836.1346006882846, 840.8948881389827, 20660.690089648666, 21460.141594336365, 21562.275017998523, 21676.783232167476, 21728.913554322222, 803.1606970715616, 837.7696133563319, 849.4223927729204, 873.5195642396657, 879.6305551668314, 10677.092447141924, 11417.257878237888, 11445.71385715347, 11465.910738479832, 11507.424628768527, 630181.0, 704257.4361384583, 732397.0, 732740.0, 741932.0, 155593.0, 157007.21496392446, 160651.19674401553, 161762.0, 163471.0, 957.1385752822376, 966.8114717630135, 996.4021587047858, 996.5151923633879, 998.4408731110601, 18705.81294187095, 19030.143643535797, 19283.035848207815, 19287.324943626907, 19335.903320483307, 53508.929821404134, 58872.35074421576, 58907.70546147242, 59996.10005850003, 60115.39884601273, 85407.30259835768, 86966.56516717376, 87015.56492217498, 87190.69213961669, 89079.55460222658, 8081.111077781557, 8150.5924703763385, 8223.465474744122, 8494.490330579938, 8541.95729021351, 8824.779380515538, 9122.680706175252, 9172.082791720775, 9237.845269341333, 9243.445393276425, 7793.571353575608, 7856.37149028065, 7885.921140788519, 8247.505149691038, 8388.161183881562, 7740.535567865946, 7817.491863028885, 7910.7231246906085, 8020.639071241691, 8191.590420479071, 7893.763187104396, 8001.879971800544, 8121.715739949073, 8354.498730076215, 8481.151884811716, 12665.683357916127, 13240.0, 13241.867581324064, 13275.137116087424, 13573.660658483617, 11807.763844722886, 11824.0, 12079.57721479744, 12140.15018948665, 12200.633980980585], "value": 9610.512849448165, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1243.6455610151106, 1428.9356978935946, 1443.747344214762, 1456.5193486149565, 1459.2557795524278, 7584.938108664777, 7811.2579304966075, 8144.307733842623, 8595.785105372377, 8613.138686131384, 8033.879491807627, 8296.460730052542, 8316.750497485085, 8404.62179201936, 8422.620982055807, 2282.1784157703214, 2325.1745630301252, 2330.650402439631, 2334.9766502335015, 2338.2985104468676, 2071.585682863426, 2154.719886414764, 2292.64464008079, 2341.906323747048, 2344.038944032947, 2242.3273018094587, 2304.44693273614, 2313.6298192289237, 2315.0392587076344, 2322.593546129429, 2292.083166733312, 2298.230092918874, 2314.9421264468356, 2317.0963324303534, 2325.3256555598855, 2300.367398965281, 2301.160076572039, 2335.0309621507054, 2348.43637526994, 2360.280114565057, 8446.366522510836, 8508.404411691185, 8513.914860851373, 8527.445716028438, 8550.230479256834, 5149.0474262261605, 5292.629515933886, 5294.205869119624, 5299.867503312409, 5320.76056577455, 3401.6598340166033, 3580.606133325332, 3611.602723700391, 3638.7089032877307, 3653.780773153619, 1043.2957753516423, 1126.7859106769688, 1126.881677423875, 1202.8857258560422, 1269.156011252516, 9659.46872921989, 10201.38791672502, 10864.78270434587, 10930.01629853317, 10948.124150067975, 3981.0, 4099.487564054493, 4465.218586747306, 4715.151272770866, 4758.119747846659, 9515.905670847802, 9535.713928582154, 9570.042995700374, 9594.42433453995, 9602.519874006412, 4482.7758612069265, 5289.497497737709, 5303.7348132593215, 5311.575073994071, 5352.678839269656, 4607.378003969421, 4724.102420540086, 4761.952370478489, 4790.161721698689, 4819.566239038433, 3886.7084968627464, 3891.610838916086, 3952.209558088394, 3967.7222594418113, 4014.4781178446615, 1161.8780028096928, 1179.941002949849, 1204.1450570095155, 1298.1561984709824, 1300.765862144823, 759.5784339691414, 782.3545574900729, 900.9819803604022, 911.6171208092617, 916.3768637326682, 5554.1391084381985, 5564.527015203672, 5799.3620701722775, 5836.066229403275, 5849.385814489502, 775.3835700618022, 973.9707808765748, 986.3982970388057, 995.721198064543, 1024.4621573673733, 3412.897613071612, 3662.487251784752, 3694.5935947045805, 3716.7026637868908, 3818.694504439638, 9780.315377923476, 10005.649802256887, 10072.244581656258, 10123.898761012442, 10154.187664986783, 8200.220979006985, 8314.334853211849, 9841.311108222355, 9984.951580084133, 10035.046670566284, 6795.694193761293, 7460.925390746132, 7692.499987500794, 7797.142314345418, 7803.68785248595, 4792.688475249098, 5566.638168519034, 5636.774529018958, 5742.19609254713, 5808.564357673107, 7220.422366210795, 7281.5631062136445, 7595.620218989139, 9257.953710231406, 9318.72043838686, 9111.817763644558, 9139.680111196076, 9398.906010939803, 9496.952515237379, 9505.28710346713, 6106.419890110433, 7059.435245180476, 7261.346478816932, 7283.016792733125, 7308.634568271672, 7778.88331675014, 7829.686812527435, 7887.84224315522, 7896.210378962288, 7963.0, 5801.680907550125, 6974.68613912365, 7121.216666166821, 7123.715051398093, 7136.179339376038, 6883.965580172067, 6971.6862741175755, 7021.087258656342, 7151.0, 7202.3877970373105, 6207.379262073757, 6238.532110091852, 6411.102445657519, 6414.069959855888, 6427.935720642734, 4782.043591281688, 4873.0, 4914.582260507754, 5012.799488020439, 5096.592272618256, 3871.8644847430205, 4937.8271760488215, 4959.950400495949, 5014.749262536786, 5159.974200128975], "value": 4373.727626987932, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.5122947341092, 354.7658545360063, 355.1937102776697, 358.6711234874789, 372.2034845430779, 4170.812313445895, 4937.679050861691, 5123.846284611458, 5303.575713942875, 5511.476409741073, 3943.4676318696966, 5200.635955483118, 5378.0, 5407.188921661753, 5407.567394608432, 3734.3464893643504, 3885.630865067814, 4097.344424892018, 4285.4214681017975, 4304.24675681756, 3169.51033014484, 3435.4368762213135, 3867.323218436762, 3880.8939452256113, 4239.894002649928, 3520.802927004818, 3903.687704983609, 4162.0, 4217.810198541058, 4225.281702110633, 3398.320335932823, 4173.914782156661, 4195.964956995855, 4206.747595144301, 4207.454490033531, 3037.9088627341216, 3062.739667128297, 3549.538559987185, 3769.378052621314, 3831.5210598675167, 4571.748553829538, 4973.452920178777, 5205.973970130125, 5240.004399164147, 5257.663471576353, 3924.5682974872743, 4166.541680415152, 4674.719516829001, 4871.585915197177, 4885.682430642032, 4762.357081793948, 5329.626926115174, 5728.283964504437, 5778.566607504412, 5790.305163380422, 3717.5353080864893, 3803.7637767725323, 4127.607877251657, 4141.7100802943805, 4145.958540414587, 5607.803551975376, 5712.1431785232135, 6023.193791016168, 6047.606905551125, 6053.243344581927, 4100.6877799104595, 4241.936370954438, 4255.212785634668, 4255.957440425618, 4265.829366825292, 3706.09200745819, 3784.5647750509042, 3985.4619626350072, 4281.486221653359, 4328.11273688893, 4870.9269360959615, 4902.779374928065, 4908.312836202935, 4916.459189489154, 4991.950080499221, 5757.021306377842, 5792.507636850831, 5809.738561764731, 5819.447152520504, 5854.0, 1149.8965093141658, 1212.3635091576816, 1398.6083896508994, 1406.5006922542555, 1427.6437384484782, 976.7460460280379, 989.302541708094, 998.5157198758596, 1005.949702514886, 1007.3351587951917, 4293.763842988603, 4854.203944540472, 5039.017391608601, 5048.974755126201, 5082.695038297714, 5332.000999250562, 5400.515762749179, 5643.35101463337, 5677.574181936371, 5686.2039314496005, 4206.57934206583, 4376.354562265124, 4699.859004229878, 4973.850784476472, 5073.061483625541, 2708.6976070340447, 2835.3195233143897, 2958.896438624638, 2964.644242690848, 2978.5681076243823, 4620.53794620542, 4829.8024398898415, 5766.0, 5809.0, 5838.941610583925, 5346.518813306821, 5421.566274698014, 5646.265985421951, 6039.094135879654, 6059.273107164437, 5096.643234973516, 5811.27359080115, 6065.5754097212775, 6091.451769340781, 6105.419985101586, 5597.069011191133, 5718.94118117469, 5726.198332233407, 5942.553319075013, 5990.7304171311525, 4679.766011699469, 4869.293952376815, 5846.707664616837, 5862.765489380377, 5873.882522349443, 5153.634286914045, 5194.74026298676, 5381.973090134525, 6055.1522786810765, 6111.419415155553, 3833.4824798651825, 3902.765834049966, 3953.6639385652543, 3959.742616729903, 3991.840326386912, 4914.950850491449, 5282.735863206747, 5935.605132793877, 5944.840756052528, 5970.731317090828, 5338.798770276812, 5416.264786282902, 5598.776048957996, 5760.127958413616, 5768.827145070279, 4831.855044348676, 5296.894062118813, 5519.0959119103845, 5785.971070144622, 5791.333996590446, 4706.0823139487475, 5088.592912566912, 5403.973509933775, 5562.638428502134, 5626.493615574618, 5250.422453530032, 5421.3765416975975, 5739.938111449395, 5748.109043098327, 5772.497451987457, 5158.948410515847, 5642.971785141048, 5651.463111004448, 5669.6314739541795, 5688.0], "value": 4150.054661822015, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.8778019956409, 720.8990741296222, 773.0182668011626, 809.2554849538424, 816.738643634037, 10975.670729878106, 13974.441022359106, 14150.0, 14198.858011419883, 14227.004109712325, 14305.856941430582, 14464.0, 14481.855181448209, 14496.565103046925, 14555.490557830479, 679.5650783498564, 696.4846013949666, 710.5239489542006, 846.8094678697274, 872.4634349874844, 879.810840669256, 879.9956000219989, 880.5773228850156, 881.1408876345575, 882.686646240585, 924.6393906376485, 925.2089463508704, 926.2775035472342, 928.3408779766374, 930.144267274106, 905.4069584422214, 910.9817803643956, 918.3892711346946, 920.9769755756092, 922.4327038871113, 674.9325067493261, 679.6160169504215, 682.0655701688669, 682.5972676121063, 684.4524380495584, 14625.0493717908, 14981.850181498154, 14992.400303987828, 15636.843631563766, 15891.682166356608, 14265.572032838927, 14501.202433866134, 14649.022381978402, 14661.900357473232, 14785.852141478555, 11422.657320280405, 11517.25137866036, 11668.94165529167, 11961.880381196164, 12077.758444831054, 3356.5972083350152, 3709.684676802475, 3793.9241215175543, 3805.219929914361, 3892.5134358205223, 68807.9999999995, 104844.0, 107293.46353268262, 108155.2961175969, 111196.88803112027, 8572.228499435081, 9425.245980321557, 9524.714258572254, 10374.118199952938, 10389.84415233772, 19338.806611933982, 20457.874816885214, 21880.78119218819, 22981.425464363525, 23432.297031089096, 7587.393008559302, 7590.278923502258, 7616.885746713804, 7688.769336919901, 7783.494072885244, 4010.6189911958318, 4063.674906007512, 4068.7762173080173, 4084.118868828877, 4102.364133559303, 3591.6049234584175, 3675.5956844747056, 3676.613955534684, 3695.519582454264, 3722.4230244312175, 627.237462729731, 660.6564586415134, 665.2645396100859, 667.0594461808827, 673.0, 659.2945548263313, 672.3041651890368, 675.1391975231521, 691.8962155676688, 708.7802781137864, 7998.760037198894, 9124.49815260167, 9556.900956389965, 10267.579649824263, 10627.309224900357, 703.6200451756097, 715.1882613234017, 716.9103862017248, 722.4473277942378, 725.3616817200935, 4536.432945881765, 4893.4617192108835, 4901.926471102936, 4901.995091006406, 4902.607791376681, 78013.0, 92610.53694731483, 98990.5050474743, 99550.10347620088, 101388.0, 86544.0, 88117.67823482653, 89253.10746892578, 90712.54643726871, 91101.54449227711, 78922.6053869727, 79247.20752792398, 80572.0, 80866.59566702247, 81178.59410702909, 7974.960125199337, 9399.34204605671, 9488.87234053189, 9589.472579008221, 9591.232701384011, 39790.801045995744, 52831.0, 53009.0, 53746.0, 54036.91926161533, 68133.65933170302, 68362.97455538072, 69204.30795691979, 71056.64471677608, 71338.92991605234, 10826.296290741077, 11287.871212878646, 11557.901999310052, 11905.642830715093, 12020.699482513008, 11417.200795944203, 13168.736625267633, 13171.47314107425, 13186.934065329995, 13801.102928309629, 11968.820467693165, 12005.579804706793, 12239.082068844695, 12282.447289872156, 12302.200356976768, 12008.759824803281, 12075.818862716891, 12150.57472988441, 12189.268643881396, 12249.87750122523, 11326.716832079264, 11614.825777613174, 12071.336076515876, 12487.937560312505, 13484.460621575026, 9503.0, 11138.443077846237, 11591.0, 11727.706807329543, 11755.588554400554, 11092.833607495733, 11284.887151128383, 11560.595379161688, 11671.941640291745, 12033.278003319829], "value": 7343.788595650835, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1574.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 133.0, 103.0, 131.0, 177.0, 174.0, 126.0, 140.0, 174.0, 119.0], "value": 138.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 115.0, 111.0, 158.0, 147.0, 121.0, 116.0, 113.0, 163.0, 146.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 200.0, 197.0, 198.0, 189.0, 194.0, 193.0, 194.0, 197.0, 200.0], "value": 195.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 88.0, 65.0, 51.0, 74.0, 84.0, 65.0, 74.0], "value": 69.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 84.0, 99.0, 100.0, 58.0, 102.0, 103.0, 104.0, 115.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 89.0, 87.0, 100.0, 87.0, 128.0, 87.0, 89.0, 96.0], "value": 94.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 96.0, 99.0, 98.0, 97.0, 157.0, 95.0, 98.0, 130.0], "value": 106.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 92.0, 92.0, 94.0, 92.0, 97.0, 138.0, 148.0, 91.0], "value": 106.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 61.0, 63.0, 63.0, 62.0, 63.0, 63.0, 63.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 81.0, 81.0, 75.0, 69.0, 93.0, 109.0, 111.0, 86.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 85.0, 85.0, 83.0, 85.0, 92.0, 83.0, 84.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 138.0, 176.0, 172.0, 158.0, 159.0, 143.0, 182.0, 153.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 171.0, 170.0, 172.0, 178.0, 246.0, 201.0, 172.0, 173.0], "value": 182.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 82.0, 68.0, 64.0, 64.0, 67.0, 67.0, 67.0, 75.0], "value": 69.2, "unit": "score"}]}]} 09:37:42 INFO - Return code: 0 09:37:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:37:42 ERROR - # TBPL SUCCESS # 09:37:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:42 INFO - 2017-01-31 09:37:42.287 screenresolution[1929:12580] starting screenresolution argv=screenresolution get 09:37:42 INFO - 2017-01-31 09:37:42.290 screenresolution[1929:12580] Display 0: 1600x1200x32@60 09:37:42 INFO - 2017-01-31 09:37:42.296 screenresolution[1930:12583] starting screenresolution argv=screenresolution list 09:37:42 INFO - Available Modes on Display 0 09:37:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:42 INFO - Intel Iris: 09:37:42 INFO - Chipset Model: Intel Iris 09:37:42 INFO - Type: GPU 09:37:42 INFO - Bus: Built-In 09:37:42 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:42 INFO - Vendor: Intel (0x8086) 09:37:42 INFO - Device ID: 0x0a2e 09:37:42 INFO - Revision ID: 0x0009 09:37:42 INFO - Displays: 09:37:42 INFO - Display: 09:37:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:42 INFO - Display Serial Number: 1600x1200 60 09:37:42 INFO - Main Display: Yes 09:37:42 INFO - Mirror: Off 09:37:42 INFO - Online: Yes 09:37:42 INFO - Rotation: Supported 09:37:42 INFO - Return code: 0 09:37:42 INFO - Running post-action listener: _resource_record_post_action 09:37:42 INFO - [mozharness: 2017-01-31 17:37:42.530510Z] Finished run-tests step (success) 09:37:42 INFO - Running post-run listener: _resource_record_post_run 09:37:42 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 2795008; Write bytes: 506496000; Read time: 27; Write time: 1865 09:37:42 INFO - TinderboxPrint: CPU usage
54.4% 09:37:42 INFO - TinderboxPrint: I/O read bytes / time
2,795,008 / 27 09:37:42 INFO - TinderboxPrint: I/O write bytes / time
506,496,000 / 1,865 09:37:42 INFO - TinderboxPrint: CPU idle
2,182.8 (45.6%) 09:37:42 INFO - TinderboxPrint: CPU system
969.1 (20.3%) 09:37:42 INFO - TinderboxPrint: CPU user
1,633.8 (34.1%) 09:37:42 INFO - TinderboxPrint: Swap in / out
211,714,048 / 0 09:37:42 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207194624; Write bytes: 201484288; Read time: 15869; Write time: 412 09:37:42 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1857024; Write bytes: 294984704; Read time: 23; Write time: 1418 09:37:42 INFO - Running post-run listener: _upload_blobber_files 09:37:42 INFO - Blob upload gear active. 09:37:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:37:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:37:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:37:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:37:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:37:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:37:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:37:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:44 INFO - (blobuploader) - INFO - Done attempting. 09:37:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:37:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:37:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:45 INFO - (blobuploader) - INFO - Done attempting. 09:37:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:37:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:37:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:46 INFO - (blobuploader) - INFO - Done attempting. 09:37:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:37:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:37:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:48 INFO - (blobuploader) - INFO - Done attempting. 09:37:48 INFO - (blobuploader) - INFO - Iteration through files over. 09:37:48 INFO - Return code: 0 09:37:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:37:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:37:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ce081f83b3093d05883a2b904bf5d914f55cc0c33b1270884cab331a51d428493d3649ad674aa0404ab6bf3cbee33eb04a44007adde04ad237232e60556e00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/722d0e2ecd17b38919f374c08217963ebf5161f608394a84be7eb22b94aa82f0bb0161179717aea2feaf246b93382edf9520cd0a54c315245de6ab54b4b4f3eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3b10d736052184d9c00db081bc6d242b1e92ccfbe5d28feafe25d1c1943d3947b630b3b414c3ceab0bca4934d00f70779daefc7ae4f7e75f1827e963829ee7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c1e2bc39818ba364a6c1a1676549055a81bcdce294ee768e9df0bb2627c993284b6fcb89e76767798813f4df46d5b2eb7bf04cf9217342f5ecb819d72998170"} 09:37:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:37:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:37:48 INFO - Contents: 09:37:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ce081f83b3093d05883a2b904bf5d914f55cc0c33b1270884cab331a51d428493d3649ad674aa0404ab6bf3cbee33eb04a44007adde04ad237232e60556e00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/722d0e2ecd17b38919f374c08217963ebf5161f608394a84be7eb22b94aa82f0bb0161179717aea2feaf246b93382edf9520cd0a54c315245de6ab54b4b4f3eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3b10d736052184d9c00db081bc6d242b1e92ccfbe5d28feafe25d1c1943d3947b630b3b414c3ceab0bca4934d00f70779daefc7ae4f7e75f1827e963829ee7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c1e2bc39818ba364a6c1a1676549055a81bcdce294ee768e9df0bb2627c993284b6fcb89e76767798813f4df46d5b2eb7bf04cf9217342f5ecb819d72998170"} 09:37:48 INFO - Running post-run listener: copy_logs_to_upload_dir 09:37:48 INFO - Copying logs to upload dir... 09:37:48 INFO - mkdir: /builds/slave/test/build/upload/logs 09:37:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.651376 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-01-31 09:37:48.790313) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 09:37:48.794693) ========= 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.EukExdqBo9/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.ArGSAcfLQ2/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ce081f83b3093d05883a2b904bf5d914f55cc0c33b1270884cab331a51d428493d3649ad674aa0404ab6bf3cbee33eb04a44007adde04ad237232e60556e00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/722d0e2ecd17b38919f374c08217963ebf5161f608394a84be7eb22b94aa82f0bb0161179717aea2feaf246b93382edf9520cd0a54c315245de6ab54b4b4f3eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3b10d736052184d9c00db081bc6d242b1e92ccfbe5d28feafe25d1c1943d3947b630b3b414c3ceab0bca4934d00f70779daefc7ae4f7e75f1827e963829ee7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c1e2bc39818ba364a6c1a1676549055a81bcdce294ee768e9df0bb2627c993284b6fcb89e76767798813f4df46d5b2eb7bf04cf9217342f5ecb819d72998170"} build_url:https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010567 build_url: 'https://queue.taskcluster.net/v1/task/H5s_RGX9RFqbqN0M8dDY2w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ce081f83b3093d05883a2b904bf5d914f55cc0c33b1270884cab331a51d428493d3649ad674aa0404ab6bf3cbee33eb04a44007adde04ad237232e60556e00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/722d0e2ecd17b38919f374c08217963ebf5161f608394a84be7eb22b94aa82f0bb0161179717aea2feaf246b93382edf9520cd0a54c315245de6ab54b4b4f3eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3b10d736052184d9c00db081bc6d242b1e92ccfbe5d28feafe25d1c1943d3947b630b3b414c3ceab0bca4934d00f70779daefc7ae4f7e75f1827e963829ee7f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c1e2bc39818ba364a6c1a1676549055a81bcdce294ee768e9df0bb2627c993284b6fcb89e76767798813f4df46d5b2eb7bf04cf9217342f5ecb819d72998170"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 09:37:48.831402) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:37:48.831789) ========= 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.EukExdqBo9/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.ArGSAcfLQ2/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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.005041 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:37:48.884175) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 09:37:48.887579) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 09:37:48.887980) ========= ========= Total master_lag: 0.21 =========