builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0341 starttime: 1484929801.7 results: success (0) buildid: 20170120072124 builduid: 09cbbd6b74274dcda28083296fd80182 revision: dc33d7851ba557007fc49819d80320f9bf9f613e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:01.704622) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:01.705108) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:01.705392) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:02.064028) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:02.064575) ========= 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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-20 08:30:02-- 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% 166M=0s 2017-01-20 08:30:02 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.196805 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:02.286022) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:02.286341) ========= 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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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.024504 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:02.336425) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:02.337086) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc33d7851ba557007fc49819d80320f9bf9f613e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc33d7851ba557007fc49819d80320f9bf9f613e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qGmkvkA3u0/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.QVhQR8ZId1/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-20 08:30:02,442 truncating revision to first 12 chars 2017-01-20 08:30:02,442 Setting DEBUG logging. 2017-01-20 08:30:02,442 attempt 1/10 2017-01-20 08:30:02,443 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dc33d7851ba5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-20 08:30:02,713 unpacking tar archive at: mozilla-aurora-dc33d7851ba5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.527698 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:02.889916) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:02.890405) ========= script_repo_revision: dc33d7851ba557007fc49819d80320f9bf9f613e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:02.890865) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:02.891152) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 08:30:02.912512) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-20 08:30:02.913162) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qGmkvkA3u0/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.QVhQR8ZId1/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 08:30:03 INFO - MultiFileLogger online at 20170120 08:30:03 in /builds/slave/test 08:30:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:30:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:30:03 INFO - {'append_to_log': False, 08:30:03 INFO - 'base_work_dir': '/builds/slave/test', 08:30:03 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:30:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:30:03 INFO - 'branch': 'Mozilla-Aurora', 08:30:03 INFO - 'buildbot_json_path': 'buildprops.json', 08:30:03 INFO - 'config_files': ('talos/mac_config.py',), 08:30:03 INFO - 'default_actions': ('clobber', 08:30:03 INFO - 'read-buildbot-config', 08:30:03 INFO - 'download-and-extract', 08:30:03 INFO - 'populate-webroot', 08:30:03 INFO - 'create-virtualenv', 08:30:03 INFO - 'install', 08:30:03 INFO - 'run-tests'), 08:30:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:30:03 INFO - 'download_minidump_stackwalk': True, 08:30:03 INFO - 'download_symbols': 'ondemand', 08:30:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:30:03 INFO - 'tooltool.py': '/tools/tooltool.py', 08:30:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:30:03 INFO - '/tools/misc-python/virtualenv.py')}, 08:30:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:30:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:30:03 INFO - 'installer_path': 'installer.exe', 08:30:03 INFO - 'log_level': 'info', 08:30:03 INFO - 'log_name': 'talos', 08:30:03 INFO - 'log_to_console': True, 08:30:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:30:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:30:03 INFO - 'opt_config_files': (), 08:30:03 INFO - 'pip_index': False, 08:30:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:03 INFO - 'cmd': ('bash', 08:30:03 INFO - '-c', 08:30:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:03 INFO - 'enabled': True, 08:30:03 INFO - 'halt_on_failure': False, 08:30:03 INFO - 'name': 'check_screen_resolution'},), 08:30:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:03 INFO - 'cmd': ('bash', 08:30:03 INFO - '-c', 08:30:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:03 INFO - 'enabled': True, 08:30:03 INFO - 'halt_on_failure': False, 08:30:03 INFO - 'name': 'check_screen_resolution'},), 08:30:03 INFO - 'run_cmd_checks_enabled': True, 08:30:03 INFO - 'sps_profile': False, 08:30:03 INFO - 'sps_profile_interval': 0, 08:30:03 INFO - 'suite': 'dromaeojs-e10s', 08:30:03 INFO - 'system_bits': '32', 08:30:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:30:03 INFO - 'title': 't-yosemite-r7-0341', 08:30:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:30:03 INFO - 'use_talos_json': True, 08:30:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:30:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:30:03 INFO - 'work_dir': 'build'} 08:30:03 INFO - [mozharness: 2017-01-20 16:30:03.124923Z] Running clobber step. 08:30:03 INFO - Running pre-action listener: _resource_record_pre_action 08:30:03 INFO - Running main action method: clobber 08:30:03 INFO - rmtree: /builds/slave/test/build 08:30:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:30:05 INFO - Running post-action listener: _resource_record_post_action 08:30:05 INFO - [mozharness: 2017-01-20 16:30:05.895902Z] Finished clobber step (success) 08:30:05 INFO - [mozharness: 2017-01-20 16:30:05.896133Z] Running read-buildbot-config step. 08:30:05 INFO - Running pre-action listener: _resource_record_pre_action 08:30:05 INFO - Running main action method: read_buildbot_config 08:30:05 INFO - Using buildbot properties: 08:30:05 INFO - { 08:30:05 INFO - "project": "", 08:30:05 INFO - "product": "firefox", 08:30:05 INFO - "script_repo_revision": "production", 08:30:05 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:30:05 INFO - "repository": "", 08:30:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:30:05 INFO - "buildid": "20170120072124", 08:30:05 INFO - "pgo_build": "False", 08:30:05 INFO - "basedir": "/builds/slave/test", 08:30:05 INFO - "buildnumber": 19, 08:30:05 INFO - "slavename": "t-yosemite-r7-0341", 08:30:05 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:30:05 INFO - "platform": "yosemite_r7", 08:30:05 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:30:05 INFO - "branch": "mozilla-aurora", 08:30:05 INFO - "repo_path": "releases/mozilla-aurora", 08:30:05 INFO - "revision": "dc33d7851ba557007fc49819d80320f9bf9f613e", 08:30:05 INFO - "stage_platform": "macosx64", 08:30:05 INFO - "builduid": "09cbbd6b74274dcda28083296fd80182", 08:30:05 INFO - "slavebuilddir": "test" 08:30:05 INFO - } 08:30:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:30:05 INFO - Running post-action listener: _resource_record_post_action 08:30:05 INFO - [mozharness: 2017-01-20 16:30:05.902011Z] Finished read-buildbot-config step (success) 08:30:05 INFO - [mozharness: 2017-01-20 16:30:05.902156Z] Running download-and-extract step. 08:30:05 INFO - Running pre-action listener: _resource_record_pre_action 08:30:05 INFO - Running main action method: download_and_extract 08:30:05 INFO - mkdir: /builds/slave/test/build/tests 08:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:05 INFO - trying https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:30:05 INFO - Downloading https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:30:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:30:07 INFO - Downloaded 1199 bytes. 08:30:07 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:30:07 INFO - Using the following test package requirements: 08:30:07 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:30:07 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:30:07 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:30:07 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:07 INFO - u'jsshell-mac.zip'], 08:30:07 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:30:07 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:30:07 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:30:07 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:30:07 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:30:07 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:30:07 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:30:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:30:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:30:07 INFO - Fetch https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:30:08 INFO - Http code: 200 08:30:08 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 08:30:08 INFO - x-amz-cf-id: 6ZArDxdux4xEL4PqPkabfDxforfCGKj_tl3dLoZp6fOHcWzOClZ_mw== 08:30:08 INFO - x-amz-version-id: G2kBvCKXNqp4GwbB15_lvnFFef.IklqB 08:30:08 INFO - Expected file size: 16276117 08:30:08 INFO - Obtained file size: 16276117 08:30:14 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:30:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:30:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:30:14 INFO - Fetch https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:30:15 INFO - Http code: 200 08:30:15 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 08:30:15 INFO - x-amz-cf-id: dJ1ftd00cUK7G-kqSrYbPUEh2Eoyv8-ytHAohW2WpIEd5DHXf92F5Q== 08:30:15 INFO - x-amz-version-id: G2kBvCKXNqp4GwbB15_lvnFFef.IklqB 08:30:15 INFO - Expected file size: 16276117 08:30:15 INFO - Obtained file size: 16276117 08:30:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:30:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:30:22 INFO - Fetch https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:30:22 INFO - Http code: 200 08:30:22 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 08:30:22 INFO - x-amz-cf-id: c8vkcN7WmieP8lVoEUOPe8vcUcMan_Q2q4-hDEYdddMGULIgE5GGLg== 08:30:22 INFO - x-amz-version-id: wyiyMBHxT2E3kOkjrA_MJgdK_Vo8gUyQ 08:30:22 INFO - Expected file size: 14409038 08:30:22 INFO - Obtained file size: 14409038 08:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:23 INFO - trying https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:30:23 INFO - Downloading https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:30:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:30:26 INFO - Downloaded 109971998 bytes. 08:30:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:30:26 INFO - mkdir: /builds/slave/test/properties 08:30:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:30:26 INFO - Writing to file /builds/slave/test/properties/build_url 08:30:26 INFO - Contents: 08:30:26 INFO - build_url:https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:30:26 INFO - Running post-action listener: _resource_record_post_action 08:30:26 INFO - Running post-action listener: set_extra_try_arguments 08:30:26 INFO - [mozharness: 2017-01-20 16:30:26.673406Z] Finished download-and-extract step (success) 08:30:26 INFO - [mozharness: 2017-01-20 16:30:26.673555Z] Running populate-webroot step. 08:30:26 INFO - Running pre-action listener: _resource_record_pre_action 08:30:26 INFO - Running main action method: populate_webroot 08:30:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:30:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:30:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:30:26 INFO - '--tppagecycles', 08:30:26 INFO - '7'], 08:30:26 INFO - 'tests': ['tsvgm']}}, 08:30:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:30:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:30:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:30:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:30:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:30:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:30:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:26 INFO - 'talos_options': ['--disable-e10s'], 08:30:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:26 INFO - 'talos_options': ['--disable-e10s'], 08:30:26 INFO - 'tests': ['damp', 'tps']}, 08:30:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:26 INFO - 'tests': ['damp', 'tps']}, 08:30:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:30:26 INFO - 'tests': ['dromaeo_dom']}, 08:30:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:30:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:30:26 INFO - 'tests': ['basic_compositor_video']}, 08:30:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:30:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:30:26 INFO - 'tests': ['a11yr', 08:30:26 INFO - 'ts_paint', 08:30:26 INFO - 'tpaint', 08:30:26 INFO - 'sessionrestore', 08:30:26 INFO - 'sessionrestore_no_auto_restore', 08:30:26 INFO - 'tabpaint']}, 08:30:26 INFO - 'other-e10s': {'tests': ['a11yr', 08:30:26 INFO - 'ts_paint', 08:30:26 INFO - 'tpaint', 08:30:26 INFO - 'sessionrestore', 08:30:26 INFO - 'sessionrestore_no_auto_restore', 08:30:26 INFO - 'tabpaint']}, 08:30:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:30:26 INFO - 'ts_paint', 08:30:26 INFO - 'tpaint', 08:30:26 INFO - 'sessionrestore', 08:30:26 INFO - 'sessionrestore_no_auto_restore', 08:30:26 INFO - 'tabpaint']}, 08:30:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:30:26 INFO - 'ts_paint', 08:30:26 INFO - 'tpaint', 08:30:26 INFO - 'sessionrestore', 08:30:26 INFO - 'sessionrestore_no_auto_restore', 08:30:26 INFO - 'tabpaint']}, 08:30:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:30:26 INFO - 'tests': ['a11yr', 08:30:26 INFO - 'ts_paint', 08:30:26 INFO - 'tpaint', 08:30:26 INFO - 'sessionrestore', 08:30:26 INFO - 'sessionrestore_no_auto_restore', 08:30:26 INFO - 'tabpaint']}, 08:30:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:30:26 INFO - 'tests': ['a11yr', 08:30:26 INFO - 'ts_paint', 08:30:26 INFO - 'tpaint', 08:30:26 INFO - 'sessionrestore', 08:30:26 INFO - 'sessionrestore_no_auto_restore', 08:30:26 INFO - 'tabpaint']}, 08:30:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:30:26 INFO - 'tests': ['tsvgx', 08:30:26 INFO - 'tsvgr_opacity', 08:30:26 INFO - 'tart', 08:30:26 INFO - 'tscrollx', 08:30:26 INFO - 'cart']}, 08:30:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:30:26 INFO - 'tsvgr_opacity', 08:30:26 INFO - 'tart', 08:30:26 INFO - 'tscrollx', 08:30:26 INFO - 'cart']}, 08:30:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:26 INFO - 'talos_options': ['--disable-e10s'], 08:30:26 INFO - 'tests': ['tp5o']}, 08:30:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:26 INFO - 'tests': ['tp5o']}, 08:30:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:26 INFO - 'talos_options': ['--disable-e10s', 08:30:26 INFO - '--xperf_path', 08:30:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:26 INFO - 'tests': ['tp5n']}, 08:30:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:26 INFO - 'talos_options': ['--xperf_path', 08:30:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:26 INFO - 'tests': ['tp5n']}}, 08:30:26 INFO - 'talos.zip': {'path': '', 08:30:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:30:26 INFO - Running post-action listener: _resource_record_post_action 08:30:26 INFO - [mozharness: 2017-01-20 16:30:26.692064Z] Finished populate-webroot step (success) 08:30:26 INFO - [mozharness: 2017-01-20 16:30:26.692177Z] Running create-virtualenv step. 08:30:26 INFO - Running pre-action listener: _resource_record_pre_action 08:30:26 INFO - Running main action method: create_virtualenv 08:30:26 INFO - Creating virtualenv /builds/slave/test/build/venv 08:30:26 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 08:30:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:30:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:30:26 INFO - Using real prefix '/tools/python27' 08:30:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:30:27 INFO - Installing distribute.............................................................................................................................................................................................done. 08:30:30 INFO - Installing pip.................done. 08:30:30 INFO - Return code: 0 08:30:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:30:30 INFO - Reading from file tmpfile_stdout 08:30:30 INFO - Output received: 08:30:30 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) 08:30:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ecaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d8d76580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eabed30>, '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 0x10e924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eab6b20>, '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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 08:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGmkvkA3u0/Render', 08:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:30 INFO - 'HOME': '/Users/cltbld', 08:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:30 INFO - 'LOGNAME': 'cltbld', 08:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:30 INFO - 'MOZ_NO_REMOTE': '1', 08:30:30 INFO - 'NO_EM_RESTART': '1', 08:30:30 INFO - 'PAGER': '/bin/cat', 08:30:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:30 INFO - 'PWD': '/builds/slave/test', 08:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:30 INFO - 'SHELL': '/bin/bash', 08:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVhQR8ZId1/Listeners', 08:30:30 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 08:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:30 INFO - 'USER': 'cltbld', 08:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:30 INFO - 'XPC_FLAGS': '0x0', 08:30:30 INFO - 'XPC_SERVICE_NAME': '0', 08:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:30 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 08:30:30 INFO - Cleaning up... 08:30:30 INFO - Return code: 0 08:30:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ecaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d8d76580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eabed30>, '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 0x10e924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eab6b20>, '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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 08:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGmkvkA3u0/Render', 08:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:30 INFO - 'HOME': '/Users/cltbld', 08:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:30 INFO - 'LOGNAME': 'cltbld', 08:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:30 INFO - 'MOZ_NO_REMOTE': '1', 08:30:30 INFO - 'NO_EM_RESTART': '1', 08:30:30 INFO - 'PAGER': '/bin/cat', 08:30:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:30 INFO - 'PWD': '/builds/slave/test', 08:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:30 INFO - 'SHELL': '/bin/bash', 08:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVhQR8ZId1/Listeners', 08:30:30 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 08:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:30 INFO - 'USER': 'cltbld', 08:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:30 INFO - 'XPC_FLAGS': '0x0', 08:30:30 INFO - 'XPC_SERVICE_NAME': '0', 08:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:31 INFO - Downloading/unpacking psutil>=3.1.1 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:30:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:34 INFO - Installing collected packages: psutil 08:30:34 INFO - Running setup.py install for psutil 08:30:34 INFO - building 'psutil._psutil_osx' extension 08:30:34 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 08:30:35 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 08:30:35 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 08:30:35 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 08:30:35 INFO - building 'psutil._psutil_posix' extension 08:30:35 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 08:30:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:30:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:30:35 INFO - ^ 08:30:35 INFO - 1 warning generated. 08:30:35 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 08:30:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:35 INFO - Successfully installed psutil 08:30:35 INFO - Cleaning up... 08:30:35 INFO - Return code: 0 08:30:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:35 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 0x10ecaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d8d76580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eabed30>, '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 0x10e924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eab6b20>, '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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 08:30:35 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 08:30:35 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 08:30:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGmkvkA3u0/Render', 08:30:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:35 INFO - 'HOME': '/Users/cltbld', 08:30:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:35 INFO - 'LOGNAME': 'cltbld', 08:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:35 INFO - 'MOZ_NO_REMOTE': '1', 08:30:35 INFO - 'NO_EM_RESTART': '1', 08:30:35 INFO - 'PAGER': '/bin/cat', 08:30:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:35 INFO - 'PWD': '/builds/slave/test', 08:30:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:35 INFO - 'SHELL': '/bin/bash', 08:30:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVhQR8ZId1/Listeners', 08:30:35 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 08:30:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:35 INFO - 'USER': 'cltbld', 08:30:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:35 INFO - 'XPC_FLAGS': '0x0', 08:30:35 INFO - 'XPC_SERVICE_NAME': '0', 08:30:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:30:38 INFO - Installing collected packages: mozsystemmonitor 08:30:38 INFO - Running setup.py install for mozsystemmonitor 08:30:38 INFO - Successfully installed mozsystemmonitor 08:30:38 INFO - Cleaning up... 08:30:39 INFO - Return code: 0 08:30:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:39 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 0x10ecaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d8d76580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eabed30>, '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 0x10e924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eab6b20>, '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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 08:30:39 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 08:30:39 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 08:30:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGmkvkA3u0/Render', 08:30:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:39 INFO - 'HOME': '/Users/cltbld', 08:30:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:39 INFO - 'LOGNAME': 'cltbld', 08:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:39 INFO - 'MOZ_NO_REMOTE': '1', 08:30:39 INFO - 'NO_EM_RESTART': '1', 08:30:39 INFO - 'PAGER': '/bin/cat', 08:30:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:39 INFO - 'PWD': '/builds/slave/test', 08:30:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:39 INFO - 'SHELL': '/bin/bash', 08:30:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVhQR8ZId1/Listeners', 08:30:39 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 08:30:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:39 INFO - 'USER': 'cltbld', 08:30:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:39 INFO - 'XPC_FLAGS': '0x0', 08:30:39 INFO - 'XPC_SERVICE_NAME': '0', 08:30:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:39 INFO - Downloading/unpacking jsonschema==2.5.1 08:30:39 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 08:30:39 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 08:30:39 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 08:30:39 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 08:30:39 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 08:30:39 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 08:30:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:30:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:42 INFO - Downloading functools32-3.2.3-2.tar.gz 08:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:30:42 INFO - warning: no files found matching '*.txt' 08:30:42 INFO - no previously-included directories found matching 'build' 08:30:42 INFO - no previously-included directories found matching 'dist' 08:30:42 INFO - no previously-included directories found matching '.git*' 08:30:42 INFO - Installing collected packages: jsonschema, functools32 08:30:42 INFO - Running setup.py install for functools32 08:30:42 INFO - warning: no files found matching '*.txt' 08:30:42 INFO - no previously-included directories found matching 'build' 08:30:42 INFO - no previously-included directories found matching 'dist' 08:30:42 INFO - no previously-included directories found matching '.git*' 08:30:42 INFO - Successfully installed jsonschema functools32 08:30:42 INFO - Cleaning up... 08:30:42 INFO - Return code: 0 08:30:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:42 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 0x10ecaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d8d76580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eabed30>, '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 0x10e924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eab6b20>, '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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 08:30:42 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 08:30:42 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 08:30:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGmkvkA3u0/Render', 08:30:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:42 INFO - 'HOME': '/Users/cltbld', 08:30:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:42 INFO - 'LOGNAME': 'cltbld', 08:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:42 INFO - 'MOZ_NO_REMOTE': '1', 08:30:42 INFO - 'NO_EM_RESTART': '1', 08:30:42 INFO - 'PAGER': '/bin/cat', 08:30:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:42 INFO - 'PWD': '/builds/slave/test', 08:30:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:42 INFO - 'SHELL': '/bin/bash', 08:30:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVhQR8ZId1/Listeners', 08:30:42 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 08:30:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:42 INFO - 'USER': 'cltbld', 08:30:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:42 INFO - 'XPC_FLAGS': '0x0', 08:30:42 INFO - 'XPC_SERVICE_NAME': '0', 08:30:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:30:42 INFO - Cleaning up... 08:30:42 INFO - Return code: 0 08:30:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:42 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 0x10ecaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d8d76580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eabed30>, '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 0x10e924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eab6b20>, '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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 08:30:42 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 08:30:42 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 08:30:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGmkvkA3u0/Render', 08:30:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:42 INFO - 'HOME': '/Users/cltbld', 08:30:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:42 INFO - 'LOGNAME': 'cltbld', 08:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:42 INFO - 'MOZ_NO_REMOTE': '1', 08:30:42 INFO - 'NO_EM_RESTART': '1', 08:30:42 INFO - 'PAGER': '/bin/cat', 08:30:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:42 INFO - 'PWD': '/builds/slave/test', 08:30:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:42 INFO - 'SHELL': '/bin/bash', 08:30:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVhQR8ZId1/Listeners', 08:30:42 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 08:30:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:42 INFO - 'USER': 'cltbld', 08:30:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:42 INFO - 'XPC_FLAGS': '0x0', 08:30:42 INFO - 'XPC_SERVICE_NAME': '0', 08:30:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:43 INFO - Downloading/unpacking blobuploader==1.2.4 08:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:45 INFO - Downloading blobuploader-1.2.4.tar.gz 08:30:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:30:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:30:45 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 08:30:45 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 08:30:45 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 08:30:45 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 08:30:45 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 08:30:45 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 08:30:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:30:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:30:46 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 08:30:46 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 08:30:46 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 08:30:46 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 08:30:46 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 08:30:46 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 08:30:46 INFO - Downloading docopt-0.6.1.tar.gz 08:30:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:30:47 INFO - Installing collected packages: blobuploader, requests, docopt 08:30:47 INFO - Running setup.py install for blobuploader 08:30:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:30:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:30:47 INFO - Running setup.py install for requests 08:30:47 INFO - Running setup.py install for docopt 08:30:47 INFO - Successfully installed blobuploader requests docopt 08:30:47 INFO - Cleaning up... 08:30:47 INFO - Return code: 0 08:30:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:47 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 0x10ecaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d8d76580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eabed30>, '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 0x10e924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eab6b20>, '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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 08:30:47 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 08:30:47 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 08:30:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGmkvkA3u0/Render', 08:30:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:47 INFO - 'HOME': '/Users/cltbld', 08:30:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:47 INFO - 'LOGNAME': 'cltbld', 08:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:47 INFO - 'MOZ_NO_REMOTE': '1', 08:30:47 INFO - 'NO_EM_RESTART': '1', 08:30:47 INFO - 'PAGER': '/bin/cat', 08:30:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:47 INFO - 'PWD': '/builds/slave/test', 08:30:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:47 INFO - 'SHELL': '/bin/bash', 08:30:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVhQR8ZId1/Listeners', 08:30:47 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 08:30:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:47 INFO - 'USER': 'cltbld', 08:30:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:47 INFO - 'XPC_FLAGS': '0x0', 08:30:47 INFO - 'XPC_SERVICE_NAME': '0', 08:30:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:30:48 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-hT57ZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:30:48 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-14ga3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:30:48 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-HySpO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:30:48 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-SbB9H7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:30:48 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-YjNtXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:30:48 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-cmIsmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:30:48 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-fo_SOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:30:48 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-OlVc4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:30:49 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-A_prBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:30:49 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-BtXHsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:30:49 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-iY9q49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:30:49 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-8sVLxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:30:49 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-8pMAW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:30:49 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-p8K162-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:49 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-hvB35e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:30:49 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-OT755M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:30:49 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-IXHUKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:30:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:30:49 INFO - Running setup.py install for manifestparser 08:30:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:30:50 INFO - Running setup.py install for mozcrash 08:30:50 INFO - Running setup.py install for mozdebug 08:30:50 INFO - Running setup.py install for mozdevice 08:30:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:30:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:30:50 INFO - Running setup.py install for mozfile 08:30:50 INFO - Running setup.py install for mozhttpd 08:30:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:30:50 INFO - Running setup.py install for mozinfo 08:30:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:30:50 INFO - Running setup.py install for mozInstall 08:30:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:30:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:30:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:30:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:30:51 INFO - Running setup.py install for mozleak 08:30:51 INFO - Running setup.py install for mozlog 08:30:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:30:51 INFO - Running setup.py install for moznetwork 08:30:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:30:51 INFO - Running setup.py install for mozprocess 08:30:51 INFO - Running setup.py install for mozprofile 08:30:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:30:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:30:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:30:51 INFO - Running setup.py install for mozrunner 08:30:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:30:52 INFO - Running setup.py install for mozscreenshot 08:30:52 INFO - Running setup.py install for moztest 08:30:52 INFO - Running setup.py install for mozversion 08:30:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:30:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:30:52 INFO - Cleaning up... 08:30:52 INFO - Return code: 0 08:30:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30:52 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')]} 08:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:52 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')]} 08:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:52 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 0x10ecaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d8d76580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eabed30>, '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 0x10e924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eab6b20>, '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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 08:30:52 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 08:30:52 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 08:30:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGmkvkA3u0/Render', 08:30:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:52 INFO - 'HOME': '/Users/cltbld', 08:30:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:52 INFO - 'LOGNAME': 'cltbld', 08:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:52 INFO - 'MOZ_NO_REMOTE': '1', 08:30:52 INFO - 'NO_EM_RESTART': '1', 08:30:52 INFO - 'PAGER': '/bin/cat', 08:30:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:52 INFO - 'PWD': '/builds/slave/test', 08:30:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:52 INFO - 'SHELL': '/bin/bash', 08:30:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVhQR8ZId1/Listeners', 08:30:52 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 08:30:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:52 INFO - 'USER': 'cltbld', 08:30:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:52 INFO - 'XPC_FLAGS': '0x0', 08:30:52 INFO - 'XPC_SERVICE_NAME': '0', 08:30:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:30:52 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-9qXYXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:30:52 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)) 08:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:30:52 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-G9CADv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:30:53 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)) 08:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:30:53 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-OUgBT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:30:53 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)) 08:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:30:53 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-duAqiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:30:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 08:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:30:53 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-5roPly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:30:53 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)) 08:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:30:53 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-qxvKsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:30:53 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)) 08:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:30:53 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-wEny4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:30:53 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)) 08:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:30:53 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-KWYGwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:30:53 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)) 08:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:30:53 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-J6XKOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:30:53 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)) 08:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:30:53 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-z0_VLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:30:53 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)) 08:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:30:53 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-JsQVNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:30:53 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)) 08:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:30:53 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-adLD8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 08:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:30:54 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-xcchBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:30:54 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)) 08:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:30:54 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-wyPAxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:30:54 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)) 08:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:54 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-qqcvwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:54 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)) 08:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:30:54 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-ydi8me-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:30:54 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)) 08:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:30:54 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-6iOsDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:30:54 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)) 08:30:54 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)) 08:30:54 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)) 08:30:54 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)) 08:30:54 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:30:54 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:30:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:30:54 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 08:30:54 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 08:30:54 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 08:30:54 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 08:30:54 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 08:30:54 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 08:30:57 INFO - Downloading blessings-1.6.tar.gz 08:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:30:57 INFO - Installing collected packages: blessings 08:30:57 INFO - Running setup.py install for blessings 08:30:57 INFO - Successfully installed blessings 08:30:57 INFO - Cleaning up... 08:30:57 INFO - Return code: 0 08:30:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:30:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:30:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:30:57 INFO - Reading from file tmpfile_stdout 08:30:57 INFO - Current package versions: 08:30:57 INFO - blessings == 1.6 08:30:57 INFO - blobuploader == 1.2.4 08:30:57 INFO - docopt == 0.6.1 08:30:57 INFO - functools32 == 3.2.3-2 08:30:57 INFO - jsonschema == 2.5.1 08:30:57 INFO - manifestparser == 1.1 08:30:57 INFO - mozInstall == 1.12 08:30:57 INFO - mozcrash == 1.0 08:30:57 INFO - mozdebug == 0.1 08:30:57 INFO - mozdevice == 0.48 08:30:57 INFO - mozfile == 1.2 08:30:57 INFO - mozhttpd == 0.7 08:30:57 INFO - mozinfo == 0.9 08:30:57 INFO - mozleak == 0.1 08:30:57 INFO - mozlog == 3.4 08:30:57 INFO - moznetwork == 0.27 08:30:57 INFO - mozprocess == 0.23 08:30:57 INFO - mozprofile == 0.28 08:30:57 INFO - mozrunner == 6.13 08:30:57 INFO - mozscreenshot == 0.1 08:30:57 INFO - mozsystemmonitor == 0.3 08:30:57 INFO - moztest == 0.8 08:30:57 INFO - mozversion == 1.4 08:30:57 INFO - psutil == 3.1.1 08:30:57 INFO - requests == 1.2.3 08:30:57 INFO - wsgiref == 0.1.2 08:30:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30:57 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')]} 08:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:58 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 0x10ecaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d8d76580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eabed30>, '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 0x10e924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eab6b20>, '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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 08:30:58 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 08:30:58 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 08:30:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGmkvkA3u0/Render', 08:30:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:58 INFO - 'HOME': '/Users/cltbld', 08:30:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:58 INFO - 'LOGNAME': 'cltbld', 08:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:58 INFO - 'MOZ_NO_REMOTE': '1', 08:30:58 INFO - 'NO_EM_RESTART': '1', 08:30:58 INFO - 'PAGER': '/bin/cat', 08:30:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:58 INFO - 'PWD': '/builds/slave/test', 08:30:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:58 INFO - 'SHELL': '/bin/bash', 08:30:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVhQR8ZId1/Listeners', 08:30:58 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 08:30:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:58 INFO - 'USER': 'cltbld', 08:30:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:58 INFO - 'XPC_FLAGS': '0x0', 08:30:58 INFO - 'XPC_SERVICE_NAME': '0', 08:30:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:58 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)) 08:30:58 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)) 08:30:58 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)) 08:30:58 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)) 08:30:58 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)) 08:30:58 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)) 08:30:58 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)) 08:30:58 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)) 08:30:58 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)) 08:30:58 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)) 08:30:58 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)) 08:30:58 INFO - Cleaning up... 08:30:58 INFO - Return code: 0 08:30:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:59 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 0x10ecaa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec20570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d8d76580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eabed30>, '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 0x10e924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eab6b20>, '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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 08:30:59 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 08:30:59 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 08:30:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGmkvkA3u0/Render', 08:30:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:59 INFO - 'HOME': '/Users/cltbld', 08:30:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:59 INFO - 'LOGNAME': 'cltbld', 08:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:59 INFO - 'MOZ_NO_REMOTE': '1', 08:30:59 INFO - 'NO_EM_RESTART': '1', 08:30:59 INFO - 'PAGER': '/bin/cat', 08:30:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:59 INFO - 'PWD': '/builds/slave/test', 08:30:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:59 INFO - 'SHELL': '/bin/bash', 08:30:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVhQR8ZId1/Listeners', 08:30:59 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 08:30:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:59 INFO - 'USER': 'cltbld', 08:30:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:59 INFO - 'XPC_FLAGS': '0x0', 08:30:59 INFO - 'XPC_SERVICE_NAME': '0', 08:30:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:30:59 INFO - Cleaning up... 08:30:59 INFO - Return code: 0 08:30:59 INFO - Running post-action listener: _resource_record_post_action 08:30:59 INFO - Running post-action listener: _start_resource_monitoring 08:30:59 INFO - Starting resource monitoring. 08:30:59 INFO - [mozharness: 2017-01-20 16:30:59.684999Z] Finished create-virtualenv step (success) 08:30:59 INFO - [mozharness: 2017-01-20 16:30:59.685989Z] Running install step. 08:30:59 INFO - Running pre-action listener: _resource_record_pre_action 08:30:59 INFO - Running main action method: install 08:30:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:30:59 INFO - Reading from file tmpfile_stdout 08:30:59 INFO - Detecting whether we're running mozinstall >=1.0... 08:30:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:31:00 INFO - Reading from file tmpfile_stdout 08:31:00 INFO - Output received: 08:31:00 INFO - Usage: mozinstall [options] installer 08:31:00 INFO - Options: 08:31:00 INFO - -h, --help show this help message and exit 08:31:00 INFO - -d DEST, --destination=DEST 08:31:00 INFO - Directory to install application into. [default: 08:31:00 INFO - "/builds/slave/test"] 08:31:00 INFO - --app=APP Application being installed. [default: firefox] 08:31:00 INFO - mkdir: /builds/slave/test/build/application 08:31:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:31:31 INFO - Reading from file tmpfile_stdout 08:31:31 INFO - Output received: 08:31:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:31:31 INFO - Running post-action listener: _resource_record_post_action 08:31:31 INFO - [mozharness: 2017-01-20 16:31:31.058037Z] Finished install step (success) 08:31:31 INFO - [mozharness: 2017-01-20 16:31:31.058204Z] Running run-tests step. 08:31:31 INFO - Running pre-action listener: _resource_record_pre_action 08:31:31 INFO - Running main action method: run_tests 08:31:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:31:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:31:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:31:31 INFO - 2017-01-20 08:31:31.072 screenresolution[1621:7305] starting screenresolution argv=screenresolution get 08:31:31 INFO - 2017-01-20 08:31:31.075 screenresolution[1621:7305] Display 0: 1600x1200x32@60 08:31:31 INFO - 2017-01-20 08:31:31.081 screenresolution[1622:7308] starting screenresolution argv=screenresolution list 08:31:31 INFO - Available Modes on Display 0 08:31:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:31:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:31:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:31:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:31:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:31:31 INFO - Intel Iris: 08:31:31 INFO - Chipset Model: Intel Iris 08:31:31 INFO - Type: GPU 08:31:31 INFO - Bus: Built-In 08:31:31 INFO - VRAM (Dynamic, Max): 1536 MB 08:31:31 INFO - Vendor: Intel (0x8086) 08:31:31 INFO - Device ID: 0x0a2e 08:31:31 INFO - Revision ID: 0x0009 08:31:31 INFO - Displays: 08:31:31 INFO - Display: 08:31:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:31:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:31:31 INFO - Display Serial Number: 1600x1200 60 08:31:31 INFO - Main Display: Yes 08:31:31 INFO - Mirror: Off 08:31:31 INFO - Online: Yes 08:31:31 INFO - Rotation: Supported 08:31:31 INFO - Return code: 0 08:31:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:31:31 INFO - Python 2.7.3 08:31:31 INFO - Return code: 0 08:31:31 INFO - grabbing minidump binary from tooltool 08:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:31 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 0x10eabed30>, '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 0x10e924db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eab6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:31:31 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 08:31:31 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 08:31:31 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 08:31:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:31:31 INFO - Return code: 0 08:31:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:31:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:31:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:31:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:31:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:31:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0341', '--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 08:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0341 --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 08:31:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGmkvkA3u0/Render', 08:31:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:31 INFO - 'HOME': '/Users/cltbld', 08:31:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:31 INFO - 'LOGNAME': 'cltbld', 08:31:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:31:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:31 INFO - 'MOZ_NO_REMOTE': '1', 08:31:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:31:31 INFO - 'NO_EM_RESTART': '1', 08:31:31 INFO - 'PAGER': '/bin/cat', 08:31:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:31 INFO - 'PWD': '/builds/slave/test', 08:31:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:31:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:31 INFO - 'SHELL': '/bin/bash', 08:31:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVhQR8ZId1/Listeners', 08:31:31 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 08:31:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:31 INFO - 'USER': 'cltbld', 08:31:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:31 INFO - 'XPC_FLAGS': '0x0', 08:31:31 INFO - 'XPC_SERVICE_NAME': '0', 08:31:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0341', '--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 08:31:31 INFO - mozversion application_buildid: 20170120072124 08:31:31 INFO - mozversion application_changeset: dc33d7851ba557007fc49819d80320f9bf9f613e 08:31:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:31:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:31:31 INFO - mozversion application_name: Firefox 08:31:31 INFO - mozversion application_remotingname: firefox-dev 08:31:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:31:31 INFO - mozversion application_vendor: Mozilla 08:31:31 INFO - mozversion application_version: 52.0a2 08:31:31 INFO - mozversion platform_buildid: 20170120072124 08:31:31 INFO - mozversion platform_changeset: dc33d7851ba557007fc49819d80320f9bf9f613e 08:31:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:31:31 INFO - mozversion platform_version: 52.0a2 08:31:31 INFO - using testdate: 1484929891 08:31:31 INFO - actual date: 1484929891 08:31:31 INFO - starting webserver on 'localhost:49243' 08:31:31 INFO - SUITE-START | Running 2 tests 08:31:31 INFO - TEST-START | dromaeo_css 08:31:31 INFO - operating with platform_type : mac_ 08:31:31 INFO - Initialising browser for dromaeo_css test... 08:31:31 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpWbwmA8/profile http://localhost:49243/getInfo.html) 08:31:34 INFO - PROCESS | 1632 | 2017-01-20 08:31:34.679 plugin-container[1634:7492] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 08:31:34 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:31:34 INFO - PROCESS | 1632 | 2017-01-20 08:31:34.682 plugin-container[1634:7492] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 08:31:34 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:31:34 INFO - PROCESS | 1632 | 2017-01-20 08:31:34.682 plugin-container[1634:7492] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:31:34 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 08:31:34 INFO - PROCESS | 1632 | colorDepth:24 08:31:34 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 08:31:34 INFO - PROCESS | 1632 | __metrics 08:31:34 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 08:31:35 INFO - TEST-INFO | 1632: exit 0 08:31:35 INFO - Browser initialized. 08:31:35 INFO - Running cycle 1/1 for dromaeo_css test... 08:31:35 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpWbwmA8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:38 INFO - PROCESS | 1635 | 2017-01-20 08:31:38.236 plugin-container[1636:7624] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 08:31:38 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:31:38 INFO - PROCESS | 1635 | 2017-01-20 08:31:38.237 plugin-container[1636:7624] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 08:31:38 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:31:38 INFO - PROCESS | 1635 | 2017-01-20 08:31:38.237 plugin-container[1636:7624] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:31:47 INFO - PROCESS | 1635 | RSS: Main: 228651008 08:31:47 INFO - PROCESS | 1635 | 08:34:49 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 08:34:51 INFO - PROCESS | 1635 | RSS: Main: 233721856 08:34:51 INFO - PROCESS | 1635 | 08:37:53 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 08:37:54 INFO - PROCESS | 1635 | RSS: Main: 243666944 08:37:54 INFO - PROCESS | 1635 | 08:40:56 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 08:40:56 INFO - PROCESS | 1635 | RSS: Main: 246648832 08:40:56 INFO - PROCESS | 1635 | 08:43:58 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 08:43:59 INFO - PROCESS | 1635 | RSS: Main: 247062528 08:43:59 INFO - PROCESS | 1635 | 08:47:02 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 08:47:02 INFO - PROCESS | 1635 | RSS: Main: 250544128 08:47:02 INFO - PROCESS | 1635 | 08:50:05 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 08:50:05 INFO - PROCESS | 1635 | RSS: Main: 251650048 08:50:05 INFO - PROCESS | 1635 | 08:50:05 INFO - PROCESS | 1635 | __start_tp_report 08:50:05 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 08:50:05 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 08:50:05 INFO - PROCESS | 1635 | |i|pagename|runs| 08:50:05 INFO - PROCESS | 1635 | |0;dojo.html;3035.696430356963;3240.3357311751106;3414.197663549066;3466.2027733621267;3473.1143558392623;5685.943140568598;5695.829125126248;5715.142728590703;5766.0486019806685;5776.191333213354;5706.686132262724;5785.103308987103;5800.594600785819;5808.535317174616;5818.389069147742;5271.314729085214;5277.894442111175;5318.361796584415;5345.518903298722;5364;5230.2416149658275;5230.660007099526;5332.066888294552;5334.733263336818;5398.757055932492;5111.948880511184;5159.380874295071;5182.326297581328;5279.155335146377;5303.098473259516;5145.562627176694;5167.354080739908;5169.224616307546;5209.270702101709;5233.9738301308635;4184;5091.287219789232;5129.846104616868;5214.556762675177;5255.159174532057;5229.058769421503;5562.4993750562235;5618.831435056954;5759.078547432433;5788.623739456964;6147.78482753106;6194.225721784777;6261.592996455216;6268.404501572389;6273.090401891745;6227.1282020517165;6237.532185086091;6252.468540174091;6286.968565157145;6381.52138589603;4970.403551573834;5698.458646428583;5819.330776960619;5833.416658334218;5871.794487192928;5344.759485823147;6306.24325080984;6376.234851817719;6379.106925030499;6403.679816009181;6193.349698281706;6195.4424101831055;6202.534809889276;6219.036049412348;6240.968795155995;6053.8486537836025;6252.968735156295;6271.874562508816;6300.369963003663;6300.621962682254;5125.333706618116;5993.730282137314;6109.617229398918;6168.691565421711;6252.09344645033;6202.689865506707;6240.563160578716;6266.905996410058;6279.152314437492;6293.779717709858;4949.232868905326;4975.353204083518;4976.129177393942;4979.153543897546;4992.101421744048;4299.720518166309;4351.934720979188;4383.671224658164;4386.868393948187;4488.12481566098;5763.827085187451;5786.2477877876445;5849.853753656107;5855.414458554111;5913.881722365614;6171.166892469549;6276.811695649138;6286.748530058838;6292.779752708633;6319.05214217871;5723.570732195102;5759;5823.970880145657;5864.091065884794;5943.435373639584;5425.6202065855805;5454.209139674805;5479;5481.780728770805;5486.31421072366;6131.0096813291075;6388.8083357499345;6412.038194270804;6419.775307864203;6446.484281257582;6174.907376389448;6205.6586887721605;6250.843728906631;6290.961991271389;6293.937060629335;6428.164338635947;6442.065900444316;6447.258565264867;6448.065030570635;6466.838329041811;6230;6236.65698386575;6238.313785483684;6259.718312676032;6283.057541368831;5862.94137058624;6162.722677479434;6188.907166392418;6195.411435913581;6290.2766181889665;6185.474234689922;6196.256449226122;6223.315435302023;6224.2842073160355;6224.844378890564;6148.9692551536955;6151.723172457339;6199.721012554536;6203.193584833943;6204.937950620436;5087.186050232093;5747.9137812932;5787.565932554991;5981.850453738517;6027.608205466806;5420.620556561003;5900.350961394157;5946.226990491208;6023.969880150747;6025.0359942409;5381.11211650089;5793.391693872251;5794.159846822103;5794.710264486843;5825.533957283492 08:50:05 INFO - PROCESS | 1635 | |1;ext.html;458.008411788478;462.3296220480302;464.50762192076394;498.6384870968546;499.6602310428909;30055.248618784513;37004.44493332599;39042.82871513852;39082;39098.413523797164;39647.801760991155;40359.596404035954;40435;40514.79742601283;40562.18875622486;9312.720618381443;9369.344145909788;9499.667511637093;9561.378510396802;9585.185259252972;9436.764080897998;9546;9557.13985741283;9559.28305377096;9572.234221262283;9410.058994100604;9510.144087032166;9578.856317155249;9582;9598.952005239964;9485.004074572174;9572.952135239279;9573.473458959754;9592.808143837085;9604.327697061244;9419.811603767886;9426.010268921733;9455.33812633116;9526;9558.56986435612;38885.22229555422;39107.8044609778;39407.80296098501;39527.20945581101;39533.60466395357;3282.4091663500562;3298.356820420019;3383.441732114198;3445.8277086145845;3466.757326987112;29957.101286961428;30140.698593014007;30154.396912061857;30541;30802.845985769927;3134.2634480896972;3214.9357012859846;3221.7100460958627;3265.053134590966;3301.4717645176656;396745;509100.27197824087;512094;516515.9999999962;520510;57728.422715773144;58115.8376832461;58257.70871145702;58268;58299.70850145722;51873.2219016715;61525.76948461006;61742;61898.69050654718;62062;15984.76022859658;16196.75704864428;16212.351505939867;16256.431024914307;16310.673786524441;12098.697532561757;12467;12661.113722039552;12699.174553653984;12733.936330318475;15853.207339633203;16008.759868601981;16298.348066077462;16361.91819040921;16383.836161638232;6242.939829856136;6256.43692067707;6268.968655156695;6273.643600673618;6302.873942521124;812.573398965536;988.1600639456404;1027.3527677563088;1061.6039907521586;1109.8168802147716;29619.407611847644;29634.407311854073;29714.851425742734;29880.85059574688;30463.543046854316;1043.6138628707274;1091.9727006824894;1127.1546340244815;1140.2816225777708;1158.449736375215;12017.755206094305;15054.849451505565;16172.272247748879;16319.59201019984;16449.342026319053;463152;640431;643917;644583;645255;56173.71913140408;56339.43660563424;56893.71553142208;56895;57076.42923570711;1265.367316341829;1292.0245214862907;1323.014354306029;1373.6565858535366;1384.487739536378;29495.55756663609;29763.851180743957;30267.545986809775;30425.23936901595;30885;48794.02411951812;49370.50629493659;50263;50300.748496257285;50308.24537631865;485844;487380;489625;489783;646711;22486.5502689942;25776.84004219852;25936.22191334263;26014.089506867167;26332.21003369902;33838.83080584581;43784.343234850865;44104.77947610241;44802.3279650799;45361.185552577525;36764.87339107937;37201.06997325089;37202.06994825151;38006.42990355092;39132;29956.251093722833;37249.6275037257;37364.318605662265;38094.23811523809;38274.617253827106;35673;36079;36808;36837.44743828898;37580.2483950314;27083.322916926445;31932;33603;33855.15362115967;33911.660883391836;24659.630105548073;24930.626040609768;25001.499970000135;25285.74714252834;25301.746982529938 08:50:05 INFO - PROCESS | 1635 | |2;jquery.html;331.3555135261915;365.74032436969765;373.5218919782677;382.9923401531971;391.7257919456379;18713.34503292385;19641;19674.508137296576;20248.987550622463;20460.28389006385;19652;19679.032096790314;19901;20129.396118116445;20168.294109706163;5669.149627555858;5856.56075794315;5865.208196893407;5940.198073260098;5940.435658612447;4091.89770255743;4322.762248076355;5209.505097015796;5596.104623260279;6467.870642587145;6395.8401039973905;6461.483081353481;6468.450181734558;6479.319671434502;6496.870062598745;6315.74737010519;6385.138006369127;6407.551471396981;6468.256150542654;6470.029495575654;5548.500634942875;5576;5642.097264437671;5644.971775141139;5653.23681303027;24455.510889782283;24507.754922450724;24610.8925098371;24729.887155078064;25033.62449563258;2332.323626148415;2354.764523547649;2413.3966508372905;2631.5657916443765;3107.1300035989957;14635;14779;14952.40190392383;15523.223838808128;15576.610584735472;1831.4139475367888;1913.1868955693913;1928.9710654340197;1935.5161209697576;1952.2971730177126;115650;116096.41951790187;116438;116670;117071;9014.23379012778;10597.470126493645;10913.83629245562;10985.61550345734;11329.376884271443;13369.598912032654;13561.050726449252;13574;13604;13615.795763063563;8330.125336839666;8398.908141941509;8539.444936079259;8554.828903421896;8688.044315125331;6946.305369463013;8427.789305267417;8506.532140732319;8596.355273354524;8668.176523230282;6491.318411566812;6574.76988305407;6595.934040659532;6630.9668451657435;6678.666066696645;1376.7108907129414;1689.2313997131275;1702.7786387769759;1737.1661602430652;1817.8727489075638;851.82537579796;907.138218692245;925.8796356473615;981.5337714585514;988.678679429174;16562.751558726628;18099.110622265893;20292.594148116954;20412.285570004977;20686.58626827455;937.5452905341041;944.9102335278137;963.6868017894212;989.8913217196642;1038.9792204156026;9923.851142232872;10697.572097116028;10736.30214036085;10753.677389678165;10775.407352595526;596790;597829;604011;607635;609209;115124;120087;122469;123232;123470;924.3390975452584;932.3892850183141;954.0983770337103;959.1128206409044;960.1646567486192;17814.821851781315;17837;18151.81848181501;18160.091995399915;18273;40678.59321406748;42424.78787606042;42764.930876727085;54574;54929.72535137299;76169.23830761621;76604;76780.08049798799;77089.61455192688;77946;5915.319738230159;6829.146356705412;6955.408790252885;7018.719251230098;7109.573425594481;8045.436819422584;11544.769104617693;11584.536618535165;11612;11661.367408562977;9805.950970245103;10147.34042287249;10158.034986676254;10310.484475776331;10761.946190268998;10109.64616238428;10215.642452514126;10246.948765256126;10345.515480370275;10353.432013494352;10446.738831529272;10462.895371046496;10609.416482093558;10783.51474183648;10934.125269978542;7034.648267586703;7051.753188638373;7209.0628218331285;7210.1708303545765;7246.311600397954;8196.795080123045;10255.33340332876;10365.585376585153;10711.036006759428;10727.785444290914 08:50:05 INFO - PROCESS | 1635 | |3;mootools.html;1466.9119852808828;1482.9110253384797;1631.681822044701;1753.614204874927;1756.4818378578318;7560.810979725509;7818.022747156606;8079.070906845707;8490.98108227013;8561.101084386144;8072.596370181497;8345.499270043787;8364.665413383458;8391.916080839204;8485.957570212156;2313;2337.9946622952125;2338.5907466193435;2338.684277622525;2358.457554762406;2322.3497420722224;2366.917157899465;2376.192094687806;2383.0348708772976;2386.522695460906;2317.571249318873;2326.3718795925092;2342.8711420871846;2346.483773569812;2359.0092161292214;2256.4697296135396;2262.332611879483;2283.748787633359;2286.2341115726254;2294.921386948132;2332.3819187915215;2359.127122964514;2359.5634807560655;2366.1954935322074;2366.645003249509;7127.928720712778;8309.792255193608;8341.20758527939;8379;8451.619677114544;5004.9749751251375;5064.063148317573;5223.660462069991;5227.2159176123505;5241.842744717665;3382.678645528671;3452.361313157074;3577.302426026926;3581.5287451658273;3600.3537400010973;1034.5861655337849;1041.1722680469045;1089.6349722842858;1090.0571006079763;1104.0946423932378;10924.617638382699;11034.393108379036;11049.613263535817;11073.889261107366;11089.500972456259;4113.868686111284;4455.509893911667;4525.434320709911;4743.501932297128;4788.090262850047;9489.66786162481;9596.66411675588;9601.90398096024;9612.327137100476;9617.903820961701;4582.991741816795;5087.720175390315;5239;5249.632525723239;5253.658512196695;4285.61429471349;4414.80133393998;4624.421947256593;4761.880952976134;4791.95208047922;3838.846446142179;3882.2235552889533;3913.021744563859;3974.4435778990965;3994.1612261424843;1058.708855064848;1270.733146039342;1273.5988163728396;1281.9679508012375;1295.7019885426405;874.9063670411985;879.384430898361;886.7118186589385;887.3699673232078;900.4282280751639;5607.186957891083;5774.82675519735;5779.913301300484;5909.881802363929;5955.196048533393;889.1641856654724;891.3804905590682;908.7455512456522;912.0742446416892;930.6696122876282;3768.3970564709516;3857.40210267409;3871.0128917125953;3873.12854607711;3876.321643712339;10072.848907266396;10455.94772026135;10473.84289235662;10491.94754026225;10511.94744026275;10144.188464922789;10277.640282590073;10301.53643086063;10411.583536658449;10434.113100386401;7509.436792240604;7792.337651299703;7906.169852165554;7907.604619768988;7954.602269886483;5791.131330300489;5811.82564523065;5884.470397664059;5937.5249980000635;5996.790112346047;9273.21177699876;9289.256859451092;9409.529523523934;9603.759906002126;9608.90391096108;9298.628054877729;9570.808583828424;9609.183219426428;9660.758481037748;9703.417794932328;6597.505187111082;7263.237360077116;7266.200717920919;7339.9266007341375;7387.445941554298;8042.919570804217;8095.069067057364;8170.387220958333;8175.591220439073;8184.181581841768;7035.542689725152;7097.432205423657;7226.060612120601;7254.96372518134;7344.449166312441;6310.652914089769;6901.24086350491;6956.060931774348;6988.021676965331;7084.25615310385;5822.3304320003745;6809.046733457221;6820.556663816836;6898.482613803884;6910.17077950629;4321.58944900234;4665.693605790384;4845.442774081026;4874.098291816025;4895;4845.248986407112;4897.975510122569;4928.753562321941;5056.519630635084;5087.567556757717 08:50:05 INFO - PROCESS | 1635 | |4;prototype.html;302.9303260250142;303.8890804856227;312.8185652321654;315.84681429506685;316.56314286284925;4359.122978833966;4650.581447669713;5219.973900130494;5264.473552644743;5418.647787893789;4892.3884514435695;5451.3458384993755;5476.835694929158;5492.945070549294;5508.9724551377185;4196.958030419695;4216.915661686764;4222.303319952204;4229.323308270677;4237.682173836959;4084.7549147051272;4166.666666666674;4201.033762234688;4212.452381190441;4242.448481697391;4155.400170934182;4161.417401563784;4173.791310434482;4181.853635122771;4202.852900148496;4141.834326626931;4160.750354978696;4174.582541745832;4175.707700460969;4218.367244913257;3676.5955744868043;3690.4464330350393;3694.058015206132;3701.925961480755;3706.7590606610484;4315.5900189481945;4331.870043898688;4891.551456223354;5331.653442526263;5365.570754339644;3992.8003599819895;4321.935170972438;4428.734275943421;4577.542245775429;4805.639577031701;5713.771449142029;5782.219400380936;5789.536837053068;5805.36141024487;5828.377230246364;3615.529981102466;3846.6921246776233;3879.825407856625;3921.4902262628807;3926.685865130811;5322.680639161663;5376.327959005124;6016.518678505709;6025.517958563304;6031.758729650809;3240.562524059269;3245.6407618115136;3963.2667956428154;3966.484357033567;3988.1425493518454;3211.277462570903;3841.654251117357;3875.418687196943;3988.481497405319;4048.5141782985643;4010.3357106800936;4268.40242366069;4691.217337411762;4719.457262414866;4722.929156062662;5085.7711402986215;5825.339778163203;5828.213191219131;5866.7066646668345;5870.358338747581;1042.723678225271;1047.7328281288223;1218.3542722357165;1306.3794697518792;1324.324594456834;708.479267738212;787.3740376400775;851.9659213631385;861.9051904290523;896.188949001155;4544.840930567414;5139.460356662496;5147.433782283946;5149.304843846107;5193.3900490847955;4635.629149668019;4753.217543421217;5035.5468007878735;5526.447355264441;5639.830805075772;4556.245845349565;4676.064787042537;4834.734089625104;4898.730569818694;5147.536721695065;2445.5108978204075;2833.518301888643;2921.3134913295357;2945.499265124891;2958.715825433767;5011.997600479918;5560.915621453859;5801.9419805802245;5803;5871.383504732027;4983.725895075733;5008.1486147355035;5252.713085294127;5293.935365207572;5508.017113839015;4780.581883952921;5153.742312884416;5187.507186817246;5859.882802344015;5879.7060146993335;5522.321673988193;5533.6403133796175;5647.604667673223;5688.175214593944;5713.315081633021;4993.650444468853;5124.487551244994;5252.422233554383;5411.918821217606;5563.332400112013;5496.917546236889;5780.912796738843;5814;5826.203422357207;5859.765609375577;3106.7980581262145;3728.4220945752368;3735.3089678409583;3758.3047136279874;3779.905502362463;4630.7453090079025;5229.895402091861;5602.702891814337;5605.383407825218;5621.831345059655;4700.743643051423;5570.972145139248;5650.26546548946;5663.830085097454;5702.7718891243885;5649.265595472562;5778.855528611818;5793.826185214451;5808;5820.330661973758;5326.920096198638;5637.238972738628;5680.4319568042865;5709.518028347893;5715.828525144252;5254.106801843772;5536.446355364431;5856.385079566584;5878.500327472213;5896.469317761251;5705.515031222392;5738.799142030009;5762.193292938908;5767;5801.419858014165 08:50:05 INFO - PROCESS | 1635 | |5;yui.html;600.6996501749126;660.1385192324018;693.9479539034573;716.7097325583137;756.5347311403489;14024.2286674233;14899.40402383906;14953;14962.775558366633;15003.774943375858;13418.396172172252;13428.127171733831;13531.932340338284;13552.864471355284;13563;638.4222278837636;667.9599224046573;812.3744716568239;829.8298848735994;835.6197929941892;797.2346547314585;826.1449399871135;842.296682270305;842.6713581703141;846.3440833354139;859.1623167411755;864.9221570058694;877.4998250996935;884.0010787809798;884.2439714044496;865.446114486729;866.1035827918074;867.0116067682847;871.4596949891045;873.0789017586444;632.1813251838834;648.5265755998148;649.6881496881523;650.4211251985737;651.062470043141;13029.934850325782;13648.181109133484;13917.58247252584;14008.369623366973;14064.507742229076;12512.81230781539;13645.113067650671;13652.86347136526;13657;13889.930550347284;10762.300450470695;11118.388488633122;11190.66428007161;11423.885761142365;11453.198276120675;4250.659947204216;4397.340398940152;4401.5598440156045;4403.251447253974;4524.049949510607;46553.30170047418;70800.29199707988;72555;73472;75684.86472702914;8799.912000880036;10249.538770755335;10495.685129446128;10512.526936287886;10620.203484738675;16724.58188545296;19866.801331986786;19939.601207976048;20114.798852011587;20226.696599551018;6379.202599675041;7636.885446718304;7706.921031055656;7780.922190778133;7798.649060792278;4165.10450253197;4173.373993900939;4179.707420480538;4241.575842415734;4261.765602891809;3368.444206705915;3469.6530346965606;3471.375152472579;3507.4914137449928;3513.4729790531114;644.8291202831253;674.2549482821412;698.0611078099947;702.2977022977022;708.6031822179596;504.63161891819175;561.0014174768918;562.5190462154881;627.5575719118011;655.9212894452696;7503.737369192052;8372.943875485404;8983.730488085368;9073.364864459425;9295.11696388842;629.4115002472703;640.1901594482982;644.4940721533667;665.6838001949133;680.829792551862;4123.835046598102;4474.239379305526;4487.932681009787;4497.0155627725;4560.5895469407915;66855.99716004264;69951;82667.17332826594;109701;111592.88407115988;43951.12097758027;63771.40571485657;73802.7859164226;86540;103932.48033759934;89611.20777584543;89615.65576516227;92971.53514232521;94508.52745736364;94549;8342.561464318092;8630.525321107149;8664.870026949475;8672.523011234443;8795.91204087951;53894.19158712701;56527;57128.42871571231;57219;58261.70869145627;62789.68605156945;63353.68323158355;74932.25067749471;76050;80811.59594202226;9224.907750922674;10870.510827012962;11026.669199924016;11029.558817647205;11066.723331916766;12241.265524068584;13028.348582571023;13404.798928015893;13483.73032539324;13641.931790340985;12455.564055258023;12631.305278209788;12715.10994230395;12806.743865122831;12902.003977054626;12381.384993984915;12777.808332874829;12818.615441536396;12962.546310879074;13336.533221337206;11488.253263537843;12491.625251242478;12569.49960285963;12580.245185288912;12665.683357916127;11023.338599684044;11091.279066860629;11126.165537584551;11156.888431115585;11296;11088.33469991803;11122.165837562054;11222.214444988773;11238.83141752858;11560.13299002595 08:50:05 INFO - PROCESS | 1635 | __end_tp_report 08:50:05 INFO - PROCESS | 1635 | __start_cc_report 08:50:05 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,367 08:50:05 INFO - PROCESS | 1635 | __end_cc_report 08:50:05 INFO - PROCESS | 1635 | __startTimestamp1484931005753__endTimestamp 08:50:05 INFO - PROCESS | 1635 | 08:50:05 INFO - PROCESS | 1635 | ------- Summary: start ------- 08:50:05 INFO - PROCESS | 1635 | Number of tests: 6 08:50:05 INFO - PROCESS | 1635 | 08:50:05 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5734.65 Median:5862.94 stddev:667.89 (11.4%) stddev-sans-first:635.49 08:50:05 INFO - PROCESS | 1635 | Values: 3035.7 3240.3 3414.2 3466.2 3473.1 5685.9 5695.8 5715.1 5766.0 5776.2 5706.7 5785.1 5800.6 5808.5 5818.4 5271.3 5277.9 5318.4 5345.5 5364.0 5230.2 5230.7 5332.1 5334.7 5398.8 5111.9 5159.4 5182.3 5279.2 5303.1 5145.6 5167.4 5169.2 5209.3 5234.0 4184.0 5091.3 5129.8 5214.6 5255.2 5229.1 5562.5 5618.8 5759.1 5788.6 6147.8 6194.2 6261.6 6268.4 6273.1 6227.1 6237.5 6252.5 6287.0 6381.5 4970.4 5698.5 5819.3 5833.4 5871.8 5344.8 6306.2 6376.2 6379.1 6403.7 6193.3 6195.4 6202.5 6219.0 6241.0 6053.8 6253.0 6271.9 6300.4 6300.6 5125.3 5993.7 6109.6 6168.7 6252.1 6202.7 6240.6 6266.9 6279.2 6293.8 4949.2 4975.4 4976.1 4979.2 4992.1 4299.7 4351.9 4383.7 4386.9 4488.1 5763.8 5786.2 5849.9 5855.4 5913.9 6171.2 6276.8 6286.7 6292.8 6319.1 5723.6 5759.0 5824.0 5864.1 5943.4 5425.6 5454.2 5479.0 5481.8 5486.3 6131.0 6388.8 6412.0 6419.8 6446.5 6174.9 6205.7 6250.8 6291.0 6293.9 6428.2 6442.1 6447.3 6448.1 6466.8 6230.0 6236.7 6238.3 6259.7 6283.1 5862.9 6162.7 6188.9 6195.4 6290.3 6185.5 6196.3 6223.3 6224.3 6224.8 6149.0 6151.7 6199.7 6203.2 6204.9 5087.2 5747.9 5787.6 5981.9 6027.6 5420.6 5900.4 5946.2 6024.0 6025.0 5381.1 5793.4 5794.2 5794.7 5825.5 08:50:05 INFO - PROCESS | 1635 | 08:50:05 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:66924.69 Median:38964.03 stddev:145715.54 (374.0%) stddev-sans-first:146038.85 08:50:05 INFO - PROCESS | 1635 | Values: 458.0 462.3 464.5 498.6 499.7 30055.2 37004.4 39042.8 39082.0 39098.4 39647.8 40359.6 40435.0 40514.8 40562.2 9312.7 9369.3 9499.7 9561.4 9585.2 9436.8 9546.0 9557.1 9559.3 9572.2 9410.1 9510.1 9578.9 9582.0 9599.0 9485.0 9573.0 9573.5 9592.8 9604.3 9419.8 9426.0 9455.3 9526.0 9558.6 38885.2 39107.8 39407.8 39527.2 39533.6 3282.4 3298.4 3383.4 3445.8 3466.8 29957.1 30140.7 30154.4 30541.0 30802.8 3134.3 3214.9 3221.7 3265.1 3301.5 396745.0 509100.3 512094.0 516516.0 520510.0 57728.4 58115.8 58257.7 58268.0 58299.7 51873.2 61525.8 61742.0 61898.7 62062.0 15984.8 16196.8 16212.4 16256.4 16310.7 12098.7 12467.0 12661.1 12699.2 12733.9 15853.2 16008.8 16298.3 16361.9 16383.8 6242.9 6256.4 6269.0 6273.6 6302.9 812.6 988.2 1027.4 1061.6 1109.8 29619.4 29634.4 29714.9 29880.9 30463.5 1043.6 1092.0 1127.2 1140.3 1158.4 12017.8 15054.8 16172.3 16319.6 16449.3 463152.0 640431.0 643917.0 644583.0 645255.0 56173.7 56339.4 56893.7 56895.0 57076.4 1265.4 1292.0 1323.0 1373.7 1384.5 29495.6 29763.9 30267.5 30425.2 30885.0 48794.0 49370.5 50263.0 50300.7 50308.2 485844.0 487380.0 489625.0 489783.0 646711.0 22486.6 25776.8 25936.2 26014.1 26332.2 33838.8 43784.3 44104.8 44802.3 45361.2 36764.9 37201.1 37202.1 38006.4 39132.0 29956.3 37249.6 37364.3 38094.2 38274.6 35673.0 36079.0 36808.0 36837.4 37580.2 27083.3 31932.0 33603.0 33855.2 33911.7 24659.6 24930.6 25001.5 25285.7 25301.7 08:50:05 INFO - PROCESS | 1635 | 08:50:05 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:34552.02 Median:1719.24 stddev:100398.14 (5839.7%) stddev-sans-first:100646.91 08:50:05 INFO - PROCESS | 1635 | Values: 331.4 365.7 373.5 383.0 391.7 18713.3 19641.0 19674.5 20249.0 20460.3 19652.0 19679.0 19901.0 20129.4 20168.3 5669.1 5856.6 5865.2 5940.2 5940.4 4091.9 4322.8 5209.5 5596.1 6467.9 6395.8 6461.5 6468.5 6479.3 6496.9 6315.7 6385.1 6407.6 6468.3 6470.0 5548.5 5576.0 5642.1 5645.0 5653.2 24455.5 24507.8 24610.9 24729.9 25033.6 2332.3 2354.8 2413.4 2631.6 3107.1 14635.0 14779.0 14952.4 15523.2 15576.6 1831.4 1913.2 1929.0 1935.5 1952.3 115650.0 116096.4 116438.0 116670.0 117071.0 9014.2 10597.5 10913.8 10985.6 11329.4 13369.6 13561.1 13574.0 13604.0 13615.8 8330.1 8398.9 8539.4 8554.8 8688.0 6946.3 8427.8 8506.5 8596.4 8668.2 6491.3 6574.8 6595.9 6631.0 6678.7 1376.7 1689.2 1702.8 1737.2 1817.9 851.8 907.1 925.9 981.5 988.7 16562.8 18099.1 20292.6 20412.3 20686.6 937.5 944.9 963.7 989.9 1039.0 9923.9 10697.6 10736.3 10753.7 10775.4 596790.0 597829.0 604011.0 607635.0 609209.0 115124.0 120087.0 122469.0 123232.0 123470.0 924.3 932.4 954.1 959.1 960.2 17814.8 17837.0 18151.8 18160.1 18273.0 40678.6 42424.8 42764.9 54574.0 54929.7 76169.2 76604.0 76780.1 77089.6 77946.0 5915.3 6829.1 6955.4 7018.7 7109.6 8045.4 11544.8 11584.5 11612.0 11661.4 9806.0 10147.3 10158.0 10310.5 10761.9 10109.6 10215.6 10246.9 10345.5 10353.4 10446.7 10462.9 10609.4 10783.5 10934.1 7034.6 7051.8 7209.1 7210.2 7246.3 8196.8 10255.3 10365.6 10711.0 10727.8 08:50:05 INFO - PROCESS | 1635 | 08:50:05 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5502.72 Median:5060.29 stddev:3027.73 (59.8%) stddev-sans-first:3021.03 08:50:05 INFO - PROCESS | 1635 | Values: 1466.9 1482.9 1631.7 1753.6 1756.5 7560.8 7818.0 8079.1 8491.0 8561.1 8072.6 8345.5 8364.7 8391.9 8486.0 2313.0 2338.0 2338.6 2338.7 2358.5 2322.3 2366.9 2376.2 2383.0 2386.5 2317.6 2326.4 2342.9 2346.5 2359.0 2256.5 2262.3 2283.7 2286.2 2294.9 2332.4 2359.1 2359.6 2366.2 2366.6 7127.9 8309.8 8341.2 8379.0 8451.6 5005.0 5064.1 5223.7 5227.2 5241.8 3382.7 3452.4 3577.3 3581.5 3600.4 1034.6 1041.2 1089.6 1090.1 1104.1 10924.6 11034.4 11049.6 11073.9 11089.5 4113.9 4455.5 4525.4 4743.5 4788.1 9489.7 9596.7 9601.9 9612.3 9617.9 4583.0 5087.7 5239.0 5249.6 5253.7 4285.6 4414.8 4624.4 4761.9 4792.0 3838.8 3882.2 3913.0 3974.4 3994.2 1058.7 1270.7 1273.6 1282.0 1295.7 874.9 879.4 886.7 887.4 900.4 5607.2 5774.8 5779.9 5909.9 5955.2 889.2 891.4 908.7 912.1 930.7 3768.4 3857.4 3871.0 3873.1 3876.3 10072.8 10455.9 10473.8 10491.9 10511.9 10144.2 10277.6 10301.5 10411.6 10434.1 7509.4 7792.3 7906.2 7907.6 7954.6 5791.1 5811.8 5884.5 5937.5 5996.8 9273.2 9289.3 9409.5 9603.8 9608.9 9298.6 9570.8 9609.2 9660.8 9703.4 6597.5 7263.2 7266.2 7339.9 7387.4 8042.9 8095.1 8170.4 8175.6 8184.2 7035.5 7097.4 7226.1 7255.0 7344.4 6310.7 6901.2 6956.1 6988.0 7084.3 5822.3 6809.0 6820.6 6898.5 6910.2 4321.6 4665.7 4845.4 4874.1 4895.0 4845.2 4898.0 4928.8 5056.5 5087.6 08:50:05 INFO - PROCESS | 1635 | 08:50:05 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4535.76 Median:5060.66 stddev:1401.71 (27.7%) stddev-sans-first:1369.16 08:50:05 INFO - PROCESS | 1635 | Values: 302.9 303.9 312.8 315.8 316.6 4359.1 4650.6 5220.0 5264.5 5418.6 4892.4 5451.3 5476.8 5492.9 5509.0 4197.0 4216.9 4222.3 4229.3 4237.7 4084.8 4166.7 4201.0 4212.5 4242.4 4155.4 4161.4 4173.8 4181.9 4202.9 4141.8 4160.8 4174.6 4175.7 4218.4 3676.6 3690.4 3694.1 3701.9 3706.8 4315.6 4331.9 4891.6 5331.7 5365.6 3992.8 4321.9 4428.7 4577.5 4805.6 5713.8 5782.2 5789.5 5805.4 5828.4 3615.5 3846.7 3879.8 3921.5 3926.7 5322.7 5376.3 6016.5 6025.5 6031.8 3240.6 3245.6 3963.3 3966.5 3988.1 3211.3 3841.7 3875.4 3988.5 4048.5 4010.3 4268.4 4691.2 4719.5 4722.9 5085.8 5825.3 5828.2 5866.7 5870.4 1042.7 1047.7 1218.4 1306.4 1324.3 708.5 787.4 852.0 861.9 896.2 4544.8 5139.5 5147.4 5149.3 5193.4 4635.6 4753.2 5035.5 5526.4 5639.8 4556.2 4676.1 4834.7 4898.7 5147.5 2445.5 2833.5 2921.3 2945.5 2958.7 5012.0 5560.9 5801.9 5803.0 5871.4 4983.7 5008.1 5252.7 5293.9 5508.0 4780.6 5153.7 5187.5 5859.9 5879.7 5522.3 5533.6 5647.6 5688.2 5713.3 4993.7 5124.5 5252.4 5411.9 5563.3 5496.9 5780.9 5814.0 5826.2 5859.8 3106.8 3728.4 3735.3 3758.3 3779.9 4630.7 5229.9 5602.7 5605.4 5621.8 4700.7 5571.0 5650.3 5663.8 5702.8 5649.3 5778.9 5793.8 5808.0 5820.3 5326.9 5637.2 5680.4 5709.5 5715.8 5254.1 5536.4 5856.4 5878.5 5896.5 5705.5 5738.8 5762.2 5767.0 5801.4 08:50:05 INFO - PROCESS | 1635 | 08:50:05 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:18858.15 Median:4542.32 stddev:26723.96 (588.3%) stddev-sans-first:26763.77 08:50:05 INFO - PROCESS | 1635 | Values: 600.7 660.1 693.9 716.7 756.5 14024.2 14899.4 14953.0 14962.8 15003.8 13418.4 13428.1 13531.9 13552.9 13563.0 638.4 668.0 812.4 829.8 835.6 797.2 826.1 842.3 842.7 846.3 859.2 864.9 877.5 884.0 884.2 865.4 866.1 867.0 871.5 873.1 632.2 648.5 649.7 650.4 651.1 13029.9 13648.2 13917.6 14008.4 14064.5 12512.8 13645.1 13652.9 13657.0 13889.9 10762.3 11118.4 11190.7 11423.9 11453.2 4250.7 4397.3 4401.6 4403.3 4524.0 46553.3 70800.3 72555.0 73472.0 75684.9 8799.9 10249.5 10495.7 10512.5 10620.2 16724.6 19866.8 19939.6 20114.8 20226.7 6379.2 7636.9 7706.9 7780.9 7798.6 4165.1 4173.4 4179.7 4241.6 4261.8 3368.4 3469.7 3471.4 3507.5 3513.5 644.8 674.3 698.1 702.3 708.6 504.6 561.0 562.5 627.6 655.9 7503.7 8372.9 8983.7 9073.4 9295.1 629.4 640.2 644.5 665.7 680.8 4123.8 4474.2 4487.9 4497.0 4560.6 66856.0 69951.0 82667.2 109701.0 111592.9 43951.1 63771.4 73802.8 86540.0 103932.5 89611.2 89615.7 92971.5 94508.5 94549.0 8342.6 8630.5 8664.9 8672.5 8795.9 53894.2 56527.0 57128.4 57219.0 58261.7 62789.7 63353.7 74932.3 76050.0 80811.6 9224.9 10870.5 11026.7 11029.6 11066.7 12241.3 13028.3 13404.8 13483.7 13641.9 12455.6 12631.3 12715.1 12806.7 12902.0 12381.4 12777.8 12818.6 12962.5 13336.5 11488.3 12491.6 12569.5 12580.2 12665.7 11023.3 11091.3 11126.2 11156.9 11296.0 11088.3 11122.2 11222.2 11238.8 11560.1 08:50:05 INFO - PROCESS | 1635 | -------- Summary: end -------- 08:50:05 INFO - PROCESS | 1635 | 08:50:06 INFO - TEST-INFO | 1635: exit 0 08:50:06 INFO - TEST-OK | dromaeo_css | took 1114762ms 08:50:06 INFO - TEST-START | kraken 08:50:06 INFO - operating with platform_type : mac_ 08:50:06 INFO - Initialising browser for kraken test... 08:50:06 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpu7kmUO/profile http://localhost:49243/getInfo.html) 08:50:09 INFO - PROCESS | 1647 | 2017-01-20 08:50:09.202 plugin-container[1648:10081] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 08:50:09 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:09 INFO - PROCESS | 1647 | 2017-01-20 08:50:09.234 plugin-container[1648:10081] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 08:50:09 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:09 INFO - PROCESS | 1647 | 2017-01-20 08:50:09.234 plugin-container[1648:10081] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:09 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 08:50:09 INFO - PROCESS | 1647 | colorDepth:24 08:50:09 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 08:50:09 INFO - PROCESS | 1647 | __metrics 08:50:09 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 08:50:10 INFO - TEST-INFO | 1647: exit 0 08:50:10 INFO - Browser initialized. 08:50:10 INFO - Running cycle 1/1 for kraken test... 08:50:10 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpu7kmUO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:12 INFO - PROCESS | 1649 | 2017-01-20 08:50:12.989 plugin-container[1650:10201] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 08:50:12 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:12 INFO - PROCESS | 1649 | 2017-01-20 08:50:12.989 plugin-container[1650:10201] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434f, name = 'com.apple.CFPasteboardClient' 08:50:12 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:12 INFO - PROCESS | 1649 | 2017-01-20 08:50:12.990 plugin-container[1650:10201] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:22 INFO - PROCESS | 1649 | RSS: Main: 229044224 08:50:22 INFO - PROCESS | 1649 | 08:50:25 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 08:50:26 INFO - PROCESS | 1649 | RSS: Main: 241590272 08:50:26 INFO - PROCESS | 1649 | 08:50:28 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 08:50:29 INFO - PROCESS | 1649 | RSS: Main: 249171968 08:50:29 INFO - PROCESS | 1649 | 08:50:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 08:50:33 INFO - PROCESS | 1649 | RSS: Main: 252805120 08:50:33 INFO - PROCESS | 1649 | 08:50:35 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 08:50:35 INFO - PROCESS | 1649 | RSS: Main: 254324736 08:50:35 INFO - PROCESS | 1649 | 08:50:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:50:38 INFO - PROCESS | 1649 | RSS: Main: 254312448 08:50:38 INFO - PROCESS | 1649 | 08:50:41 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 08:50:41 INFO - PROCESS | 1649 | RSS: Main: 258625536 08:50:41 INFO - PROCESS | 1649 | 08:50:45 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 08:50:45 INFO - PROCESS | 1649 | RSS: Main: 258568192 08:50:45 INFO - PROCESS | 1649 | 08:50:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 08:50:49 INFO - PROCESS | 1649 | RSS: Main: 258060288 08:50:49 INFO - PROCESS | 1649 | 08:50:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:50:51 INFO - PROCESS | 1649 | RSS: Main: 257290240 08:50:51 INFO - PROCESS | 1649 | 08:50:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:50:54 INFO - PROCESS | 1649 | RSS: Main: 257708032 08:50:54 INFO - PROCESS | 1649 | 08:50:56 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:50:57 INFO - PROCESS | 1649 | RSS: Main: 257757184 08:50:57 INFO - PROCESS | 1649 | 08:51:00 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:51:00 INFO - PROCESS | 1649 | RSS: Main: 257777664 08:51:00 INFO - PROCESS | 1649 | 08:51:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:51:04 INFO - PROCESS | 1649 | RSS: Main: 258129920 08:51:04 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 08:51:06 INFO - PROCESS | 1649 | RSS: Main: 259784704 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | __start_tp_report 08:51:06 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 08:51:06 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 08:51:06 INFO - PROCESS | 1649 | |i|pagename|runs| 08:51:06 INFO - PROCESS | 1649 | |0;ai-astar;115;117;138;154;116;119;118;118;117;117 08:51:06 INFO - PROCESS | 1649 | |1;audio-beat-detection;144;129;142;157;117;114;115;114;147;148 08:51:06 INFO - PROCESS | 1649 | |2;audio-dft;196;197;203;198;194;195;197;202;200;195 08:51:06 INFO - PROCESS | 1649 | |3;audio-fft;84;60;61;89;73;60;62;60;78;59 08:51:06 INFO - PROCESS | 1649 | |4;audio-oscillator;86;71;90;72;70;71;89;56;90;113 08:51:06 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;89;86;87;87;89;85;89;97;89;85 08:51:06 INFO - PROCESS | 1649 | |6;imaging-darkroom;95;118;137;96;99;140;153;150;99;118 08:51:06 INFO - PROCESS | 1649 | |7;imaging-desaturate;73;139;136;105;134;143;127;90;85;88 08:51:06 INFO - PROCESS | 1649 | |8;json-parse-financial;57;59;56;60;61;60;62;62;60;62 08:51:06 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;69;76;71;78;74;70;76;105;93;70 08:51:06 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;97;106;79;86;101;105;109;79;89;85 08:51:06 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;146;142;142;143;145;145;152;147;219;147 08:51:06 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;168;172;171;166;207;233;173;173;169;174 08:51:06 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;69;68;67;68;69;70;66;69;71;71 08:51:06 INFO - PROCESS | 1649 | __end_tp_report 08:51:06 INFO - PROCESS | 1649 | __start_cc_report 08:51:06 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,960 08:51:06 INFO - PROCESS | 1649 | __end_cc_report 08:51:06 INFO - PROCESS | 1649 | __startTimestamp1484931066438__endTimestamp 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | ------- Summary: start ------- 08:51:06 INFO - PROCESS | 1649 | Number of tests: 14 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:122.90 Median:118.00 stddev:12.79 (10.8%) stddev-sans-first:13.25 08:51:06 INFO - PROCESS | 1649 | Values: 115.0 117.0 138.0 154.0 116.0 119.0 118.0 118.0 117.0 117.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:143.00 stddev:16.72 (11.7%) stddev-sans-first:17.23 08:51:06 INFO - PROCESS | 1649 | Values: 144.0 129.0 142.0 157.0 117.0 114.0 115.0 114.0 147.0 148.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:197.70 Median:197.50 stddev:3.06 (1.5%) stddev-sans-first:3.18 08:51:06 INFO - PROCESS | 1649 | Values: 196.0 197.0 203.0 198.0 194.0 195.0 197.0 202.0 200.0 195.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:68.60 Median:67.50 stddev:11.43 (16.9%) stddev-sans-first:10.68 08:51:06 INFO - PROCESS | 1649 | Values: 84.0 60.0 61.0 89.0 73.0 60.0 62.0 60.0 78.0 59.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:79.00 stddev:15.99 (20.2%) stddev-sans-first:16.85 08:51:06 INFO - PROCESS | 1649 | Values: 86.0 71.0 90.0 72.0 70.0 71.0 89.0 56.0 90.0 113.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.00 stddev:3.47 (3.9%) stddev-sans-first:3.67 08:51:06 INFO - PROCESS | 1649 | Values: 89.0 86.0 87.0 87.0 89.0 85.0 89.0 97.0 89.0 85.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:120.50 Median:124.00 stddev:23.01 (18.6%) stddev-sans-first:22.48 08:51:06 INFO - PROCESS | 1649 | Values: 95.0 118.0 137.0 96.0 99.0 140.0 153.0 150.0 99.0 118.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:112.00 Median:108.00 stddev:26.53 (24.6%) stddev-sans-first:24.09 08:51:06 INFO - PROCESS | 1649 | Values: 73.0 139.0 136.0 105.0 134.0 143.0 127.0 90.0 85.0 88.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:60.50 stddev:2.08 (3.4%) stddev-sans-first:1.92 08:51:06 INFO - PROCESS | 1649 | Values: 57.0 59.0 56.0 60.0 61.0 60.0 62.0 62.0 60.0 62.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:78.20 Median:75.00 stddev:11.72 (15.6%) stddev-sans-first:11.95 08:51:06 INFO - PROCESS | 1649 | Values: 69.0 76.0 71.0 78.0 74.0 70.0 76.0 105.0 93.0 70.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:93.60 Median:82.00 stddev:11.38 (13.9%) stddev-sans-first:12.01 08:51:06 INFO - PROCESS | 1649 | Values: 97.0 106.0 79.0 86.0 101.0 105.0 109.0 79.0 89.0 85.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:152.80 Median:146.50 stddev:23.45 (16.0%) stddev-sans-first:24.74 08:51:06 INFO - PROCESS | 1649 | Values: 146.0 142.0 142.0 143.0 145.0 145.0 152.0 147.0 219.0 147.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.60 Median:173.00 stddev:21.79 (12.6%) stddev-sans-first:22.63 08:51:06 INFO - PROCESS | 1649 | Values: 168.0 172.0 171.0 166.0 207.0 233.0 173.0 173.0 169.0 174.0 08:51:06 INFO - PROCESS | 1649 | 08:51:06 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:1.62 (2.3%) stddev-sans-first:1.72 08:51:06 INFO - PROCESS | 1649 | Values: 69.0 68.0 67.0 68.0 69.0 70.0 66.0 69.0 71.0 71.0 08:51:06 INFO - PROCESS | 1649 | -------- Summary: end -------- 08:51:06 INFO - PROCESS | 1649 | 08:51:07 INFO - TEST-INFO | 1649: exit 0 08:51:07 INFO - TEST-OK | kraken | took 61049ms 08:51:07 INFO - SUITE-END | took 1175s 08:51:07 INFO - Completed test suite (00:19:35) 08:51:07 INFO - javascript benchmark 08:51:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7148.823291783358, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3035.696430356963, 3240.3357311751106, 3414.197663549066, 3466.2027733621267, 3473.1143558392623, 5685.943140568598, 5695.829125126248, 5715.142728590703, 5766.0486019806685, 5776.191333213354, 5706.686132262724, 5785.103308987103, 5800.594600785819, 5808.535317174616, 5818.389069147742, 5271.314729085214, 5277.894442111175, 5318.361796584415, 5345.518903298722, 5364.0, 5230.2416149658275, 5230.660007099526, 5332.066888294552, 5334.733263336818, 5398.757055932492, 5111.948880511184, 5159.380874295071, 5182.326297581328, 5279.155335146377, 5303.098473259516, 5145.562627176694, 5167.354080739908, 5169.224616307546, 5209.270702101709, 5233.9738301308635, 4184.0, 5091.287219789232, 5129.846104616868, 5214.556762675177, 5255.159174532057, 5229.058769421503, 5562.4993750562235, 5618.831435056954, 5759.078547432433, 5788.623739456964, 6147.78482753106, 6194.225721784777, 6261.592996455216, 6268.404501572389, 6273.090401891745, 6227.1282020517165, 6237.532185086091, 6252.468540174091, 6286.968565157145, 6381.52138589603, 4970.403551573834, 5698.458646428583, 5819.330776960619, 5833.416658334218, 5871.794487192928, 5344.759485823147, 6306.24325080984, 6376.234851817719, 6379.106925030499, 6403.679816009181, 6193.349698281706, 6195.4424101831055, 6202.534809889276, 6219.036049412348, 6240.968795155995, 6053.8486537836025, 6252.968735156295, 6271.874562508816, 6300.369963003663, 6300.621962682254, 5125.333706618116, 5993.730282137314, 6109.617229398918, 6168.691565421711, 6252.09344645033, 6202.689865506707, 6240.563160578716, 6266.905996410058, 6279.152314437492, 6293.779717709858, 4949.232868905326, 4975.353204083518, 4976.129177393942, 4979.153543897546, 4992.101421744048, 4299.720518166309, 4351.934720979188, 4383.671224658164, 4386.868393948187, 4488.12481566098, 5763.827085187451, 5786.2477877876445, 5849.853753656107, 5855.414458554111, 5913.881722365614, 6171.166892469549, 6276.811695649138, 6286.748530058838, 6292.779752708633, 6319.05214217871, 5723.570732195102, 5759.0, 5823.970880145657, 5864.091065884794, 5943.435373639584, 5425.6202065855805, 5454.209139674805, 5479.0, 5481.780728770805, 5486.31421072366, 6131.0096813291075, 6388.8083357499345, 6412.038194270804, 6419.775307864203, 6446.484281257582, 6174.907376389448, 6205.6586887721605, 6250.843728906631, 6290.961991271389, 6293.937060629335, 6428.164338635947, 6442.065900444316, 6447.258565264867, 6448.065030570635, 6466.838329041811, 6230.0, 6236.65698386575, 6238.313785483684, 6259.718312676032, 6283.057541368831, 5862.94137058624, 6162.722677479434, 6188.907166392418, 6195.411435913581, 6290.2766181889665, 6185.474234689922, 6196.256449226122, 6223.315435302023, 6224.2842073160355, 6224.844378890564, 6148.9692551536955, 6151.723172457339, 6199.721012554536, 6203.193584833943, 6204.937950620436, 5087.186050232093, 5747.9137812932, 5787.565932554991, 5981.850453738517, 6027.608205466806, 5420.620556561003, 5900.350961394157, 5946.226990491208, 6023.969880150747, 6025.0359942409, 5381.11211650089, 5793.391693872251, 5794.159846822103, 5794.710264486843, 5825.533957283492], "value": 5691.54425292084, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.008411788478, 462.3296220480302, 464.50762192076394, 498.6384870968546, 499.6602310428909, 30055.248618784513, 37004.44493332599, 39042.82871513852, 39082.0, 39098.413523797164, 39647.801760991155, 40359.596404035954, 40435.0, 40514.79742601283, 40562.18875622486, 9312.720618381443, 9369.344145909788, 9499.667511637093, 9561.378510396802, 9585.185259252972, 9436.764080897998, 9546.0, 9557.13985741283, 9559.28305377096, 9572.234221262283, 9410.058994100604, 9510.144087032166, 9578.856317155249, 9582.0, 9598.952005239964, 9485.004074572174, 9572.952135239279, 9573.473458959754, 9592.808143837085, 9604.327697061244, 9419.811603767886, 9426.010268921733, 9455.33812633116, 9526.0, 9558.56986435612, 38885.22229555422, 39107.8044609778, 39407.80296098501, 39527.20945581101, 39533.60466395357, 3282.4091663500562, 3298.356820420019, 3383.441732114198, 3445.8277086145845, 3466.757326987112, 29957.101286961428, 30140.698593014007, 30154.396912061857, 30541.0, 30802.845985769927, 3134.2634480896972, 3214.9357012859846, 3221.7100460958627, 3265.053134590966, 3301.4717645176656, 396745.0, 509100.27197824087, 512094.0, 516515.9999999962, 520510.0, 57728.422715773144, 58115.8376832461, 58257.70871145702, 58268.0, 58299.70850145722, 51873.2219016715, 61525.76948461006, 61742.0, 61898.69050654718, 62062.0, 15984.76022859658, 16196.75704864428, 16212.351505939867, 16256.431024914307, 16310.673786524441, 12098.697532561757, 12467.0, 12661.113722039552, 12699.174553653984, 12733.936330318475, 15853.207339633203, 16008.759868601981, 16298.348066077462, 16361.91819040921, 16383.836161638232, 6242.939829856136, 6256.43692067707, 6268.968655156695, 6273.643600673618, 6302.873942521124, 812.573398965536, 988.1600639456404, 1027.3527677563088, 1061.6039907521586, 1109.8168802147716, 29619.407611847644, 29634.407311854073, 29714.851425742734, 29880.85059574688, 30463.543046854316, 1043.6138628707274, 1091.9727006824894, 1127.1546340244815, 1140.2816225777708, 1158.449736375215, 12017.755206094305, 15054.849451505565, 16172.272247748879, 16319.59201019984, 16449.342026319053, 463152.0, 640431.0, 643917.0, 644583.0, 645255.0, 56173.71913140408, 56339.43660563424, 56893.71553142208, 56895.0, 57076.42923570711, 1265.367316341829, 1292.0245214862907, 1323.014354306029, 1373.6565858535366, 1384.487739536378, 29495.55756663609, 29763.851180743957, 30267.545986809775, 30425.23936901595, 30885.0, 48794.02411951812, 49370.50629493659, 50263.0, 50300.748496257285, 50308.24537631865, 485844.0, 487380.0, 489625.0, 489783.0, 646711.0, 22486.5502689942, 25776.84004219852, 25936.22191334263, 26014.089506867167, 26332.21003369902, 33838.83080584581, 43784.343234850865, 44104.77947610241, 44802.3279650799, 45361.185552577525, 36764.87339107937, 37201.06997325089, 37202.06994825151, 38006.42990355092, 39132.0, 29956.251093722833, 37249.6275037257, 37364.318605662265, 38094.23811523809, 38274.617253827106, 35673.0, 36079.0, 36808.0, 36837.44743828898, 37580.2483950314, 27083.322916926445, 31932.0, 33603.0, 33855.15362115967, 33911.660883391836, 24659.630105548073, 24930.626040609768, 25001.499970000135, 25285.74714252834, 25301.746982529938], "value": 19315.373891674393, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.3555135261915, 365.74032436969765, 373.5218919782677, 382.9923401531971, 391.7257919456379, 18713.34503292385, 19641.0, 19674.508137296576, 20248.987550622463, 20460.28389006385, 19652.0, 19679.032096790314, 19901.0, 20129.396118116445, 20168.294109706163, 5669.149627555858, 5856.56075794315, 5865.208196893407, 5940.198073260098, 5940.435658612447, 4091.89770255743, 4322.762248076355, 5209.505097015796, 5596.104623260279, 6467.870642587145, 6395.8401039973905, 6461.483081353481, 6468.450181734558, 6479.319671434502, 6496.870062598745, 6315.74737010519, 6385.138006369127, 6407.551471396981, 6468.256150542654, 6470.029495575654, 5548.500634942875, 5576.0, 5642.097264437671, 5644.971775141139, 5653.23681303027, 24455.510889782283, 24507.754922450724, 24610.8925098371, 24729.887155078064, 25033.62449563258, 2332.323626148415, 2354.764523547649, 2413.3966508372905, 2631.5657916443765, 3107.1300035989957, 14635.0, 14779.0, 14952.40190392383, 15523.223838808128, 15576.610584735472, 1831.4139475367888, 1913.1868955693913, 1928.9710654340197, 1935.5161209697576, 1952.2971730177126, 115650.0, 116096.41951790187, 116438.0, 116670.0, 117071.0, 9014.23379012778, 10597.470126493645, 10913.83629245562, 10985.61550345734, 11329.376884271443, 13369.598912032654, 13561.050726449252, 13574.0, 13604.0, 13615.795763063563, 8330.125336839666, 8398.908141941509, 8539.444936079259, 8554.828903421896, 8688.044315125331, 6946.305369463013, 8427.789305267417, 8506.532140732319, 8596.355273354524, 8668.176523230282, 6491.318411566812, 6574.76988305407, 6595.934040659532, 6630.9668451657435, 6678.666066696645, 1376.7108907129414, 1689.2313997131275, 1702.7786387769759, 1737.1661602430652, 1817.8727489075638, 851.82537579796, 907.138218692245, 925.8796356473615, 981.5337714585514, 988.678679429174, 16562.751558726628, 18099.110622265893, 20292.594148116954, 20412.285570004977, 20686.58626827455, 937.5452905341041, 944.9102335278137, 963.6868017894212, 989.8913217196642, 1038.9792204156026, 9923.851142232872, 10697.572097116028, 10736.30214036085, 10753.677389678165, 10775.407352595526, 596790.0, 597829.0, 604011.0, 607635.0, 609209.0, 115124.0, 120087.0, 122469.0, 123232.0, 123470.0, 924.3390975452584, 932.3892850183141, 954.0983770337103, 959.1128206409044, 960.1646567486192, 17814.821851781315, 17837.0, 18151.81848181501, 18160.091995399915, 18273.0, 40678.59321406748, 42424.78787606042, 42764.930876727085, 54574.0, 54929.72535137299, 76169.23830761621, 76604.0, 76780.08049798799, 77089.61455192688, 77946.0, 5915.319738230159, 6829.146356705412, 6955.408790252885, 7018.719251230098, 7109.573425594481, 8045.436819422584, 11544.769104617693, 11584.536618535165, 11612.0, 11661.367408562977, 9805.950970245103, 10147.34042287249, 10158.034986676254, 10310.484475776331, 10761.946190268998, 10109.64616238428, 10215.642452514126, 10246.948765256126, 10345.515480370275, 10353.432013494352, 10446.738831529272, 10462.895371046496, 10609.416482093558, 10783.51474183648, 10934.125269978542, 7034.648267586703, 7051.753188638373, 7209.0628218331285, 7210.1708303545765, 7246.311600397954, 8196.795080123045, 10255.33340332876, 10365.585376585153, 10711.036006759428, 10727.785444290914], "value": 9422.397506958356, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1466.9119852808828, 1482.9110253384797, 1631.681822044701, 1753.614204874927, 1756.4818378578318, 7560.810979725509, 7818.022747156606, 8079.070906845707, 8490.98108227013, 8561.101084386144, 8072.596370181497, 8345.499270043787, 8364.665413383458, 8391.916080839204, 8485.957570212156, 2313.0, 2337.9946622952125, 2338.5907466193435, 2338.684277622525, 2358.457554762406, 2322.3497420722224, 2366.917157899465, 2376.192094687806, 2383.0348708772976, 2386.522695460906, 2317.571249318873, 2326.3718795925092, 2342.8711420871846, 2346.483773569812, 2359.0092161292214, 2256.4697296135396, 2262.332611879483, 2283.748787633359, 2286.2341115726254, 2294.921386948132, 2332.3819187915215, 2359.127122964514, 2359.5634807560655, 2366.1954935322074, 2366.645003249509, 7127.928720712778, 8309.792255193608, 8341.20758527939, 8379.0, 8451.619677114544, 5004.9749751251375, 5064.063148317573, 5223.660462069991, 5227.2159176123505, 5241.842744717665, 3382.678645528671, 3452.361313157074, 3577.302426026926, 3581.5287451658273, 3600.3537400010973, 1034.5861655337849, 1041.1722680469045, 1089.6349722842858, 1090.0571006079763, 1104.0946423932378, 10924.617638382699, 11034.393108379036, 11049.613263535817, 11073.889261107366, 11089.500972456259, 4113.868686111284, 4455.509893911667, 4525.434320709911, 4743.501932297128, 4788.090262850047, 9489.66786162481, 9596.66411675588, 9601.90398096024, 9612.327137100476, 9617.903820961701, 4582.991741816795, 5087.720175390315, 5239.0, 5249.632525723239, 5253.658512196695, 4285.61429471349, 4414.80133393998, 4624.421947256593, 4761.880952976134, 4791.95208047922, 3838.846446142179, 3882.2235552889533, 3913.021744563859, 3974.4435778990965, 3994.1612261424843, 1058.708855064848, 1270.733146039342, 1273.5988163728396, 1281.9679508012375, 1295.7019885426405, 874.9063670411985, 879.384430898361, 886.7118186589385, 887.3699673232078, 900.4282280751639, 5607.186957891083, 5774.82675519735, 5779.913301300484, 5909.881802363929, 5955.196048533393, 889.1641856654724, 891.3804905590682, 908.7455512456522, 912.0742446416892, 930.6696122876282, 3768.3970564709516, 3857.40210267409, 3871.0128917125953, 3873.12854607711, 3876.321643712339, 10072.848907266396, 10455.94772026135, 10473.84289235662, 10491.94754026225, 10511.94744026275, 10144.188464922789, 10277.640282590073, 10301.53643086063, 10411.583536658449, 10434.113100386401, 7509.436792240604, 7792.337651299703, 7906.169852165554, 7907.604619768988, 7954.602269886483, 5791.131330300489, 5811.82564523065, 5884.470397664059, 5937.5249980000635, 5996.790112346047, 9273.21177699876, 9289.256859451092, 9409.529523523934, 9603.759906002126, 9608.90391096108, 9298.628054877729, 9570.808583828424, 9609.183219426428, 9660.758481037748, 9703.417794932328, 6597.505187111082, 7263.237360077116, 7266.200717920919, 7339.9266007341375, 7387.445941554298, 8042.919570804217, 8095.069067057364, 8170.387220958333, 8175.591220439073, 8184.181581841768, 7035.542689725152, 7097.432205423657, 7226.060612120601, 7254.96372518134, 7344.449166312441, 6310.652914089769, 6901.24086350491, 6956.060931774348, 6988.021676965331, 7084.25615310385, 5822.3304320003745, 6809.046733457221, 6820.556663816836, 6898.482613803884, 6910.17077950629, 4321.58944900234, 4665.693605790384, 4845.442774081026, 4874.098291816025, 4895.0, 4845.248986407112, 4897.975510122569, 4928.753562321941, 5056.519630635084, 5087.567556757717], "value": 4456.78890128079, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.9303260250142, 303.8890804856227, 312.8185652321654, 315.84681429506685, 316.56314286284925, 4359.122978833966, 4650.581447669713, 5219.973900130494, 5264.473552644743, 5418.647787893789, 4892.3884514435695, 5451.3458384993755, 5476.835694929158, 5492.945070549294, 5508.9724551377185, 4196.958030419695, 4216.915661686764, 4222.303319952204, 4229.323308270677, 4237.682173836959, 4084.7549147051272, 4166.666666666674, 4201.033762234688, 4212.452381190441, 4242.448481697391, 4155.400170934182, 4161.417401563784, 4173.791310434482, 4181.853635122771, 4202.852900148496, 4141.834326626931, 4160.750354978696, 4174.582541745832, 4175.707700460969, 4218.367244913257, 3676.5955744868043, 3690.4464330350393, 3694.058015206132, 3701.925961480755, 3706.7590606610484, 4315.5900189481945, 4331.870043898688, 4891.551456223354, 5331.653442526263, 5365.570754339644, 3992.8003599819895, 4321.935170972438, 4428.734275943421, 4577.542245775429, 4805.639577031701, 5713.771449142029, 5782.219400380936, 5789.536837053068, 5805.36141024487, 5828.377230246364, 3615.529981102466, 3846.6921246776233, 3879.825407856625, 3921.4902262628807, 3926.685865130811, 5322.680639161663, 5376.327959005124, 6016.518678505709, 6025.517958563304, 6031.758729650809, 3240.562524059269, 3245.6407618115136, 3963.2667956428154, 3966.484357033567, 3988.1425493518454, 3211.277462570903, 3841.654251117357, 3875.418687196943, 3988.481497405319, 4048.5141782985643, 4010.3357106800936, 4268.40242366069, 4691.217337411762, 4719.457262414866, 4722.929156062662, 5085.7711402986215, 5825.339778163203, 5828.213191219131, 5866.7066646668345, 5870.358338747581, 1042.723678225271, 1047.7328281288223, 1218.3542722357165, 1306.3794697518792, 1324.324594456834, 708.479267738212, 787.3740376400775, 851.9659213631385, 861.9051904290523, 896.188949001155, 4544.840930567414, 5139.460356662496, 5147.433782283946, 5149.304843846107, 5193.3900490847955, 4635.629149668019, 4753.217543421217, 5035.5468007878735, 5526.447355264441, 5639.830805075772, 4556.245845349565, 4676.064787042537, 4834.734089625104, 4898.730569818694, 5147.536721695065, 2445.5108978204075, 2833.518301888643, 2921.3134913295357, 2945.499265124891, 2958.715825433767, 5011.997600479918, 5560.915621453859, 5801.9419805802245, 5803.0, 5871.383504732027, 4983.725895075733, 5008.1486147355035, 5252.713085294127, 5293.935365207572, 5508.017113839015, 4780.581883952921, 5153.742312884416, 5187.507186817246, 5859.882802344015, 5879.7060146993335, 5522.321673988193, 5533.6403133796175, 5647.604667673223, 5688.175214593944, 5713.315081633021, 4993.650444468853, 5124.487551244994, 5252.422233554383, 5411.918821217606, 5563.332400112013, 5496.917546236889, 5780.912796738843, 5814.0, 5826.203422357207, 5859.765609375577, 3106.7980581262145, 3728.4220945752368, 3735.3089678409583, 3758.3047136279874, 3779.905502362463, 4630.7453090079025, 5229.895402091861, 5602.702891814337, 5605.383407825218, 5621.831345059655, 4700.743643051423, 5570.972145139248, 5650.26546548946, 5663.830085097454, 5702.7718891243885, 5649.265595472562, 5778.855528611818, 5793.826185214451, 5808.0, 5820.330661973758, 5326.920096198638, 5637.238972738628, 5680.4319568042865, 5709.518028347893, 5715.828525144252, 5254.106801843772, 5536.446355364431, 5856.385079566584, 5878.500327472213, 5896.469317761251, 5705.515031222392, 5738.799142030009, 5762.193292938908, 5767.0, 5801.419858014165], "value": 4077.8759358845728, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.6996501749126, 660.1385192324018, 693.9479539034573, 716.7097325583137, 756.5347311403489, 14024.2286674233, 14899.40402383906, 14953.0, 14962.775558366633, 15003.774943375858, 13418.396172172252, 13428.127171733831, 13531.932340338284, 13552.864471355284, 13563.0, 638.4222278837636, 667.9599224046573, 812.3744716568239, 829.8298848735994, 835.6197929941892, 797.2346547314585, 826.1449399871135, 842.296682270305, 842.6713581703141, 846.3440833354139, 859.1623167411755, 864.9221570058694, 877.4998250996935, 884.0010787809798, 884.2439714044496, 865.446114486729, 866.1035827918074, 867.0116067682847, 871.4596949891045, 873.0789017586444, 632.1813251838834, 648.5265755998148, 649.6881496881523, 650.4211251985737, 651.062470043141, 13029.934850325782, 13648.181109133484, 13917.58247252584, 14008.369623366973, 14064.507742229076, 12512.81230781539, 13645.113067650671, 13652.86347136526, 13657.0, 13889.930550347284, 10762.300450470695, 11118.388488633122, 11190.66428007161, 11423.885761142365, 11453.198276120675, 4250.659947204216, 4397.340398940152, 4401.5598440156045, 4403.251447253974, 4524.049949510607, 46553.30170047418, 70800.29199707988, 72555.0, 73472.0, 75684.86472702914, 8799.912000880036, 10249.538770755335, 10495.685129446128, 10512.526936287886, 10620.203484738675, 16724.58188545296, 19866.801331986786, 19939.601207976048, 20114.798852011587, 20226.696599551018, 6379.202599675041, 7636.885446718304, 7706.921031055656, 7780.922190778133, 7798.649060792278, 4165.10450253197, 4173.373993900939, 4179.707420480538, 4241.575842415734, 4261.765602891809, 3368.444206705915, 3469.6530346965606, 3471.375152472579, 3507.4914137449928, 3513.4729790531114, 644.8291202831253, 674.2549482821412, 698.0611078099947, 702.2977022977022, 708.6031822179596, 504.63161891819175, 561.0014174768918, 562.5190462154881, 627.5575719118011, 655.9212894452696, 7503.737369192052, 8372.943875485404, 8983.730488085368, 9073.364864459425, 9295.11696388842, 629.4115002472703, 640.1901594482982, 644.4940721533667, 665.6838001949133, 680.829792551862, 4123.835046598102, 4474.239379305526, 4487.932681009787, 4497.0155627725, 4560.5895469407915, 66855.99716004264, 69951.0, 82667.17332826594, 109701.0, 111592.88407115988, 43951.12097758027, 63771.40571485657, 73802.7859164226, 86540.0, 103932.48033759934, 89611.20777584543, 89615.65576516227, 92971.53514232521, 94508.52745736364, 94549.0, 8342.561464318092, 8630.525321107149, 8664.870026949475, 8672.523011234443, 8795.91204087951, 53894.19158712701, 56527.0, 57128.42871571231, 57219.0, 58261.70869145627, 62789.68605156945, 63353.68323158355, 74932.25067749471, 76050.0, 80811.59594202226, 9224.907750922674, 10870.510827012962, 11026.669199924016, 11029.558817647205, 11066.723331916766, 12241.265524068584, 13028.348582571023, 13404.798928015893, 13483.73032539324, 13641.931790340985, 12455.564055258023, 12631.305278209788, 12715.10994230395, 12806.743865122831, 12902.003977054626, 12381.384993984915, 12777.808332874829, 12818.615441536396, 12962.546310879074, 13336.533221337206, 11488.253263537843, 12491.625251242478, 12569.49960285963, 12580.245185288912, 12665.683357916127, 11023.338599684044, 11091.279066860629, 11126.165537584551, 11156.888431115585, 11296.0, 11088.33469991803, 11122.165837562054, 11222.214444988773, 11238.83141752858, 11560.13299002595], "value": 7089.425946825842, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1557.3999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 138.0, 154.0, 116.0, 119.0, 118.0, 118.0, 117.0, 117.0], "value": 122.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 129.0, 142.0, 157.0, 117.0, 114.0, 115.0, 114.0, 147.0, 148.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 197.0, 203.0, 198.0, 194.0, 195.0, 197.0, 202.0, 200.0, 195.0], "value": 197.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 61.0, 89.0, 73.0, 60.0, 62.0, 60.0, 78.0, 59.0], "value": 68.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 90.0, 72.0, 70.0, 71.0, 89.0, 56.0, 90.0, 113.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 87.0, 87.0, 89.0, 85.0, 89.0, 97.0, 89.0, 85.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 118.0, 137.0, 96.0, 99.0, 140.0, 153.0, 150.0, 99.0, 118.0], "value": 120.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 139.0, 136.0, 105.0, 134.0, 143.0, 127.0, 90.0, 85.0, 88.0], "value": 112.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 56.0, 60.0, 61.0, 60.0, 62.0, 62.0, 60.0, 62.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 71.0, 78.0, 74.0, 70.0, 76.0, 105.0, 93.0, 70.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 106.0, 79.0, 86.0, 101.0, 105.0, 109.0, 79.0, 89.0, 85.0], "value": 93.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 142.0, 142.0, 143.0, 145.0, 145.0, 152.0, 147.0, 219.0, 147.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 172.0, 171.0, 166.0, 207.0, 233.0, 173.0, 173.0, 169.0, 174.0], "value": 180.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 67.0, 68.0, 69.0, 70.0, 66.0, 69.0, 71.0, 71.0], "value": 68.8, "unit": "score"}]}]} 08:51:07 INFO - Return code: 0 08:51:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:51:07 ERROR - # TBPL SUCCESS # 08:51:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:51:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:51:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:51:07 INFO - 2017-01-20 08:51:07.784 screenresolution[1654:10583] starting screenresolution argv=screenresolution get 08:51:07 INFO - 2017-01-20 08:51:07.787 screenresolution[1654:10583] Display 0: 1600x1200x32@60 08:51:07 INFO - 2017-01-20 08:51:07.793 screenresolution[1655:10586] starting screenresolution argv=screenresolution list 08:51:07 INFO - Available Modes on Display 0 08:51:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:51:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:51:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:51:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:51:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:51:08 INFO - Intel Iris: 08:51:08 INFO - Chipset Model: Intel Iris 08:51:08 INFO - Type: GPU 08:51:08 INFO - Bus: Built-In 08:51:08 INFO - VRAM (Dynamic, Max): 1536 MB 08:51:08 INFO - Vendor: Intel (0x8086) 08:51:08 INFO - Device ID: 0x0a2e 08:51:08 INFO - Revision ID: 0x0009 08:51:08 INFO - Displays: 08:51:08 INFO - Display: 08:51:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:51:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:51:08 INFO - Display Serial Number: 1600x1200 60 08:51:08 INFO - Main Display: Yes 08:51:08 INFO - Mirror: Off 08:51:08 INFO - Online: Yes 08:51:08 INFO - Rotation: Supported 08:51:08 INFO - Return code: 0 08:51:08 INFO - Running post-action listener: _resource_record_post_action 08:51:08 INFO - [mozharness: 2017-01-20 16:51:08.035964Z] Finished run-tests step (success) 08:51:08 INFO - Running post-run listener: _resource_record_post_run 08:51:08 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 39086592; Write bytes: 656812032; Read time: 253; Write time: 1917 08:51:08 INFO - TinderboxPrint: CPU usage
54.3% 08:51:08 INFO - TinderboxPrint: I/O read bytes / time
39,086,592 / 253 08:51:08 INFO - TinderboxPrint: I/O write bytes / time
656,812,032 / 1,917 08:51:08 INFO - TinderboxPrint: CPU idle
2,208.6 (45.7%) 08:51:08 INFO - TinderboxPrint: CPU system
974.7 (20.2%) 08:51:08 INFO - TinderboxPrint: CPU user
1,646.4 (34.1%) 08:51:08 INFO - TinderboxPrint: Swap in / out
388,603,904 / 0 08:51:08 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346694144; Write bytes: 345110016; Read time: 26914; Write time: 724 08:51:08 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36850176; Write bytes: 304476672; Read time: 229; Write time: 1163 08:51:08 INFO - Running post-run listener: _upload_blobber_files 08:51:08 INFO - Blob upload gear active. 08:51:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:51:08 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 08:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:51:08 INFO - (blobuploader) - INFO - Open directory for files ... 08:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:10 INFO - (blobuploader) - INFO - Done attempting. 08:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:11 INFO - (blobuploader) - INFO - Done attempting. 08:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:51:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:13 INFO - (blobuploader) - INFO - Done attempting. 08:51:13 INFO - (blobuploader) - INFO - Iteration through files over. 08:51:13 INFO - Return code: 0 08:51:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:51:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:51:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f8128cbb08523f78599c06376203226e40b01fecd0673f1f042ebaba58ff7e84d15f009c42aa5f6fbddd7f213e98b3756dbd1d4cc6835ac7b69cf394c806d72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df1a5de23d41d6c769c125dee42bde8600dbff35b63c91ab813321b387b32e368db79fa38ff24ca375773838018d04834fa56bba3108bcbd536adf2ce4208712", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/235f442de518015d900adb2a4e8e615b746763b7e9ce43dede61000adf225dfaf240cd25947f09cae10e9d75b63d3962db6ccd47da547223ee9e411c2b6e1e7c"} 08:51:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:51:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:51:13 INFO - Contents: 08:51:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f8128cbb08523f78599c06376203226e40b01fecd0673f1f042ebaba58ff7e84d15f009c42aa5f6fbddd7f213e98b3756dbd1d4cc6835ac7b69cf394c806d72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df1a5de23d41d6c769c125dee42bde8600dbff35b63c91ab813321b387b32e368db79fa38ff24ca375773838018d04834fa56bba3108bcbd536adf2ce4208712", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/235f442de518015d900adb2a4e8e615b746763b7e9ce43dede61000adf225dfaf240cd25947f09cae10e9d75b63d3962db6ccd47da547223ee9e411c2b6e1e7c"} 08:51:13 INFO - Running post-run listener: copy_logs_to_upload_dir 08:51:13 INFO - Copying logs to upload dir... 08:51:13 INFO - mkdir: /builds/slave/test/build/upload/logs 08:51:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.337030 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-20 08:51:13.287120) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 08:51:13.290241) ========= 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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f8128cbb08523f78599c06376203226e40b01fecd0673f1f042ebaba58ff7e84d15f009c42aa5f6fbddd7f213e98b3756dbd1d4cc6835ac7b69cf394c806d72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df1a5de23d41d6c769c125dee42bde8600dbff35b63c91ab813321b387b32e368db79fa38ff24ca375773838018d04834fa56bba3108bcbd536adf2ce4208712", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/235f442de518015d900adb2a4e8e615b746763b7e9ce43dede61000adf225dfaf240cd25947f09cae10e9d75b63d3962db6ccd47da547223ee9e411c2b6e1e7c"} build_url:https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011129 build_url: 'https://queue.taskcluster.net/v1/task/cCstWKhaSFCvk-uqVGtSIQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f8128cbb08523f78599c06376203226e40b01fecd0673f1f042ebaba58ff7e84d15f009c42aa5f6fbddd7f213e98b3756dbd1d4cc6835ac7b69cf394c806d72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df1a5de23d41d6c769c125dee42bde8600dbff35b63c91ab813321b387b32e368db79fa38ff24ca375773838018d04834fa56bba3108bcbd536adf2ce4208712", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/235f442de518015d900adb2a4e8e615b746763b7e9ce43dede61000adf225dfaf240cd25947f09cae10e9d75b63d3962db6ccd47da547223ee9e411c2b6e1e7c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 08:51:13.324027) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 08:51:13.324359) ========= 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.qGmkvkA3u0/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.QVhQR8ZId1/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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.005184 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 08:51:13.372584) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 08:51:13.372921) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 08:51:13.373254) ========= ========= Total master_lag: 0.18 =========