builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0078 starttime: 1488733231.37 results: success (0) buildid: 20170305080023 builduid: 0f8d69372e4e45109dd13a35d4ac1e68 revision: 8d026c60151005ad942e3d4389318fe28a0c8c54 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:31.373146) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:31.373716) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:31.374026) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:31.403070) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:31.403571) ========= 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.jrLaerj9C9/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.JHVDSYAays/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-05 09:00:31-- 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% 219M=0s 2017-03-05 09:00:31 (219 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.158639 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:31.706458) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:31.706848) ========= 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.jrLaerj9C9/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.JHVDSYAays/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.029449 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:31.780626) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-05 09:00:31.781044) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8d026c60151005ad942e3d4389318fe28a0c8c54 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8d026c60151005ad942e3d4389318fe28a0c8c54 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jrLaerj9C9/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.JHVDSYAays/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-05 09:00:31,893 truncating revision to first 12 chars 2017-03-05 09:00:31,893 Setting DEBUG logging. 2017-03-05 09:00:31,893 attempt 1/10 2017-03-05 09:00:31,893 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8d026c601510?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-05 09:00:32,598 unpacking tar archive at: mozilla-central-8d026c601510/testing/mozharness/ program finished with exit code 0 elapsedTime=0.980057 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-05 09:00:32.793760) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:32.794091) ========= script_repo_revision: 8d026c60151005ad942e3d4389318fe28a0c8c54 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:32.794503) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:32.794793) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-05 09:00:32.815429) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-05 09:00:32.815867) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jrLaerj9C9/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.JHVDSYAays/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:00:33 INFO - MultiFileLogger online at 20170305 09:00:33 in /builds/slave/test 09:00:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:00:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:00:33 INFO - {'append_to_log': False, 09:00:33 INFO - 'base_work_dir': '/builds/slave/test', 09:00:33 INFO - 'blob_upload_branch': 'Firefox', 09:00:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:00:33 INFO - 'branch': 'Firefox', 09:00:33 INFO - 'buildbot_json_path': 'buildprops.json', 09:00:33 INFO - 'config_files': ('talos/mac_config.py',), 09:00:33 INFO - 'default_actions': ('clobber', 09:00:33 INFO - 'read-buildbot-config', 09:00:33 INFO - 'download-and-extract', 09:00:33 INFO - 'populate-webroot', 09:00:33 INFO - 'create-virtualenv', 09:00:33 INFO - 'install', 09:00:33 INFO - 'run-tests'), 09:00:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:00:33 INFO - 'download_minidump_stackwalk': True, 09:00:33 INFO - 'download_symbols': 'ondemand', 09:00:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:00:33 INFO - 'tooltool.py': '/tools/tooltool.py', 09:00:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:00:33 INFO - '/tools/misc-python/virtualenv.py')}, 09:00:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:00:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:00:33 INFO - 'gecko_profile': False, 09:00:33 INFO - 'gecko_profile_interval': 0, 09:00:33 INFO - 'installer_path': 'installer.exe', 09:00:33 INFO - 'log_level': 'info', 09:00:33 INFO - 'log_name': 'talos', 09:00:33 INFO - 'log_to_console': True, 09:00:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:00:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:00:33 INFO - 'opt_config_files': (), 09:00:33 INFO - 'pip_index': False, 09:00:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:00:33 INFO - 'cmd': ('bash', 09:00:33 INFO - '-c', 09:00:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:00:33 INFO - 'enabled': True, 09:00:33 INFO - 'halt_on_failure': False, 09:00:33 INFO - 'name': 'check_screen_resolution'},), 09:00:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:00:33 INFO - 'cmd': ('bash', 09:00:33 INFO - '-c', 09:00:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:00:33 INFO - 'enabled': True, 09:00:33 INFO - 'halt_on_failure': False, 09:00:33 INFO - 'name': 'check_screen_resolution'},), 09:00:33 INFO - 'run_cmd_checks_enabled': True, 09:00:33 INFO - 'suite': 'dromaeojs-e10s', 09:00:33 INFO - 'system_bits': '32', 09:00:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:00:33 INFO - 'title': 't-yosemite-r7-0078', 09:00:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:00:33 INFO - 'use_talos_json': True, 09:00:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:00:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:00:33 INFO - 'work_dir': 'build'} 09:00:33 INFO - [mozharness: 2017-03-05 17:00:33.032329Z] Running clobber step. 09:00:33 INFO - Running pre-action listener: _resource_record_pre_action 09:00:33 INFO - Running main action method: clobber 09:00:33 INFO - rmtree: /builds/slave/test/build 09:00:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:00:35 INFO - Running post-action listener: _resource_record_post_action 09:00:35 INFO - [mozharness: 2017-03-05 17:00:35.619727Z] Finished clobber step (success) 09:00:35 INFO - [mozharness: 2017-03-05 17:00:35.619903Z] Running read-buildbot-config step. 09:00:35 INFO - Running pre-action listener: _resource_record_pre_action 09:00:35 INFO - Running main action method: read_buildbot_config 09:00:35 INFO - Using buildbot properties: 09:00:35 INFO - { 09:00:35 INFO - "project": "", 09:00:35 INFO - "product": "firefox", 09:00:35 INFO - "script_repo_revision": "production", 09:00:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:00:35 INFO - "repository": "", 09:00:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:00:35 INFO - "buildid": "20170305080023", 09:00:35 INFO - "pgo_build": "False", 09:00:35 INFO - "basedir": "/builds/slave/test", 09:00:35 INFO - "buildnumber": 86, 09:00:35 INFO - "slavename": "t-yosemite-r7-0078", 09:00:35 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:00:35 INFO - "platform": "yosemite_r7", 09:00:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:00:35 INFO - "branch": "mozilla-central", 09:00:35 INFO - "repo_path": "mozilla-central", 09:00:35 INFO - "revision": "8d026c60151005ad942e3d4389318fe28a0c8c54", 09:00:35 INFO - "stage_platform": "macosx64", 09:00:35 INFO - "builduid": "0f8d69372e4e45109dd13a35d4ac1e68", 09:00:35 INFO - "slavebuilddir": "test" 09:00:35 INFO - } 09:00:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 09:00:35 INFO - Running post-action listener: _resource_record_post_action 09:00:35 INFO - [mozharness: 2017-03-05 17:00:35.624061Z] Finished read-buildbot-config step (success) 09:00:35 INFO - [mozharness: 2017-03-05 17:00:35.624221Z] Running download-and-extract step. 09:00:35 INFO - Running pre-action listener: _resource_record_pre_action 09:00:35 INFO - Running main action method: download_and_extract 09:00:35 INFO - mkdir: /builds/slave/test/build/tests 09:00:35 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:00:35 INFO - trying https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 09:00:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 09:00:36 INFO - Downloaded 1199 bytes. 09:00:36 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:00:36 INFO - Using the following test package requirements: 09:00:36 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:00:36 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:00:36 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 09:00:36 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:00:36 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 09:00:36 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:00:36 INFO - u'jsshell-mac.zip'], 09:00:36 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:00:36 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 09:00:36 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:00:36 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:00:36 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 09:00:36 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:00:36 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 09:00:36 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:00:36 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 09:00:36 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:00:36 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 09:00:36 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:00:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:00:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:00:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:00:38 INFO - Expected file size: 51775172 09:00:38 INFO - Obtained file size: 51775172 09:00:54 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:00:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:00:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:00:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:00:56 INFO - Expected file size: 51775172 09:00:56 INFO - Obtained file size: 51775172 09:01:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 09:01:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:01:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 09:01:12 INFO - Expected file size: 14404424 09:01:12 INFO - Obtained file size: 14404424 09:01:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:13 INFO - trying https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:01:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 09:01:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 09:01:15 INFO - Downloaded 75120570 bytes. 09:01:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:01:15 INFO - mkdir: /builds/slave/test/properties 09:01:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:01:15 INFO - Writing to file /builds/slave/test/properties/build_url 09:01:15 INFO - Contents: 09:01:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:01:15 INFO - Running post-action listener: _resource_record_post_action 09:01:15 INFO - Running post-action listener: set_extra_try_arguments 09:01:15 INFO - [mozharness: 2017-03-05 17:01:15.989796Z] Finished download-and-extract step (success) 09:01:15 INFO - [mozharness: 2017-03-05 17:01:15.989940Z] Running populate-webroot step. 09:01:15 INFO - Running pre-action listener: _resource_record_pre_action 09:01:15 INFO - Running main action method: populate_webroot 09:01:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:15 INFO - '--tppagecycles', 09:01:15 INFO - '7'], 09:01:15 INFO - 'tests': ['tsvgm']}}, 09:01:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:01:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:01:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:01:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:01:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:01:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:01:15 INFO - 'talos_options': ['--disable-e10s'], 09:01:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:01:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:01:16 INFO - 'talos_options': ['--disable-e10s'], 09:01:16 INFO - 'tests': ['damp', 'tps']}, 09:01:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:01:16 INFO - 'tests': ['damp', 'tps']}, 09:01:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:01:16 INFO - 'tests': ['dromaeo_dom']}, 09:01:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:01:16 INFO - 'tests': ['basic_compositor_video']}, 09:01:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:01:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:01:16 INFO - 'tests': ['a11yr', 09:01:16 INFO - 'ts_paint', 09:01:16 INFO - 'tpaint', 09:01:16 INFO - 'sessionrestore', 09:01:16 INFO - 'sessionrestore_no_auto_restore', 09:01:16 INFO - 'tabpaint']}, 09:01:16 INFO - 'other-e10s': {'tests': ['a11yr', 09:01:16 INFO - 'ts_paint', 09:01:16 INFO - 'tpaint', 09:01:16 INFO - 'sessionrestore', 09:01:16 INFO - 'sessionrestore_no_auto_restore', 09:01:16 INFO - 'tabpaint']}, 09:01:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:01:16 INFO - 'ts_paint', 09:01:16 INFO - 'tpaint', 09:01:16 INFO - 'sessionrestore', 09:01:16 INFO - 'sessionrestore_no_auto_restore', 09:01:16 INFO - 'tabpaint']}, 09:01:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:01:16 INFO - 'ts_paint', 09:01:16 INFO - 'tpaint', 09:01:16 INFO - 'sessionrestore', 09:01:16 INFO - 'sessionrestore_no_auto_restore', 09:01:16 INFO - 'tabpaint']}, 09:01:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:01:16 INFO - 'tests': ['a11yr', 09:01:16 INFO - 'ts_paint', 09:01:16 INFO - 'tpaint', 09:01:16 INFO - 'sessionrestore', 09:01:16 INFO - 'sessionrestore_no_auto_restore', 09:01:16 INFO - 'tabpaint']}, 09:01:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:01:16 INFO - 'tests': ['a11yr', 09:01:16 INFO - 'ts_paint', 09:01:16 INFO - 'tpaint', 09:01:16 INFO - 'sessionrestore', 09:01:16 INFO - 'sessionrestore_no_auto_restore', 09:01:16 INFO - 'tabpaint']}, 09:01:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:01:16 INFO - 'tests': ['tsvgx', 09:01:16 INFO - 'tsvgr_opacity', 09:01:16 INFO - 'tart', 09:01:16 INFO - 'tscrollx', 09:01:16 INFO - 'cart', 09:01:16 INFO - 'tsvg_static']}, 09:01:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:01:16 INFO - 'tsvgr_opacity', 09:01:16 INFO - 'tart', 09:01:16 INFO - 'tscrollx', 09:01:16 INFO - 'cart', 09:01:16 INFO - 'tsvg_static']}, 09:01:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:01:16 INFO - 'talos_options': ['--disable-e10s'], 09:01:16 INFO - 'tests': ['tp5o']}, 09:01:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:01:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:01:16 INFO - 'talos_options': ['--disable-e10s', 09:01:16 INFO - '--xperf_path', 09:01:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:16 INFO - 'tests': ['tp5n']}, 09:01:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:01:16 INFO - 'talos_options': ['--xperf_path', 09:01:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:16 INFO - 'tests': ['tp5n']}}, 09:01:16 INFO - 'talos.zip': {'path': '', 09:01:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:16 INFO - Running post-action listener: _resource_record_post_action 09:01:16 INFO - [mozharness: 2017-03-05 17:01:16.008435Z] Finished populate-webroot step (success) 09:01:16 INFO - [mozharness: 2017-03-05 17:01:16.008551Z] Running create-virtualenv step. 09:01:16 INFO - Running pre-action listener: _resource_record_pre_action 09:01:16 INFO - Running main action method: create_virtualenv 09:01:16 INFO - Creating virtualenv /builds/slave/test/build/venv 09:01:16 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:01:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:01:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:16 INFO - Using real prefix '/tools/python27' 09:01:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:01:16 INFO - Installing distribute.............................................................................................................................................................................................done. 09:01:19 INFO - Installing pip.................done. 09:01:19 INFO - Return code: 0 09:01:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:01:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:01:20 INFO - Reading from file tmpfile_stdout 09:01:20 INFO - Output received: 09:01:20 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:01:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:01:20 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:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:20 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:01:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:20 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 0x10c7dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae22c7f3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5bdd30>, '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 0x10c4e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2be8>, '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.jrLaerj9C9/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.JHVDSYAays/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:01:20 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:01:20 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:01:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jrLaerj9C9/Render', 09:01:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:20 INFO - 'HOME': '/Users/cltbld', 09:01:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:20 INFO - 'LOGNAME': 'cltbld', 09:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:20 INFO - 'MOZ_NO_REMOTE': '1', 09:01:20 INFO - 'NO_EM_RESTART': '1', 09:01:20 INFO - 'PAGER': '/bin/cat', 09:01:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:20 INFO - 'PWD': '/builds/slave/test', 09:01:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:20 INFO - 'SHELL': '/bin/bash', 09:01:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JHVDSYAays/Listeners', 09:01:20 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 09:01:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:20 INFO - 'USER': 'cltbld', 09:01:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:20 INFO - 'XPC_FLAGS': '0x0', 09:01:20 INFO - 'XPC_SERVICE_NAME': '0', 09:01:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:20 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:01:20 INFO - Cleaning up... 09:01:20 INFO - Return code: 0 09:01:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:01:20 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:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:20 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:01:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:20 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 0x10c7dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae22c7f3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5bdd30>, '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 0x10c4e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2be8>, '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.jrLaerj9C9/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.JHVDSYAays/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:01:20 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:01:20 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:01:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jrLaerj9C9/Render', 09:01:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:20 INFO - 'HOME': '/Users/cltbld', 09:01:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:20 INFO - 'LOGNAME': 'cltbld', 09:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:20 INFO - 'MOZ_NO_REMOTE': '1', 09:01:20 INFO - 'NO_EM_RESTART': '1', 09:01:20 INFO - 'PAGER': '/bin/cat', 09:01:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:20 INFO - 'PWD': '/builds/slave/test', 09:01:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:20 INFO - 'SHELL': '/bin/bash', 09:01:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JHVDSYAays/Listeners', 09:01:20 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 09:01:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:20 INFO - 'USER': 'cltbld', 09:01:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:20 INFO - 'XPC_FLAGS': '0x0', 09:01:20 INFO - 'XPC_SERVICE_NAME': '0', 09:01:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:20 INFO - Downloading/unpacking psutil>=3.1.1 09:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:01:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:24 INFO - Installing collected packages: psutil 09:01:24 INFO - Running setup.py install for psutil 09:01:24 INFO - building 'psutil._psutil_osx' extension 09:01:24 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:01:25 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:01:25 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:01:25 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:01:25 INFO - building 'psutil._psutil_posix' extension 09:01:25 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:01:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:01:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:01:25 INFO - ^ 09:01:25 INFO - 1 warning generated. 09:01:25 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:01:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:25 INFO - Successfully installed psutil 09:01:25 INFO - Cleaning up... 09:01:25 INFO - Return code: 0 09:01:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:01: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:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae22c7f3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5bdd30>, '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 0x10c4e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2be8>, '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.jrLaerj9C9/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.JHVDSYAays/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:01: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:01: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 mozsystemmonitor==0.3 09:01:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jrLaerj9C9/Render', 09:01:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:25 INFO - 'HOME': '/Users/cltbld', 09:01:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:25 INFO - 'LOGNAME': 'cltbld', 09:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:25 INFO - 'MOZ_NO_REMOTE': '1', 09:01:25 INFO - 'NO_EM_RESTART': '1', 09:01:25 INFO - 'PAGER': '/bin/cat', 09:01:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:25 INFO - 'PWD': '/builds/slave/test', 09:01:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:25 INFO - 'SHELL': '/bin/bash', 09:01:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JHVDSYAays/Listeners', 09:01:25 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 09:01:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:25 INFO - 'USER': 'cltbld', 09:01:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:25 INFO - 'XPC_FLAGS': '0x0', 09:01:25 INFO - 'XPC_SERVICE_NAME': '0', 09:01:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:01:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:01:28 INFO - Installing collected packages: mozsystemmonitor 09:01:28 INFO - Running setup.py install for mozsystemmonitor 09:01:29 INFO - Successfully installed mozsystemmonitor 09:01:29 INFO - Cleaning up... 09:01:29 INFO - Return code: 0 09:01:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:01: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:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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', '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 0x10c7dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae22c7f3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5bdd30>, '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 0x10c4e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2be8>, '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.jrLaerj9C9/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.JHVDSYAays/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:01: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:01: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 jsonschema==2.5.1 09:01:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jrLaerj9C9/Render', 09:01:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:29 INFO - 'HOME': '/Users/cltbld', 09:01:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:29 INFO - 'LOGNAME': 'cltbld', 09:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:29 INFO - 'MOZ_NO_REMOTE': '1', 09:01:29 INFO - 'NO_EM_RESTART': '1', 09:01:29 INFO - 'PAGER': '/bin/cat', 09:01:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:29 INFO - 'PWD': '/builds/slave/test', 09:01:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:29 INFO - 'SHELL': '/bin/bash', 09:01:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JHVDSYAays/Listeners', 09:01:29 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 09:01:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:29 INFO - 'USER': 'cltbld', 09:01:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:29 INFO - 'XPC_FLAGS': '0x0', 09:01:29 INFO - 'XPC_SERVICE_NAME': '0', 09:01:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:29 INFO - Downloading/unpacking jsonschema==2.5.1 09:01: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:01: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:01: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:01: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:01: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:01: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:01:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:01:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:01:31 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:01:31 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:01:31 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:01:31 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:01:31 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:01:31 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:01:32 INFO - Downloading functools32-3.2.3-2.tar.gz 09:01:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:01:32 INFO - warning: no files found matching '*.txt' 09:01:32 INFO - no previously-included directories found matching 'build' 09:01:32 INFO - no previously-included directories found matching 'dist' 09:01:32 INFO - no previously-included directories found matching '.git*' 09:01:32 INFO - Installing collected packages: jsonschema, functools32 09:01:32 INFO - Running setup.py install for functools32 09:01:32 INFO - warning: no files found matching '*.txt' 09:01:32 INFO - no previously-included directories found matching 'build' 09:01:32 INFO - no previously-included directories found matching 'dist' 09:01:32 INFO - no previously-included directories found matching '.git*' 09:01:32 INFO - Successfully installed jsonschema functools32 09:01:32 INFO - Cleaning up... 09:01:32 INFO - Return code: 0 09:01:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:01:32 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:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:32 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:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:32 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 0x10c7dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae22c7f3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5bdd30>, '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 0x10c4e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2be8>, '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.jrLaerj9C9/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.JHVDSYAays/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:01:32 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:01:32 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:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jrLaerj9C9/Render', 09:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:32 INFO - 'HOME': '/Users/cltbld', 09:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:32 INFO - 'LOGNAME': 'cltbld', 09:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:32 INFO - 'MOZ_NO_REMOTE': '1', 09:01:32 INFO - 'NO_EM_RESTART': '1', 09:01:32 INFO - 'PAGER': '/bin/cat', 09:01:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:32 INFO - 'PWD': '/builds/slave/test', 09:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:32 INFO - 'SHELL': '/bin/bash', 09:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JHVDSYAays/Listeners', 09:01:32 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 09:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:32 INFO - 'USER': 'cltbld', 09:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:32 INFO - 'XPC_FLAGS': '0x0', 09:01:32 INFO - 'XPC_SERVICE_NAME': '0', 09:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:01:33 INFO - Cleaning up... 09:01:33 INFO - Return code: 0 09:01:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:33 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 0x10c7dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae22c7f3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5bdd30>, '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 0x10c4e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2be8>, '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.jrLaerj9C9/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.JHVDSYAays/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:01:33 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:01:33 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:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jrLaerj9C9/Render', 09:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:33 INFO - 'HOME': '/Users/cltbld', 09:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:33 INFO - 'LOGNAME': 'cltbld', 09:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:33 INFO - 'MOZ_NO_REMOTE': '1', 09:01:33 INFO - 'NO_EM_RESTART': '1', 09:01:33 INFO - 'PAGER': '/bin/cat', 09:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:33 INFO - 'PWD': '/builds/slave/test', 09:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:33 INFO - 'SHELL': '/bin/bash', 09:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JHVDSYAays/Listeners', 09:01:33 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 09:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:33 INFO - 'USER': 'cltbld', 09:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:33 INFO - 'XPC_FLAGS': '0x0', 09:01:33 INFO - 'XPC_SERVICE_NAME': '0', 09:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:33 INFO - Downloading/unpacking blobuploader==1.2.4 09:01: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:01: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:01: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:01: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:01: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:01: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:01:35 INFO - Downloading blobuploader-1.2.4.tar.gz 09:01:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:01:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:01:36 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:01:36 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:01:36 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:01:36 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:01:36 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:01:36 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:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:01:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:01:36 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:01:36 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:01:36 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:01:36 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:01:36 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:01:36 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:01:37 INFO - Downloading docopt-0.6.1.tar.gz 09:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:01:37 INFO - Installing collected packages: blobuploader, requests, docopt 09:01:37 INFO - Running setup.py install for blobuploader 09:01:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:01:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:01:37 INFO - Running setup.py install for requests 09:01:37 INFO - Running setup.py install for docopt 09:01:38 INFO - Successfully installed blobuploader requests docopt 09:01:38 INFO - Cleaning up... 09:01:38 INFO - Return code: 0 09:01:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:38 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:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:38 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:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:38 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 0x10c7dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae22c7f3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5bdd30>, '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 0x10c4e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2be8>, '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.jrLaerj9C9/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.JHVDSYAays/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:01:38 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:01:38 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:01:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jrLaerj9C9/Render', 09:01:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:38 INFO - 'HOME': '/Users/cltbld', 09:01:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:38 INFO - 'LOGNAME': 'cltbld', 09:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:38 INFO - 'MOZ_NO_REMOTE': '1', 09:01:38 INFO - 'NO_EM_RESTART': '1', 09:01:38 INFO - 'PAGER': '/bin/cat', 09:01:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:38 INFO - 'PWD': '/builds/slave/test', 09:01:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:38 INFO - 'SHELL': '/bin/bash', 09:01:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JHVDSYAays/Listeners', 09:01:38 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 09:01:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:38 INFO - 'USER': 'cltbld', 09:01:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:38 INFO - 'XPC_FLAGS': '0x0', 09:01:38 INFO - 'XPC_SERVICE_NAME': '0', 09:01:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-U64ohC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-YZbZCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-JMsjQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-jQ0iEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-8DzomU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-kPD8hM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-52PkLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-TCTe_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-QvHDeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-m6WaXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-tlzoa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-C0Hspv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-P3kNwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-72EQC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-fXfHwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-KAkjlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:40 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-RmLSiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:01:40 INFO - Running setup.py install for manifestparser 09:01:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:01:40 INFO - Running setup.py install for mozcrash 09:01:40 INFO - Running setup.py install for mozdebug 09:01:40 INFO - Running setup.py install for mozdevice 09:01:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:01:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:01:40 INFO - Running setup.py install for mozfile 09:01:40 INFO - Running setup.py install for mozhttpd 09:01:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Running setup.py install for mozinfo 09:01:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Running setup.py install for mozInstall 09:01:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Running setup.py install for mozleak 09:01:41 INFO - Running setup.py install for mozlog 09:01:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Running setup.py install for moznetwork 09:01:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:01:41 INFO - Running setup.py install for mozprocess 09:01:41 INFO - Running setup.py install for mozprofile 09:01:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Running setup.py install for mozrunner 09:01:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Running setup.py install for mozscreenshot 09:01:42 INFO - Running setup.py install for moztest 09:01:42 INFO - Running setup.py install for mozversion 09:01:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:01:42 INFO - Cleaning up... 09:01:42 INFO - Return code: 0 09:01:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:42 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 0x10c7dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae22c7f3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5bdd30>, '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 0x10c4e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2be8>, '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.jrLaerj9C9/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.JHVDSYAays/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:01:42 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:01:42 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:01:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jrLaerj9C9/Render', 09:01:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:42 INFO - 'HOME': '/Users/cltbld', 09:01:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:42 INFO - 'LOGNAME': 'cltbld', 09:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:42 INFO - 'MOZ_NO_REMOTE': '1', 09:01:42 INFO - 'NO_EM_RESTART': '1', 09:01:42 INFO - 'PAGER': '/bin/cat', 09:01:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:42 INFO - 'PWD': '/builds/slave/test', 09:01:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:42 INFO - 'SHELL': '/bin/bash', 09:01:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JHVDSYAays/Listeners', 09:01:42 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 09:01:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:42 INFO - 'USER': 'cltbld', 09:01:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:42 INFO - 'XPC_FLAGS': '0x0', 09:01:42 INFO - 'XPC_SERVICE_NAME': '0', 09:01:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-hZ8w8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-roPE0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-WX9p5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-wj17kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-NR8o7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-_aBfzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-XFumyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ZnPege-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-XWKA63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:43 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:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-lNgily-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:44 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:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-iMSP_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:44 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:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-8Jholg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-_JhpQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:44 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:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-pQpr7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:44 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:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-mQfHCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:44 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:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-N4lIt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:44 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:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-sN2szd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:44 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:01:44 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:01:44 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:01:44 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:01:44 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:01:44 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:01:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:01:44 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:01:44 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:01:44 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:01:44 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:01:44 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:01:44 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:01:47 INFO - Downloading blessings-1.6.tar.gz 09:01:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:01:47 INFO - Installing collected packages: blessings 09:01:47 INFO - Running setup.py install for blessings 09:01:47 INFO - Successfully installed blessings 09:01:47 INFO - Cleaning up... 09:01:47 INFO - Return code: 0 09:01:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:01:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:48 INFO - Reading from file tmpfile_stdout 09:01:48 INFO - Current package versions: 09:01:48 INFO - blessings == 1.6 09:01:48 INFO - blobuploader == 1.2.4 09:01:48 INFO - docopt == 0.6.1 09:01:48 INFO - functools32 == 3.2.3-2 09:01:48 INFO - jsonschema == 2.5.1 09:01:48 INFO - manifestparser == 1.1 09:01:48 INFO - mozInstall == 1.12 09:01:48 INFO - mozcrash == 1.0 09:01:48 INFO - mozdebug == 0.1 09:01:48 INFO - mozdevice == 0.49 09:01:48 INFO - mozfile == 1.2 09:01:48 INFO - mozhttpd == 0.7 09:01:48 INFO - mozinfo == 0.9 09:01:48 INFO - mozleak == 0.1 09:01:48 INFO - mozlog == 3.4 09:01:48 INFO - moznetwork == 0.27 09:01:48 INFO - mozprocess == 0.25 09:01:48 INFO - mozprofile == 0.28 09:01:48 INFO - mozrunner == 6.13 09:01:48 INFO - mozscreenshot == 0.1 09:01:48 INFO - mozsystemmonitor == 0.3 09:01:48 INFO - moztest == 0.8 09:01:48 INFO - mozversion == 1.4 09:01:48 INFO - psutil == 3.1.1 09:01:48 INFO - requests == 1.2.3 09:01:48 INFO - wsgiref == 0.1.2 09:01:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:48 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:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 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:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 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 0x10c7dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae22c7f3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5bdd30>, '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 0x10c4e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2be8>, '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.jrLaerj9C9/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.JHVDSYAays/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:01:48 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:01:48 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:01:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jrLaerj9C9/Render', 09:01:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:48 INFO - 'HOME': '/Users/cltbld', 09:01:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:48 INFO - 'LOGNAME': 'cltbld', 09:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:48 INFO - 'MOZ_NO_REMOTE': '1', 09:01:48 INFO - 'NO_EM_RESTART': '1', 09:01:48 INFO - 'PAGER': '/bin/cat', 09:01:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:48 INFO - 'PWD': '/builds/slave/test', 09:01:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:48 INFO - 'SHELL': '/bin/bash', 09:01:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JHVDSYAays/Listeners', 09:01:48 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 09:01:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:48 INFO - 'USER': 'cltbld', 09:01:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:48 INFO - 'XPC_FLAGS': '0x0', 09:01:48 INFO - 'XPC_SERVICE_NAME': '0', 09:01:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:48 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:01:48 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:01:48 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:01:48 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:01:48 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:01:48 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:01:48 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:01:48 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:01:48 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:01:48 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:01:48 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:01:48 INFO - Cleaning up... 09:01:48 INFO - Return code: 0 09:01:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:01:48 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:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 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:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 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 0x10c7dfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae22c7f3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5bdd30>, '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 0x10c4e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2be8>, '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.jrLaerj9C9/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.JHVDSYAays/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:01:48 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:01:48 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:01:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jrLaerj9C9/Render', 09:01:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:48 INFO - 'HOME': '/Users/cltbld', 09:01:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:48 INFO - 'LOGNAME': 'cltbld', 09:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:48 INFO - 'MOZ_NO_REMOTE': '1', 09:01:48 INFO - 'NO_EM_RESTART': '1', 09:01:48 INFO - 'PAGER': '/bin/cat', 09:01:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:48 INFO - 'PWD': '/builds/slave/test', 09:01:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:48 INFO - 'SHELL': '/bin/bash', 09:01:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JHVDSYAays/Listeners', 09:01:48 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 09:01:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:48 INFO - 'USER': 'cltbld', 09:01:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:48 INFO - 'XPC_FLAGS': '0x0', 09:01:48 INFO - 'XPC_SERVICE_NAME': '0', 09:01:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:01:48 INFO - Cleaning up... 09:01:48 INFO - Return code: 0 09:01:48 INFO - Running post-action listener: _resource_record_post_action 09:01:48 INFO - Running post-action listener: _start_resource_monitoring 09:01:48 INFO - Starting resource monitoring. 09:01:48 INFO - [mozharness: 2017-03-05 17:01:48.672348Z] Finished create-virtualenv step (success) 09:01:48 INFO - [mozharness: 2017-03-05 17:01:48.673331Z] Running install step. 09:01:48 INFO - Running pre-action listener: _resource_record_pre_action 09:01:48 INFO - Running main action method: install 09:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:48 INFO - Reading from file tmpfile_stdout 09:01:48 INFO - Detecting whether we're running mozinstall >=1.0... 09:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:01:49 INFO - Reading from file tmpfile_stdout 09:01:49 INFO - Output received: 09:01:49 INFO - Usage: mozinstall [options] installer 09:01:49 INFO - Options: 09:01:49 INFO - -h, --help show this help message and exit 09:01:49 INFO - -d DEST, --destination=DEST 09:01:49 INFO - Directory to install application into. [default: 09:01:49 INFO - "/builds/slave/test"] 09:01:49 INFO - --app=APP Application being installed. [default: firefox] 09:01:49 INFO - mkdir: /builds/slave/test/build/application 09:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:02:09 INFO - Reading from file tmpfile_stdout 09:02:09 INFO - Output received: 09:02:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:02:09 INFO - Running post-action listener: _resource_record_post_action 09:02:09 INFO - [mozharness: 2017-03-05 17:02:09.267132Z] Finished install step (success) 09:02:09 INFO - [mozharness: 2017-03-05 17:02:09.267261Z] Running run-tests step. 09:02:09 INFO - Running pre-action listener: _resource_record_pre_action 09:02:09 INFO - Running main action method: run_tests 09:02:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:02:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:02:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:02:09 INFO - 2017-03-05 09:02:09.281 screenresolution[1627:7987] starting screenresolution argv=screenresolution get 09:02:09 INFO - 2017-03-05 09:02:09.284 screenresolution[1627:7987] Display 0: 1600x1200x32@60 09:02:09 INFO - 2017-03-05 09:02:09.290 screenresolution[1628:7990] starting screenresolution argv=screenresolution list 09:02:09 INFO - Available Modes on Display 0 09:02:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:02:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:02:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:02:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:02:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:02:09 INFO - Intel Iris: 09:02:09 INFO - Chipset Model: Intel Iris 09:02:09 INFO - Type: GPU 09:02:09 INFO - Bus: Built-In 09:02:09 INFO - VRAM (Dynamic, Max): 1536 MB 09:02:09 INFO - Vendor: Intel (0x8086) 09:02:09 INFO - Device ID: 0x0a2e 09:02:09 INFO - Revision ID: 0x0009 09:02:09 INFO - Displays: 09:02:09 INFO - Display: 09:02:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:02:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:02:09 INFO - Display Serial Number: 1600x1200 60 09:02:09 INFO - Main Display: Yes 09:02:09 INFO - Mirror: Off 09:02:09 INFO - Online: Yes 09:02:09 INFO - Rotation: Supported 09:02:09 INFO - Return code: 0 09:02:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:02:09 INFO - Python 2.7.3 09:02:09 INFO - Return code: 0 09:02:09 INFO - grabbing minidump binary from tooltool 09:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:09 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 0x10c5bdd30>, '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 0x10c4e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:02:09 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:02:09 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:02:09 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:02:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:09 INFO - Return code: 0 09:02:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:02:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:02:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:02:09 INFO - ENV: RUST_BACKTRACE is now 1 09:02:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:02:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:02:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--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:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0078 --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:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jrLaerj9C9/Render', 09:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:09 INFO - 'HOME': '/Users/cltbld', 09:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:09 INFO - 'LOGNAME': 'cltbld', 09:02:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:02:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:09 INFO - 'MOZ_NO_REMOTE': '1', 09:02:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:02:09 INFO - 'NO_EM_RESTART': '1', 09:02:09 INFO - 'PAGER': '/bin/cat', 09:02:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:09 INFO - 'PWD': '/builds/slave/test', 09:02:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:09 INFO - 'RUST_BACKTRACE': '1', 09:02:09 INFO - 'SHELL': '/bin/bash', 09:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JHVDSYAays/Listeners', 09:02:09 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 09:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:09 INFO - 'USER': 'cltbld', 09:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:09 INFO - 'XPC_FLAGS': '0x0', 09:02:09 INFO - 'XPC_SERVICE_NAME': '0', 09:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--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:02:09 INFO - mozversion application_buildid: 20170305080023 09:02:09 INFO - mozversion application_changeset: 8d026c60151005ad942e3d4389318fe28a0c8c54 09:02:09 INFO - mozversion application_display_name: Nightly 09:02:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:09 INFO - mozversion application_name: Firefox 09:02:09 INFO - mozversion application_remotingname: firefox 09:02:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:02:09 INFO - mozversion application_vendor: Mozilla 09:02:09 INFO - mozversion application_version: 54.0a1 09:02:09 INFO - mozversion platform_buildid: 20170305080023 09:02:09 INFO - mozversion platform_changeset: 8d026c60151005ad942e3d4389318fe28a0c8c54 09:02:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:02:09 INFO - mozversion platform_version: 54.0a1 09:02:09 INFO - using testdate: 1488733329 09:02:09 INFO - actual date: 1488733329 09:02:09 INFO - starting webserver on 'localhost:49242' 09:02:09 INFO - SUITE-START | Running 2 tests 09:02:09 INFO - TEST-START | dromaeo_css 09:02:09 INFO - operating with platform_type : mac_ 09:02:09 INFO - Initialising browser for dromaeo_css test... 09:02:09 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpRE2adi/profile http://localhost:49242/getInfo.html) 09:02:12 INFO - PROCESS | 1638 | 2017-03-05 09:02:12.743 plugin-container[1641:8182] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 09:02:12 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:12 INFO - PROCESS | 1638 | 2017-03-05 09:02:12.746 plugin-container[1641:8182] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 09:02:12 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:12 INFO - PROCESS | 1638 | 2017-03-05 09:02:12.746 plugin-container[1641:8182] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:02:12 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 09:02:12 INFO - PROCESS | 1638 | colorDepth:24 09:02:12 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 09:02:12 INFO - PROCESS | 1638 | __metrics 09:02:12 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 09:02:13 INFO - TEST-INFO | 1638: exit 0 09:02:13 INFO - Browser initialized. 09:02:13 INFO - Running cycle 1/1 for dromaeo_css test... 09:02:13 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpRE2adi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:16 INFO - PROCESS | 1642 | 2017-03-05 09:02:16.036 plugin-container[1643:8313] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 09:02:16 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:16 INFO - PROCESS | 1642 | 2017-03-05 09:02:16.038 plugin-container[1643:8313] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:02:16 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:16 INFO - PROCESS | 1642 | 2017-03-05 09:02:16.038 plugin-container[1643:8313] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:02:25 INFO - PROCESS | 1642 | RSS: Main: 227332096 09:02:25 INFO - PROCESS | 1642 | 09:05:29 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:05:31 INFO - PROCESS | 1642 | RSS: Main: 254033920 09:05:31 INFO - PROCESS | 1642 | 09:07:14 INFO - PROCESS | 1642 | ************************* 09:07:14 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 09:07:14 INFO - PROCESS | 1642 | 09:07:14 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:07:14 INFO - PROCESS | 1642 | Full stack: 09:07:14 INFO - PROCESS | 1642 | ************************* 09:08:33 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:08:34 INFO - PROCESS | 1642 | RSS: Main: 257548288 09:08:34 INFO - PROCESS | 1642 | 09:11:36 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:11:37 INFO - PROCESS | 1642 | RSS: Main: 269746176 09:11:37 INFO - PROCESS | 1642 | 09:14:39 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:14:39 INFO - PROCESS | 1642 | RSS: Main: 262569984 09:14:39 INFO - PROCESS | 1642 | 09:17:42 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:17:43 INFO - PROCESS | 1642 | RSS: Main: 272343040 09:17:43 INFO - PROCESS | 1642 | 09:20:46 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:20:46 INFO - PROCESS | 1642 | RSS: Main: 266301440 09:20:46 INFO - PROCESS | 1642 | 09:20:46 INFO - PROCESS | 1642 | __start_tp_report 09:20:46 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 09:20:46 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 09:20:46 INFO - PROCESS | 1642 | |i|pagename|runs| 09:20:46 INFO - PROCESS | 1642 | |0;dojo.html;3422.6748458896395;3428.348613763383;3429.0570093224364;3434.467657513086;3458.152752575619;5765.365809760923;5824.825255242345;5854.7658093676255;5898.616589921658;5938.851528711784;5876.44173803488;5952.821415357535;5971.4327138921735;6042.395760423956;6103.05402662588;5390.272313237702;5404.243405923174;5423.159410291411;5494.340679118512;5497.917531237014;5421.864453388677;5430.348358197022;5525.281713377275;5526.834194974157;5586.13414920688;5311.760970756325;5365.061114304981;5467.261919640838;5484.780608775644;5495.203195536645;5429.077056900336;5434.130539113762;5449.264349312832;5450.127979523297;5480.479354461319;5291.814786482455;5384.219288203228;5407.486288802596;5422.6475279106735;5468.48049435303;5690.374058853523;5793.768249270024;5796.043652797283;5796.942030579725;5851.151583020438;6249.187605611287;6259.155014073133;6265.968670156665;6292.528060395489;6297.874042519124;6366.649834259114;6369.140166077566;6383.904241436382;6385.904211436832;6451.741930322782;6148.877022459616;6153.046277826944;6163.167972323679;6184.412480814361;6189.288231853395;6114.266954506099;6293.96853015732;6377.840553986095;6429.710663020175;6435.935640643627;6146.200993870768;6245.656488893154;6295.433410993032;6307.022411526221;6314.431701146918;6171.876562468726;6331.050342448578;6362.650054247061;6392.968035159887;6403.359664033559;6309;6327.114204011442;6332;6342.873142537216;6348.333424990402;6206.348333425016;6252.468540174045;6297.433231009231;6321.589096708699;6334.208223972004;5174.353205849269;5194.818181363709;5200.739963001834;5206.140986737221;5239.659422137549;4607.216773148572;4622.653301002439;4627.768611569408;4629.421322334708;4650.255959046536;5871.471567558855;5880.411958804086;6089.208402907682;6097.603655762361;6117.694115294306;5177.275181474596;6288.496920246369;6296.685165741695;6299.275583307978;6334.9366506334345;6050.42520960508;6077.027675571976;6079.118527813443;6106.969465152646;6107.175531303306;4813.566778989908;5677.829665110054;5689.317281926195;5690.231818704422;5710.2576665033275;6287.559870808999;6335.841603959938;6431.324710905287;6465.3857883501;6520.380563846522;5477.849651573214;6274.042045623866;6367.872642547216;6394.360563943568;6397.904031439625;5572.275604171432;6575.967120164369;6610.07458955737;6612.537122401386;6620;6167.907481387872;6235.657038862724;6252.968735156295;6274.435300822948;6348.365163483615;6222.968885155546;6322.778702745381;6339;6371.522135839923;6376.979683250657;6144.754209831557;6165.753369865155;6305.054241863757;6307.779227727191;6323.588966717149;6119.472088496418;6196.628202307876;6220.098085777447;6304.369563043659;6319.6208227506495;5927.4665280125;6051.364606716232;6262.655553944394;6296.779612713533;6301.558890877594;5548.916766248429;6104.38956104386;6141.263048434216;6183.876322473615;6194.504439644727;6187.721552530237;6257.217847769029;6274.1843560336865;6316.842078948063;6330.240371155491 09:20:46 INFO - PROCESS | 1642 | |1;ext.html;689.54145493247;699.6606645776801;793.777742232175;930.1117432851619;932.4405356785925;36015.27969440613;36811.631883681155;38139.237215255715;38193.42709859354;38197.236055278874;34684.61261549541;34925.476117858256;34941.825290873574;34945;35015;7646;7794.3764498839955;10305.021023002802;10599.311044782104;10702.250842441032;10460.424676642782;10554.208434367414;10555;10572.947135264312;10596.099331556827;10541.947290263537;10546.314489558192;10547.05076543111;10581.894181058167;10587.04716575508;10505.789884202311;10518;10532.842007369896;10547.472626368652;10555.577776888915;9236.79921610193;10197.286189966706;10294.43380614066;10329.74175645615;10344.017318354745;32120.839395803105;32241.838790805898;32245.355092898244;32277.354452911044;32279.83860080708;3632.7093832493338;3653.8355773990234;3660.816959152032;3687.760295580779;3820.59883712209;24400.633990490158;24469.38826529333;24542.263732088068;25224.369390765194;25309.87345063263;3021.6071910651694;3065.647450543194;3069.2020074780717;3132.7807053506262;3171.841407929617;323574;325858;326363;327188.9999999976;327935;34645.307093857984;42326.190642842295;42373.643953407234;42422.78788606099;42545;35950.1012474685;43429.91425214332;43612.56387436148;43732;43843;10442.738931526772;12910.741785164431;12926.353682315847;12927.095103342865;12930.870691292967;10728.570857165625;10797.46012699362;10891;10896.78206435867;10900.182486313559;10141.442220677933;10399.54406383107;10725.981031801966;11189.272697274813;11318.377489238006;5605.747741351649;5634.352049514277;5640.971795140998;5650.802221922295;5653.717314134276;1123.3147779854282;1127.6504283671925;1135;1151.1999160583482;1152.6714886257544;22032.669509957363;23813.928373223247;25145.622815657778;25443.982240710164;25635.97456101735;1230.5693007447321;1234.2656119608819;1236.0544183699476;1282.3652292115462;1297.3059413213884;11210.831837522444;11968.521259149711;12276.570320038934;12392.380380980914;13254.867451325363;372079;373003;373570;375316;376165;36943.445848312294;51898.48101519012;52239.47760522422;52284.95430091377;52503.737481312346;1348.2921466230307;1367.8358596968228;1382.6336020954454;1387.1815628779068;1388.6389538719804;23168.4207894804;23707.762922370555;25957.740422595532;26037.479250415265;26059;38351.80824095861;38560.03599910025;38567.80716096402;38595.99999999888;38758.80620596879;411014;413552;414396;415649;416931;20250.89874550618;20569.897150514153;20695.172193112106;20831;20864.791352086286;31119.844400777853;33975.980720578424;35395.82302088473;35656.64343356533;35809.82095089508;28872.679641841456;28891.5666265002;29567.965121220655;30176.54735179018;30431.69568304377;30022.549661754656;30184.09447716572;30430.08709738623;30461.23846903845;30466.69533304727;29059.418811623225;29809.85095074511;30183.09450716482;30272.84863575668;30475.542866856573;25479.36301592475;25520.74479255258;25931.351716206493;26529.142959992616;26630.20109396721;17846.46460606184;19763.50591235173;21195.046222919696;21406.7859321411;21412 09:20:46 INFO - PROCESS | 1642 | |2;jquery.html;360.5349099661437;380.7962739934136;395.274670978754;398.8703671306826;436.90555159325885;17318;19619.607607847833;19830.70253946192;20619.896900515512;20753.481162970915;20928.790712092876;21132.788672113275;21470.89264553675;21517.24689635863;21609.13563457464;6357.65032923189;6648.434883034948;6669.0996715443525;6685.632290224035;6703.363180497845;6381.3937675920715;6560;6578.1448411706415;6617.305182955793;6693.799186024427;6607.603543787364;6618.470522358224;6639.73441062357;6640.966795166041;6643.302453242412;6343.14367560378;6511.967440162793;6585.769498067592;6595.571287866297;6597.076409302654;5243;6400.23197216337;6418.358164183544;6437;6603.801885943381;20440.591188176302;24253.87873060641;24604.630930536056;25090.49819003628;25218.87390563054;2793.25978615667;2818.1263808219314;2828.632277803893;2861.756750676195;2890.219640697021;14662.607052330011;15457.922710386489;15598.986065905794;15990.841236433049;16394.754078688828;1722.6899158151527;1755.3680674957004;1782.6523827853573;1794.1298470241923;1803.6663217304708;125098;127591.08613370807;146168.53831461657;147123;148610;16884.915575422045;20690;20902.477438063866;20903.477413064797;20912.47718807042;30616.23459413488;30708.232294192825;30736.924207652624;30856.69143308583;30940.535891961637;9745.853812192823;9916.752081198028;10058.547365368577;10091.44497052669;10175.135113515285;8197.83604327922;8271.421000529905;8331.33349332052;8459.577021149042;8470.407071504935;7575.848483030309;7696.692132314644;9429.62281508732;9465.763355916019;9537.570809313598;3723.702103831687;3860;3914.3345631242755;3936.586658400884;3949.1706741584017;717.5551158281899;761.7333933123473;847.8007668197969;884.7832281091174;906.2885634776801;20419.4895127623;20937.685934710993;21055.368338949855;21210.04554795038;21443.14227430885;887.7083843008407;943.7216021273741;974.6101559376306;974.7611835100446;991.8115558043949;6586.769463068866;6590.736370545124;6669.899951500634;6772.288909664513;6808.183018037768;623731;640180;645511.4488551201;648821.7558912175;648967;97548.51225743968;102242.93271201875;150153.0741775051;153126;160024;1228.9078319126102;1254.2161149281699;1283.5764197814512;1291.5156816193928;1292.7479141567303;11759;11951.521939122338;12061.75876482483;12061.879381206076;12095.75808483843;59670.403295966484;61312.69343653253;61898.38101618926;62143;62149.757004860556;80897.59551202442;82618;84605;90044.29867103735;92104.53947730218;7568.167501574712;10440.582376704846;10910.23628346008;11219.382933938712;11471.598494052669;9408.81182376335;11402.543898243977;12056.819147712617;12809.807852882028;12857.357132143168;10893.291936024134;11104.111671066457;11116.333020018825;11295.548178073113;11399.430028498376;10147.492625368553;10585.470726463493;10767.676969690921;10885.183611229031;11029.558817647205;11339.525861637934;11365.659030229106;11500.654980350602;11590.768184636428;11641.126915481203;11332.716682082684;11692.532298707956;11733.530658773894;11742.17804753659;11786.233894796811;11077.778444431227;11276.94361528187;11301.9828215461;11467.197296189186;11534.653960381202 09:20:46 INFO - PROCESS | 1642 | |3;mootools.html;1470.985290147098;1500.1449173970834;1537.4311504743246;1555.6577552938354;1558.4856997190925;10242.641507547238;10612.20408469366;10874.891251087487;10901.454927253646;10924.235303528747;10676.466176691174;10837.566497340096;10844.837327440095;10884.455777211148;10891.945540272307;2318.6290193569034;2350.565145448089;2350.882455877208;2355.49356888269;2359.1624973134462;1993.6810110382341;2293.4037150340946;2322.698049253603;2341.4146463384154;2342.1334106380577;2300.3214051854743;2310.526342099874;2321.3152120124605;2322.454223257533;2326.6626339180807;2140.646793279107;2172.935261721745;2275.6245219538755;2282.7260728712495;2291.186628746796;1882.218879165145;1954.9413517594494;1984.335247692025;2318.9188378406675;2339.695839540866;10832.458377081115;10847.132229421706;10860.837087443695;10904.945475272652;10909.83635245472;5737.196792449061;5867.237259156325;5926.140709597084;5952.7321270542925;5996.910046349309;3274.4269752793166;3305;3577.3560759063616;3587.641235876418;3590.856365745367;1201.5974648492765;1216.4039620585938;1802.4412432145946;1820.8088150744113;1910.6656335141433;12592.677768834232;12674.556390526288;14059.50791722295;14489.130652160904;14844.332005059798;3982.104026594051;4530.4336957879605;4601.12578610063;4690.953090469052;4698.718076915396;11217.326960382403;11276.71808204785;11299.152563557602;11327.490262938187;11345.659630211107;5641.26663533738;5730.1404789281105;5837.3287071986415;5839.007368747323;5858.121281807763;5096.770645320969;5220.686758794484;5307.124324486494;5315.734213289397;5384.488473595002;3646.762960407565;3723.957291958256;3745.887623371303;3782.7541209821825;3786.8260839139953;1030.6959446963165;1035.803197392493;1046.398320965448;1046.837740150278;1052.3159946034984;903.1961554216753;918.5453200665713;938.5729493080632;941.8540126280437;972.1299437003842;6344.416913116428;6421.656095525193;6549.377809108127;6624.86750264992;6680.86638267232;958.7219706285099;984.8768903887014;1008.1027885182194;1010.1817527802357;1010.9140723038624;3828.9234215315537;4183.602557757008;4201.768902710381;4312.159128969821;4317.438732964694;12728;13148.211107333591;13187.53843615469;13188.868111318956;13207.801882971764;12768.233905965672;12913.160644558073;13120.671983200305;13135.671608209872;13161.473541058442;8455.619497122645;8515.872261916076;8540.658373664985;8919.598618062073;10045.598176073021;6616.503762217757;6632.369924857131;6751.527393082437;6762.6618669065365;6897.137857767779;11813.173077884465;11941.462634181657;12079.094067945114;12136.393180341125;12162.878371216175;11833.704157396134;11943.16397852142;12221.633350999484;12244.632661020183;12299.954503866913;7951;7975.5613441258965;7985.760427187193;8030.156833532628;8058.194180581895;9547.42715437076;9680.177184939359;9780;9798.902010979798;9920.851187232342;8628.352873534635;8633.654653813777;8645.270102683566;8753.737387878375;8764.035956044703;7325.230850760807;7375.262473752582;8159.428839981382;8473.872891906503;8475.194856488624;6601.504887133388;7586.393088553013;7664.233576642291;7690.1928046907815;7711.38308935295;5435.429279925551;5565.38780734127;5652.378238393691;5696.11710184922;5700.3444603871085;5674.375818659862;5808.94191058084;5813.505852002458;5827.621204621686;5847.093700476433 09:20:46 INFO - PROCESS | 1642 | |4;prototype.html;314.5910316588435;357.96778289953915;371.6506483905131;382.95213098362706;388.92804831106247;4973.403191617011;5059.873503162418;5515.8345249642525;5521.585881058916;5526.364468086166;4412.9621396422235;5068.8225912093085;5169.663971841827;5389.9759045781275;5455.945440545594;4026.65107189092;4314.741115533078;4338.761368124752;4403.713758605696;4409.80155892984;3492.794985729919;3732.514773079496;3828.4257361395735;4445.777711114448;4460.420145381096;3997.8800635980965;4244.493204912258;4446.333050042504;4466.97766511167;4483.932741008871;3768.3970564709653;4295.2912769393015;4402.40567523385;4416.801243943998;4417.823287068513;4029.657479114279;4037.858674946392;4047.332190188615;4062.9730850516553;4066.593340665939;4499.370088187657;4816.54242846929;5202.92195617066;5222.32109825724;5275.525202731734;4858.975705121487;4914.729689867056;5143.588512918957;5157.968406318714;5158.188787111034;5820.272465941757;5832.970835145839;5837.854053648693;5845.678487683176;5871.500922421556;4317.892052698676;4327.675424343155;4587.288970209623;4597.402337696111;4619.376384188159;5098.796048158107;5324.6538974966115;5569.387367389584;5876.9118463223085;6153.507719382484;4111.6299533042165;4700;4701.224297990793;4720.457147428021;4731.793392684912;4171.728837625545;4410.33844923264;4510.1656193604285;4783.86656739836;4849.9030019399415;4362.934555981663;4471.5081341052455;4761.833335833229;4909.748014256342;5147.6654017488745;5696.601237913306;5749.3963133871175;5790.467814633071;5800.50695690871;5822.243108395966;1498.9432450122638;1538.6152462783584;1546.6056155680456;1616.3292233722873;1617.4904904954901;1064.7391389109719;1090.4002798460815;1095.9452027398597;1106.3085571517802;1205.2406983600276;4562.840300589463;4703.341532185496;4740.597049250783;5006.299118123393;5390.326209223847;5522.98411079961;5527.972360138174;5788.87117012187;6060.575759696779;6078.6048906820915;4856;4867.221244600847;5052.646314758005;5082.983403319277;5509;2835.5189209441737;3117.072488664254;3130.906072817819;3144.748420126384;3147.748180145583;5678.564468496583;6234.376562343729;6245.564939283376;6278.654673992883;6291.842703932438;5291.788328466818;5390.568754499631;5620.4098569649595;5977.342492325841;6222.12890195373;5181.974090129601;5377.784888604412;5995.310539288038;6222.066689996536;6269.247690277105;5448.782048718007;5452.945470545244;5623.662580245198;5991.88016239664;6076.72654730547;5237.450067742984;5758.827235182951;5995.430434108753;6002.939970600238;6013.759449621966;5682.5453963681975;5983.311919129182;6030.638161710312;6075.817725468243;6137.7851775187655;4134.772587507716;4141.97929010353;4165.312723400726;4729.43246810385;4747.548982846624;5244.767479642204;5640.774369025357;5811.820567287267;5880.296112770606;6044.24446944132;5696.458836410535;5783.768649253982;5832.891750567379;5865.181793643984;5900.262467191601;5306.548943339705;5663.543560341297;5820.417958204146;5884.439559341545;5937.524998000236;4732.645051621211;4851.854444366674;4903;5557.916631250454;5748.655080695172;5345.33183352081;5709.202490779571;5870.765169393348;5889.705514724333;5960.403959604005;4748.857534273978;5705.343885453226;5718.595926849088;5737.540996720336;5793.217915581343 09:20:46 INFO - PROCESS | 1642 | |5;yui.html;717.9425645948324;757.7688804914499;773.7059917231451;788.4310222656915;796.8525822722796;14706.264686765659;15508.844911550896;15772.526824195293;15820.446284380034;16879.57801054971;15782.684346313066;17023.48929532116;17078.402255921046;17197.312107515714;17229.913850430763;915.7252824152761;950.363256618064;954.7947191353858;960.3133759362055;961.5865177973469;954.4464210757778;959.1319855530721;961.1637875048682;961.2070042215165;963.8554216867456;941.5715849288591;989.0356902020544;992.5682328187225;993.7714325705092;994.5474808961941;978.9755256118654;978.9951050244739;980.3480685344238;982.1504398695113;984.0307297312141;721.9314165154302;725.0900120348981;731.2139450091173;734.8016035670393;737.72704099483;14108;14732.337044833008;15757.2121393931;15800.130992795392;16021.198940052951;13447.462101515928;13448.663783405394;13692.383842727102;13721.656958576015;13748.862511374959;9078.668593944869;11624.360660163687;11699.30889947854;11704.239224450383;11859.762804743857;3510.771799833028;3578.471692316852;3635.8000309983204;3635.909102272438;3758.267137908109;82634;91196;96124.51937740267;102182;104248;9090.000099988996;10523.368597884151;10630.787384252271;11159.944200278947;11282.323060616225;21399.46501337448;21746;21780.237691680715;21821.23625673094;21870.23454179096;7685.769426917202;7717.22827717235;7752;7756.263155000266;7765.378769698411;4206.221848957953;4222;4231.132617813341;4275.551067137968;4350.282203436397;2878.553824157259;3343.548620936191;3415.777974431654;3419.26485805553;3423.4180189367867;566.2723400430427;570.1106274212159;585.9209006784115;592.4283066840519;593.946544810969;631.6604924170974;641.371455973149;673.6261374936859;675.3584095109666;695.9965200173966;9156;9718;9870.506474676382;9949.353292035994;9951.751206219902;620.4756980351596;659.8482349059743;675.4798804920184;680.2381332907175;696.2376198063113;4557.886052848639;4828.855134345975;4883.671035759415;4946.653734238569;4952.306677065214;86456;97229;100731;101324;102854.48572757088;45722.542774572496;73661.6316918412;77442.2255777435;89638;93855;71145;72492;72794.63602681953;73351.63324183345;74218;10484.056434920894;10541.904615674222;10668;10690.679279621623;10770.892291076989;38600;46631.53368466408;46889.53110468988;47444;47653;60697.57209711562;61097.77804443975;61718;62330.37669623246;62827.37172628215;10314.742131446774;12297.893189612978;12317.815232771694;12340.136190466581;12722.363881805688;14086.22525761093;14101.576952691436;14283.285835707966;14494.710105798036;14696.191709456083;10772.407517586294;11445.084393248686;11646.301221926711;12495.937520312704;13782.173069615856;10821.56713731442;13025.348732563145;13127.212367257995;13295.800562991757;13511.729765404834;12485.375731213584;12510.02645489464;12663;12832.935835321137;13324.400401982128;10633.946830265799;10681.732956676145;10789.298257832712;10821.40482273451;11159.888401115884;8122.837543248984;9174.67888623895;10618.575256989943;10634.680959571226;10743.946280268548 09:20:46 INFO - PROCESS | 1642 | __end_tp_report 09:20:46 INFO - PROCESS | 1642 | __start_cc_report 09:20:46 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,427 09:20:46 INFO - PROCESS | 1642 | __end_cc_report 09:20:46 INFO - PROCESS | 1642 | __startTimestamp1488734446549__endTimestamp 09:20:46 INFO - PROCESS | 1642 | 09:20:46 INFO - PROCESS | 1642 | ------- Summary: start ------- 09:20:46 INFO - PROCESS | 1642 | Number of tests: 6 09:20:46 INFO - PROCESS | 1642 | 09:20:46 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5899.86 Median:6148.88 stddev:628.04 (10.2%) stddev-sans-first:599.15 09:20:46 INFO - PROCESS | 1642 | Values: 3422.7 3428.3 3429.1 3434.5 3458.2 5765.4 5824.8 5854.8 5898.6 5938.9 5876.4 5952.8 5971.4 6042.4 6103.1 5390.3 5404.2 5423.2 5494.3 5497.9 5421.9 5430.3 5525.3 5526.8 5586.1 5311.8 5365.1 5467.3 5484.8 5495.2 5429.1 5434.1 5449.3 5450.1 5480.5 5291.8 5384.2 5407.5 5422.6 5468.5 5690.4 5793.8 5796.0 5796.9 5851.2 6249.2 6259.2 6266.0 6292.5 6297.9 6366.6 6369.1 6383.9 6385.9 6451.7 6148.9 6153.0 6163.2 6184.4 6189.3 6114.3 6294.0 6377.8 6429.7 6435.9 6146.2 6245.7 6295.4 6307.0 6314.4 6171.9 6331.1 6362.7 6393.0 6403.4 6309.0 6327.1 6332.0 6342.9 6348.3 6206.3 6252.5 6297.4 6321.6 6334.2 5174.4 5194.8 5200.7 5206.1 5239.7 4607.2 4622.7 4627.8 4629.4 4650.3 5871.5 5880.4 6089.2 6097.6 6117.7 5177.3 6288.5 6296.7 6299.3 6334.9 6050.4 6077.0 6079.1 6107.0 6107.2 4813.6 5677.8 5689.3 5690.2 5710.3 6287.6 6335.8 6431.3 6465.4 6520.4 5477.8 6274.0 6367.9 6394.4 6397.9 5572.3 6576.0 6610.1 6612.5 6620.0 6167.9 6235.7 6253.0 6274.4 6348.4 6223.0 6322.8 6339.0 6371.5 6377.0 6144.8 6165.8 6305.1 6307.8 6323.6 6119.5 6196.6 6220.1 6304.4 6319.6 5927.5 6051.4 6262.7 6296.8 6301.6 5548.9 6104.4 6141.3 6183.9 6194.5 6187.7 6257.2 6274.2 6316.8 6330.2 09:20:46 INFO - PROCESS | 1642 | 09:20:46 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:49156.82 Median:29688.91 stddev:98931.36 (333.2%) stddev-sans-first:99141.96 09:20:46 INFO - PROCESS | 1642 | Values: 689.5 699.7 793.8 930.1 932.4 36015.3 36811.6 38139.2 38193.4 38197.2 34684.6 34925.5 34941.8 34945.0 35015.0 7646.0 7794.4 10305.0 10599.3 10702.3 10460.4 10554.2 10555.0 10572.9 10596.1 10541.9 10546.3 10547.1 10581.9 10587.0 10505.8 10518.0 10532.8 10547.5 10555.6 9236.8 10197.3 10294.4 10329.7 10344.0 32120.8 32241.8 32245.4 32277.4 32279.8 3632.7 3653.8 3660.8 3687.8 3820.6 24400.6 24469.4 24542.3 25224.4 25309.9 3021.6 3065.6 3069.2 3132.8 3171.8 323574.0 325858.0 326363.0 327189.0 327935.0 34645.3 42326.2 42373.6 42422.8 42545.0 35950.1 43429.9 43612.6 43732.0 43843.0 10442.7 12910.7 12926.4 12927.1 12930.9 10728.6 10797.5 10891.0 10896.8 10900.2 10141.4 10399.5 10726.0 11189.3 11318.4 5605.7 5634.4 5641.0 5650.8 5653.7 1123.3 1127.7 1135.0 1151.2 1152.7 22032.7 23813.9 25145.6 25444.0 25636.0 1230.6 1234.3 1236.1 1282.4 1297.3 11210.8 11968.5 12276.6 12392.4 13254.9 372079.0 373003.0 373570.0 375316.0 376165.0 36943.4 51898.5 52239.5 52285.0 52503.7 1348.3 1367.8 1382.6 1387.2 1388.6 23168.4 23707.8 25957.7 26037.5 26059.0 38351.8 38560.0 38567.8 38596.0 38758.8 411014.0 413552.0 414396.0 415649.0 416931.0 20250.9 20569.9 20695.2 20831.0 20864.8 31119.8 33976.0 35395.8 35656.6 35809.8 28872.7 28891.6 29568.0 30176.5 30431.7 30022.5 30184.1 30430.1 30461.2 30466.7 29059.4 29809.9 30183.1 30272.8 30475.5 25479.4 25520.7 25931.4 26529.1 26630.2 17846.5 19763.5 21195.0 21406.8 21412.0 09:20:46 INFO - PROCESS | 1642 | 09:20:46 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:38372.42 Median:2805.69 stddev:107337.18 (3825.7%) stddev-sans-first:107600.34 09:20:46 INFO - PROCESS | 1642 | Values: 360.5 380.8 395.3 398.9 436.9 17318.0 19619.6 19830.7 20619.9 20753.5 20928.8 21132.8 21470.9 21517.2 21609.1 6357.7 6648.4 6669.1 6685.6 6703.4 6381.4 6560.0 6578.1 6617.3 6693.8 6607.6 6618.5 6639.7 6641.0 6643.3 6343.1 6512.0 6585.8 6595.6 6597.1 5243.0 6400.2 6418.4 6437.0 6603.8 20440.6 24253.9 24604.6 25090.5 25218.9 2793.3 2818.1 2828.6 2861.8 2890.2 14662.6 15457.9 15599.0 15990.8 16394.8 1722.7 1755.4 1782.7 1794.1 1803.7 125098.0 127591.1 146168.5 147123.0 148610.0 16884.9 20690.0 20902.5 20903.5 20912.5 30616.2 30708.2 30736.9 30856.7 30940.5 9745.9 9916.8 10058.5 10091.4 10175.1 8197.8 8271.4 8331.3 8459.6 8470.4 7575.8 7696.7 9429.6 9465.8 9537.6 3723.7 3860.0 3914.3 3936.6 3949.2 717.6 761.7 847.8 884.8 906.3 20419.5 20937.7 21055.4 21210.0 21443.1 887.7 943.7 974.6 974.8 991.8 6586.8 6590.7 6669.9 6772.3 6808.2 623731.0 640180.0 645511.4 648821.8 648967.0 97548.5 102242.9 150153.1 153126.0 160024.0 1228.9 1254.2 1283.6 1291.5 1292.7 11759.0 11951.5 12061.8 12061.9 12095.8 59670.4 61312.7 61898.4 62143.0 62149.8 80897.6 82618.0 84605.0 90044.3 92104.5 7568.2 10440.6 10910.2 11219.4 11471.6 9408.8 11402.5 12056.8 12809.8 12857.4 10893.3 11104.1 11116.3 11295.5 11399.4 10147.5 10585.5 10767.7 10885.2 11029.6 11339.5 11365.7 11500.7 11590.8 11641.1 11332.7 11692.5 11733.5 11742.2 11786.2 11077.8 11276.9 11302.0 11467.2 11534.7 09:20:46 INFO - PROCESS | 1642 | 09:20:46 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:6478.72 Median:3441.18 stddev:3981.93 (115.7%) stddev-sans-first:3975.32 09:20:46 INFO - PROCESS | 1642 | Values: 1471.0 1500.1 1537.4 1555.7 1558.5 10242.6 10612.2 10874.9 10901.5 10924.2 10676.5 10837.6 10844.8 10884.5 10891.9 2318.6 2350.6 2350.9 2355.5 2359.2 1993.7 2293.4 2322.7 2341.4 2342.1 2300.3 2310.5 2321.3 2322.5 2326.7 2140.6 2172.9 2275.6 2282.7 2291.2 1882.2 1954.9 1984.3 2318.9 2339.7 10832.5 10847.1 10860.8 10904.9 10909.8 5737.2 5867.2 5926.1 5952.7 5996.9 3274.4 3305.0 3577.4 3587.6 3590.9 1201.6 1216.4 1802.4 1820.8 1910.7 12592.7 12674.6 14059.5 14489.1 14844.3 3982.1 4530.4 4601.1 4691.0 4698.7 11217.3 11276.7 11299.2 11327.5 11345.7 5641.3 5730.1 5837.3 5839.0 5858.1 5096.8 5220.7 5307.1 5315.7 5384.5 3646.8 3724.0 3745.9 3782.8 3786.8 1030.7 1035.8 1046.4 1046.8 1052.3 903.2 918.5 938.6 941.9 972.1 6344.4 6421.7 6549.4 6624.9 6680.9 958.7 984.9 1008.1 1010.2 1010.9 3828.9 4183.6 4201.8 4312.2 4317.4 12728.0 13148.2 13187.5 13188.9 13207.8 12768.2 12913.2 13120.7 13135.7 13161.5 8455.6 8515.9 8540.7 8919.6 10045.6 6616.5 6632.4 6751.5 6762.7 6897.1 11813.2 11941.5 12079.1 12136.4 12162.9 11833.7 11943.2 12221.6 12244.6 12300.0 7951.0 7975.6 7985.8 8030.2 8058.2 9547.4 9680.2 9780.0 9798.9 9920.9 8628.4 8633.7 8645.3 8753.7 8764.0 7325.2 7375.3 8159.4 8473.9 8475.2 6601.5 7586.4 7664.2 7690.2 7711.4 5435.4 5565.4 5652.4 5696.1 5700.3 5674.4 5808.9 5813.5 5827.6 5847.1 09:20:46 INFO - PROCESS | 1642 | 09:20:46 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4780.48 Median:5145.63 stddev:1366.23 (26.6%) stddev-sans-first:1328.30 09:20:46 INFO - PROCESS | 1642 | Values: 314.6 358.0 371.7 383.0 388.9 4973.4 5059.9 5515.8 5521.6 5526.4 4413.0 5068.8 5169.7 5390.0 5455.9 4026.7 4314.7 4338.8 4403.7 4409.8 3492.8 3732.5 3828.4 4445.8 4460.4 3997.9 4244.5 4446.3 4467.0 4483.9 3768.4 4295.3 4402.4 4416.8 4417.8 4029.7 4037.9 4047.3 4063.0 4066.6 4499.4 4816.5 5202.9 5222.3 5275.5 4859.0 4914.7 5143.6 5158.0 5158.2 5820.3 5833.0 5837.9 5845.7 5871.5 4317.9 4327.7 4587.3 4597.4 4619.4 5098.8 5324.7 5569.4 5876.9 6153.5 4111.6 4700.0 4701.2 4720.5 4731.8 4171.7 4410.3 4510.2 4783.9 4849.9 4362.9 4471.5 4761.8 4909.7 5147.7 5696.6 5749.4 5790.5 5800.5 5822.2 1498.9 1538.6 1546.6 1616.3 1617.5 1064.7 1090.4 1095.9 1106.3 1205.2 4562.8 4703.3 4740.6 5006.3 5390.3 5523.0 5528.0 5788.9 6060.6 6078.6 4856.0 4867.2 5052.6 5083.0 5509.0 2835.5 3117.1 3130.9 3144.7 3147.7 5678.6 6234.4 6245.6 6278.7 6291.8 5291.8 5390.6 5620.4 5977.3 6222.1 5182.0 5377.8 5995.3 6222.1 6269.2 5448.8 5452.9 5623.7 5991.9 6076.7 5237.5 5758.8 5995.4 6002.9 6013.8 5682.5 5983.3 6030.6 6075.8 6137.8 4134.8 4142.0 4165.3 4729.4 4747.5 5244.8 5640.8 5811.8 5880.3 6044.2 5696.5 5783.8 5832.9 5865.2 5900.3 5306.5 5663.5 5820.4 5884.4 5937.5 4732.6 4851.9 4903.0 5557.9 5748.7 5345.3 5709.2 5870.8 5889.7 5960.4 4748.9 5705.3 5718.6 5737.5 5793.2 09:20:46 INFO - PROCESS | 1642 | 09:20:46 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:19164.70 Median:4312.92 stddev:26845.88 (622.5%) stddev-sans-first:26885.46 09:20:46 INFO - PROCESS | 1642 | Values: 717.9 757.8 773.7 788.4 796.9 14706.3 15508.8 15772.5 15820.4 16879.6 15782.7 17023.5 17078.4 17197.3 17229.9 915.7 950.4 954.8 960.3 961.6 954.4 959.1 961.2 961.2 963.9 941.6 989.0 992.6 993.8 994.5 979.0 979.0 980.3 982.2 984.0 721.9 725.1 731.2 734.8 737.7 14108.0 14732.3 15757.2 15800.1 16021.2 13447.5 13448.7 13692.4 13721.7 13748.9 9078.7 11624.4 11699.3 11704.2 11859.8 3510.8 3578.5 3635.8 3635.9 3758.3 82634.0 91196.0 96124.5 102182.0 104248.0 9090.0 10523.4 10630.8 11159.9 11282.3 21399.5 21746.0 21780.2 21821.2 21870.2 7685.8 7717.2 7752.0 7756.3 7765.4 4206.2 4222.0 4231.1 4275.6 4350.3 2878.6 3343.5 3415.8 3419.3 3423.4 566.3 570.1 585.9 592.4 593.9 631.7 641.4 673.6 675.4 696.0 9156.0 9718.0 9870.5 9949.4 9951.8 620.5 659.8 675.5 680.2 696.2 4557.9 4828.9 4883.7 4946.7 4952.3 86456.0 97229.0 100731.0 101324.0 102854.5 45722.5 73661.6 77442.2 89638.0 93855.0 71145.0 72492.0 72794.6 73351.6 74218.0 10484.1 10541.9 10668.0 10690.7 10770.9 38600.0 46631.5 46889.5 47444.0 47653.0 60697.6 61097.8 61718.0 62330.4 62827.4 10314.7 12297.9 12317.8 12340.1 12722.4 14086.2 14101.6 14283.3 14494.7 14696.2 10772.4 11445.1 11646.3 12495.9 13782.2 10821.6 13025.3 13127.2 13295.8 13511.7 12485.4 12510.0 12663.0 12832.9 13324.4 10633.9 10681.7 10789.3 10821.4 11159.9 8122.8 9174.7 10618.6 10634.7 10743.9 09:20:46 INFO - PROCESS | 1642 | -------- Summary: end -------- 09:20:46 INFO - PROCESS | 1642 | 09:20:47 INFO - TEST-INFO | 1642: exit 0 09:20:47 INFO - TEST-OK | dromaeo_css | took 1117310ms 09:20:47 INFO - TEST-START | kraken 09:20:47 INFO - operating with platform_type : mac_ 09:20:47 INFO - Initialising browser for kraken test... 09:20:47 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpoBBuuV/profile http://localhost:49242/getInfo.html) 09:20:49 INFO - PROCESS | 1650 | 2017-03-05 09:20:49.865 plugin-container[1651:10800] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 09:20:49 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:49 INFO - PROCESS | 1650 | 2017-03-05 09:20:49.868 plugin-container[1651:10800] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 09:20:49 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:49 INFO - PROCESS | 1650 | 2017-03-05 09:20:49.868 plugin-container[1651:10800] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:20:49 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 09:20:49 INFO - PROCESS | 1650 | colorDepth:24 09:20:49 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 09:20:49 INFO - PROCESS | 1650 | __metrics 09:20:50 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 09:20:50 INFO - TEST-INFO | 1650: exit 0 09:20:50 INFO - Browser initialized. 09:20:50 INFO - Running cycle 1/1 for kraken test... 09:20:50 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpoBBuuV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:53 INFO - PROCESS | 1652 | 2017-03-05 09:20:53.108 plugin-container[1653:10927] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 09:20:53 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:53 INFO - PROCESS | 1652 | 2017-03-05 09:20:53.109 plugin-container[1653:10927] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c27, name = 'com.apple.CFPasteboardClient' 09:20:53 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:53 INFO - PROCESS | 1652 | 2017-03-05 09:20:53.109 plugin-container[1653:10927] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:21:02 INFO - PROCESS | 1652 | RSS: Main: 226390016 09:21:02 INFO - PROCESS | 1652 | 09:21:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:07 INFO - PROCESS | 1652 | RSS: Main: 241213440 09:21:07 INFO - PROCESS | 1652 | 09:21:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:21:10 INFO - PROCESS | 1652 | RSS: Main: 249380864 09:21:10 INFO - PROCESS | 1652 | 09:21:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:21:14 INFO - PROCESS | 1652 | RSS: Main: 252223488 09:21:14 INFO - PROCESS | 1652 | 09:21:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:21:17 INFO - PROCESS | 1652 | RSS: Main: 254275584 09:21:17 INFO - PROCESS | 1652 | 09:21:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:19 INFO - PROCESS | 1652 | RSS: Main: 254590976 09:21:19 INFO - PROCESS | 1652 | 09:21:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:23 INFO - PROCESS | 1652 | RSS: Main: 260546560 09:21:23 INFO - PROCESS | 1652 | 09:21:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:21:27 INFO - PROCESS | 1652 | RSS: Main: 259678208 09:21:27 INFO - PROCESS | 1652 | 09:21:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:21:31 INFO - PROCESS | 1652 | RSS: Main: 259362816 09:21:31 INFO - PROCESS | 1652 | 09:21:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:21:33 INFO - PROCESS | 1652 | RSS: Main: 259170304 09:21:33 INFO - PROCESS | 1652 | 09:21:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:21:36 INFO - PROCESS | 1652 | RSS: Main: 259121152 09:21:36 INFO - PROCESS | 1652 | 09:21:38 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:21:39 INFO - PROCESS | 1652 | RSS: Main: 259235840 09:21:39 INFO - PROCESS | 1652 | 09:21:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:21:43 INFO - PROCESS | 1652 | RSS: Main: 259784704 09:21:43 INFO - PROCESS | 1652 | 09:21:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:21:46 INFO - PROCESS | 1652 | RSS: Main: 268185600 09:21:46 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:21:48 INFO - PROCESS | 1652 | RSS: Main: 271294464 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | __start_tp_report 09:21:48 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 09:21:48 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 09:21:48 INFO - PROCESS | 1652 | |i|pagename|runs| 09:21:48 INFO - PROCESS | 1652 | |0;ai-astar;114;121;120;119;171;105;176;179;161;120 09:21:48 INFO - PROCESS | 1652 | |1;audio-beat-detection;145;114;110;152;125;149;137;112;144;112 09:21:48 INFO - PROCESS | 1652 | |2;audio-dft;202;211;240;283;238;200;198;200;198;242 09:21:48 INFO - PROCESS | 1652 | |3;audio-fft;84;61;60;86;66;86;65;60;72;59 09:21:48 INFO - PROCESS | 1652 | |4;audio-oscillator;90;71;87;72;73;91;115;70;70;86 09:21:48 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;90;95;104;89;90;91;137;121;88;90 09:21:48 INFO - PROCESS | 1652 | |6;imaging-darkroom;97;97;98;97;96;106;99;97;97;127 09:21:48 INFO - PROCESS | 1652 | |7;imaging-desaturate;98;146;152;91;109;148;124;95;90;96 09:21:48 INFO - PROCESS | 1652 | |8;json-parse-financial;68;81;101;78;67;65;68;66;61;67 09:21:48 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;57;58;64;61;63;77;50;55;60;83 09:21:48 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;92;91;93;88;113;110;108;89;88;88 09:21:48 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;151;160;166;155;135;147;143;144;194;154 09:21:48 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;178;189;172;175;161;213;219;168;219;172 09:21:48 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;68;70;66;67;74;105;89;92;69;74 09:21:48 INFO - PROCESS | 1652 | __end_tp_report 09:21:48 INFO - PROCESS | 1652 | __start_cc_report 09:21:48 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1021 09:21:48 INFO - PROCESS | 1652 | __end_cc_report 09:21:48 INFO - PROCESS | 1652 | __startTimestamp1488734508969__endTimestamp 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | ------- Summary: start ------- 09:21:48 INFO - PROCESS | 1652 | Number of tests: 14 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:138.60 Median:141.00 stddev:29.25 (20.7%) stddev-sans-first:29.64 09:21:48 INFO - PROCESS | 1652 | Values: 114.0 121.0 120.0 119.0 171.0 105.0 176.0 179.0 161.0 120.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:140.50 stddev:17.14 (12.2%) stddev-sans-first:17.30 09:21:48 INFO - PROCESS | 1652 | Values: 145.0 114.0 110.0 152.0 125.0 149.0 137.0 112.0 144.0 112.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:221.20 Median:224.50 stddev:28.55 (12.7%) stddev-sans-first:29.42 09:21:48 INFO - PROCESS | 1652 | Values: 202.0 211.0 240.0 283.0 238.0 200.0 198.0 200.0 198.0 242.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:69.90 Median:69.00 stddev:11.33 (16.4%) stddev-sans-first:10.81 09:21:48 INFO - PROCESS | 1652 | Values: 84.0 61.0 60.0 86.0 66.0 86.0 65.0 60.0 72.0 59.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:82.50 Median:79.50 stddev:14.39 (18.1%) stddev-sans-first:15.00 09:21:48 INFO - PROCESS | 1652 | Values: 90.0 71.0 87.0 72.0 73.0 91.0 115.0 70.0 70.0 86.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:99.50 Median:90.00 stddev:16.65 (18.5%) stddev-sans-first:17.30 09:21:48 INFO - PROCESS | 1652 | Values: 90.0 95.0 104.0 89.0 90.0 91.0 137.0 121.0 88.0 90.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:101.10 Median:97.00 stddev:9.54 (9.8%) stddev-sans-first:10.00 09:21:48 INFO - PROCESS | 1652 | Values: 97.0 97.0 98.0 97.0 96.0 106.0 99.0 97.0 97.0 127.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:114.90 Median:90.50 stddev:25.36 (28.0%) stddev-sans-first:26.15 09:21:48 INFO - PROCESS | 1652 | Values: 98.0 146.0 152.0 91.0 109.0 148.0 124.0 95.0 90.0 96.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:72.20 Median:67.50 stddev:11.76 (17.4%) stddev-sans-first:12.38 09:21:48 INFO - PROCESS | 1652 | Values: 68.0 81.0 101.0 78.0 67.0 65.0 68.0 66.0 61.0 67.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:62.00 stddev:10.02 (16.2%) stddev-sans-first:10.41 09:21:48 INFO - PROCESS | 1652 | Values: 57.0 58.0 64.0 61.0 63.0 77.0 50.0 55.0 60.0 83.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:96.00 Median:88.50 stddev:10.11 (11.4%) stddev-sans-first:10.62 09:21:48 INFO - PROCESS | 1652 | Values: 92.0 91.0 93.0 88.0 113.0 110.0 108.0 89.0 88.0 88.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:154.90 Median:154.50 stddev:16.37 (10.6%) stddev-sans-first:17.31 09:21:48 INFO - PROCESS | 1652 | Values: 151.0 160.0 166.0 155.0 135.0 147.0 143.0 144.0 194.0 154.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.60 Median:183.50 stddev:22.21 (12.1%) stddev-sans-first:23.34 09:21:48 INFO - PROCESS | 1652 | Values: 178.0 189.0 172.0 175.0 161.0 213.0 219.0 168.0 219.0 172.0 09:21:48 INFO - PROCESS | 1652 | 09:21:48 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:72.00 stddev:13.27 (18.4%) stddev-sans-first:13.63 09:21:48 INFO - PROCESS | 1652 | Values: 68.0 70.0 66.0 67.0 74.0 105.0 89.0 92.0 69.0 74.0 09:21:48 INFO - PROCESS | 1652 | -------- Summary: end -------- 09:21:48 INFO - PROCESS | 1652 | 09:21:49 INFO - TEST-INFO | 1652: exit 0 09:21:49 INFO - TEST-OK | kraken | took 62761ms 09:21:49 INFO - SUITE-END | took 1180s 09:21:50 INFO - Completed test suite (00:19:40) 09:21:50 INFO - javascript benchmark 09:21:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7469.841931889037, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3422.6748458896395, 3428.348613763383, 3429.0570093224364, 3434.467657513086, 3458.152752575619, 5765.365809760923, 5824.825255242345, 5854.7658093676255, 5898.616589921658, 5938.851528711784, 5876.44173803488, 5952.821415357535, 5971.4327138921735, 6042.395760423956, 6103.05402662588, 5390.272313237702, 5404.243405923174, 5423.159410291411, 5494.340679118512, 5497.917531237014, 5421.864453388677, 5430.348358197022, 5525.281713377275, 5526.834194974157, 5586.13414920688, 5311.760970756325, 5365.061114304981, 5467.261919640838, 5484.780608775644, 5495.203195536645, 5429.077056900336, 5434.130539113762, 5449.264349312832, 5450.127979523297, 5480.479354461319, 5291.814786482455, 5384.219288203228, 5407.486288802596, 5422.6475279106735, 5468.48049435303, 5690.374058853523, 5793.768249270024, 5796.043652797283, 5796.942030579725, 5851.151583020438, 6249.187605611287, 6259.155014073133, 6265.968670156665, 6292.528060395489, 6297.874042519124, 6366.649834259114, 6369.140166077566, 6383.904241436382, 6385.904211436832, 6451.741930322782, 6148.877022459616, 6153.046277826944, 6163.167972323679, 6184.412480814361, 6189.288231853395, 6114.266954506099, 6293.96853015732, 6377.840553986095, 6429.710663020175, 6435.935640643627, 6146.200993870768, 6245.656488893154, 6295.433410993032, 6307.022411526221, 6314.431701146918, 6171.876562468726, 6331.050342448578, 6362.650054247061, 6392.968035159887, 6403.359664033559, 6309.0, 6327.114204011442, 6332.0, 6342.873142537216, 6348.333424990402, 6206.348333425016, 6252.468540174045, 6297.433231009231, 6321.589096708699, 6334.208223972004, 5174.353205849269, 5194.818181363709, 5200.739963001834, 5206.140986737221, 5239.659422137549, 4607.216773148572, 4622.653301002439, 4627.768611569408, 4629.421322334708, 4650.255959046536, 5871.471567558855, 5880.411958804086, 6089.208402907682, 6097.603655762361, 6117.694115294306, 5177.275181474596, 6288.496920246369, 6296.685165741695, 6299.275583307978, 6334.9366506334345, 6050.42520960508, 6077.027675571976, 6079.118527813443, 6106.969465152646, 6107.175531303306, 4813.566778989908, 5677.829665110054, 5689.317281926195, 5690.231818704422, 5710.2576665033275, 6287.559870808999, 6335.841603959938, 6431.324710905287, 6465.3857883501, 6520.380563846522, 5477.849651573214, 6274.042045623866, 6367.872642547216, 6394.360563943568, 6397.904031439625, 5572.275604171432, 6575.967120164369, 6610.07458955737, 6612.537122401386, 6620.0, 6167.907481387872, 6235.657038862724, 6252.968735156295, 6274.435300822948, 6348.365163483615, 6222.968885155546, 6322.778702745381, 6339.0, 6371.522135839923, 6376.979683250657, 6144.754209831557, 6165.753369865155, 6305.054241863757, 6307.779227727191, 6323.588966717149, 6119.472088496418, 6196.628202307876, 6220.098085777447, 6304.369563043659, 6319.6208227506495, 5927.4665280125, 6051.364606716232, 6262.655553944394, 6296.779612713533, 6301.558890877594, 5548.916766248429, 6104.38956104386, 6141.263048434216, 6183.876322473615, 6194.504439644727, 6187.721552530237, 6257.217847769029, 6274.1843560336865, 6316.842078948063, 6330.240371155491], "value": 5861.729149115887, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.54145493247, 699.6606645776801, 793.777742232175, 930.1117432851619, 932.4405356785925, 36015.27969440613, 36811.631883681155, 38139.237215255715, 38193.42709859354, 38197.236055278874, 34684.61261549541, 34925.476117858256, 34941.825290873574, 34945.0, 35015.0, 7646.0, 7794.3764498839955, 10305.021023002802, 10599.311044782104, 10702.250842441032, 10460.424676642782, 10554.208434367414, 10555.0, 10572.947135264312, 10596.099331556827, 10541.947290263537, 10546.314489558192, 10547.05076543111, 10581.894181058167, 10587.04716575508, 10505.789884202311, 10518.0, 10532.842007369896, 10547.472626368652, 10555.577776888915, 9236.79921610193, 10197.286189966706, 10294.43380614066, 10329.74175645615, 10344.017318354745, 32120.839395803105, 32241.838790805898, 32245.355092898244, 32277.354452911044, 32279.83860080708, 3632.7093832493338, 3653.8355773990234, 3660.816959152032, 3687.760295580779, 3820.59883712209, 24400.633990490158, 24469.38826529333, 24542.263732088068, 25224.369390765194, 25309.87345063263, 3021.6071910651694, 3065.647450543194, 3069.2020074780717, 3132.7807053506262, 3171.841407929617, 323574.0, 325858.0, 326363.0, 327188.9999999976, 327935.0, 34645.307093857984, 42326.190642842295, 42373.643953407234, 42422.78788606099, 42545.0, 35950.1012474685, 43429.91425214332, 43612.56387436148, 43732.0, 43843.0, 10442.738931526772, 12910.741785164431, 12926.353682315847, 12927.095103342865, 12930.870691292967, 10728.570857165625, 10797.46012699362, 10891.0, 10896.78206435867, 10900.182486313559, 10141.442220677933, 10399.54406383107, 10725.981031801966, 11189.272697274813, 11318.377489238006, 5605.747741351649, 5634.352049514277, 5640.971795140998, 5650.802221922295, 5653.717314134276, 1123.3147779854282, 1127.6504283671925, 1135.0, 1151.1999160583482, 1152.6714886257544, 22032.669509957363, 23813.928373223247, 25145.622815657778, 25443.982240710164, 25635.97456101735, 1230.5693007447321, 1234.2656119608819, 1236.0544183699476, 1282.3652292115462, 1297.3059413213884, 11210.831837522444, 11968.521259149711, 12276.570320038934, 12392.380380980914, 13254.867451325363, 372079.0, 373003.0, 373570.0, 375316.0, 376165.0, 36943.445848312294, 51898.48101519012, 52239.47760522422, 52284.95430091377, 52503.737481312346, 1348.2921466230307, 1367.8358596968228, 1382.6336020954454, 1387.1815628779068, 1388.6389538719804, 23168.4207894804, 23707.762922370555, 25957.740422595532, 26037.479250415265, 26059.0, 38351.80824095861, 38560.03599910025, 38567.80716096402, 38595.99999999888, 38758.80620596879, 411014.0, 413552.0, 414396.0, 415649.0, 416931.0, 20250.89874550618, 20569.897150514153, 20695.172193112106, 20831.0, 20864.791352086286, 31119.844400777853, 33975.980720578424, 35395.82302088473, 35656.64343356533, 35809.82095089508, 28872.679641841456, 28891.5666265002, 29567.965121220655, 30176.54735179018, 30431.69568304377, 30022.549661754656, 30184.09447716572, 30430.08709738623, 30461.23846903845, 30466.69533304727, 29059.418811623225, 29809.85095074511, 30183.09450716482, 30272.84863575668, 30475.542866856573, 25479.36301592475, 25520.74479255258, 25931.351716206493, 26529.142959992616, 26630.20109396721, 17846.46460606184, 19763.50591235173, 21195.046222919696, 21406.7859321411, 21412.0], "value": 17038.827241573283, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.5349099661437, 380.7962739934136, 395.274670978754, 398.8703671306826, 436.90555159325885, 17318.0, 19619.607607847833, 19830.70253946192, 20619.896900515512, 20753.481162970915, 20928.790712092876, 21132.788672113275, 21470.89264553675, 21517.24689635863, 21609.13563457464, 6357.65032923189, 6648.434883034948, 6669.0996715443525, 6685.632290224035, 6703.363180497845, 6381.3937675920715, 6560.0, 6578.1448411706415, 6617.305182955793, 6693.799186024427, 6607.603543787364, 6618.470522358224, 6639.73441062357, 6640.966795166041, 6643.302453242412, 6343.14367560378, 6511.967440162793, 6585.769498067592, 6595.571287866297, 6597.076409302654, 5243.0, 6400.23197216337, 6418.358164183544, 6437.0, 6603.801885943381, 20440.591188176302, 24253.87873060641, 24604.630930536056, 25090.49819003628, 25218.87390563054, 2793.25978615667, 2818.1263808219314, 2828.632277803893, 2861.756750676195, 2890.219640697021, 14662.607052330011, 15457.922710386489, 15598.986065905794, 15990.841236433049, 16394.754078688828, 1722.6899158151527, 1755.3680674957004, 1782.6523827853573, 1794.1298470241923, 1803.6663217304708, 125098.0, 127591.08613370807, 146168.53831461657, 147123.0, 148610.0, 16884.915575422045, 20690.0, 20902.477438063866, 20903.477413064797, 20912.47718807042, 30616.23459413488, 30708.232294192825, 30736.924207652624, 30856.69143308583, 30940.535891961637, 9745.853812192823, 9916.752081198028, 10058.547365368577, 10091.44497052669, 10175.135113515285, 8197.83604327922, 8271.421000529905, 8331.33349332052, 8459.577021149042, 8470.407071504935, 7575.848483030309, 7696.692132314644, 9429.62281508732, 9465.763355916019, 9537.570809313598, 3723.702103831687, 3860.0, 3914.3345631242755, 3936.586658400884, 3949.1706741584017, 717.5551158281899, 761.7333933123473, 847.8007668197969, 884.7832281091174, 906.2885634776801, 20419.4895127623, 20937.685934710993, 21055.368338949855, 21210.04554795038, 21443.14227430885, 887.7083843008407, 943.7216021273741, 974.6101559376306, 974.7611835100446, 991.8115558043949, 6586.769463068866, 6590.736370545124, 6669.899951500634, 6772.288909664513, 6808.183018037768, 623731.0, 640180.0, 645511.4488551201, 648821.7558912175, 648967.0, 97548.51225743968, 102242.93271201875, 150153.0741775051, 153126.0, 160024.0, 1228.9078319126102, 1254.2161149281699, 1283.5764197814512, 1291.5156816193928, 1292.7479141567303, 11759.0, 11951.521939122338, 12061.75876482483, 12061.879381206076, 12095.75808483843, 59670.403295966484, 61312.69343653253, 61898.38101618926, 62143.0, 62149.757004860556, 80897.59551202442, 82618.0, 84605.0, 90044.29867103735, 92104.53947730218, 7568.167501574712, 10440.582376704846, 10910.23628346008, 11219.382933938712, 11471.598494052669, 9408.81182376335, 11402.543898243977, 12056.819147712617, 12809.807852882028, 12857.357132143168, 10893.291936024134, 11104.111671066457, 11116.333020018825, 11295.548178073113, 11399.430028498376, 10147.492625368553, 10585.470726463493, 10767.676969690921, 10885.183611229031, 11029.558817647205, 11339.525861637934, 11365.659030229106, 11500.654980350602, 11590.768184636428, 11641.126915481203, 11332.716682082684, 11692.532298707956, 11733.530658773894, 11742.17804753659, 11786.233894796811, 11077.778444431227, 11276.94361528187, 11301.9828215461, 11467.197296189186, 11534.653960381202], "value": 10773.714668950963, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1470.985290147098, 1500.1449173970834, 1537.4311504743246, 1555.6577552938354, 1558.4856997190925, 10242.641507547238, 10612.20408469366, 10874.891251087487, 10901.454927253646, 10924.235303528747, 10676.466176691174, 10837.566497340096, 10844.837327440095, 10884.455777211148, 10891.945540272307, 2318.6290193569034, 2350.565145448089, 2350.882455877208, 2355.49356888269, 2359.1624973134462, 1993.6810110382341, 2293.4037150340946, 2322.698049253603, 2341.4146463384154, 2342.1334106380577, 2300.3214051854743, 2310.526342099874, 2321.3152120124605, 2322.454223257533, 2326.6626339180807, 2140.646793279107, 2172.935261721745, 2275.6245219538755, 2282.7260728712495, 2291.186628746796, 1882.218879165145, 1954.9413517594494, 1984.335247692025, 2318.9188378406675, 2339.695839540866, 10832.458377081115, 10847.132229421706, 10860.837087443695, 10904.945475272652, 10909.83635245472, 5737.196792449061, 5867.237259156325, 5926.140709597084, 5952.7321270542925, 5996.910046349309, 3274.4269752793166, 3305.0, 3577.3560759063616, 3587.641235876418, 3590.856365745367, 1201.5974648492765, 1216.4039620585938, 1802.4412432145946, 1820.8088150744113, 1910.6656335141433, 12592.677768834232, 12674.556390526288, 14059.50791722295, 14489.130652160904, 14844.332005059798, 3982.104026594051, 4530.4336957879605, 4601.12578610063, 4690.953090469052, 4698.718076915396, 11217.326960382403, 11276.71808204785, 11299.152563557602, 11327.490262938187, 11345.659630211107, 5641.26663533738, 5730.1404789281105, 5837.3287071986415, 5839.007368747323, 5858.121281807763, 5096.770645320969, 5220.686758794484, 5307.124324486494, 5315.734213289397, 5384.488473595002, 3646.762960407565, 3723.957291958256, 3745.887623371303, 3782.7541209821825, 3786.8260839139953, 1030.6959446963165, 1035.803197392493, 1046.398320965448, 1046.837740150278, 1052.3159946034984, 903.1961554216753, 918.5453200665713, 938.5729493080632, 941.8540126280437, 972.1299437003842, 6344.416913116428, 6421.656095525193, 6549.377809108127, 6624.86750264992, 6680.86638267232, 958.7219706285099, 984.8768903887014, 1008.1027885182194, 1010.1817527802357, 1010.9140723038624, 3828.9234215315537, 4183.602557757008, 4201.768902710381, 4312.159128969821, 4317.438732964694, 12728.0, 13148.211107333591, 13187.53843615469, 13188.868111318956, 13207.801882971764, 12768.233905965672, 12913.160644558073, 13120.671983200305, 13135.671608209872, 13161.473541058442, 8455.619497122645, 8515.872261916076, 8540.658373664985, 8919.598618062073, 10045.598176073021, 6616.503762217757, 6632.369924857131, 6751.527393082437, 6762.6618669065365, 6897.137857767779, 11813.173077884465, 11941.462634181657, 12079.094067945114, 12136.393180341125, 12162.878371216175, 11833.704157396134, 11943.16397852142, 12221.633350999484, 12244.632661020183, 12299.954503866913, 7951.0, 7975.5613441258965, 7985.760427187193, 8030.156833532628, 8058.194180581895, 9547.42715437076, 9680.177184939359, 9780.0, 9798.902010979798, 9920.851187232342, 8628.352873534635, 8633.654653813777, 8645.270102683566, 8753.737387878375, 8764.035956044703, 7325.230850760807, 7375.262473752582, 8159.428839981382, 8473.872891906503, 8475.194856488624, 6601.504887133388, 7586.393088553013, 7664.233576642291, 7690.1928046907815, 7711.38308935295, 5435.429279925551, 5565.38780734127, 5652.378238393691, 5696.11710184922, 5700.3444603871085, 5674.375818659862, 5808.94191058084, 5813.505852002458, 5827.621204621686, 5847.093700476433], "value": 5000.728466153667, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.5910316588435, 357.96778289953915, 371.6506483905131, 382.95213098362706, 388.92804831106247, 4973.403191617011, 5059.873503162418, 5515.8345249642525, 5521.585881058916, 5526.364468086166, 4412.9621396422235, 5068.8225912093085, 5169.663971841827, 5389.9759045781275, 5455.945440545594, 4026.65107189092, 4314.741115533078, 4338.761368124752, 4403.713758605696, 4409.80155892984, 3492.794985729919, 3732.514773079496, 3828.4257361395735, 4445.777711114448, 4460.420145381096, 3997.8800635980965, 4244.493204912258, 4446.333050042504, 4466.97766511167, 4483.932741008871, 3768.3970564709653, 4295.2912769393015, 4402.40567523385, 4416.801243943998, 4417.823287068513, 4029.657479114279, 4037.858674946392, 4047.332190188615, 4062.9730850516553, 4066.593340665939, 4499.370088187657, 4816.54242846929, 5202.92195617066, 5222.32109825724, 5275.525202731734, 4858.975705121487, 4914.729689867056, 5143.588512918957, 5157.968406318714, 5158.188787111034, 5820.272465941757, 5832.970835145839, 5837.854053648693, 5845.678487683176, 5871.500922421556, 4317.892052698676, 4327.675424343155, 4587.288970209623, 4597.402337696111, 4619.376384188159, 5098.796048158107, 5324.6538974966115, 5569.387367389584, 5876.9118463223085, 6153.507719382484, 4111.6299533042165, 4700.0, 4701.224297990793, 4720.457147428021, 4731.793392684912, 4171.728837625545, 4410.33844923264, 4510.1656193604285, 4783.86656739836, 4849.9030019399415, 4362.934555981663, 4471.5081341052455, 4761.833335833229, 4909.748014256342, 5147.6654017488745, 5696.601237913306, 5749.3963133871175, 5790.467814633071, 5800.50695690871, 5822.243108395966, 1498.9432450122638, 1538.6152462783584, 1546.6056155680456, 1616.3292233722873, 1617.4904904954901, 1064.7391389109719, 1090.4002798460815, 1095.9452027398597, 1106.3085571517802, 1205.2406983600276, 4562.840300589463, 4703.341532185496, 4740.597049250783, 5006.299118123393, 5390.326209223847, 5522.98411079961, 5527.972360138174, 5788.87117012187, 6060.575759696779, 6078.6048906820915, 4856.0, 4867.221244600847, 5052.646314758005, 5082.983403319277, 5509.0, 2835.5189209441737, 3117.072488664254, 3130.906072817819, 3144.748420126384, 3147.748180145583, 5678.564468496583, 6234.376562343729, 6245.564939283376, 6278.654673992883, 6291.842703932438, 5291.788328466818, 5390.568754499631, 5620.4098569649595, 5977.342492325841, 6222.12890195373, 5181.974090129601, 5377.784888604412, 5995.310539288038, 6222.066689996536, 6269.247690277105, 5448.782048718007, 5452.945470545244, 5623.662580245198, 5991.88016239664, 6076.72654730547, 5237.450067742984, 5758.827235182951, 5995.430434108753, 6002.939970600238, 6013.759449621966, 5682.5453963681975, 5983.311919129182, 6030.638161710312, 6075.817725468243, 6137.7851775187655, 4134.772587507716, 4141.97929010353, 4165.312723400726, 4729.43246810385, 4747.548982846624, 5244.767479642204, 5640.774369025357, 5811.820567287267, 5880.296112770606, 6044.24446944132, 5696.458836410535, 5783.768649253982, 5832.891750567379, 5865.181793643984, 5900.262467191601, 5306.548943339705, 5663.543560341297, 5820.417958204146, 5884.439559341545, 5937.524998000236, 4732.645051621211, 4851.854444366674, 4903.0, 5557.916631250454, 5748.655080695172, 5345.33183352081, 5709.202490779571, 5870.765169393348, 5889.705514724333, 5960.403959604005, 4748.857534273978, 5705.343885453226, 5718.595926849088, 5737.540996720336, 5793.217915581343], "value": 4369.970401984807, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.9425645948324, 757.7688804914499, 773.7059917231451, 788.4310222656915, 796.8525822722796, 14706.264686765659, 15508.844911550896, 15772.526824195293, 15820.446284380034, 16879.57801054971, 15782.684346313066, 17023.48929532116, 17078.402255921046, 17197.312107515714, 17229.913850430763, 915.7252824152761, 950.363256618064, 954.7947191353858, 960.3133759362055, 961.5865177973469, 954.4464210757778, 959.1319855530721, 961.1637875048682, 961.2070042215165, 963.8554216867456, 941.5715849288591, 989.0356902020544, 992.5682328187225, 993.7714325705092, 994.5474808961941, 978.9755256118654, 978.9951050244739, 980.3480685344238, 982.1504398695113, 984.0307297312141, 721.9314165154302, 725.0900120348981, 731.2139450091173, 734.8016035670393, 737.72704099483, 14108.0, 14732.337044833008, 15757.2121393931, 15800.130992795392, 16021.198940052951, 13447.462101515928, 13448.663783405394, 13692.383842727102, 13721.656958576015, 13748.862511374959, 9078.668593944869, 11624.360660163687, 11699.30889947854, 11704.239224450383, 11859.762804743857, 3510.771799833028, 3578.471692316852, 3635.8000309983204, 3635.909102272438, 3758.267137908109, 82634.0, 91196.0, 96124.51937740267, 102182.0, 104248.0, 9090.000099988996, 10523.368597884151, 10630.787384252271, 11159.944200278947, 11282.323060616225, 21399.46501337448, 21746.0, 21780.237691680715, 21821.23625673094, 21870.23454179096, 7685.769426917202, 7717.22827717235, 7752.0, 7756.263155000266, 7765.378769698411, 4206.221848957953, 4222.0, 4231.132617813341, 4275.551067137968, 4350.282203436397, 2878.553824157259, 3343.548620936191, 3415.777974431654, 3419.26485805553, 3423.4180189367867, 566.2723400430427, 570.1106274212159, 585.9209006784115, 592.4283066840519, 593.946544810969, 631.6604924170974, 641.371455973149, 673.6261374936859, 675.3584095109666, 695.9965200173966, 9156.0, 9718.0, 9870.506474676382, 9949.353292035994, 9951.751206219902, 620.4756980351596, 659.8482349059743, 675.4798804920184, 680.2381332907175, 696.2376198063113, 4557.886052848639, 4828.855134345975, 4883.671035759415, 4946.653734238569, 4952.306677065214, 86456.0, 97229.0, 100731.0, 101324.0, 102854.48572757088, 45722.542774572496, 73661.6316918412, 77442.2255777435, 89638.0, 93855.0, 71145.0, 72492.0, 72794.63602681953, 73351.63324183345, 74218.0, 10484.056434920894, 10541.904615674222, 10668.0, 10690.679279621623, 10770.892291076989, 38600.0, 46631.53368466408, 46889.53110468988, 47444.0, 47653.0, 60697.57209711562, 61097.77804443975, 61718.0, 62330.37669623246, 62827.37172628215, 10314.742131446774, 12297.893189612978, 12317.815232771694, 12340.136190466581, 12722.363881805688, 14086.22525761093, 14101.576952691436, 14283.285835707966, 14494.710105798036, 14696.191709456083, 10772.407517586294, 11445.084393248686, 11646.301221926711, 12495.937520312704, 13782.173069615856, 10821.56713731442, 13025.348732563145, 13127.212367257995, 13295.800562991757, 13511.729765404834, 12485.375731213584, 12510.02645489464, 12663.0, 12832.935835321137, 13324.400401982128, 10633.946830265799, 10681.732956676145, 10789.298257832712, 10821.40482273451, 11159.888401115884, 8122.837543248984, 9174.67888623895, 10618.575256989943, 10634.680959571226, 10743.946280268548], "value": 7387.367906315972, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1607.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 121.0, 120.0, 119.0, 171.0, 105.0, 176.0, 179.0, 161.0, 120.0], "value": 138.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 114.0, 110.0, 152.0, 125.0, 149.0, 137.0, 112.0, 144.0, 112.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 211.0, 240.0, 283.0, 238.0, 200.0, 198.0, 200.0, 198.0, 242.0], "value": 221.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 61.0, 60.0, 86.0, 66.0, 86.0, 65.0, 60.0, 72.0, 59.0], "value": 69.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 87.0, 72.0, 73.0, 91.0, 115.0, 70.0, 70.0, 86.0], "value": 82.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 104.0, 89.0, 90.0, 91.0, 137.0, 121.0, 88.0, 90.0], "value": 99.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 98.0, 97.0, 96.0, 106.0, 99.0, 97.0, 97.0, 127.0], "value": 101.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 146.0, 152.0, 91.0, 109.0, 148.0, 124.0, 95.0, 90.0, 96.0], "value": 114.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 81.0, 101.0, 78.0, 67.0, 65.0, 68.0, 66.0, 61.0, 67.0], "value": 72.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 64.0, 61.0, 63.0, 77.0, 50.0, 55.0, 60.0, 83.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 93.0, 88.0, 113.0, 110.0, 108.0, 89.0, 88.0, 88.0], "value": 96.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 160.0, 166.0, 155.0, 135.0, 147.0, 143.0, 144.0, 194.0, 154.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 189.0, 172.0, 175.0, 161.0, 213.0, 219.0, 168.0, 219.0, 172.0], "value": 186.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 66.0, 67.0, 74.0, 105.0, 89.0, 92.0, 69.0, 74.0], "value": 77.4, "unit": "score"}]}]} 09:21:50 INFO - Return code: 0 09:21:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:21:50 ERROR - # TBPL SUCCESS # 09:21:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:21:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:21:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:21:50 INFO - 2017-03-05 09:21:50.313 screenresolution[1657:11335] starting screenresolution argv=screenresolution get 09:21:50 INFO - 2017-03-05 09:21:50.315 screenresolution[1657:11335] Display 0: 1600x1200x32@60 09:21:50 INFO - 2017-03-05 09:21:50.321 screenresolution[1658:11338] starting screenresolution argv=screenresolution list 09:21:50 INFO - Available Modes on Display 0 09:21:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:21:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:21:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:21:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:21:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:21:50 INFO - Intel Iris: 09:21:50 INFO - Chipset Model: Intel Iris 09:21:50 INFO - Type: GPU 09:21:50 INFO - Bus: Built-In 09:21:50 INFO - VRAM (Dynamic, Max): 1536 MB 09:21:50 INFO - Vendor: Intel (0x8086) 09:21:50 INFO - Device ID: 0x0a2e 09:21:50 INFO - Revision ID: 0x0009 09:21:50 INFO - Displays: 09:21:50 INFO - Display: 09:21:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:21:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:21:50 INFO - Display Serial Number: 1600x1200 60 09:21:50 INFO - Main Display: Yes 09:21:50 INFO - Mirror: Off 09:21:50 INFO - Online: Yes 09:21:50 INFO - Rotation: Supported 09:21:50 INFO - Return code: 0 09:21:50 INFO - Running post-action listener: _resource_record_post_action 09:21:50 INFO - [mozharness: 2017-03-05 17:21:50.563873Z] Finished run-tests step (success) 09:21:50 INFO - Running post-run listener: _resource_record_post_run 09:21:50 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 34632192; Write bytes: 518874112; Read time: 319; Write time: 1645 09:21:50 INFO - TinderboxPrint: CPU usage
54.9% 09:21:50 INFO - TinderboxPrint: I/O read bytes / time
34,632,192 / 319 09:21:50 INFO - TinderboxPrint: I/O write bytes / time
518,874,112 / 1,645 09:21:50 INFO - TinderboxPrint: CPU idle
2,168.6 (45.1%) 09:21:50 INFO - TinderboxPrint: CPU system
966.8 (20.1%) 09:21:50 INFO - TinderboxPrint: CPU user
1,670.4 (34.8%) 09:21:50 INFO - TinderboxPrint: Swap in / out
240,566,272 / 0 09:21:50 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203612672; Write bytes: 204978176; Read time: 15618; Write time: 449 09:21:50 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 32846336; Write bytes: 303184896; Read time: 301; Write time: 1167 09:21:51 INFO - Running post-run listener: _upload_blobber_files 09:21:51 INFO - Blob upload gear active. 09:21:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:21:51 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:21:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:21:51 INFO - (blobuploader) - INFO - Open directory for files ... 09:21:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:21:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:21:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:52 INFO - (blobuploader) - INFO - Done attempting. 09:21:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:21:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:21:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:53 INFO - (blobuploader) - INFO - Done attempting. 09:21:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:21:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:21:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:54 INFO - (blobuploader) - INFO - Done attempting. 09:21:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:21:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:21:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:56 INFO - (blobuploader) - INFO - Done attempting. 09:21:56 INFO - (blobuploader) - INFO - Iteration through files over. 09:21:57 INFO - Return code: 0 09:21:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:21:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:21:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ac425c66b1ee687bea510cac74b83499a326e8f560a7e63a1eecb764345077545bbd6f8b0e41996ec490ec95c866d3a608d9424457ce0898fe539d20a73825e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c380db1502e91ef2469a3cbf4c80bb4aef76b32450e2e59cfb146337a50d3adad7904b17a13a92954151a26bc1abc37f061c2b4a424741113288d4ff1e85d20", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02fe3574bdd089b98eae7fb72c67b40ff843e2ef749747f0fe2760f6b238e16fb9333ad1a8eb006f6a6f3b5dc735e5f3463bd3c3ffbb9791f848abe4183f807d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c049e67f583b616d665f33dc86197992cba85bf95ca20bac2a67350469e587e432124fbbd59c11ca061dccf545d982bf07b6bec7b0a192199a9e4b12d5aa20a"} 09:21:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:21:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:21:57 INFO - Contents: 09:21:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ac425c66b1ee687bea510cac74b83499a326e8f560a7e63a1eecb764345077545bbd6f8b0e41996ec490ec95c866d3a608d9424457ce0898fe539d20a73825e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c380db1502e91ef2469a3cbf4c80bb4aef76b32450e2e59cfb146337a50d3adad7904b17a13a92954151a26bc1abc37f061c2b4a424741113288d4ff1e85d20", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02fe3574bdd089b98eae7fb72c67b40ff843e2ef749747f0fe2760f6b238e16fb9333ad1a8eb006f6a6f3b5dc735e5f3463bd3c3ffbb9791f848abe4183f807d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c049e67f583b616d665f33dc86197992cba85bf95ca20bac2a67350469e587e432124fbbd59c11ca061dccf545d982bf07b6bec7b0a192199a9e4b12d5aa20a"} 09:21:57 INFO - Running post-run listener: copy_logs_to_upload_dir 09:21:57 INFO - Copying logs to upload dir... 09:21:57 INFO - mkdir: /builds/slave/test/build/upload/logs 09:21:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.203422 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-05 09:21:57.060813) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-05 09:21:57.064281) ========= 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.jrLaerj9C9/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.JHVDSYAays/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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/Firefox/sha512/6ac425c66b1ee687bea510cac74b83499a326e8f560a7e63a1eecb764345077545bbd6f8b0e41996ec490ec95c866d3a608d9424457ce0898fe539d20a73825e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c380db1502e91ef2469a3cbf4c80bb4aef76b32450e2e59cfb146337a50d3adad7904b17a13a92954151a26bc1abc37f061c2b4a424741113288d4ff1e85d20", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02fe3574bdd089b98eae7fb72c67b40ff843e2ef749747f0fe2760f6b238e16fb9333ad1a8eb006f6a6f3b5dc735e5f3463bd3c3ffbb9791f848abe4183f807d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c049e67f583b616d665f33dc86197992cba85bf95ca20bac2a67350469e587e432124fbbd59c11ca061dccf545d982bf07b6bec7b0a192199a9e4b12d5aa20a"} build_url:https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011228 build_url: 'https://queue.taskcluster.net/v1/task/Ql_wADdPTbO9WeoHqeFeCg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ac425c66b1ee687bea510cac74b83499a326e8f560a7e63a1eecb764345077545bbd6f8b0e41996ec490ec95c866d3a608d9424457ce0898fe539d20a73825e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c380db1502e91ef2469a3cbf4c80bb4aef76b32450e2e59cfb146337a50d3adad7904b17a13a92954151a26bc1abc37f061c2b4a424741113288d4ff1e85d20", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02fe3574bdd089b98eae7fb72c67b40ff843e2ef749747f0fe2760f6b238e16fb9333ad1a8eb006f6a6f3b5dc735e5f3463bd3c3ffbb9791f848abe4183f807d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c049e67f583b616d665f33dc86197992cba85bf95ca20bac2a67350469e587e432124fbbd59c11ca061dccf545d982bf07b6bec7b0a192199a9e4b12d5aa20a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-05 09:21:57.102470) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-05 09:21:57.102921) ========= 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.jrLaerj9C9/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.JHVDSYAays/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.005153 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-05 09:21:57.152862) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-05 09:21:57.153283) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-05 09:21:57.153866) ========= ========= Total master_lag: 0.33 =========