builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0322 starttime: 1479986102.17 results: success (0) buildid: 20161124020338 builduid: 9edfea74f9b444a3bb8da94f5aeee042 revision: 7f611a717df6bf178b2cab2da4ef8c005f80da8a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.166673) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.167089) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.167384) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.193835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.194211) ========= 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.UR3aibgE3p/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.20YPDoEBcx/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 --2016-11-24 03:15: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% 18.0M=0.001s 2016-11-24 03:15:02 (18.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.181858 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.399135) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.399452) ========= 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.UR3aibgE3p/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.20YPDoEBcx/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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.020223 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:02.447805) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-24 03:15:02.448176) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7f611a717df6bf178b2cab2da4ef8c005f80da8a --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 7f611a717df6bf178b2cab2da4ef8c005f80da8a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UR3aibgE3p/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.20YPDoEBcx/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 2016-11-24 03:15:02,531 truncating revision to first 12 chars 2016-11-24 03:15:02,531 Setting DEBUG logging. 2016-11-24 03:15:02,531 attempt 1/10 2016-11-24 03:15:02,531 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7f611a717df6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-24 03:15:04,198 unpacking tar archive at: mozilla-aurora-7f611a717df6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.947460 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-24 03:15:04.419118) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:04.419425) ========= script_repo_revision: 7f611a717df6bf178b2cab2da4ef8c005f80da8a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:04.419830) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:04.420116) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 03:15:04.440374) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-24 03:15:04.440740) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.UR3aibgE3p/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.20YPDoEBcx/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 03:15:04 INFO - MultiFileLogger online at 20161124 03:15:04 in /builds/slave/test 03:15:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:15:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:15:04 INFO - {'append_to_log': False, 03:15:04 INFO - 'base_work_dir': '/builds/slave/test', 03:15:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:15:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:15:04 INFO - 'branch': 'Mozilla-Aurora', 03:15:04 INFO - 'buildbot_json_path': 'buildprops.json', 03:15:04 INFO - 'config_files': ('talos/mac_config.py',), 03:15:04 INFO - 'default_actions': ('clobber', 03:15:04 INFO - 'read-buildbot-config', 03:15:04 INFO - 'download-and-extract', 03:15:04 INFO - 'populate-webroot', 03:15:04 INFO - 'create-virtualenv', 03:15:04 INFO - 'install', 03:15:04 INFO - 'run-tests'), 03:15:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:15:04 INFO - 'download_minidump_stackwalk': True, 03:15:04 INFO - 'download_symbols': 'ondemand', 03:15:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:15:04 INFO - 'tooltool.py': '/tools/tooltool.py', 03:15:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:15:04 INFO - '/tools/misc-python/virtualenv.py')}, 03:15:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:15:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:15:04 INFO - 'installer_path': 'installer.exe', 03:15:04 INFO - 'log_level': 'info', 03:15:04 INFO - 'log_name': 'talos', 03:15:04 INFO - 'log_to_console': True, 03:15:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:15:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:15:04 INFO - 'opt_config_files': (), 03:15:04 INFO - 'pip_index': False, 03:15:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:15:04 INFO - 'cmd': ('bash', 03:15:04 INFO - '-c', 03:15:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:15:04 INFO - 'enabled': True, 03:15:04 INFO - 'halt_on_failure': False, 03:15:04 INFO - 'name': 'check_screen_resolution'},), 03:15:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:15:04 INFO - 'cmd': ('bash', 03:15:04 INFO - '-c', 03:15:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:15:04 INFO - 'enabled': True, 03:15:04 INFO - 'halt_on_failure': False, 03:15:04 INFO - 'name': 'check_screen_resolution'},), 03:15:04 INFO - 'run_cmd_checks_enabled': True, 03:15:04 INFO - 'sps_profile': False, 03:15:04 INFO - 'sps_profile_interval': 0, 03:15:04 INFO - 'suite': 'dromaeojs', 03:15:04 INFO - 'system_bits': '32', 03:15:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:15:04 INFO - 'title': 't-yosemite-r7-0322', 03:15:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:15:04 INFO - 'use_talos_json': True, 03:15:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:15:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:15:04 INFO - 'work_dir': 'build'} 03:15:04 INFO - [mozharness: 2016-11-24 11:15:04.642280Z] Running clobber step. 03:15:04 INFO - Running pre-action listener: _resource_record_pre_action 03:15:04 INFO - Running main action method: clobber 03:15:04 INFO - rmtree: /builds/slave/test/build 03:15:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:15:05 INFO - Running post-action listener: _resource_record_post_action 03:15:05 INFO - [mozharness: 2016-11-24 11:15:05.548164Z] Finished clobber step (success) 03:15:05 INFO - [mozharness: 2016-11-24 11:15:05.548290Z] Running read-buildbot-config step. 03:15:05 INFO - Running pre-action listener: _resource_record_pre_action 03:15:05 INFO - Running main action method: read_buildbot_config 03:15:05 INFO - Using buildbot properties: 03:15:05 INFO - { 03:15:05 INFO - "project": "", 03:15:05 INFO - "product": "firefox", 03:15:05 INFO - "script_repo_revision": "production", 03:15:05 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:15:05 INFO - "repository": "", 03:15:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:15:05 INFO - "buildid": "20161124020338", 03:15:05 INFO - "pgo_build": "False", 03:15:05 INFO - "basedir": "/builds/slave/test", 03:15:05 INFO - "buildnumber": 41, 03:15:05 INFO - "slavename": "t-yosemite-r7-0322", 03:15:05 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:15:05 INFO - "platform": "yosemite_r7", 03:15:05 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:15:05 INFO - "branch": "mozilla-aurora", 03:15:05 INFO - "repo_path": "releases/mozilla-aurora", 03:15:05 INFO - "revision": "7f611a717df6bf178b2cab2da4ef8c005f80da8a", 03:15:05 INFO - "stage_platform": "macosx64", 03:15:05 INFO - "builduid": "9edfea74f9b444a3bb8da94f5aeee042", 03:15:05 INFO - "slavebuilddir": "test" 03:15:05 INFO - } 03:15:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:15:05 INFO - Running post-action listener: _resource_record_post_action 03:15:05 INFO - [mozharness: 2016-11-24 11:15:05.551425Z] Finished read-buildbot-config step (success) 03:15:05 INFO - [mozharness: 2016-11-24 11:15:05.551561Z] Running download-and-extract step. 03:15:05 INFO - Running pre-action listener: _resource_record_pre_action 03:15:05 INFO - Running main action method: download_and_extract 03:15:05 INFO - mkdir: /builds/slave/test/build/tests 03:15: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')]} 03:15:05 INFO - trying https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:15:05 INFO - Downloading https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/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 03:15:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/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 03:15:06 INFO - Downloaded 1199 bytes. 03:15:06 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:15:06 INFO - Using the following test package requirements: 03:15:06 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:15:06 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:06 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:15:06 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:06 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:15:06 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:06 INFO - u'jsshell-mac.zip'], 03:15:06 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:06 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:15:06 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:15:06 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:06 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:15:06 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:06 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:15:06 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:06 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:15:06 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:15:06 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:15:06 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:15:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:15:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:15:06 INFO - Fetch https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:15:07 INFO - Http code: 200 03:15:07 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 03:15:07 INFO - x-amz-cf-id: vxA_6eLw817MVMVPN8YiZQ0zIWoxt7G2syYu9KJ38-YfQzoUVWy_vg== 03:15:07 INFO - x-amz-version-id: .JkhS4NWSfW97BSkDFQ8csygiCCMciA6 03:15:08 INFO - Expected file size: 16278237 03:15:08 INFO - Obtained file size: 16278237 03:15: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 03:15:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:15:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:15:14 INFO - Fetch https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:15:15 INFO - Http code: 200 03:15:15 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 03:15:15 INFO - x-amz-cf-id: D5-RoBp5r7RJoWzBvEYucoRkZEHEyLLY4HlxnEsO7djesThZjTHacQ== 03:15:15 INFO - x-amz-version-id: .JkhS4NWSfW97BSkDFQ8csygiCCMciA6 03:15:15 INFO - Expected file size: 16278237 03:15:15 INFO - Obtained file size: 16278237 03:15:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:15:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:15:21 INFO - Fetch https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:15:23 INFO - Http code: 200 03:15:23 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 03:15:23 INFO - x-amz-cf-id: JRSaVdkOt4Tc_PBdli8bSDyCx39mSVa099lUsxoHRb7mkywey4Cmyw== 03:15:23 INFO - x-amz-version-id: ryLXl2Who.UWJ3mcswxZJ3J_NeAsAePn 03:15:24 INFO - Expected file size: 14409038 03:15:24 INFO - Obtained file size: 14409038 03:15:24 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')]} 03:15:24 INFO - trying https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:15:24 INFO - Downloading https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:15:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/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 03:15:27 INFO - Downloaded 109697047 bytes. 03:15:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:15:27 INFO - mkdir: /builds/slave/test/properties 03:15:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:15:27 INFO - Writing to file /builds/slave/test/properties/build_url 03:15:27 INFO - Contents: 03:15:27 INFO - build_url:https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:15:27 INFO - Running post-action listener: _resource_record_post_action 03:15:27 INFO - Running post-action listener: set_extra_try_arguments 03:15:27 INFO - [mozharness: 2016-11-24 11:15:27.858161Z] Finished download-and-extract step (success) 03:15:27 INFO - [mozharness: 2016-11-24 11:15:27.858260Z] Running populate-webroot step. 03:15:27 INFO - Running pre-action listener: _resource_record_pre_action 03:15:27 INFO - Running main action method: populate_webroot 03:15:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:15:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:15:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:15:27 INFO - '--tppagecycles', 03:15:27 INFO - '7'], 03:15:27 INFO - 'tests': ['tsvgm']}}, 03:15:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:15:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:15:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:15:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:15:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:15:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:15:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:27 INFO - 'talos_options': ['--disable-e10s'], 03:15:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:27 INFO - 'talos_options': ['--disable-e10s'], 03:15:27 INFO - 'tests': ['damp', 'tps']}, 03:15:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:27 INFO - 'tests': ['damp', 'tps']}, 03:15:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:15:27 INFO - 'tests': ['dromaeo_dom']}, 03:15:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:15:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:15:27 INFO - 'tests': ['basic_compositor_video']}, 03:15:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:15:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:15:27 INFO - 'tests': ['a11yr', 03:15:27 INFO - 'ts_paint', 03:15:27 INFO - 'tpaint', 03:15:27 INFO - 'sessionrestore', 03:15:27 INFO - 'sessionrestore_no_auto_restore', 03:15:27 INFO - 'tabpaint']}, 03:15:27 INFO - 'other-e10s': {'tests': ['a11yr', 03:15:27 INFO - 'ts_paint', 03:15:27 INFO - 'tpaint', 03:15:27 INFO - 'sessionrestore', 03:15:27 INFO - 'sessionrestore_no_auto_restore', 03:15:27 INFO - 'tabpaint']}, 03:15:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:15:27 INFO - 'ts_paint', 03:15:27 INFO - 'tpaint', 03:15:27 INFO - 'sessionrestore', 03:15:27 INFO - 'sessionrestore_no_auto_restore', 03:15:27 INFO - 'tabpaint']}, 03:15:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:15:27 INFO - 'ts_paint', 03:15:27 INFO - 'tpaint', 03:15:27 INFO - 'sessionrestore', 03:15:27 INFO - 'sessionrestore_no_auto_restore', 03:15:27 INFO - 'tabpaint']}, 03:15:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:15:27 INFO - 'tests': ['a11yr', 03:15:27 INFO - 'ts_paint', 03:15:27 INFO - 'tpaint', 03:15:27 INFO - 'sessionrestore', 03:15:27 INFO - 'sessionrestore_no_auto_restore', 03:15:27 INFO - 'tabpaint']}, 03:15:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:15:27 INFO - 'tests': ['a11yr', 03:15:27 INFO - 'ts_paint', 03:15:27 INFO - 'tpaint', 03:15:27 INFO - 'sessionrestore', 03:15:27 INFO - 'sessionrestore_no_auto_restore', 03:15:27 INFO - 'tabpaint']}, 03:15:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:15:27 INFO - 'tests': ['tsvgx', 03:15:27 INFO - 'tsvgr_opacity', 03:15:27 INFO - 'tart', 03:15:27 INFO - 'tscrollx', 03:15:27 INFO - 'cart']}, 03:15:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:15:27 INFO - 'tsvgr_opacity', 03:15:27 INFO - 'tart', 03:15:27 INFO - 'tscrollx', 03:15:27 INFO - 'cart']}, 03:15:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:27 INFO - 'talos_options': ['--disable-e10s'], 03:15:27 INFO - 'tests': ['tp5o']}, 03:15:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:27 INFO - 'tests': ['tp5o']}, 03:15:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:27 INFO - 'talos_options': ['--disable-e10s', 03:15:27 INFO - '--xperf_path', 03:15:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:27 INFO - 'tests': ['tp5n']}, 03:15:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:15:27 INFO - 'talos_options': ['--xperf_path', 03:15:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:27 INFO - 'tests': ['tp5n']}}, 03:15:27 INFO - 'talos.zip': {'path': '', 03:15:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:15:27 INFO - Running post-action listener: _resource_record_post_action 03:15:27 INFO - [mozharness: 2016-11-24 11:15:27.876839Z] Finished populate-webroot step (success) 03:15:27 INFO - [mozharness: 2016-11-24 11:15:27.876949Z] Running create-virtualenv step. 03:15:27 INFO - Running pre-action listener: _resource_record_pre_action 03:15:27 INFO - Running main action method: create_virtualenv 03:15:27 INFO - Creating virtualenv /builds/slave/test/build/venv 03:15:27 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 03:15:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:15:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:15:27 INFO - Using real prefix '/tools/python27' 03:15:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:15:28 INFO - Installing distribute.............................................................................................................................................................................................done. 03:15:31 INFO - Installing pip.................done. 03:15:31 INFO - Return code: 0 03:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:15:31 INFO - Reading from file tmpfile_stdout 03:15:31 INFO - Output received: 03:15:31 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) 03:15:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:15: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')]} 03:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10915c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903a57c940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f70d30>, '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 0x108dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f68b20>, '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.UR3aibgE3p/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.20YPDoEBcx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 03:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:15:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UR3aibgE3p/Render', 03:15:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:32 INFO - 'HOME': '/Users/cltbld', 03:15:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:32 INFO - 'LOGNAME': 'cltbld', 03:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:32 INFO - 'MOZ_NO_REMOTE': '1', 03:15:32 INFO - 'NO_EM_RESTART': '1', 03:15:32 INFO - 'PAGER': '/bin/cat', 03:15:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:32 INFO - 'PWD': '/builds/slave/test', 03:15:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:32 INFO - 'SHELL': '/bin/bash', 03:15:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20YPDoEBcx/Listeners', 03:15:32 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 03:15:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:32 INFO - 'USER': 'cltbld', 03:15:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:32 INFO - 'XPC_FLAGS': '0x0', 03:15:32 INFO - 'XPC_SERVICE_NAME': '0', 03:15:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:32 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 03:15:32 INFO - Cleaning up... 03:15:32 INFO - Return code: 0 03:15:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10915c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903a57c940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f70d30>, '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 0x108dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f68b20>, '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.UR3aibgE3p/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.20YPDoEBcx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 03:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:15:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UR3aibgE3p/Render', 03:15:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:32 INFO - 'HOME': '/Users/cltbld', 03:15:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:32 INFO - 'LOGNAME': 'cltbld', 03:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:32 INFO - 'MOZ_NO_REMOTE': '1', 03:15:32 INFO - 'NO_EM_RESTART': '1', 03:15:32 INFO - 'PAGER': '/bin/cat', 03:15:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:32 INFO - 'PWD': '/builds/slave/test', 03:15:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:32 INFO - 'SHELL': '/bin/bash', 03:15:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20YPDoEBcx/Listeners', 03:15:32 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 03:15:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:32 INFO - 'USER': 'cltbld', 03:15:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:32 INFO - 'XPC_FLAGS': '0x0', 03:15:32 INFO - 'XPC_SERVICE_NAME': '0', 03:15:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:32 INFO - Downloading/unpacking psutil>=3.1.1 03:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:15:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:15:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:15:36 INFO - Installing collected packages: psutil 03:15:36 INFO - Running setup.py install for psutil 03:15:36 INFO - building 'psutil._psutil_osx' extension 03:15:36 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 03:15:37 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 03:15:37 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 03:15:37 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 03:15:37 INFO - building 'psutil._psutil_posix' extension 03:15:37 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 03:15:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:15:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:15:37 INFO - ^ 03:15:37 INFO - 1 warning generated. 03:15:37 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 03:15:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:15:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:15:37 INFO - Successfully installed psutil 03:15:37 INFO - Cleaning up... 03:15:37 INFO - Return code: 0 03:15:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:15:37 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')]} 03:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:37 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')]} 03:15:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:37 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 0x10915c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903a57c940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f70d30>, '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 0x108dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f68b20>, '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.UR3aibgE3p/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.20YPDoEBcx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 03:15:37 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 03:15:37 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 03:15:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UR3aibgE3p/Render', 03:15:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:37 INFO - 'HOME': '/Users/cltbld', 03:15:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:37 INFO - 'LOGNAME': 'cltbld', 03:15:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:37 INFO - 'MOZ_NO_REMOTE': '1', 03:15:37 INFO - 'NO_EM_RESTART': '1', 03:15:37 INFO - 'PAGER': '/bin/cat', 03:15:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:37 INFO - 'PWD': '/builds/slave/test', 03:15:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:37 INFO - 'SHELL': '/bin/bash', 03:15:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20YPDoEBcx/Listeners', 03:15:37 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 03:15:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:37 INFO - 'USER': 'cltbld', 03:15:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:37 INFO - 'XPC_FLAGS': '0x0', 03:15:37 INFO - 'XPC_SERVICE_NAME': '0', 03:15:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:15:38 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 03:15:38 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 03:15:38 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 03:15:38 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 03:15:38 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 03:15:38 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 03:15:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:15:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:15:40 INFO - Installing collected packages: mozsystemmonitor 03:15:40 INFO - Running setup.py install for mozsystemmonitor 03:15:40 INFO - Successfully installed mozsystemmonitor 03:15:40 INFO - Cleaning up... 03:15:40 INFO - Return code: 0 03:15:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:15:40 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')]} 03:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:40 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')]} 03:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:40 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 0x10915c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903a57c940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f70d30>, '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 0x108dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f68b20>, '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.UR3aibgE3p/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.20YPDoEBcx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 03:15:40 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 03:15:40 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 03:15:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UR3aibgE3p/Render', 03:15:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:40 INFO - 'HOME': '/Users/cltbld', 03:15:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:40 INFO - 'LOGNAME': 'cltbld', 03:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:40 INFO - 'MOZ_NO_REMOTE': '1', 03:15:40 INFO - 'NO_EM_RESTART': '1', 03:15:40 INFO - 'PAGER': '/bin/cat', 03:15:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:40 INFO - 'PWD': '/builds/slave/test', 03:15:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:40 INFO - 'SHELL': '/bin/bash', 03:15:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20YPDoEBcx/Listeners', 03:15:40 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 03:15:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:40 INFO - 'USER': 'cltbld', 03:15:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:40 INFO - 'XPC_FLAGS': '0x0', 03:15:40 INFO - 'XPC_SERVICE_NAME': '0', 03:15:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:41 INFO - Downloading/unpacking jsonschema==2.5.1 03:15: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 03:15: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 03:15: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 03:15: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 03:15: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 03:15: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 03:15:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:15:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:15: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 03:15: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 03:15: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 03:15: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 03:15: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 03:15: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 03:15:43 INFO - Downloading functools32-3.2.3-2.tar.gz 03:15:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:15:44 INFO - warning: no files found matching '*.txt' 03:15:44 INFO - no previously-included directories found matching 'build' 03:15:44 INFO - no previously-included directories found matching 'dist' 03:15:44 INFO - no previously-included directories found matching '.git*' 03:15:44 INFO - Installing collected packages: jsonschema, functools32 03:15:44 INFO - Running setup.py install for functools32 03:15:44 INFO - warning: no files found matching '*.txt' 03:15:44 INFO - no previously-included directories found matching 'build' 03:15:44 INFO - no previously-included directories found matching 'dist' 03:15:44 INFO - no previously-included directories found matching '.git*' 03:15:44 INFO - Successfully installed jsonschema functools32 03:15:44 INFO - Cleaning up... 03:15:44 INFO - Return code: 0 03:15:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10915c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903a57c940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f70d30>, '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 0x108dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f68b20>, '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.UR3aibgE3p/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.20YPDoEBcx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 03:15:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:15:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:15:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UR3aibgE3p/Render', 03:15:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:44 INFO - 'HOME': '/Users/cltbld', 03:15:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:44 INFO - 'LOGNAME': 'cltbld', 03:15:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:44 INFO - 'MOZ_NO_REMOTE': '1', 03:15:44 INFO - 'NO_EM_RESTART': '1', 03:15:44 INFO - 'PAGER': '/bin/cat', 03:15:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:44 INFO - 'PWD': '/builds/slave/test', 03:15:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:44 INFO - 'SHELL': '/bin/bash', 03:15:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20YPDoEBcx/Listeners', 03:15:44 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 03:15:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:44 INFO - 'USER': 'cltbld', 03:15:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:44 INFO - 'XPC_FLAGS': '0x0', 03:15:44 INFO - 'XPC_SERVICE_NAME': '0', 03:15:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:15:44 INFO - Cleaning up... 03:15:44 INFO - Return code: 0 03:15:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10915c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903a57c940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f70d30>, '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 0x108dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f68b20>, '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.UR3aibgE3p/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.20YPDoEBcx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 03:15:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:15:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:15:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UR3aibgE3p/Render', 03:15:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:44 INFO - 'HOME': '/Users/cltbld', 03:15:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:44 INFO - 'LOGNAME': 'cltbld', 03:15:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:44 INFO - 'MOZ_NO_REMOTE': '1', 03:15:44 INFO - 'NO_EM_RESTART': '1', 03:15:44 INFO - 'PAGER': '/bin/cat', 03:15:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:44 INFO - 'PWD': '/builds/slave/test', 03:15:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:44 INFO - 'SHELL': '/bin/bash', 03:15:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20YPDoEBcx/Listeners', 03:15:44 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 03:15:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:44 INFO - 'USER': 'cltbld', 03:15:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:44 INFO - 'XPC_FLAGS': '0x0', 03:15:44 INFO - 'XPC_SERVICE_NAME': '0', 03:15:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:44 INFO - Downloading/unpacking blobuploader==1.2.4 03:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:15:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:47 INFO - Downloading blobuploader-1.2.4.tar.gz 03:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:15:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:15:47 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 03:15:47 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 03:15:47 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 03:15:47 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 03:15:47 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 03:15:47 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 03:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:15:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:15:48 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 03:15:48 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 03:15:48 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 03:15:48 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 03:15:48 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 03:15:48 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 03:15:48 INFO - Downloading docopt-0.6.1.tar.gz 03:15:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:15:48 INFO - Installing collected packages: blobuploader, requests, docopt 03:15:48 INFO - Running setup.py install for blobuploader 03:15:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:15:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:15:48 INFO - Running setup.py install for requests 03:15:49 INFO - Running setup.py install for docopt 03:15:49 INFO - Successfully installed blobuploader requests docopt 03:15:49 INFO - Cleaning up... 03:15:49 INFO - Return code: 0 03:15:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:49 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 0x10915c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903a57c940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f70d30>, '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 0x108dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f68b20>, '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.UR3aibgE3p/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.20YPDoEBcx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 03:15:49 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 03:15:49 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 03:15:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UR3aibgE3p/Render', 03:15:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:49 INFO - 'HOME': '/Users/cltbld', 03:15:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:49 INFO - 'LOGNAME': 'cltbld', 03:15:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:49 INFO - 'MOZ_NO_REMOTE': '1', 03:15:49 INFO - 'NO_EM_RESTART': '1', 03:15:49 INFO - 'PAGER': '/bin/cat', 03:15:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:49 INFO - 'PWD': '/builds/slave/test', 03:15:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:49 INFO - 'SHELL': '/bin/bash', 03:15:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20YPDoEBcx/Listeners', 03:15:49 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 03:15:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:49 INFO - 'USER': 'cltbld', 03:15:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:49 INFO - 'XPC_FLAGS': '0x0', 03:15:49 INFO - 'XPC_SERVICE_NAME': '0', 03:15:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:15:49 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-rE9fTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:15:49 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-a1w7Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:15:50 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-OCFzNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:15:50 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-WWlCyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:15:50 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-7hu4JW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:15:50 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-vFm66D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:15:50 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-3Vuk1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:15:50 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-H2D6dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:15:50 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-cLBF2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:15:50 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-Xga5xt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:15:50 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-D6U2Jr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:15:50 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-jFNkWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:15:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-Tvz5AN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:15:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-xhhf5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-YetIru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:15:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-Houaio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:15:51 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-tfJcPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:15:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:15:51 INFO - Running setup.py install for manifestparser 03:15:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:15:51 INFO - Running setup.py install for mozcrash 03:15:51 INFO - Running setup.py install for mozdebug 03:15:52 INFO - Running setup.py install for mozdevice 03:15:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Running setup.py install for mozfile 03:15:52 INFO - Running setup.py install for mozhttpd 03:15:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Running setup.py install for mozinfo 03:15:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Running setup.py install for mozInstall 03:15:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:15:52 INFO - Running setup.py install for mozleak 03:15:52 INFO - Running setup.py install for mozlog 03:15:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Running setup.py install for moznetwork 03:15:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Running setup.py install for mozprocess 03:15:53 INFO - Running setup.py install for mozprofile 03:15:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Running setup.py install for mozrunner 03:15:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Running setup.py install for mozscreenshot 03:15:53 INFO - Running setup.py install for moztest 03:15:54 INFO - Running setup.py install for mozversion 03:15:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:15:54 INFO - Cleaning up... 03:15:54 INFO - Return code: 0 03:15:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:54 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 0x10915c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903a57c940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f70d30>, '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 0x108dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f68b20>, '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.UR3aibgE3p/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.20YPDoEBcx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 03:15:54 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 03:15:54 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 03:15:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UR3aibgE3p/Render', 03:15:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:54 INFO - 'HOME': '/Users/cltbld', 03:15:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:54 INFO - 'LOGNAME': 'cltbld', 03:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:54 INFO - 'MOZ_NO_REMOTE': '1', 03:15:54 INFO - 'NO_EM_RESTART': '1', 03:15:54 INFO - 'PAGER': '/bin/cat', 03:15:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:54 INFO - 'PWD': '/builds/slave/test', 03:15:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:54 INFO - 'SHELL': '/bin/bash', 03:15:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20YPDoEBcx/Listeners', 03:15:54 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 03:15:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:54 INFO - 'USER': 'cltbld', 03:15:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:54 INFO - 'XPC_FLAGS': '0x0', 03:15:54 INFO - 'XPC_SERVICE_NAME': '0', 03:15:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:15:54 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-gZu04Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:15:54 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)) 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:15:54 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-M9wK4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:15:54 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)) 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:15:54 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-5R1Sky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:15:54 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)) 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:15:54 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-fEYNSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:15:54 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)) 03:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:15:54 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-aZ7XWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:15:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-oF7TSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:15:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-gPrrwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:15:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-cuKVnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:15:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-aReReF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:15:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-JCqg63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:15:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-wgb28z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:15:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-rBKa90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:15:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-P5CMim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:15:55 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-dsqmng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:15:56 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)) 03:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-rYYqBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:56 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)) 03:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:15:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-_ZBPqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 03:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:15:56 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-rELEbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:15:56 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)) 03:15:56 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)) 03:15:56 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)) 03:15:56 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)) 03:15:56 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)) 03:15:56 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)) 03:15:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:15:56 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 03:15:56 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 03:15:56 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 03:15:56 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 03:15:56 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 03:15:56 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 03:15:58 INFO - Downloading blessings-1.6.tar.gz 03:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:15:59 INFO - Installing collected packages: blessings 03:15:59 INFO - Running setup.py install for blessings 03:15:59 INFO - Successfully installed blessings 03:15:59 INFO - Cleaning up... 03:15:59 INFO - Return code: 0 03:15:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:15:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:15:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:15:59 INFO - Reading from file tmpfile_stdout 03:15:59 INFO - Current package versions: 03:15:59 INFO - blessings == 1.6 03:15:59 INFO - blobuploader == 1.2.4 03:15:59 INFO - docopt == 0.6.1 03:15:59 INFO - functools32 == 3.2.3-2 03:15:59 INFO - jsonschema == 2.5.1 03:15:59 INFO - manifestparser == 1.1 03:15:59 INFO - mozInstall == 1.12 03:15:59 INFO - mozcrash == 1.0 03:15:59 INFO - mozdebug == 0.1 03:15:59 INFO - mozdevice == 0.48 03:15:59 INFO - mozfile == 1.2 03:15:59 INFO - mozhttpd == 0.7 03:15:59 INFO - mozinfo == 0.9 03:15:59 INFO - mozleak == 0.1 03:15:59 INFO - mozlog == 3.4 03:15:59 INFO - moznetwork == 0.27 03:15:59 INFO - mozprocess == 0.23 03:15:59 INFO - mozprofile == 0.28 03:15:59 INFO - mozrunner == 6.13 03:15:59 INFO - mozscreenshot == 0.1 03:15:59 INFO - mozsystemmonitor == 0.3 03:15:59 INFO - moztest == 0.7 03:15:59 INFO - mozversion == 1.4 03:15:59 INFO - psutil == 3.1.1 03:15:59 INFO - requests == 1.2.3 03:15:59 INFO - wsgiref == 0.1.2 03:15:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:15:59 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')]} 03:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:59 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')]} 03:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:59 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 0x10915c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903a57c940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f70d30>, '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 0x108dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f68b20>, '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.UR3aibgE3p/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.20YPDoEBcx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 03:15:59 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 03:15:59 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 03:15:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UR3aibgE3p/Render', 03:15:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:59 INFO - 'HOME': '/Users/cltbld', 03:15:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:59 INFO - 'LOGNAME': 'cltbld', 03:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:59 INFO - 'MOZ_NO_REMOTE': '1', 03:15:59 INFO - 'NO_EM_RESTART': '1', 03:15:59 INFO - 'PAGER': '/bin/cat', 03:15:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:59 INFO - 'PWD': '/builds/slave/test', 03:15:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:59 INFO - 'SHELL': '/bin/bash', 03:15:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20YPDoEBcx/Listeners', 03:15:59 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 03:15:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:59 INFO - 'USER': 'cltbld', 03:15:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:59 INFO - 'XPC_FLAGS': '0x0', 03:15:59 INFO - 'XPC_SERVICE_NAME': '0', 03:15:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:59 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)) 03:15:59 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)) 03:15:59 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)) 03:15:59 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)) 03:15:59 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)) 03:15:59 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)) 03:15:59 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)) 03:15:59 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)) 03:15:59 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)) 03:15:59 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)) 03:15:59 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)) 03:15:59 INFO - Cleaning up... 03:15:59 INFO - Return code: 0 03:15:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:15:59 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')]} 03:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:59 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')]} 03:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15: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 0x10915c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f903a57c940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f70d30>, '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 0x108dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f68b20>, '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.UR3aibgE3p/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.20YPDoEBcx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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 03:15: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 03:15: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 03:15:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UR3aibgE3p/Render', 03:15:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:59 INFO - 'HOME': '/Users/cltbld', 03:15:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:59 INFO - 'LOGNAME': 'cltbld', 03:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:59 INFO - 'MOZ_NO_REMOTE': '1', 03:15:59 INFO - 'NO_EM_RESTART': '1', 03:15:59 INFO - 'PAGER': '/bin/cat', 03:15:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:59 INFO - 'PWD': '/builds/slave/test', 03:15:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:59 INFO - 'SHELL': '/bin/bash', 03:15:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20YPDoEBcx/Listeners', 03:15:59 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 03:15:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:59 INFO - 'USER': 'cltbld', 03:15:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:59 INFO - 'XPC_FLAGS': '0x0', 03:15:59 INFO - 'XPC_SERVICE_NAME': '0', 03:15:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:16:00 INFO - Cleaning up... 03:16:00 INFO - Return code: 0 03:16:00 INFO - Running post-action listener: _resource_record_post_action 03:16:00 INFO - Running post-action listener: _start_resource_monitoring 03:16:00 INFO - Starting resource monitoring. 03:16:00 INFO - [mozharness: 2016-11-24 11:16:00.132327Z] Finished create-virtualenv step (success) 03:16:00 INFO - [mozharness: 2016-11-24 11:16:00.133122Z] Running install step. 03:16:00 INFO - Running pre-action listener: _resource_record_pre_action 03:16:00 INFO - Running main action method: install 03:16:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:00 INFO - Reading from file tmpfile_stdout 03:16:00 INFO - Detecting whether we're running mozinstall >=1.0... 03:16:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:16:00 INFO - Reading from file tmpfile_stdout 03:16:00 INFO - Output received: 03:16:00 INFO - Usage: mozinstall [options] installer 03:16:00 INFO - Options: 03:16:00 INFO - -h, --help show this help message and exit 03:16:00 INFO - -d DEST, --destination=DEST 03:16:00 INFO - Directory to install application into. [default: 03:16:00 INFO - "/builds/slave/test"] 03:16:00 INFO - --app=APP Application being installed. [default: firefox] 03:16:00 INFO - mkdir: /builds/slave/test/build/application 03:16: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'] 03:16: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 03:16:32 INFO - Reading from file tmpfile_stdout 03:16:32 INFO - Output received: 03:16:32 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:16:32 INFO - Running post-action listener: _resource_record_post_action 03:16:32 INFO - [mozharness: 2016-11-24 11:16:32.039894Z] Finished install step (success) 03:16:32 INFO - [mozharness: 2016-11-24 11:16:32.040045Z] Running run-tests step. 03:16:32 INFO - Running pre-action listener: _resource_record_pre_action 03:16:32 INFO - Running main action method: run_tests 03:16:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:16:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:16:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:16:32 INFO - 2016-11-24 03:16:32.054 screenresolution[2177:20941] starting screenresolution argv=screenresolution get 03:16:32 INFO - 2016-11-24 03:16:32.057 screenresolution[2177:20941] Display 0: 1600x1200x32@60 03:16:32 INFO - 2016-11-24 03:16:32.063 screenresolution[2178:20943] starting screenresolution argv=screenresolution list 03:16:32 INFO - Available Modes on Display 0 03:16:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:16:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:16:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:16:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:16:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:16:32 INFO - Intel Iris: 03:16:32 INFO - Chipset Model: Intel Iris 03:16:32 INFO - Type: GPU 03:16:32 INFO - Bus: Built-In 03:16:32 INFO - VRAM (Dynamic, Max): 1536 MB 03:16:32 INFO - Vendor: Intel (0x8086) 03:16:32 INFO - Device ID: 0x0a2e 03:16:32 INFO - Revision ID: 0x0009 03:16:32 INFO - Displays: 03:16:32 INFO - Display: 03:16:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:16:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:16:32 INFO - Display Serial Number: 1600x1200 60 03:16:32 INFO - Main Display: Yes 03:16:32 INFO - Mirror: Off 03:16:32 INFO - Online: Yes 03:16:32 INFO - Rotation: Supported 03:16:32 INFO - Return code: 0 03:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:16:32 INFO - Python 2.7.3 03:16:32 INFO - Return code: 0 03:16:32 INFO - grabbing minidump binary from tooltool 03:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:32 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 0x108f70d30>, '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 0x108dd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108f68b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:16:32 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 03:16:32 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 03:16:32 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 03:16:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:16:32 INFO - Return code: 0 03:16:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:16:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:16:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:16:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:16:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:16:32 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0322', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:16:32 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0322 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UR3aibgE3p/Render', 03:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:32 INFO - 'HOME': '/Users/cltbld', 03:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:32 INFO - 'LOGNAME': 'cltbld', 03:16:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:16:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:32 INFO - 'MOZ_NO_REMOTE': '1', 03:16:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:16:32 INFO - 'NO_EM_RESTART': '1', 03:16:32 INFO - 'PAGER': '/bin/cat', 03:16:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:32 INFO - 'PWD': '/builds/slave/test', 03:16:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:32 INFO - 'SHELL': '/bin/bash', 03:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20YPDoEBcx/Listeners', 03:16:32 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 03:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:32 INFO - 'USER': 'cltbld', 03:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:32 INFO - 'XPC_FLAGS': '0x0', 03:16:32 INFO - 'XPC_SERVICE_NAME': '0', 03:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0322', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:16:32 INFO - mozversion application_buildid: 20161124020338 03:16:32 INFO - mozversion application_changeset: 7f611a717df6bf178b2cab2da4ef8c005f80da8a 03:16:32 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:16:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:16:32 INFO - mozversion application_name: Firefox 03:16:32 INFO - mozversion application_remotingname: firefox-dev 03:16:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:16:32 INFO - mozversion application_vendor: Mozilla 03:16:32 INFO - mozversion application_version: 52.0a2 03:16:32 INFO - mozversion platform_buildid: 20161124020338 03:16:32 INFO - mozversion platform_changeset: 7f611a717df6bf178b2cab2da4ef8c005f80da8a 03:16:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:16:32 INFO - mozversion platform_version: 52.0a2 03:16:32 INFO - using testdate: 1479986192 03:16:32 INFO - actual date: 1479986192 03:16:32 INFO - starting webserver on 'localhost:52464' 03:16:32 INFO - SUITE-START | Running 2 tests 03:16:32 INFO - TEST-START | dromaeo_css 03:16:32 INFO - operating with platform_type : mac_ 03:16:32 INFO - Initialising browser for dromaeo_css test... 03:16:32 INFO - TEST-INFO | started process 2188 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmp_CZQd3/profile http://localhost:52464/getInfo.html) 03:16:35 INFO - PROCESS | 2188 | __metrics Screen width/height:1600/1200 03:16:35 INFO - PROCESS | 2188 | colorDepth:24 03:16:35 INFO - PROCESS | 2188 | Browser inner width/height: 1024/689 03:16:35 INFO - PROCESS | 2188 | __metrics 03:16:35 INFO - PROCESS | 2188 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:16:35 INFO - PROCESS | 2188 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:16:36 INFO - TEST-INFO | 2188: exit 0 03:16:36 INFO - Browser initialized. 03:16:36 INFO - Running cycle 1/1 for dromaeo_css test... 03:16:36 INFO - TEST-INFO | started process 2190 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmp_CZQd3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:16:48 INFO - PROCESS | 2190 | RSS: Main: 223166464 03:16:48 INFO - PROCESS | 2190 | 03:17:40 INFO - PROCESS | 2190 | 1479986260254 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:19:53 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:52464/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52464/tests/dromaeo/cssquery-ext.html) 03:19:55 INFO - PROCESS | 2190 | RSS: Main: 364855296 03:19:55 INFO - PROCESS | 2190 | 03:22:57 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:52464/tests/dromaeo/cssquery-ext.html (next: http://localhost:52464/tests/dromaeo/cssquery-jquery.html) 03:22:58 INFO - PROCESS | 2190 | RSS: Main: 347951104 03:22:58 INFO - PROCESS | 2190 | 03:26:00 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:52464/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52464/tests/dromaeo/cssquery-mootools.html) 03:26:01 INFO - PROCESS | 2190 | RSS: Main: 318578688 03:26:01 INFO - PROCESS | 2190 | 03:29:03 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:52464/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52464/tests/dromaeo/cssquery-prototype.html) 03:29:04 INFO - PROCESS | 2190 | RSS: Main: 320630784 03:29:04 INFO - PROCESS | 2190 | 03:32:07 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:52464/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52464/tests/dromaeo/cssquery-yui.html) 03:32:08 INFO - PROCESS | 2190 | RSS: Main: 307625984 03:32:08 INFO - PROCESS | 2190 | 03:35:11 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:52464/tests/dromaeo/cssquery-yui.html (next: http://localhost:52464/tests/dromaeo/cssquery-dojo.html) 03:35:11 INFO - PROCESS | 2190 | RSS: Main: 357232640 03:35:11 INFO - PROCESS | 2190 | 03:35:11 INFO - PROCESS | 2190 | __start_tp_report 03:35:11 INFO - PROCESS | 2190 | _x_x_mozilla_page_load 03:35:11 INFO - PROCESS | 2190 | _x_x_mozilla_page_load_details 03:35:11 INFO - PROCESS | 2190 | |i|pagename|runs| 03:35:11 INFO - PROCESS | 2190 | |0;dojo.html;3304.140923359927;3304.669533046694;3305.735541156709;3307.7023067923888;3348.5479460272854;5578.972105139474;5603.971980140093;5653.406392328808;5661.377248502672;5691.203231547586;5562.332520097584;5646.6894320812335;5658.236138121352;5660.264165658469;5687.203791469197;5097.796088156488;5100.999999999991;5130.948690513084;5154.92267615986;5178.352705911761;5119.872003199931;5126.666766660155;5132.97433512832;5241.737913104349;5278.392984806758;5105.285260063575;5151.665141765792;5157.329547158864;5214.765335559909;5221.686698798066;5160.174372100465;5191.377034755816;5205.713685747282;5210.583153347723;5238.266642670029;5071.290019397287;5197.0385478686185;5199.350081239845;5201.08980928336;5253.028189784902;5514;5557.083081291582;5588.273524441837;5596.468335508121;5656.377798442168;6116.567196125374;6129.267100814756;6133.499688084841;6145.402195429176;6146.938530614681;6223.564350495467;6241.157443745127;6244.937550624481;6247.781327653511;6250.4374606285655;4790;5611.961787069406;5665.49010589049;5676.744546495418;5720.399358067385;6236.750529978841;6295.433410992941;6302.716377763011;6339.904901426482;6351.17434733491;6009.369016253318;6021.6989150542295;6031.004884194146;6031.306399764083;6075.453209211192;6025.066114752132;6054.455099041107;6181.258249010058;6185.350538193515;6200.441960223601;5989.670568118708;6013.9699301503215;6047.818565443044;6121.877562448726;6123.571350005457;5173.405058418255;6113.506711826627;6125.795630697795;6126.864481135888;6141.447269745744;4786.377770889762;4855.562999330077;4858.927116093261;4864.562189402971;4899.779509922062;4301.806418711166;4339.848105316362;4342.544032876565;4347.5217726050105;4407.074514351958;5614.466625670555;5663.461971112738;5671.064274394761;5688.800891968761;5724.599278050497;6207.782727604512;6208.596441231305;6209.0996805462955;6232.844178895564;6252.124702541557;5518.227448157261;5675.659460432387;5680.744366503517;5698.230738850285;5703.686297253691;5264.894702106013;5267.499587539178;5292.735363231823;5316.627836051517;5319.148936170194;6313.747450102036;6379.93620063812;6387.968060159669;6395.6482393468805;6398.104265402941;6206.10011548332;6208.441240288395;6215.65813880241;6233.438990490697;6241.344658810941;6144.941444615925;6214.533909956681;6263.1857858479925;6267.655278959701;6294.5279104068295;6064;6072.757089716361;6073.787417440369;6082.330943596113;6101.145839582373;6180.412860778219;6181.876362472815;6185.907211391923;6188.628682279078;6190.133381326708;5703.486686198262;5759.337676167127;5923.526117910471;5938.109283607493;5963.671998040149;4963.404391472902;5970.432808883323;5981.431763982415;6020.337762846171;6063;5861.413858614275;5862.149988251595;5864.9706751467675;5865.736041878211;5867.647941123546;4716.882077948078;5811.79658711943;5916.645001299936;5997.55018373615;6005;5801.332846722681;5836.474717275466;5849.883002340014;5883.970305196659;5886.793962211474 03:35:11 INFO - PROCESS | 2190 | |1;ext.html;474.94300683917936;494.91091603511376;505.69658205076945;515.5411683601593;552.6545908806995;30149.698503014963;30849.691503084963;40830;41869.58130418695;41881;40892.38661420074;41190.76427707166;41271;41357.58642413582;41513.79243103788;9196.724098277062;9390.953045234764;9404.24766018717;9416.90583094167;9450.905490945106;9287.164155226028;9401.05989401058;9494.38286511378;9495.762605934873;9503.619855205783;9493.667721629745;9523.523823808782;9540.856887146698;9542;9560.187384072362;9432.669856555021;9504;9511.619535218582;9511.952440237823;9522.952385238099;9207.171354578053;9269.860952085657;9280.582373793195;9294.209992150676;9313.90686093137;39015.60984390148;39196.60803391958;39269.607303927165;39284.60715392838;39345.40981885274;3056.3734434440885;3153.716165545089;3190.5692731481186;3343.0138109257828;3535.9292814143832;27987.440251195065;28942;29971.850140749375;30768.53847192294;30820.383592328253;3114.8442577871015;3145.9056228312957;3234.433974054531;3259.3970115528464;3283.7537184711005;485719;486275;486998;488079.55960220326;496914;32442.837785811393;33310.333793323996;60594.69702651459;60828.087578686354;60956.39043609596;33739.49390759141;34063.829680851435;34074.14814629654;34244.486332705026;34297.65702342994;14418.351174197187;14599.708005839824;14623.634409139644;14640.267986600627;14667.413303467954;9768.51157442125;12033.338166400757;12542.81185782214;12549.937250313873;12580.4338804754;7752.0697516298405;7758.883616745753;7772.533647981139;7860.567668778158;7875;3192.3774863901317;3214.8392580371356;3221.5328777327154;3224.6775322467565;3276.9344613107605;881.6561540999015;924.5977999570074;926.2034650200821;928.6053427293508;1045.033344156652;28780.71219287822;29595.556066658588;29664.703352966626;30197.547036794465;30450;857.4469467193625;862.6247582301719;973.8733964584559;1040.891450605103;1060.9469526523642;13629.386677599532;14867;14924.25378731059;15196;15314.004589701633;641102;642485;643576;647967;648717;56418;57040;57050.42949570451;57232.42767572354;57320;1237.307108019512;1251.0867067040929;1318.9010824188224;1334.799780033003;1361.5847166614278;29457.852710736308;29590.852045739633;29985.400291993603;30193.849030755584;30686;49910.25134622911;50675.746621266655;50817.23774143465;50818.74590627023;50991.23513147231;482034;486666;487377;643867;644599;39568;39605.80197098996;39888.60111398849;39955.60044399519;40241.19517609573;43917.902052448946;44039.77980110079;44455.77772111119;44623.77688111539;45103;33032.009039728844;35721.64278357287;36302.273954520235;37610.247795044495;38739.612603873604;31274.687253128086;37948.430773538974;37952;37995.81002094972;38141.809290953366;37185;37632.62367376401;37641.81179094087;38044.04889877775;38072.42891356577;32169.517457237693;33661.32677346488;33944.66055339415;34707;34985.30029399347;25348.98604055891;25405.872970635766;25419;25724;25927.74072259253 03:35:11 INFO - PROCESS | 2190 | |2;jquery.html;349.8635532142466;367.8510203367636;369.94450832375156;376.51429655744084;396.47665082091635;14401.927990360047;14888.925555372234;15447.5365739028;19359.709604355947;19935.60128797423;18685;19344.70982935257;20022;20113.396598102045;20184.293549725764;5567.498925096751;5928.881422371572;5937.435943585374;5937.584369094165;5938.198343223653;4849.5877850382585;5383.246345511631;5855.326637436707;5922.851928701795;5923;5602.467765562285;5883;5890.440408161217;5891.8527036824;5894.145348924403;5696.857578560548;5828.88342233153;5834.358220595731;5840.941590584082;5844.561657875676;4866.659333846632;5005.149124648782;5091.414487333967;5098.439171691111;5108.080545501808;24811.25566233016;24823.751762482505;24920.5015899681;25158.87420562904;25341;2378.54807586558;2406.2420337593603;2429.0769507587006;2556.514262290159;2917.50402431585;14887.255637218095;15098.094114353064;15134.394624215018;15588.532344029698;15779.605509862346;1518.769796464856;1557.704036233112;1865.9346922857771;1885.274973399338;1906.7711874575002;110984.4450777749;112776.43611781888;115544;116044.41977790142;117632;9368.002239283363;10284.691459256233;10490.21323400748;10536.893626169294;10911;13484.797728034087;13486.190828550318;13488.797668034988;13499.055066145276;13521.309836270466;7778.105517865405;7819.569923654253;8360.247577718035;8492.193241642035;8676.262517685942;8528.701495447629;8568.657253709782;8575.442596231225;8757.518336491426;8770.25452836516;6386.776462823779;6576.408123268929;6583.637899915455;6589.715005573867;6596.472282217411;1782.8395444409805;1815.5642645764922;1822.7539282197;1848.3807924345494;1863.6086421851292;884.7566919097171;973.1339108193713;978.8727465429448;984.7989742924558;1061.7451811565168;20017.099230534663;21229.68155477669;21288.78711212868;21295.78704212969;21326.680099798516;919.4299534288783;938.3384713776908;941.4304345870787;975.1857199238574;979.0454307050711;9533.140057982717;10667.093297069681;10805.189610779224;11357;11540.711482212844;546048;585464;587500;590903;601728;75522;86643;105721;113677.29484057745;116670;994.9900500994897;996.4469719305855;1019.8266294729765;1065.5737704918095;1074.1567869222622;18572.535686607942;19366;19765.60468790645;19809.801901980794;19814.60370792605;49523.50476495189;50148.74925625348;50508.49491505038;51222.48777512326;51707.48292517027;68342.65828671024;69198.30801691918;70629.64685176541;72608;73399.26600733925;6844.623545704835;7055.541389809851;7254.419646428379;7287.635618218962;7373.926260737324;9170.954145229498;12279.44742486608;12338.629841104781;12339.69150771238;12642.683932901751;10609.840852387375;10892.40091794959;10892.509837057192;11022.50398732043;11083.448317235754;10355.741106472398;10519.21105917044;10715.24993250465;10837.45812709377;11146.72133196644;9241.399309044891;10830.566777328819;10947.397893115955;10979.28634638746;11295.265807722471;6223.844403889939;6713.966430167818;7237.601931893586;7322.231165727522;7490.588017659081;9616.615335386507;10601.787964240517;10683;10884.020438160287;11032.779344413228 03:35:11 INFO - PROCESS | 2190 | |3;mootools.html;1462.2177135232653;1489.3074720255079;1625.9512214633558;1740.1995082262158;1793.4798908316588;8230.876536851945;8699;8704.390692651517;8708.259797917177;8721.258693011087;7591.354734847545;7833.981582394295;7840.294373506384;8319.126491718373;8485;2305.262316058858;2337.3689103941924;2344.859308441499;2346.143657564985;2355.4817940053163;1960.1865225931222;2367.30164601443;2373.988130059356;2377.465070359173;2378.0844374915696;2085.937421877346;2102.348272035666;2325.453518423169;2342.6486027095903;2342.824288178385;2285.2344464604375;2295.36877358726;2308.0883051194683;2311.7803808638237;2321.419645088728;2324.5815753164425;2352.4001379648253;2356.4697942962866;2356.8585884846793;2359.504504054151;8530.914690853075;8627;8628.439151455135;8638.568071596395;8638.956805215934;5111.616628752859;5272.103742363807;5291.4708529147165;5294.788208471657;5295.629305948585;3240;3468.757186996911;3476.803853806667;3597.118705917067;3600.747947643666;1007.3905287301224;1056.8573242612226;1074.4305518075432;1094.8631421072366;1099.395332567093;11232.21374503785;11260.774784504263;11429.714257143638;11433.656990290305;11449.54201831934;4527.411436513232;4694.671373003857;4781.856544303677;4810.855674329776;4832.903341933142;9675.129238368581;9693.41839489633;9697.030296970388;9712.368696034735;9725.319227653996;5228.55557277628;5229.633925625246;5231.476852314814;5232;5251.238570407269;4270.63339731283;4310.331898555729;4658.650601204923;4790.233562629963;4816.590589799906;3499.8950031499094;3603.24331890306;3794;3896.688264938798;3897.1621101462756;1086.7011571817654;1219.439058033297;1234.3026190202506;1238.8141778733261;1260.9054320925784;849.7408290471466;853.5177624642057;881.8765372847806;883.3595643158659;883.9646414143363;5482.835514934558;5508.025666140319;5610.803621873215;5718;5811.941880581225;431.4412835378193;444.48217826232724;454.3706965852318;455.21475454840134;455.5239774435723;3669.211119609297;3703.203811180609;3735.6824669903363;3789.7536660117003;3878.108035151931;8081.232282933112;10258.230632702576;10606.840897386546;10611.469426528643;10645;9962.950185249172;10387.94806025965;10528.420936848546;10562.419066951392;10593.993570610779;8093.959530202429;8200.138985406445;8215;8216.876746848802;8226.62980165882;5741.569382296261;5742.913856292243;5793.420657934173;5869.442402971711;5969.462748352669;9492.857607136037;9613.990530994144;9660.227181825578;9777.168940640126;9835.85246221293;9842.950785246028;10018;10022.398656080659;10024.298299119284;10026.949865250626;6819.2498825128205;7014.298570142945;7252.5648461092505;7404.185539590749;7561.7353392633195;8151.877721834296;8232.794180145544;8323.83352332962;8330.250277475056;8344.707935222237;7264.854702906018;7382.52013619113;7431.888521672071;7541.2458754124145;7544.773656790305;5994.730237139252;7022.157341119098;7087.645617719197;7253.637318133967;7384.704611815468;6570.244421891543;6744.0558321834005;6750.32496750321;6750.9873518972545;6751.56114852533;4800.4479484859685;4808.567228949411;4818.397700287464;4840.370751802243;4845.6608037437045;4707.129181101507;4750.857474275777;4837.516248375275;4916.680415772963;4922.901541969212 03:35:11 INFO - PROCESS | 2190 | |4;prototype.html;228.86725699094526;300.4696710306308;304.9496833022551;307.852230929154;309.78160396920174;4705.552972467614;5188.533032027117;5209.921851172231;5253.60597955153;5259.789608415659;4322.502912165102;4497.930951762193;4631.675782695213;5015.799368025275;5125.282460455539;3260.6305351752203;3933.9213215735667;3959.9010024749323;3964.3260645690157;4014.3978403239603;3461.0655123116744;3790.867319643813;3966.226585815767;3970.9205815883665;3991.0421498840215;3102.3247446378928;3492.4412094064955;3732.880135959215;3917.745346552479;3933.2330195611867;3525.4359302511475;3615.5348145040853;3914.667253283474;3916.0992971616547;3921.784301863396;3353.698167164967;3356.7314614830757;3382.2389962258294;3410.880619178317;3422.3743721732226;4142.399352093929;4992.326035985132;5000.849974500771;5040.193569028937;5040.42035165957;4187.1625674865145;4411.757353345564;4459.866204013885;4474.753888536129;4490.169318676056;4905.3623029006;5312.867178320573;5324.1215199492035;5333.759980800874;5337.786488540453;3584.91697140459;3691.243295124493;3711.9072023199637;3728.3102626014;3740.17716102457;4216.810243539048;4622.976885115587;5344.91982620261;5619.634723742944;5644.641438344179;3219.7585181111513;3561.3411518869093;3714.182879766447;3769.9434508482395;3791.810409479515;3787.4129509926006;3801.4867992820614;3827.2394698438475;3833.6549710526187;3844.826982785781;3347.9700870712045;4220.6786267552725;4441.622462090694;4449.310356894686;4471.552844715502;4516.480604730433;5059.384083667126;5305.257263983045;5481.506664400144;5492.089192727861;1012.5443550402348;1065.8188108021654;1076.6769969009265;1174.647605718281;1194.2416565480808;742.8848528478095;793.948018874993;829.9626516806758;836.7574152383705;852.1946760311446;4229.492460904711;4927.728974906415;4937.1238929206675;4942.332785074041;5014.7994080236385;5250.842474725764;5276.92084618731;5312.556224136863;5315.946840531546;5355.3841308249275;4312.396264522969;4444.200043992112;4661.3707149534375;4774.87790369263;4784.373313073572;2275.8179345652306;2382.094324540361;2417.4407507158135;2421.9878900605736;2496.4258220609363;4609.377734005933;4810.831620893252;5346.984073026113;5437.444401131462;5564.554835613141;4460.464744230713;4508.864734057984;4520.2652395300365;4801.4718380978065;5442.836714898559;5405.5135037846785;5433.755481003285;5434.538064264424;5437.238786569884;5444.972775136178;4708.458527269408;4786.401699787527;5080.898382032413;5273.6044796639635;5333.852832017122;3938.3099517647943;4977.8257760978195;5056.7471626419265;5060.240963855304;5135.87160320995;4805;4929.728864912464;5244.5279924806955;5476.67139971603;5495.450454954473;3326.850291736926;3508.9296416157135;3535.646435356444;3547.733919955962;3611.0430735854493;4681.625469962327;4827.8551643450755;4903.901921961612;5231.1107111790325;5262.842114736564;4588.738097023212;5079.161938280143;5094.15946368845;5105.821296254613;5120.540645915896;4580.312953057068;4607.6083532900075;4811.518848115161;5049.419316778471;5088.771005304844;4371.6884934518885;4634.745089020136;4902.950970490249;4992.525710057539;5051.848444546669;4171.4368560243975;4779.904401912012;4935.901281974268;5079.206436459858;5275.657082289639;4635.655659858603;4648.604868586072;5307.293280426933;5345.599080068932;5357.6158609418435 03:35:11 INFO - PROCESS | 2190 | |5;yui.html;684.9691763870622;692.2869444472195;695.777351247601;746.0972223609434;748.696777804989;14060.29698515074;14691.191984440851;14700.264986750673;14718;14720.263986800644;12713.982881369491;12746;12780.424880880359;12797.168184068052;12853.614391568244;637.2353176188569;662.3178126529658;662.7614058938802;718.7089228862319;766.4481573267238;821.0229826506438;824.6495239523209;825.3479750996736;826.305903041445;826.921442462965;866.6316815353481;866.956652167389;867;867.3494878840869;868.0234735922088;674.2886255000972;696.4846013949691;699.3880354689646;699.566268913272;865.346663269234;524.3786113455529;624.9312575616724;638.3807706524664;641.8074577626693;648.2124219073801;13860.099093558887;13896.51362202318;13897.444102235899;13897.652558686113;13912;12515.249085054926;12563.623091307276;12634.431450584654;12739.235645861278;12795;11529.538818447252;11685.883141168564;11840.940795296054;12130.33283169434;12231.877681223163;4254.893627659302;4336.262835318003;4444.799984000728;4454.57681520255;4486.057927835126;78695.99999999943;81115.18884811194;86291.56854215752;86394;90411.09588904092;10153.847692284622;10256.58973641046;10258.230632702427;10401.58393664245;10475.057244848;19754.209831606575;19769.60460790776;19785.703214451794;19793.81237125777;19858;7175.497715159993;7328.78013659591;7521.285477879593;7635.656395462218;7721.189275126143;3966.900827479336;4056.9025284462905;4145.647619952278;4162.146759914211;4275.46556680415;3197.2326641606314;3286.3920174767745;3628.3106209820576;3804.8287827047848;3813.9618603813606;671.516508114157;681.5399605266425;687.3847906124091;687.5805758487319;691.4502970138752;619.7799781077742;619.8543342314553;623.9407256310643;641.9422251997343;667.3426674725391;9183.63265469387;9402.670906518239;9415.482148481762;9668.323217374857;9862.161716254055;637.5091179791627;640.257301530232;645.0872016097263;648.8377905523619;658.7430901948244;3857.40210267409;4244.257254980366;4354;4361.738295702268;4438.4451943507065;102279;107760;107788;108413;111952;83196.99999999879;89758.10241897628;93411;97625.51187244018;98844.50577747065;80174.19825801784;82107.5894620523;83459;85105;87659;6627.536072474881;8317.916820831715;8449.23956843887;8487.957560212159;8654.22112009922;49918.25122623091;51792.74103629609;52171.217431737794;52409.73795131;55103.44896550983;69860.65069674823;71975;72337.6383118081;72520;74855.25144748483;10186.643467478603;10435.32170408921;10636.095931845879;10653.680389588324;10830.458477075957;11603.187776855877;11614.12894032934;11761.235519691534;12110.697232569257;12487.625371238513;11108.50011749457;11429.885701142883;11675.529803952884;11752.001079908303;11912.76174476523;11363.034142098015;11394.48724807369;11457.656270311903;11457.770844582896;12003.879961200277;10812.135029197803;11687.941560292144;11955.940220299191;12082.637520874388;12137.089718270989;10000.849987250052;10623.256372053882;10753.978372054642;10757.892421076001;11123.110151188048;10777.030067293981;10825.512851921525;10826.67519974402;10834.891651083704;10895.891041089488 03:35:11 INFO - PROCESS | 2190 | __end_tp_report 03:35:11 INFO - PROCESS | 2190 | __start_cc_report 03:35:11 INFO - PROCESS | 2190 | _x_x_mozilla_cycle_collect,1821 03:35:11 INFO - PROCESS | 2190 | __end_cc_report 03:35:11 INFO - PROCESS | 2190 | __startTimestamp1479987311665__endTimestamp 03:35:11 INFO - PROCESS | 2190 | 03:35:11 INFO - PROCESS | 2190 | ------- Summary: start ------- 03:35:11 INFO - PROCESS | 2190 | Number of tests: 6 03:35:11 INFO - PROCESS | 2190 | 03:35:11 INFO - PROCESS | 2190 | [#0] dojo.html Cycles:165 Average:5653.98 Median:5836.47 stddev:644.19 (11.0%) stddev-sans-first:619.23 03:35:11 INFO - PROCESS | 2190 | Values: 3304.1 3304.7 3305.7 3307.7 3348.5 5579.0 5604.0 5653.4 5661.4 5691.2 5562.3 5646.7 5658.2 5660.3 5687.2 5097.8 5101.0 5130.9 5154.9 5178.4 5119.9 5126.7 5133.0 5241.7 5278.4 5105.3 5151.7 5157.3 5214.8 5221.7 5160.2 5191.4 5205.7 5210.6 5238.3 5071.3 5197.0 5199.4 5201.1 5253.0 5514.0 5557.1 5588.3 5596.5 5656.4 6116.6 6129.3 6133.5 6145.4 6146.9 6223.6 6241.2 6244.9 6247.8 6250.4 4790.0 5612.0 5665.5 5676.7 5720.4 6236.8 6295.4 6302.7 6339.9 6351.2 6009.4 6021.7 6031.0 6031.3 6075.5 6025.1 6054.5 6181.3 6185.4 6200.4 5989.7 6014.0 6047.8 6121.9 6123.6 5173.4 6113.5 6125.8 6126.9 6141.4 4786.4 4855.6 4858.9 4864.6 4899.8 4301.8 4339.8 4342.5 4347.5 4407.1 5614.5 5663.5 5671.1 5688.8 5724.6 6207.8 6208.6 6209.1 6232.8 6252.1 5518.2 5675.7 5680.7 5698.2 5703.7 5264.9 5267.5 5292.7 5316.6 5319.1 6313.7 6379.9 6388.0 6395.6 6398.1 6206.1 6208.4 6215.7 6233.4 6241.3 6144.9 6214.5 6263.2 6267.7 6294.5 6064.0 6072.8 6073.8 6082.3 6101.1 6180.4 6181.9 6185.9 6188.6 6190.1 5703.5 5759.3 5923.5 5938.1 5963.7 4963.4 5970.4 5981.4 6020.3 6063.0 5861.4 5862.1 5865.0 5865.7 5867.6 4716.9 5811.8 5916.6 5997.6 6005.0 5801.3 5836.5 5849.9 5884.0 5886.8 03:35:11 INFO - PROCESS | 2190 | 03:35:11 INFO - PROCESS | 2190 | [#1] ext.html Cycles:180 Average:67911.79 Median:39456.70 stddev:151668.62 (384.4%) stddev-sans-first:152009.57 03:35:11 INFO - PROCESS | 2190 | Values: 474.9 494.9 505.7 515.5 552.7 30149.7 30849.7 40830.0 41869.6 41881.0 40892.4 41190.8 41271.0 41357.6 41513.8 9196.7 9391.0 9404.2 9416.9 9450.9 9287.2 9401.1 9494.4 9495.8 9503.6 9493.7 9523.5 9540.9 9542.0 9560.2 9432.7 9504.0 9511.6 9512.0 9523.0 9207.2 9269.9 9280.6 9294.2 9313.9 39015.6 39196.6 39269.6 39284.6 39345.4 3056.4 3153.7 3190.6 3343.0 3535.9 27987.4 28942.0 29971.9 30768.5 30820.4 3114.8 3145.9 3234.4 3259.4 3283.8 485719.0 486275.0 486998.0 488079.6 496914.0 32442.8 33310.3 60594.7 60828.1 60956.4 33739.5 34063.8 34074.1 34244.5 34297.7 14418.4 14599.7 14623.6 14640.3 14667.4 9768.5 12033.3 12542.8 12549.9 12580.4 7752.1 7758.9 7772.5 7860.6 7875.0 3192.4 3214.8 3221.5 3224.7 3276.9 881.7 924.6 926.2 928.6 1045.0 28780.7 29595.6 29664.7 30197.5 30450.0 857.4 862.6 973.9 1040.9 1060.9 13629.4 14867.0 14924.3 15196.0 15314.0 641102.0 642485.0 643576.0 647967.0 648717.0 56418.0 57040.0 57050.4 57232.4 57320.0 1237.3 1251.1 1318.9 1334.8 1361.6 29457.9 29590.9 29985.4 30193.8 30686.0 49910.3 50675.7 50817.2 50818.7 50991.2 482034.0 486666.0 487377.0 643867.0 644599.0 39568.0 39605.8 39888.6 39955.6 40241.2 43917.9 44039.8 44455.8 44623.8 45103.0 33032.0 35721.6 36302.3 37610.2 38739.6 31274.7 37948.4 37952.0 37995.8 38141.8 37185.0 37632.6 37641.8 38044.0 38072.4 32169.5 33661.3 33944.7 34707.0 34985.3 25349.0 25405.9 25419.0 25724.0 25927.7 03:35:11 INFO - PROCESS | 2190 | 03:35:11 INFO - PROCESS | 2190 | [#2] jquery.html Cycles:180 Average:33323.23 Median:2737.01 stddev:96621.84 (3530.2%) stddev-sans-first:96861.17 03:35:11 INFO - PROCESS | 2190 | Values: 349.9 367.9 369.9 376.5 396.5 14401.9 14888.9 15447.5 19359.7 19935.6 18685.0 19344.7 20022.0 20113.4 20184.3 5567.5 5928.9 5937.4 5937.6 5938.2 4849.6 5383.2 5855.3 5922.9 5923.0 5602.5 5883.0 5890.4 5891.9 5894.1 5696.9 5828.9 5834.4 5840.9 5844.6 4866.7 5005.1 5091.4 5098.4 5108.1 24811.3 24823.8 24920.5 25158.9 25341.0 2378.5 2406.2 2429.1 2556.5 2917.5 14887.3 15098.1 15134.4 15588.5 15779.6 1518.8 1557.7 1865.9 1885.3 1906.8 110984.4 112776.4 115544.0 116044.4 117632.0 9368.0 10284.7 10490.2 10536.9 10911.0 13484.8 13486.2 13488.8 13499.1 13521.3 7778.1 7819.6 8360.2 8492.2 8676.3 8528.7 8568.7 8575.4 8757.5 8770.3 6386.8 6576.4 6583.6 6589.7 6596.5 1782.8 1815.6 1822.8 1848.4 1863.6 884.8 973.1 978.9 984.8 1061.7 20017.1 21229.7 21288.8 21295.8 21326.7 919.4 938.3 941.4 975.2 979.0 9533.1 10667.1 10805.2 11357.0 11540.7 546048.0 585464.0 587500.0 590903.0 601728.0 75522.0 86643.0 105721.0 113677.3 116670.0 995.0 996.4 1019.8 1065.6 1074.2 18572.5 19366.0 19765.6 19809.8 19814.6 49523.5 50148.7 50508.5 51222.5 51707.5 68342.7 69198.3 70629.6 72608.0 73399.3 6844.6 7055.5 7254.4 7287.6 7373.9 9171.0 12279.4 12338.6 12339.7 12642.7 10609.8 10892.4 10892.5 11022.5 11083.4 10355.7 10519.2 10715.2 10837.5 11146.7 9241.4 10830.6 10947.4 10979.3 11295.3 6223.8 6714.0 7237.6 7322.2 7490.6 9616.6 10601.8 10683.0 10884.0 11032.8 03:35:11 INFO - PROCESS | 2190 | 03:35:11 INFO - PROCESS | 2190 | [#3] mootools.html Cycles:180 Average:5549.43 Median:4825.65 stddev:3130.39 (64.9%) stddev-sans-first:3124.10 03:35:11 INFO - PROCESS | 2190 | Values: 1462.2 1489.3 1626.0 1740.2 1793.5 8230.9 8699.0 8704.4 8708.3 8721.3 7591.4 7834.0 7840.3 8319.1 8485.0 2305.3 2337.4 2344.9 2346.1 2355.5 1960.2 2367.3 2374.0 2377.5 2378.1 2085.9 2102.3 2325.5 2342.6 2342.8 2285.2 2295.4 2308.1 2311.8 2321.4 2324.6 2352.4 2356.5 2356.9 2359.5 8530.9 8627.0 8628.4 8638.6 8639.0 5111.6 5272.1 5291.5 5294.8 5295.6 3240.0 3468.8 3476.8 3597.1 3600.7 1007.4 1056.9 1074.4 1094.9 1099.4 11232.2 11260.8 11429.7 11433.7 11449.5 4527.4 4694.7 4781.9 4810.9 4832.9 9675.1 9693.4 9697.0 9712.4 9725.3 5228.6 5229.6 5231.5 5232.0 5251.2 4270.6 4310.3 4658.7 4790.2 4816.6 3499.9 3603.2 3794.0 3896.7 3897.2 1086.7 1219.4 1234.3 1238.8 1260.9 849.7 853.5 881.9 883.4 884.0 5482.8 5508.0 5610.8 5718.0 5811.9 431.4 444.5 454.4 455.2 455.5 3669.2 3703.2 3735.7 3789.8 3878.1 8081.2 10258.2 10606.8 10611.5 10645.0 9963.0 10387.9 10528.4 10562.4 10594.0 8094.0 8200.1 8215.0 8216.9 8226.6 5741.6 5742.9 5793.4 5869.4 5969.5 9492.9 9614.0 9660.2 9777.2 9835.9 9843.0 10018.0 10022.4 10024.3 10026.9 6819.2 7014.3 7252.6 7404.2 7561.7 8151.9 8232.8 8323.8 8330.3 8344.7 7264.9 7382.5 7431.9 7541.2 7544.8 5994.7 7022.2 7087.6 7253.6 7384.7 6570.2 6744.1 6750.3 6751.0 6751.6 4800.4 4808.6 4818.4 4840.4 4845.7 4707.1 4750.9 4837.5 4916.7 4922.9 03:35:11 INFO - PROCESS | 2190 | 03:35:11 INFO - PROCESS | 2190 | [#4] prototype.html Cycles:180 Average:4196.67 Median:4635.20 stddev:1281.83 (27.7%) stddev-sans-first:1250.35 03:35:11 INFO - PROCESS | 2190 | Values: 228.9 300.5 304.9 307.9 309.8 4705.6 5188.5 5209.9 5253.6 5259.8 4322.5 4497.9 4631.7 5015.8 5125.3 3260.6 3933.9 3959.9 3964.3 4014.4 3461.1 3790.9 3966.2 3970.9 3991.0 3102.3 3492.4 3732.9 3917.7 3933.2 3525.4 3615.5 3914.7 3916.1 3921.8 3353.7 3356.7 3382.2 3410.9 3422.4 4142.4 4992.3 5000.8 5040.2 5040.4 4187.2 4411.8 4459.9 4474.8 4490.2 4905.4 5312.9 5324.1 5333.8 5337.8 3584.9 3691.2 3711.9 3728.3 3740.2 4216.8 4623.0 5344.9 5619.6 5644.6 3219.8 3561.3 3714.2 3769.9 3791.8 3787.4 3801.5 3827.2 3833.7 3844.8 3348.0 4220.7 4441.6 4449.3 4471.6 4516.5 5059.4 5305.3 5481.5 5492.1 1012.5 1065.8 1076.7 1174.6 1194.2 742.9 793.9 830.0 836.8 852.2 4229.5 4927.7 4937.1 4942.3 5014.8 5250.8 5276.9 5312.6 5315.9 5355.4 4312.4 4444.2 4661.4 4774.9 4784.4 2275.8 2382.1 2417.4 2422.0 2496.4 4609.4 4810.8 5347.0 5437.4 5564.6 4460.5 4508.9 4520.3 4801.5 5442.8 5405.5 5433.8 5434.5 5437.2 5445.0 4708.5 4786.4 5080.9 5273.6 5333.9 3938.3 4977.8 5056.7 5060.2 5135.9 4805.0 4929.7 5244.5 5476.7 5495.5 3326.9 3508.9 3535.6 3547.7 3611.0 4681.6 4827.9 4903.9 5231.1 5262.8 4588.7 5079.2 5094.2 5105.8 5120.5 4580.3 4607.6 4811.5 5049.4 5088.8 4371.7 4634.7 4903.0 4992.5 5051.8 4171.4 4779.9 4935.9 5079.2 5275.7 4635.7 4648.6 5307.3 5345.6 5357.6 03:35:11 INFO - PROCESS | 2190 | 03:35:11 INFO - PROCESS | 2190 | [#5] yui.html Cycles:180 Average:19876.69 Median:4400.09 stddev:29267.86 (665.2%) stddev-sans-first:29314.49 03:35:11 INFO - PROCESS | 2190 | Values: 685.0 692.3 695.8 746.1 748.7 14060.3 14691.2 14700.3 14718.0 14720.3 12714.0 12746.0 12780.4 12797.2 12853.6 637.2 662.3 662.8 718.7 766.4 821.0 824.6 825.3 826.3 826.9 866.6 867.0 867.0 867.3 868.0 674.3 696.5 699.4 699.6 865.3 524.4 624.9 638.4 641.8 648.2 13860.1 13896.5 13897.4 13897.7 13912.0 12515.2 12563.6 12634.4 12739.2 12795.0 11529.5 11685.9 11840.9 12130.3 12231.9 4254.9 4336.3 4444.8 4454.6 4486.1 78696.0 81115.2 86291.6 86394.0 90411.1 10153.8 10256.6 10258.2 10401.6 10475.1 19754.2 19769.6 19785.7 19793.8 19858.0 7175.5 7328.8 7521.3 7635.7 7721.2 3966.9 4056.9 4145.6 4162.1 4275.5 3197.2 3286.4 3628.3 3804.8 3814.0 671.5 681.5 687.4 687.6 691.5 619.8 619.9 623.9 641.9 667.3 9183.6 9402.7 9415.5 9668.3 9862.2 637.5 640.3 645.1 648.8 658.7 3857.4 4244.3 4354.0 4361.7 4438.4 102279.0 107760.0 107788.0 108413.0 111952.0 83197.0 89758.1 93411.0 97625.5 98844.5 80174.2 82107.6 83459.0 85105.0 87659.0 6627.5 8317.9 8449.2 8488.0 8654.2 49918.3 51792.7 52171.2 52409.7 55103.4 69860.7 71975.0 72337.6 72520.0 74855.3 10186.6 10435.3 10636.1 10653.7 10830.5 11603.2 11614.1 11761.2 12110.7 12487.6 11108.5 11429.9 11675.5 11752.0 11912.8 11363.0 11394.5 11457.7 11457.8 12003.9 10812.1 11687.9 11955.9 12082.6 12137.1 10000.8 10623.3 10754.0 10757.9 11123.1 10777.0 10825.5 10826.7 10834.9 10895.9 03:35:11 INFO - PROCESS | 2190 | -------- Summary: end -------- 03:35:11 INFO - PROCESS | 2190 | 03:35:12 INFO - TEST-INFO | 2190: exit 0 03:35:12 INFO - TEST-OK | dromaeo_css | took 1119657ms 03:35:12 INFO - TEST-START | kraken 03:35:12 INFO - operating with platform_type : mac_ 03:35:12 INFO - Initialising browser for kraken test... 03:35:12 INFO - TEST-INFO | started process 2215 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpHCSasv/profile http://localhost:52464/getInfo.html) 03:35:14 INFO - PROCESS | 2215 | __metrics Screen width/height:1600/1200 03:35:14 INFO - PROCESS | 2215 | colorDepth:24 03:35:14 INFO - PROCESS | 2215 | Browser inner width/height: 1024/689 03:35:14 INFO - PROCESS | 2215 | __metrics 03:35:15 INFO - PROCESS | 2215 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:35:15 INFO - PROCESS | 2215 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:35:15 INFO - TEST-INFO | 2215: exit 0 03:35:15 INFO - Browser initialized. 03:35:15 INFO - Running cycle 1/1 for kraken test... 03:35:15 INFO - TEST-INFO | started process 2216 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpHCSasv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:35:28 INFO - PROCESS | 2216 | RSS: Main: 222986240 03:35:28 INFO - PROCESS | 2216 | 03:35:30 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52464/tests/kraken/driver.html?testName=audio-beat-detection) 03:35:31 INFO - PROCESS | 2216 | RSS: Main: 272752640 03:35:31 INFO - PROCESS | 2216 | 03:35:34 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52464/tests/kraken/driver.html?testName=audio-dft) 03:35:35 INFO - PROCESS | 2216 | RSS: Main: 282599424 03:35:35 INFO - PROCESS | 2216 | 03:35:38 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52464/tests/kraken/driver.html?testName=audio-fft) 03:35:39 INFO - PROCESS | 2216 | RSS: Main: 378814464 03:35:39 INFO - PROCESS | 2216 | 03:35:41 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52464/tests/kraken/driver.html?testName=audio-oscillator) 03:35:41 INFO - PROCESS | 2216 | RSS: Main: 282685440 03:35:41 INFO - PROCESS | 2216 | 03:35:44 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52464/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:35:44 INFO - PROCESS | 2216 | RSS: Main: 285118464 03:35:44 INFO - PROCESS | 2216 | 03:35:47 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52464/tests/kraken/driver.html?testName=imaging-darkroom) 03:35:48 INFO - PROCESS | 2216 | RSS: Main: 325824512 03:35:48 INFO - PROCESS | 2216 | 03:35:50 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52464/tests/kraken/driver.html?testName=imaging-desaturate) 03:35:51 INFO - PROCESS | 2216 | RSS: Main: 326148096 03:35:51 INFO - PROCESS | 2216 | 03:35:54 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52464/tests/kraken/driver.html?testName=json-parse-financial) 03:35:54 INFO - PROCESS | 2216 | RSS: Main: 322670592 03:35:54 INFO - PROCESS | 2216 | 03:35:56 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52464/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:35:57 INFO - PROCESS | 2216 | RSS: Main: 301867008 03:35:57 INFO - PROCESS | 2216 | 03:35:58 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52464/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:35:59 INFO - PROCESS | 2216 | RSS: Main: 302206976 03:35:59 INFO - PROCESS | 2216 | 03:36:01 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52464/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:36:02 INFO - PROCESS | 2216 | RSS: Main: 303783936 03:36:02 INFO - PROCESS | 2216 | 03:36:05 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52464/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:36:06 INFO - PROCESS | 2216 | RSS: Main: 323428352 03:36:06 INFO - PROCESS | 2216 | 03:36:09 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52464/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:36:09 INFO - PROCESS | 2216 | RSS: Main: 316801024 03:36:09 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52464/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52464/tests/kraken/driver.html?testName=ai-astar) 03:36:11 INFO - PROCESS | 2216 | RSS: Main: 360996864 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | __start_tp_report 03:36:11 INFO - PROCESS | 2216 | _x_x_mozilla_page_load 03:36:11 INFO - PROCESS | 2216 | _x_x_mozilla_page_load_details 03:36:11 INFO - PROCESS | 2216 | |i|pagename|runs| 03:36:11 INFO - PROCESS | 2216 | |0;ai-astar;114;115;115;113;113;114;113;113;114;113 03:36:11 INFO - PROCESS | 2216 | |1;audio-beat-detection;148;112;118;141;114;112;117;138;179;113 03:36:11 INFO - PROCESS | 2216 | |2;audio-dft;184;184;182;185;183;181;212;165;247;266 03:36:11 INFO - PROCESS | 2216 | |3;audio-fft;85;76;83;116;64;58;58;60;82;58 03:36:11 INFO - PROCESS | 2216 | |4;audio-oscillator;91;70;95;70;78;100;125;89;69;93 03:36:11 INFO - PROCESS | 2216 | |5;imaging-gaussian-blur;90;87;90;87;90;87;90;88;92;86 03:36:11 INFO - PROCESS | 2216 | |6;imaging-darkroom;94;95;95;95;95;96;97;95;96;95 03:36:11 INFO - PROCESS | 2216 | |7;imaging-desaturate;88;124;115;79;81;82;85;82;80;81 03:36:11 INFO - PROCESS | 2216 | |8;json-parse-financial;55;56;54;54;57;57;58;57;57;57 03:36:11 INFO - PROCESS | 2216 | |9;json-stringify-tinderbox;64;70;69;74;74;69;69;71;71;73 03:36:11 INFO - PROCESS | 2216 | |10;stanford-crypto-aes;84;88;86;85;88;85;82;84;85;85 03:36:11 INFO - PROCESS | 2216 | |11;stanford-crypto-ccm;148;147;152;151;158;149;149;151;198;159 03:36:11 INFO - PROCESS | 2216 | |12;stanford-crypto-pbkdf2;168;170;170;169;168;170;170;224;229;170 03:36:11 INFO - PROCESS | 2216 | |13;stanford-crypto-sha256-iterative;67;67;68;67;66;67;66;68;66;67 03:36:11 INFO - PROCESS | 2216 | __end_tp_report 03:36:11 INFO - PROCESS | 2216 | __start_cc_report 03:36:11 INFO - PROCESS | 2216 | _x_x_mozilla_cycle_collect,2077 03:36:11 INFO - PROCESS | 2216 | __end_cc_report 03:36:11 INFO - PROCESS | 2216 | __startTimestamp1479987371724__endTimestamp 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | ------- Summary: start ------- 03:36:11 INFO - PROCESS | 2216 | Number of tests: 14 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 03:36:11 INFO - PROCESS | 2216 | Values: 114.0 115.0 115.0 113.0 113.0 114.0 113.0 113.0 114.0 113.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:128.00 stddev:22.13 (17.3%) stddev-sans-first:22.41 03:36:11 INFO - PROCESS | 2216 | Values: 148.0 112.0 118.0 141.0 114.0 112.0 117.0 138.0 179.0 113.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#2] audio-dft Cycles:10 Average:198.90 Median:184.50 stddev:32.72 (17.7%) stddev-sans-first:34.25 03:36:11 INFO - PROCESS | 2216 | Values: 184.0 184.0 182.0 185.0 183.0 181.0 212.0 165.0 247.0 266.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#3] audio-fft Cycles:10 Average:74.00 Median:70.00 stddev:18.55 (26.5%) stddev-sans-first:19.25 03:36:11 INFO - PROCESS | 2216 | Values: 85.0 76.0 83.0 116.0 64.0 58.0 58.0 60.0 82.0 58.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#4] audio-oscillator Cycles:10 Average:88.00 Median:83.50 stddev:17.34 (20.8%) stddev-sans-first:18.36 03:36:11 INFO - PROCESS | 2216 | Values: 91.0 70.0 95.0 70.0 78.0 100.0 125.0 89.0 69.0 93.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:90.00 stddev:1.95 (2.2%) stddev-sans-first:2.01 03:36:11 INFO - PROCESS | 2216 | Values: 90.0 87.0 90.0 87.0 90.0 87.0 90.0 88.0 92.0 86.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#6] imaging-darkroom Cycles:10 Average:95.30 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.73 03:36:11 INFO - PROCESS | 2216 | Values: 94.0 95.0 95.0 95.0 95.0 96.0 97.0 95.0 96.0 95.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#7] imaging-desaturate Cycles:10 Average:89.70 Median:81.50 stddev:16.06 (19.7%) stddev-sans-first:17.02 03:36:11 INFO - PROCESS | 2216 | Values: 88.0 124.0 115.0 79.0 81.0 82.0 85.0 82.0 80.0 81.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:57.00 stddev:1.40 (2.5%) stddev-sans-first:1.41 03:36:11 INFO - PROCESS | 2216 | Values: 55.0 56.0 54.0 54.0 57.0 57.0 58.0 57.0 57.0 57.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:2.99 (4.2%) stddev-sans-first:2.09 03:36:11 INFO - PROCESS | 2216 | Values: 64.0 70.0 69.0 74.0 74.0 69.0 69.0 71.0 71.0 73.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:85.00 stddev:1.81 (2.1%) stddev-sans-first:1.87 03:36:11 INFO - PROCESS | 2216 | Values: 84.0 88.0 86.0 85.0 88.0 85.0 82.0 84.0 85.0 85.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#11] stanford-crypto-ccm Cycles:10 Average:156.20 Median:151.50 stddev:15.22 (10.0%) stddev-sans-first:15.85 03:36:11 INFO - PROCESS | 2216 | Values: 148.0 147.0 152.0 151.0 158.0 149.0 149.0 151.0 198.0 159.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.80 Median:170.00 stddev:24.13 (14.2%) stddev-sans-first:25.14 03:36:11 INFO - PROCESS | 2216 | Values: 168.0 170.0 170.0 169.0 168.0 170.0 170.0 224.0 229.0 170.0 03:36:11 INFO - PROCESS | 2216 | 03:36:11 INFO - PROCESS | 2216 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 03:36:11 INFO - PROCESS | 2216 | Values: 67.0 67.0 68.0 67.0 66.0 67.0 66.0 68.0 66.0 67.0 03:36:11 INFO - PROCESS | 2216 | -------- Summary: end -------- 03:36:11 INFO - PROCESS | 2216 | 03:36:12 INFO - TEST-INFO | 2216: exit 0 03:36:12 INFO - TEST-OK | kraken | took 60502ms 03:36:12 INFO - SUITE-END | took 1180s 03:36:12 INFO - Completed test suite (00:19:40) 03:36:12 INFO - javascript benchmark 03:36:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3304.140923359927, 3304.669533046694, 3305.735541156709, 3307.7023067923888, 3348.5479460272854, 5578.972105139474, 5603.971980140093, 5653.406392328808, 5661.377248502672, 5691.203231547586, 5562.332520097584, 5646.6894320812335, 5658.236138121352, 5660.264165658469, 5687.203791469197, 5097.796088156488, 5100.999999999991, 5130.948690513084, 5154.92267615986, 5178.352705911761, 5119.872003199931, 5126.666766660155, 5132.97433512832, 5241.737913104349, 5278.392984806758, 5105.285260063575, 5151.665141765792, 5157.329547158864, 5214.765335559909, 5221.686698798066, 5160.174372100465, 5191.377034755816, 5205.713685747282, 5210.583153347723, 5238.266642670029, 5071.290019397287, 5197.0385478686185, 5199.350081239845, 5201.08980928336, 5253.028189784902, 5514.0, 5557.083081291582, 5588.273524441837, 5596.468335508121, 5656.377798442168, 6116.567196125374, 6129.267100814756, 6133.499688084841, 6145.402195429176, 6146.938530614681, 6223.564350495467, 6241.157443745127, 6244.937550624481, 6247.781327653511, 6250.4374606285655, 4790.0, 5611.961787069406, 5665.49010589049, 5676.744546495418, 5720.399358067385, 6236.750529978841, 6295.433410992941, 6302.716377763011, 6339.904901426482, 6351.17434733491, 6009.369016253318, 6021.6989150542295, 6031.004884194146, 6031.306399764083, 6075.453209211192, 6025.066114752132, 6054.455099041107, 6181.258249010058, 6185.350538193515, 6200.441960223601, 5989.670568118708, 6013.9699301503215, 6047.818565443044, 6121.877562448726, 6123.571350005457, 5173.405058418255, 6113.506711826627, 6125.795630697795, 6126.864481135888, 6141.447269745744, 4786.377770889762, 4855.562999330077, 4858.927116093261, 4864.562189402971, 4899.779509922062, 4301.806418711166, 4339.848105316362, 4342.544032876565, 4347.5217726050105, 4407.074514351958, 5614.466625670555, 5663.461971112738, 5671.064274394761, 5688.800891968761, 5724.599278050497, 6207.782727604512, 6208.596441231305, 6209.0996805462955, 6232.844178895564, 6252.124702541557, 5518.227448157261, 5675.659460432387, 5680.744366503517, 5698.230738850285, 5703.686297253691, 5264.894702106013, 5267.499587539178, 5292.735363231823, 5316.627836051517, 5319.148936170194, 6313.747450102036, 6379.93620063812, 6387.968060159669, 6395.6482393468805, 6398.104265402941, 6206.10011548332, 6208.441240288395, 6215.65813880241, 6233.438990490697, 6241.344658810941, 6144.941444615925, 6214.533909956681, 6263.1857858479925, 6267.655278959701, 6294.5279104068295, 6064.0, 6072.757089716361, 6073.787417440369, 6082.330943596113, 6101.145839582373, 6180.412860778219, 6181.876362472815, 6185.907211391923, 6188.628682279078, 6190.133381326708, 5703.486686198262, 5759.337676167127, 5923.526117910471, 5938.109283607493, 5963.671998040149, 4963.404391472902, 5970.432808883323, 5981.431763982415, 6020.337762846171, 6063.0, 5861.413858614275, 5862.149988251595, 5864.9706751467675, 5865.736041878211, 5867.647941123546, 4716.882077948078, 5811.79658711943, 5916.645001299936, 5997.55018373615, 6005.0, 5801.332846722681, 5836.474717275466, 5849.883002340014, 5883.970305196659, 5886.793962211474], "value": 5613.063018558276, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.94300683917936, 494.91091603511376, 505.69658205076945, 515.5411683601593, 552.6545908806995, 30149.698503014963, 30849.691503084963, 40830.0, 41869.58130418695, 41881.0, 40892.38661420074, 41190.76427707166, 41271.0, 41357.58642413582, 41513.79243103788, 9196.724098277062, 9390.953045234764, 9404.24766018717, 9416.90583094167, 9450.905490945106, 9287.164155226028, 9401.05989401058, 9494.38286511378, 9495.762605934873, 9503.619855205783, 9493.667721629745, 9523.523823808782, 9540.856887146698, 9542.0, 9560.187384072362, 9432.669856555021, 9504.0, 9511.619535218582, 9511.952440237823, 9522.952385238099, 9207.171354578053, 9269.860952085657, 9280.582373793195, 9294.209992150676, 9313.90686093137, 39015.60984390148, 39196.60803391958, 39269.607303927165, 39284.60715392838, 39345.40981885274, 3056.3734434440885, 3153.716165545089, 3190.5692731481186, 3343.0138109257828, 3535.9292814143832, 27987.440251195065, 28942.0, 29971.850140749375, 30768.53847192294, 30820.383592328253, 3114.8442577871015, 3145.9056228312957, 3234.433974054531, 3259.3970115528464, 3283.7537184711005, 485719.0, 486275.0, 486998.0, 488079.55960220326, 496914.0, 32442.837785811393, 33310.333793323996, 60594.69702651459, 60828.087578686354, 60956.39043609596, 33739.49390759141, 34063.829680851435, 34074.14814629654, 34244.486332705026, 34297.65702342994, 14418.351174197187, 14599.708005839824, 14623.634409139644, 14640.267986600627, 14667.413303467954, 9768.51157442125, 12033.338166400757, 12542.81185782214, 12549.937250313873, 12580.4338804754, 7752.0697516298405, 7758.883616745753, 7772.533647981139, 7860.567668778158, 7875.0, 3192.3774863901317, 3214.8392580371356, 3221.5328777327154, 3224.6775322467565, 3276.9344613107605, 881.6561540999015, 924.5977999570074, 926.2034650200821, 928.6053427293508, 1045.033344156652, 28780.71219287822, 29595.556066658588, 29664.703352966626, 30197.547036794465, 30450.0, 857.4469467193625, 862.6247582301719, 973.8733964584559, 1040.891450605103, 1060.9469526523642, 13629.386677599532, 14867.0, 14924.25378731059, 15196.0, 15314.004589701633, 641102.0, 642485.0, 643576.0, 647967.0, 648717.0, 56418.0, 57040.0, 57050.42949570451, 57232.42767572354, 57320.0, 1237.307108019512, 1251.0867067040929, 1318.9010824188224, 1334.799780033003, 1361.5847166614278, 29457.852710736308, 29590.852045739633, 29985.400291993603, 30193.849030755584, 30686.0, 49910.25134622911, 50675.746621266655, 50817.23774143465, 50818.74590627023, 50991.23513147231, 482034.0, 486666.0, 487377.0, 643867.0, 644599.0, 39568.0, 39605.80197098996, 39888.60111398849, 39955.60044399519, 40241.19517609573, 43917.902052448946, 44039.77980110079, 44455.77772111119, 44623.77688111539, 45103.0, 33032.009039728844, 35721.64278357287, 36302.273954520235, 37610.247795044495, 38739.612603873604, 31274.687253128086, 37948.430773538974, 37952.0, 37995.81002094972, 38141.809290953366, 37185.0, 37632.62367376401, 37641.81179094087, 38044.04889877775, 38072.42891356577, 32169.517457237693, 33661.32677346488, 33944.66055339415, 34707.0, 34985.30029399347, 25348.98604055891, 25405.872970635766, 25419.0, 25724.0, 25927.74072259253], "value": 18352.10138413134, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.8635532142466, 367.8510203367636, 369.94450832375156, 376.51429655744084, 396.47665082091635, 14401.927990360047, 14888.925555372234, 15447.5365739028, 19359.709604355947, 19935.60128797423, 18685.0, 19344.70982935257, 20022.0, 20113.396598102045, 20184.293549725764, 5567.498925096751, 5928.881422371572, 5937.435943585374, 5937.584369094165, 5938.198343223653, 4849.5877850382585, 5383.246345511631, 5855.326637436707, 5922.851928701795, 5923.0, 5602.467765562285, 5883.0, 5890.440408161217, 5891.8527036824, 5894.145348924403, 5696.857578560548, 5828.88342233153, 5834.358220595731, 5840.941590584082, 5844.561657875676, 4866.659333846632, 5005.149124648782, 5091.414487333967, 5098.439171691111, 5108.080545501808, 24811.25566233016, 24823.751762482505, 24920.5015899681, 25158.87420562904, 25341.0, 2378.54807586558, 2406.2420337593603, 2429.0769507587006, 2556.514262290159, 2917.50402431585, 14887.255637218095, 15098.094114353064, 15134.394624215018, 15588.532344029698, 15779.605509862346, 1518.769796464856, 1557.704036233112, 1865.9346922857771, 1885.274973399338, 1906.7711874575002, 110984.4450777749, 112776.43611781888, 115544.0, 116044.41977790142, 117632.0, 9368.002239283363, 10284.691459256233, 10490.21323400748, 10536.893626169294, 10911.0, 13484.797728034087, 13486.190828550318, 13488.797668034988, 13499.055066145276, 13521.309836270466, 7778.105517865405, 7819.569923654253, 8360.247577718035, 8492.193241642035, 8676.262517685942, 8528.701495447629, 8568.657253709782, 8575.442596231225, 8757.518336491426, 8770.25452836516, 6386.776462823779, 6576.408123268929, 6583.637899915455, 6589.715005573867, 6596.472282217411, 1782.8395444409805, 1815.5642645764922, 1822.7539282197, 1848.3807924345494, 1863.6086421851292, 884.7566919097171, 973.1339108193713, 978.8727465429448, 984.7989742924558, 1061.7451811565168, 20017.099230534663, 21229.68155477669, 21288.78711212868, 21295.78704212969, 21326.680099798516, 919.4299534288783, 938.3384713776908, 941.4304345870787, 975.1857199238574, 979.0454307050711, 9533.140057982717, 10667.093297069681, 10805.189610779224, 11357.0, 11540.711482212844, 546048.0, 585464.0, 587500.0, 590903.0, 601728.0, 75522.0, 86643.0, 105721.0, 113677.29484057745, 116670.0, 994.9900500994897, 996.4469719305855, 1019.8266294729765, 1065.5737704918095, 1074.1567869222622, 18572.535686607942, 19366.0, 19765.60468790645, 19809.801901980794, 19814.60370792605, 49523.50476495189, 50148.74925625348, 50508.49491505038, 51222.48777512326, 51707.48292517027, 68342.65828671024, 69198.30801691918, 70629.64685176541, 72608.0, 73399.26600733925, 6844.623545704835, 7055.541389809851, 7254.419646428379, 7287.635618218962, 7373.926260737324, 9170.954145229498, 12279.44742486608, 12338.629841104781, 12339.69150771238, 12642.683932901751, 10609.840852387375, 10892.40091794959, 10892.509837057192, 11022.50398732043, 11083.448317235754, 10355.741106472398, 10519.21105917044, 10715.24993250465, 10837.45812709377, 11146.72133196644, 9241.399309044891, 10830.566777328819, 10947.397893115955, 10979.28634638746, 11295.265807722471, 6223.844403889939, 6713.966430167818, 7237.601931893586, 7322.231165727522, 7490.588017659081, 9616.615335386507, 10601.787964240517, 10683.0, 10884.020438160287, 11032.779344413228], "value": 9401.049404812773, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1462.2177135232653, 1489.3074720255079, 1625.9512214633558, 1740.1995082262158, 1793.4798908316588, 8230.876536851945, 8699.0, 8704.390692651517, 8708.259797917177, 8721.258693011087, 7591.354734847545, 7833.981582394295, 7840.294373506384, 8319.126491718373, 8485.0, 2305.262316058858, 2337.3689103941924, 2344.859308441499, 2346.143657564985, 2355.4817940053163, 1960.1865225931222, 2367.30164601443, 2373.988130059356, 2377.465070359173, 2378.0844374915696, 2085.937421877346, 2102.348272035666, 2325.453518423169, 2342.6486027095903, 2342.824288178385, 2285.2344464604375, 2295.36877358726, 2308.0883051194683, 2311.7803808638237, 2321.419645088728, 2324.5815753164425, 2352.4001379648253, 2356.4697942962866, 2356.8585884846793, 2359.504504054151, 8530.914690853075, 8627.0, 8628.439151455135, 8638.568071596395, 8638.956805215934, 5111.616628752859, 5272.103742363807, 5291.4708529147165, 5294.788208471657, 5295.629305948585, 3240.0, 3468.757186996911, 3476.803853806667, 3597.118705917067, 3600.747947643666, 1007.3905287301224, 1056.8573242612226, 1074.4305518075432, 1094.8631421072366, 1099.395332567093, 11232.21374503785, 11260.774784504263, 11429.714257143638, 11433.656990290305, 11449.54201831934, 4527.411436513232, 4694.671373003857, 4781.856544303677, 4810.855674329776, 4832.903341933142, 9675.129238368581, 9693.41839489633, 9697.030296970388, 9712.368696034735, 9725.319227653996, 5228.55557277628, 5229.633925625246, 5231.476852314814, 5232.0, 5251.238570407269, 4270.63339731283, 4310.331898555729, 4658.650601204923, 4790.233562629963, 4816.590589799906, 3499.8950031499094, 3603.24331890306, 3794.0, 3896.688264938798, 3897.1621101462756, 1086.7011571817654, 1219.439058033297, 1234.3026190202506, 1238.8141778733261, 1260.9054320925784, 849.7408290471466, 853.5177624642057, 881.8765372847806, 883.3595643158659, 883.9646414143363, 5482.835514934558, 5508.025666140319, 5610.803621873215, 5718.0, 5811.941880581225, 431.4412835378193, 444.48217826232724, 454.3706965852318, 455.21475454840134, 455.5239774435723, 3669.211119609297, 3703.203811180609, 3735.6824669903363, 3789.7536660117003, 3878.108035151931, 8081.232282933112, 10258.230632702576, 10606.840897386546, 10611.469426528643, 10645.0, 9962.950185249172, 10387.94806025965, 10528.420936848546, 10562.419066951392, 10593.993570610779, 8093.959530202429, 8200.138985406445, 8215.0, 8216.876746848802, 8226.62980165882, 5741.569382296261, 5742.913856292243, 5793.420657934173, 5869.442402971711, 5969.462748352669, 9492.857607136037, 9613.990530994144, 9660.227181825578, 9777.168940640126, 9835.85246221293, 9842.950785246028, 10018.0, 10022.398656080659, 10024.298299119284, 10026.949865250626, 6819.2498825128205, 7014.298570142945, 7252.5648461092505, 7404.185539590749, 7561.7353392633195, 8151.877721834296, 8232.794180145544, 8323.83352332962, 8330.250277475056, 8344.707935222237, 7264.854702906018, 7382.52013619113, 7431.888521672071, 7541.2458754124145, 7544.773656790305, 5994.730237139252, 7022.157341119098, 7087.645617719197, 7253.637318133967, 7384.704611815468, 6570.244421891543, 6744.0558321834005, 6750.32496750321, 6750.9873518972545, 6751.56114852533, 4800.4479484859685, 4808.567228949411, 4818.397700287464, 4840.370751802243, 4845.6608037437045, 4707.129181101507, 4750.857474275777, 4837.516248375275, 4916.680415772963, 4922.901541969212], "value": 4387.653376577778, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [228.86725699094526, 300.4696710306308, 304.9496833022551, 307.852230929154, 309.78160396920174, 4705.552972467614, 5188.533032027117, 5209.921851172231, 5253.60597955153, 5259.789608415659, 4322.502912165102, 4497.930951762193, 4631.675782695213, 5015.799368025275, 5125.282460455539, 3260.6305351752203, 3933.9213215735667, 3959.9010024749323, 3964.3260645690157, 4014.3978403239603, 3461.0655123116744, 3790.867319643813, 3966.226585815767, 3970.9205815883665, 3991.0421498840215, 3102.3247446378928, 3492.4412094064955, 3732.880135959215, 3917.745346552479, 3933.2330195611867, 3525.4359302511475, 3615.5348145040853, 3914.667253283474, 3916.0992971616547, 3921.784301863396, 3353.698167164967, 3356.7314614830757, 3382.2389962258294, 3410.880619178317, 3422.3743721732226, 4142.399352093929, 4992.326035985132, 5000.849974500771, 5040.193569028937, 5040.42035165957, 4187.1625674865145, 4411.757353345564, 4459.866204013885, 4474.753888536129, 4490.169318676056, 4905.3623029006, 5312.867178320573, 5324.1215199492035, 5333.759980800874, 5337.786488540453, 3584.91697140459, 3691.243295124493, 3711.9072023199637, 3728.3102626014, 3740.17716102457, 4216.810243539048, 4622.976885115587, 5344.91982620261, 5619.634723742944, 5644.641438344179, 3219.7585181111513, 3561.3411518869093, 3714.182879766447, 3769.9434508482395, 3791.810409479515, 3787.4129509926006, 3801.4867992820614, 3827.2394698438475, 3833.6549710526187, 3844.826982785781, 3347.9700870712045, 4220.6786267552725, 4441.622462090694, 4449.310356894686, 4471.552844715502, 4516.480604730433, 5059.384083667126, 5305.257263983045, 5481.506664400144, 5492.089192727861, 1012.5443550402348, 1065.8188108021654, 1076.6769969009265, 1174.647605718281, 1194.2416565480808, 742.8848528478095, 793.948018874993, 829.9626516806758, 836.7574152383705, 852.1946760311446, 4229.492460904711, 4927.728974906415, 4937.1238929206675, 4942.332785074041, 5014.7994080236385, 5250.842474725764, 5276.92084618731, 5312.556224136863, 5315.946840531546, 5355.3841308249275, 4312.396264522969, 4444.200043992112, 4661.3707149534375, 4774.87790369263, 4784.373313073572, 2275.8179345652306, 2382.094324540361, 2417.4407507158135, 2421.9878900605736, 2496.4258220609363, 4609.377734005933, 4810.831620893252, 5346.984073026113, 5437.444401131462, 5564.554835613141, 4460.464744230713, 4508.864734057984, 4520.2652395300365, 4801.4718380978065, 5442.836714898559, 5405.5135037846785, 5433.755481003285, 5434.538064264424, 5437.238786569884, 5444.972775136178, 4708.458527269408, 4786.401699787527, 5080.898382032413, 5273.6044796639635, 5333.852832017122, 3938.3099517647943, 4977.8257760978195, 5056.7471626419265, 5060.240963855304, 5135.87160320995, 4805.0, 4929.728864912464, 5244.5279924806955, 5476.67139971603, 5495.450454954473, 3326.850291736926, 3508.9296416157135, 3535.646435356444, 3547.733919955962, 3611.0430735854493, 4681.625469962327, 4827.8551643450755, 4903.901921961612, 5231.1107111790325, 5262.842114736564, 4588.738097023212, 5079.161938280143, 5094.15946368845, 5105.821296254613, 5120.540645915896, 4580.312953057068, 4607.6083532900075, 4811.518848115161, 5049.419316778471, 5088.771005304844, 4371.6884934518885, 4634.745089020136, 4902.950970490249, 4992.525710057539, 5051.848444546669, 4171.4368560243975, 4779.904401912012, 4935.901281974268, 5079.206436459858, 5275.657082289639, 4635.655659858603, 4648.604868586072, 5307.293280426933, 5345.599080068932, 5357.6158609418435], "value": 3782.672501539628, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.9691763870622, 692.2869444472195, 695.777351247601, 746.0972223609434, 748.696777804989, 14060.29698515074, 14691.191984440851, 14700.264986750673, 14718.0, 14720.263986800644, 12713.982881369491, 12746.0, 12780.424880880359, 12797.168184068052, 12853.614391568244, 637.2353176188569, 662.3178126529658, 662.7614058938802, 718.7089228862319, 766.4481573267238, 821.0229826506438, 824.6495239523209, 825.3479750996736, 826.305903041445, 826.921442462965, 866.6316815353481, 866.956652167389, 867.0, 867.3494878840869, 868.0234735922088, 674.2886255000972, 696.4846013949691, 699.3880354689646, 699.566268913272, 865.346663269234, 524.3786113455529, 624.9312575616724, 638.3807706524664, 641.8074577626693, 648.2124219073801, 13860.099093558887, 13896.51362202318, 13897.444102235899, 13897.652558686113, 13912.0, 12515.249085054926, 12563.623091307276, 12634.431450584654, 12739.235645861278, 12795.0, 11529.538818447252, 11685.883141168564, 11840.940795296054, 12130.33283169434, 12231.877681223163, 4254.893627659302, 4336.262835318003, 4444.799984000728, 4454.57681520255, 4486.057927835126, 78695.99999999943, 81115.18884811194, 86291.56854215752, 86394.0, 90411.09588904092, 10153.847692284622, 10256.58973641046, 10258.230632702427, 10401.58393664245, 10475.057244848, 19754.209831606575, 19769.60460790776, 19785.703214451794, 19793.81237125777, 19858.0, 7175.497715159993, 7328.78013659591, 7521.285477879593, 7635.656395462218, 7721.189275126143, 3966.900827479336, 4056.9025284462905, 4145.647619952278, 4162.146759914211, 4275.46556680415, 3197.2326641606314, 3286.3920174767745, 3628.3106209820576, 3804.8287827047848, 3813.9618603813606, 671.516508114157, 681.5399605266425, 687.3847906124091, 687.5805758487319, 691.4502970138752, 619.7799781077742, 619.8543342314553, 623.9407256310643, 641.9422251997343, 667.3426674725391, 9183.63265469387, 9402.670906518239, 9415.482148481762, 9668.323217374857, 9862.161716254055, 637.5091179791627, 640.257301530232, 645.0872016097263, 648.8377905523619, 658.7430901948244, 3857.40210267409, 4244.257254980366, 4354.0, 4361.738295702268, 4438.4451943507065, 102279.0, 107760.0, 107788.0, 108413.0, 111952.0, 83196.99999999879, 89758.10241897628, 93411.0, 97625.51187244018, 98844.50577747065, 80174.19825801784, 82107.5894620523, 83459.0, 85105.0, 87659.0, 6627.536072474881, 8317.916820831715, 8449.23956843887, 8487.957560212159, 8654.22112009922, 49918.25122623091, 51792.74103629609, 52171.217431737794, 52409.73795131, 55103.44896550983, 69860.65069674823, 71975.0, 72337.6383118081, 72520.0, 74855.25144748483, 10186.643467478603, 10435.32170408921, 10636.095931845879, 10653.680389588324, 10830.458477075957, 11603.187776855877, 11614.12894032934, 11761.235519691534, 12110.697232569257, 12487.625371238513, 11108.50011749457, 11429.885701142883, 11675.529803952884, 11752.001079908303, 11912.76174476523, 11363.034142098015, 11394.48724807369, 11457.656270311903, 11457.770844582896, 12003.879961200277, 10812.135029197803, 11687.941560292144, 11955.940220299191, 12082.637520874388, 12137.089718270989, 10000.849987250052, 10623.256372053882, 10753.978372054642, 10757.892421076001, 11123.110151188048, 10777.030067293981, 10825.512851921525, 10826.67519974402, 10834.891651083704, 10895.891041089488], "value": 7047.165764867264, "unit": "score"}], "name": "dromaeo_css", "value": 6955.995154287055, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 113.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 112.0, 118.0, 141.0, 114.0, 112.0, 117.0, 138.0, 179.0, 113.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 182.0, 185.0, 183.0, 181.0, 212.0, 165.0, 247.0, 266.0], "value": 198.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 76.0, 83.0, 116.0, 64.0, 58.0, 58.0, 60.0, 82.0, 58.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 95.0, 70.0, 78.0, 100.0, 125.0, 89.0, 69.0, 93.0], "value": 88.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 87.0, 90.0, 87.0, 90.0, 88.0, 92.0, 86.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 97.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 124.0, 115.0, 79.0, 81.0, 82.0, 85.0, 82.0, 80.0, 81.0], "value": 89.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 54.0, 57.0, 57.0, 58.0, 57.0, 57.0, 57.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 70.0, 69.0, 74.0, 74.0, 69.0, 69.0, 71.0, 71.0, 73.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 86.0, 85.0, 88.0, 85.0, 82.0, 84.0, 85.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 152.0, 151.0, 158.0, 149.0, 149.0, 151.0, 198.0, 159.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 170.0, 169.0, 168.0, 170.0, 170.0, 224.0, 229.0, 170.0], "value": 180.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 66.0, 67.0, 66.0, 68.0, 66.0, 67.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1493.2, "alertThreshold": 2.0}]} 03:36:12 INFO - Return code: 0 03:36:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:36:13 INFO - # TBPL SUCCESS # 03:36:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:36:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:36:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:36:13 INFO - 2016-11-24 03:36:13.067 screenresolution[2220:23107] starting screenresolution argv=screenresolution get 03:36:13 INFO - 2016-11-24 03:36:13.070 screenresolution[2220:23107] Display 0: 1600x1200x32@60 03:36:13 INFO - 2016-11-24 03:36:13.076 screenresolution[2221:23110] starting screenresolution argv=screenresolution list 03:36:13 INFO - Available Modes on Display 0 03:36:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:36:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:36:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:36:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:36:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:36:13 INFO - Intel Iris: 03:36:13 INFO - Chipset Model: Intel Iris 03:36:13 INFO - Type: GPU 03:36:13 INFO - Bus: Built-In 03:36:13 INFO - VRAM (Dynamic, Max): 1536 MB 03:36:13 INFO - Vendor: Intel (0x8086) 03:36:13 INFO - Device ID: 0x0a2e 03:36:13 INFO - Revision ID: 0x0009 03:36:13 INFO - Displays: 03:36:13 INFO - Display: 03:36:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:36:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:36:13 INFO - Display Serial Number: 1600x1200 60 03:36:13 INFO - Main Display: Yes 03:36:13 INFO - Mirror: Off 03:36:13 INFO - Online: Yes 03:36:13 INFO - Rotation: Supported 03:36:13 INFO - Return code: 0 03:36:13 INFO - Running post-action listener: _resource_record_post_action 03:36:13 INFO - [mozharness: 2016-11-24 11:36:13.319383Z] Finished run-tests step (success) 03:36:13 INFO - Running post-run listener: _resource_record_post_run 03:36:13 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 544768; Write bytes: 655964160; Read time: 4; Write time: 1950 03:36:13 INFO - TinderboxPrint: CPU usage
53.5% 03:36:13 INFO - TinderboxPrint: I/O read bytes / time
544,768 / 4 03:36:13 INFO - TinderboxPrint: I/O write bytes / time
655,964,160 / 1,950 03:36:13 INFO - TinderboxPrint: CPU idle
2,254.5 (46.5%) 03:36:13 INFO - TinderboxPrint: CPU system
975.1 (20.1%) 03:36:13 INFO - TinderboxPrint: CPU user
1,620.2 (33.4%) 03:36:13 INFO - TinderboxPrint: Swap in / out
348,274,688 / 0 03:36:13 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 335694336; Write bytes: 332726784; Read time: 26817; Write time: 708 03:36:13 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 544768; Write bytes: 305505792; Read time: 4; Write time: 1188 03:36:13 INFO - Running post-run listener: _upload_blobber_files 03:36:13 INFO - Blob upload gear active. 03:36:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:36:13 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 03:36:13 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'] 03:36:13 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 03:36:14 INFO - (blobuploader) - INFO - Open directory for files ... 03:36:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:36:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:36:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:15 INFO - (blobuploader) - INFO - Done attempting. 03:36:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:36:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:36:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:16 INFO - (blobuploader) - INFO - Done attempting. 03:36:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:36:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:36:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:18 INFO - (blobuploader) - INFO - Done attempting. 03:36:18 INFO - (blobuploader) - INFO - Iteration through files over. 03:36:18 INFO - Return code: 0 03:36:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:36:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:36:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fffd9c11986e87c9d9b28ff99a163a47d30ca8fc98ec639f055c03990e02ba9afe95d70c272765ad03e5b5e72a0aedad57cf03a21cdc913ffafae29fbade4f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5136173645153bdd6c947e055328d6dad8f6c6ca7e9884a7fa367e53e64fb55e868d852cd60130c4a4389d248ed7292da1fa985ee21b11ce5b53ea280b74d3f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9efc9e4a43285e8b770c884dc19cebad79baeab8959ab25cbe50c16057f542ebbcd7be3752211c7d6b1e835ada6505d310446c9584fd532b0e5464ebba6dca0b"} 03:36:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:36:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:36:18 INFO - Contents: 03:36:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fffd9c11986e87c9d9b28ff99a163a47d30ca8fc98ec639f055c03990e02ba9afe95d70c272765ad03e5b5e72a0aedad57cf03a21cdc913ffafae29fbade4f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5136173645153bdd6c947e055328d6dad8f6c6ca7e9884a7fa367e53e64fb55e868d852cd60130c4a4389d248ed7292da1fa985ee21b11ce5b53ea280b74d3f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9efc9e4a43285e8b770c884dc19cebad79baeab8959ab25cbe50c16057f542ebbcd7be3752211c7d6b1e835ada6505d310446c9584fd532b0e5464ebba6dca0b"} 03:36:18 INFO - Running post-run listener: copy_logs_to_upload_dir 03:36:18 INFO - Copying logs to upload dir... 03:36:18 INFO - mkdir: /builds/slave/test/build/upload/logs 03:36:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.524764 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-24 03:36:19.005900) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 03:36:19.009572) ========= 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.UR3aibgE3p/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.20YPDoEBcx/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fffd9c11986e87c9d9b28ff99a163a47d30ca8fc98ec639f055c03990e02ba9afe95d70c272765ad03e5b5e72a0aedad57cf03a21cdc913ffafae29fbade4f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5136173645153bdd6c947e055328d6dad8f6c6ca7e9884a7fa367e53e64fb55e868d852cd60130c4a4389d248ed7292da1fa985ee21b11ce5b53ea280b74d3f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9efc9e4a43285e8b770c884dc19cebad79baeab8959ab25cbe50c16057f542ebbcd7be3752211c7d6b1e835ada6505d310446c9584fd532b0e5464ebba6dca0b"} build_url:https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010975 build_url: 'https://queue.taskcluster.net/v1/task/caNYwwQcRxuxewA5HVzRgQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fffd9c11986e87c9d9b28ff99a163a47d30ca8fc98ec639f055c03990e02ba9afe95d70c272765ad03e5b5e72a0aedad57cf03a21cdc913ffafae29fbade4f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5136173645153bdd6c947e055328d6dad8f6c6ca7e9884a7fa367e53e64fb55e868d852cd60130c4a4389d248ed7292da1fa985ee21b11ce5b53ea280b74d3f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9efc9e4a43285e8b770c884dc19cebad79baeab8959ab25cbe50c16057f542ebbcd7be3752211c7d6b1e835ada6505d310446c9584fd532b0e5464ebba6dca0b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 03:36:19.043523) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:36:19.043850) ========= 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.UR3aibgE3p/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.20YPDoEBcx/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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.005273 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 03:36:19.093438) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 03:36:19.093756) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 03:36:19.094094) ========= ========= Total master_lag: 0.18 =========