builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0364 starttime: 1478623009.83 results: success (0) buildid: 20161108062935 builduid: ca82d5bbd00a4f2186b6aa2f2238e06a revision: aea5b4c3d165dcde027b3b6551b146a56748e9be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:49.825651) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:49.826063) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:49.826332) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:49.853875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:49.854234) ========= 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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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-08 08:36:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.11M=0.001s 2016-11-08 08:36:49 (8.11 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080289 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:49.954599) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:49.954913) ========= 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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.021734 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:50.005315) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-08 08:36:50.007752) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev aea5b4c3d165dcde027b3b6551b146a56748e9be --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev aea5b4c3d165dcde027b3b6551b146a56748e9be --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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-08 08:36:50,086 truncating revision to first 12 chars 2016-11-08 08:36:50,086 Setting DEBUG logging. 2016-11-08 08:36:50,086 attempt 1/10 2016-11-08 08:36:50,086 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/aea5b4c3d165?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-08 08:36:51,016 unpacking tar archive at: mozilla-central-aea5b4c3d165/testing/mozharness/ program finished with exit code 0 elapsedTime=1.173301 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-08 08:36:51.299881) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:51.300198) ========= script_repo_revision: aea5b4c3d165dcde027b3b6551b146a56748e9be ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:51.300584) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:51.300844) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 08:36:51.319713) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-08 08:36:51.320101) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:36:51 INFO - MultiFileLogger online at 20161108 08:36:51 in /builds/slave/test 08:36:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:36:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:36:51 INFO - {'append_to_log': False, 08:36:51 INFO - 'base_work_dir': '/builds/slave/test', 08:36:51 INFO - 'blob_upload_branch': 'Firefox', 08:36:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:36:51 INFO - 'branch': 'Firefox', 08:36:51 INFO - 'buildbot_json_path': 'buildprops.json', 08:36:51 INFO - 'config_files': ('talos/mac_config.py',), 08:36:51 INFO - 'default_actions': ('clobber', 08:36:51 INFO - 'read-buildbot-config', 08:36:51 INFO - 'download-and-extract', 08:36:51 INFO - 'populate-webroot', 08:36:51 INFO - 'create-virtualenv', 08:36:51 INFO - 'install', 08:36:51 INFO - 'run-tests'), 08:36:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:36:51 INFO - 'download_minidump_stackwalk': True, 08:36:51 INFO - 'download_symbols': 'ondemand', 08:36:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:36:51 INFO - 'tooltool.py': '/tools/tooltool.py', 08:36:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:36:51 INFO - '/tools/misc-python/virtualenv.py')}, 08:36:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:36:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:36:51 INFO - 'installer_path': 'installer.exe', 08:36:51 INFO - 'log_level': 'info', 08:36:51 INFO - 'log_name': 'talos', 08:36:51 INFO - 'log_to_console': True, 08:36:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:36:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:36:51 INFO - 'opt_config_files': (), 08:36:51 INFO - 'pip_index': False, 08:36:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:36:51 INFO - 'cmd': ('bash', 08:36:51 INFO - '-c', 08:36:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:36:51 INFO - 'enabled': True, 08:36:51 INFO - 'halt_on_failure': False, 08:36:51 INFO - 'name': 'check_screen_resolution'},), 08:36:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:36:51 INFO - 'cmd': ('bash', 08:36:51 INFO - '-c', 08:36:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:36:51 INFO - 'enabled': True, 08:36:51 INFO - 'halt_on_failure': False, 08:36:51 INFO - 'name': 'check_screen_resolution'},), 08:36:51 INFO - 'run_cmd_checks_enabled': True, 08:36:51 INFO - 'sps_profile': False, 08:36:51 INFO - 'sps_profile_interval': 0, 08:36:51 INFO - 'suite': 'dromaeojs', 08:36:51 INFO - 'system_bits': '32', 08:36:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:36:51 INFO - 'title': 't-yosemite-r7-0364', 08:36:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:36:51 INFO - 'use_talos_json': True, 08:36:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:36:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:36:51 INFO - 'work_dir': 'build'} 08:36:51 INFO - [mozharness: 2016-11-08 16:36:51.516638Z] Running clobber step. 08:36:51 INFO - Running pre-action listener: _resource_record_pre_action 08:36:51 INFO - Running main action method: clobber 08:36:51 INFO - rmtree: /builds/slave/test/build 08:36:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:36:51 INFO - Running post-action listener: _resource_record_post_action 08:36:51 INFO - [mozharness: 2016-11-08 16:36:51.926879Z] Finished clobber step (success) 08:36:51 INFO - [mozharness: 2016-11-08 16:36:51.927009Z] Running read-buildbot-config step. 08:36:51 INFO - Running pre-action listener: _resource_record_pre_action 08:36:51 INFO - Running main action method: read_buildbot_config 08:36:51 INFO - Using buildbot properties: 08:36:51 INFO - { 08:36:51 INFO - "project": "", 08:36:51 INFO - "product": "firefox", 08:36:51 INFO - "script_repo_revision": "production", 08:36:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:36:51 INFO - "repository": "", 08:36:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:36:51 INFO - "buildid": "20161108062935", 08:36:51 INFO - "pgo_build": "False", 08:36:51 INFO - "basedir": "/builds/slave/test", 08:36:51 INFO - "buildnumber": 18, 08:36:51 INFO - "slavename": "t-yosemite-r7-0364", 08:36:51 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:36:51 INFO - "platform": "yosemite_r7", 08:36:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:36:51 INFO - "branch": "mozilla-central", 08:36:51 INFO - "repo_path": "mozilla-central", 08:36:51 INFO - "revision": "aea5b4c3d165dcde027b3b6551b146a56748e9be", 08:36:51 INFO - "stage_platform": "macosx64", 08:36:51 INFO - "builduid": "ca82d5bbd00a4f2186b6aa2f2238e06a", 08:36:51 INFO - "slavebuilddir": "test" 08:36:51 INFO - } 08:36:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:36:51 INFO - Running post-action listener: _resource_record_post_action 08:36:51 INFO - [mozharness: 2016-11-08 16:36:51.930832Z] Finished read-buildbot-config step (success) 08:36:51 INFO - [mozharness: 2016-11-08 16:36:51.930991Z] Running download-and-extract step. 08:36:51 INFO - Running pre-action listener: _resource_record_pre_action 08:36:51 INFO - Running main action method: download_and_extract 08:36:51 INFO - mkdir: /builds/slave/test/build/tests 08:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:36:51 INFO - trying https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:36:51 INFO - Downloading https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:36:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:36:53 INFO - Downloaded 1199 bytes. 08:36:53 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:36:53 INFO - Using the following test package requirements: 08:36:53 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:36:53 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:36:53 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:36:53 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:36:53 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:36:53 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:36:53 INFO - u'jsshell-mac.zip'], 08:36:53 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:36:53 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:36:53 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:36:53 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:36:53 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:36:53 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:36:53 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:36:53 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:36:53 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:36:53 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:36:53 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:36:53 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:36:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:36:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:36:53 INFO - Fetch https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:36:53 INFO - Http code: 200 08:36:53 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 08:36:53 INFO - x-amz-cf-id: 4AD4LGkyUraZHM_2q_k-Ew46mvwXlC91mxYBp-A4418s5BdgM8bg2A== 08:36:53 INFO - x-amz-version-id: rINV6su_sxI2zOZ2S1G4JiZUPESq27Mz 08:36:54 INFO - Expected file size: 16310108 08:36:54 INFO - Obtained file size: 16310108 08:37:00 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:37:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:37:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:37:00 INFO - Fetch https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:37:01 INFO - Http code: 200 08:37:01 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 08:37:01 INFO - x-amz-cf-id: W2NgH8VWqDEMkGza6FgmpnhMINP9615S1-iR1ec08ayRJp-VNp2CMA== 08:37:01 INFO - x-amz-version-id: rINV6su_sxI2zOZ2S1G4JiZUPESq27Mz 08:37:01 INFO - Expected file size: 16310108 08:37:01 INFO - Obtained file size: 16310108 08:37:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:37:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:37:07 INFO - Fetch https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:37:08 INFO - Http code: 200 08:37:08 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 08:37:08 INFO - x-amz-cf-id: IrHHDExUNS28l4QjCxhqf8eu4_ne7La2zfHl9C858MHBB_zdHo1BpA== 08:37:08 INFO - x-amz-version-id: mw2.1ojdKM_ntwU1fDsg2xSIi4JskeeH 08:37:09 INFO - Expected file size: 14409038 08:37:09 INFO - Obtained file size: 14409038 08:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:09 INFO - trying https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:37:09 INFO - Downloading https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:37:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:37:12 INFO - Downloaded 111582215 bytes. 08:37:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:37:12 INFO - mkdir: /builds/slave/test/properties 08:37:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:37:12 INFO - Writing to file /builds/slave/test/properties/build_url 08:37:12 INFO - Contents: 08:37:12 INFO - build_url:https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:37:13 INFO - Running post-action listener: _resource_record_post_action 08:37:13 INFO - Running post-action listener: set_extra_try_arguments 08:37:13 INFO - [mozharness: 2016-11-08 16:37:13.321871Z] Finished download-and-extract step (success) 08:37:13 INFO - [mozharness: 2016-11-08 16:37:13.322007Z] Running populate-webroot step. 08:37:13 INFO - Running pre-action listener: _resource_record_pre_action 08:37:13 INFO - Running main action method: populate_webroot 08:37:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:37:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:37:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:37:13 INFO - '--tppagecycles', 08:37:13 INFO - '7'], 08:37:13 INFO - 'tests': ['tsvgm']}}, 08:37:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:37:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:37:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:37:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:37:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:37:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:37:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:13 INFO - 'talos_options': ['--disable-e10s'], 08:37:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:37:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:37:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:13 INFO - 'talos_options': ['--disable-e10s'], 08:37:13 INFO - 'tests': ['damp', 'tps']}, 08:37:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:13 INFO - 'tests': ['damp', 'tps']}, 08:37:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:37:13 INFO - 'tests': ['dromaeo_dom']}, 08:37:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:37:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:37:13 INFO - 'tests': ['basic_compositor_video']}, 08:37:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:37:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:37:13 INFO - 'tests': ['a11yr', 08:37:13 INFO - 'ts_paint', 08:37:13 INFO - 'tpaint', 08:37:13 INFO - 'sessionrestore', 08:37:13 INFO - 'sessionrestore_no_auto_restore', 08:37:13 INFO - 'tabpaint']}, 08:37:13 INFO - 'other-e10s': {'tests': ['a11yr', 08:37:13 INFO - 'ts_paint', 08:37:13 INFO - 'tpaint', 08:37:13 INFO - 'sessionrestore', 08:37:13 INFO - 'sessionrestore_no_auto_restore', 08:37:13 INFO - 'tabpaint']}, 08:37:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:37:13 INFO - 'ts_paint', 08:37:13 INFO - 'tpaint', 08:37:13 INFO - 'sessionrestore', 08:37:13 INFO - 'sessionrestore_no_auto_restore', 08:37:13 INFO - 'tabpaint']}, 08:37:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:37:13 INFO - 'ts_paint', 08:37:13 INFO - 'tpaint', 08:37:13 INFO - 'sessionrestore', 08:37:13 INFO - 'sessionrestore_no_auto_restore', 08:37:13 INFO - 'tabpaint']}, 08:37:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:37:13 INFO - 'tests': ['a11yr', 08:37:13 INFO - 'ts_paint', 08:37:13 INFO - 'tpaint', 08:37:13 INFO - 'sessionrestore', 08:37:13 INFO - 'sessionrestore_no_auto_restore', 08:37:13 INFO - 'tabpaint']}, 08:37:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:37:13 INFO - 'tests': ['a11yr', 08:37:13 INFO - 'ts_paint', 08:37:13 INFO - 'tpaint', 08:37:13 INFO - 'sessionrestore', 08:37:13 INFO - 'sessionrestore_no_auto_restore', 08:37:13 INFO - 'tabpaint']}, 08:37:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:37:13 INFO - 'tests': ['tsvgx', 08:37:13 INFO - 'tsvgr_opacity', 08:37:13 INFO - 'tart', 08:37:13 INFO - 'tscrollx', 08:37:13 INFO - 'cart']}, 08:37:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:37:13 INFO - 'tsvgr_opacity', 08:37:13 INFO - 'tart', 08:37:13 INFO - 'tscrollx', 08:37:13 INFO - 'cart']}, 08:37:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:13 INFO - 'talos_options': ['--disable-e10s'], 08:37:13 INFO - 'tests': ['tp5o']}, 08:37:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:13 INFO - 'tests': ['tp5o']}, 08:37:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:13 INFO - 'talos_options': ['--disable-e10s', 08:37:13 INFO - '--xperf_path', 08:37:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:37:13 INFO - 'tests': ['tp5n']}, 08:37:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:37:13 INFO - 'talos_options': ['--xperf_path', 08:37:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:37:13 INFO - 'tests': ['tp5n']}}, 08:37:13 INFO - 'talos.zip': {'path': '', 08:37:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:37:13 INFO - Running post-action listener: _resource_record_post_action 08:37:13 INFO - [mozharness: 2016-11-08 16:37:13.340504Z] Finished populate-webroot step (success) 08:37:13 INFO - [mozharness: 2016-11-08 16:37:13.340633Z] Running create-virtualenv step. 08:37:13 INFO - Running pre-action listener: _resource_record_pre_action 08:37:13 INFO - Running main action method: create_virtualenv 08:37:13 INFO - Creating virtualenv /builds/slave/test/build/venv 08:37:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:37:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:37:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:37:13 INFO - Using real prefix '/tools/python27' 08:37:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:37:14 INFO - Installing distribute.............................................................................................................................................................................................done. 08:37:17 INFO - Installing pip.................done. 08:37:17 INFO - Return code: 0 08:37:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:37:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:37:17 INFO - Reading from file tmpfile_stdout 08:37:17 INFO - Output received: 08:37:17 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:37:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b42490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa318d67bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106956d30>, '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 0x10677cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10694eb20>, '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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:37:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:37:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:37:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vjt9VJjTWE/Render', 08:37:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:17 INFO - 'HOME': '/Users/cltbld', 08:37:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:17 INFO - 'LOGNAME': 'cltbld', 08:37:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:17 INFO - 'MOZ_NO_REMOTE': '1', 08:37:17 INFO - 'NO_EM_RESTART': '1', 08:37:17 INFO - 'PAGER': '/bin/cat', 08:37:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:17 INFO - 'PWD': '/builds/slave/test', 08:37:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:17 INFO - 'SHELL': '/bin/bash', 08:37:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U8cmPTxk6o/Listeners', 08:37:17 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 08:37:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:17 INFO - 'USER': 'cltbld', 08:37:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:17 INFO - 'XPC_FLAGS': '0x0', 08:37:17 INFO - 'XPC_SERVICE_NAME': '0', 08:37:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:37:17 INFO - Cleaning up... 08:37:17 INFO - Return code: 0 08:37:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b42490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa318d67bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106956d30>, '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 0x10677cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10694eb20>, '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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:37:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:37:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:37:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vjt9VJjTWE/Render', 08:37:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:17 INFO - 'HOME': '/Users/cltbld', 08:37:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:17 INFO - 'LOGNAME': 'cltbld', 08:37:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:17 INFO - 'MOZ_NO_REMOTE': '1', 08:37:17 INFO - 'NO_EM_RESTART': '1', 08:37:17 INFO - 'PAGER': '/bin/cat', 08:37:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:17 INFO - 'PWD': '/builds/slave/test', 08:37:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:17 INFO - 'SHELL': '/bin/bash', 08:37:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U8cmPTxk6o/Listeners', 08:37:17 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 08:37:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:17 INFO - 'USER': 'cltbld', 08:37:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:17 INFO - 'XPC_FLAGS': '0x0', 08:37:17 INFO - 'XPC_SERVICE_NAME': '0', 08:37:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:17 INFO - Downloading/unpacking psutil>=3.1.1 08:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:37:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:37:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:37:21 INFO - Installing collected packages: psutil 08:37:21 INFO - Running setup.py install for psutil 08:37:21 INFO - building 'psutil._psutil_osx' extension 08:37:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:37:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:37:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:37:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:37:21 INFO - building 'psutil._psutil_posix' extension 08:37:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:37:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:37:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:37:21 INFO - ^ 08:37:21 INFO - 1 warning generated. 08:37:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:37:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:37:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:37:22 INFO - Successfully installed psutil 08:37:22 INFO - Cleaning up... 08:37:22 INFO - Return code: 0 08:37:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b42490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa318d67bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106956d30>, '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 0x10677cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10694eb20>, '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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:37:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:37:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:37:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vjt9VJjTWE/Render', 08:37:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:22 INFO - 'HOME': '/Users/cltbld', 08:37:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:22 INFO - 'LOGNAME': 'cltbld', 08:37:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:22 INFO - 'MOZ_NO_REMOTE': '1', 08:37:22 INFO - 'NO_EM_RESTART': '1', 08:37:22 INFO - 'PAGER': '/bin/cat', 08:37:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:22 INFO - 'PWD': '/builds/slave/test', 08:37:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:22 INFO - 'SHELL': '/bin/bash', 08:37:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U8cmPTxk6o/Listeners', 08:37:22 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 08:37:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:22 INFO - 'USER': 'cltbld', 08:37:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:22 INFO - 'XPC_FLAGS': '0x0', 08:37:22 INFO - 'XPC_SERVICE_NAME': '0', 08:37:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:37:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:37:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:37:25 INFO - Installing collected packages: mozsystemmonitor 08:37:25 INFO - Running setup.py install for mozsystemmonitor 08:37:25 INFO - Successfully installed mozsystemmonitor 08:37:25 INFO - Cleaning up... 08:37:25 INFO - Return code: 0 08:37:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b42490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa318d67bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106956d30>, '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 0x10677cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10694eb20>, '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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:37:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:37:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:37:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vjt9VJjTWE/Render', 08:37:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:25 INFO - 'HOME': '/Users/cltbld', 08:37:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:25 INFO - 'LOGNAME': 'cltbld', 08:37:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:25 INFO - 'MOZ_NO_REMOTE': '1', 08:37:25 INFO - 'NO_EM_RESTART': '1', 08:37:25 INFO - 'PAGER': '/bin/cat', 08:37:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:25 INFO - 'PWD': '/builds/slave/test', 08:37:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:25 INFO - 'SHELL': '/bin/bash', 08:37:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U8cmPTxk6o/Listeners', 08:37:25 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 08:37:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:25 INFO - 'USER': 'cltbld', 08:37:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:25 INFO - 'XPC_FLAGS': '0x0', 08:37:25 INFO - 'XPC_SERVICE_NAME': '0', 08:37:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:25 INFO - Downloading/unpacking jsonschema==2.5.1 08:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:37:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:28 INFO - Downloading functools32-3.2.3-2.tar.gz 08:37:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:37:28 INFO - warning: no files found matching '*.txt' 08:37:28 INFO - no previously-included directories found matching 'build' 08:37:28 INFO - no previously-included directories found matching 'dist' 08:37:28 INFO - no previously-included directories found matching '.git*' 08:37:28 INFO - Installing collected packages: jsonschema, functools32 08:37:28 INFO - Running setup.py install for functools32 08:37:28 INFO - warning: no files found matching '*.txt' 08:37:28 INFO - no previously-included directories found matching 'build' 08:37:28 INFO - no previously-included directories found matching 'dist' 08:37:28 INFO - no previously-included directories found matching '.git*' 08:37:28 INFO - Successfully installed jsonschema functools32 08:37:28 INFO - Cleaning up... 08:37:28 INFO - Return code: 0 08:37:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:28 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 0x106b42490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa318d67bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106956d30>, '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 0x10677cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10694eb20>, '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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:37:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:37:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vjt9VJjTWE/Render', 08:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:28 INFO - 'HOME': '/Users/cltbld', 08:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:28 INFO - 'LOGNAME': 'cltbld', 08:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:28 INFO - 'MOZ_NO_REMOTE': '1', 08:37:28 INFO - 'NO_EM_RESTART': '1', 08:37:28 INFO - 'PAGER': '/bin/cat', 08:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:28 INFO - 'PWD': '/builds/slave/test', 08:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:28 INFO - 'SHELL': '/bin/bash', 08:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U8cmPTxk6o/Listeners', 08:37:28 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 08:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:28 INFO - 'USER': 'cltbld', 08:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:28 INFO - 'XPC_FLAGS': '0x0', 08:37:28 INFO - 'XPC_SERVICE_NAME': '0', 08:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:37:29 INFO - Cleaning up... 08:37:29 INFO - Return code: 0 08:37:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b42490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa318d67bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106956d30>, '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 0x10677cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10694eb20>, '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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:37:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:37:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:37:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vjt9VJjTWE/Render', 08:37:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:29 INFO - 'HOME': '/Users/cltbld', 08:37:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:29 INFO - 'LOGNAME': 'cltbld', 08:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:29 INFO - 'MOZ_NO_REMOTE': '1', 08:37:29 INFO - 'NO_EM_RESTART': '1', 08:37:29 INFO - 'PAGER': '/bin/cat', 08:37:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:29 INFO - 'PWD': '/builds/slave/test', 08:37:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:29 INFO - 'SHELL': '/bin/bash', 08:37:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U8cmPTxk6o/Listeners', 08:37:29 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 08:37:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:29 INFO - 'USER': 'cltbld', 08:37:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:29 INFO - 'XPC_FLAGS': '0x0', 08:37:29 INFO - 'XPC_SERVICE_NAME': '0', 08:37:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:29 INFO - Downloading/unpacking blobuploader==1.2.4 08:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:31 INFO - Downloading blobuploader-1.2.4.tar.gz 08:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:37:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:37:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:37: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 08:37: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 08:37: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 08:37: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 08:37: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 08:37: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 08:37:33 INFO - Downloading docopt-0.6.1.tar.gz 08:37:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:37:33 INFO - Installing collected packages: blobuploader, requests, docopt 08:37:33 INFO - Running setup.py install for blobuploader 08:37:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:37:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:37:33 INFO - Running setup.py install for requests 08:37:33 INFO - Running setup.py install for docopt 08:37:33 INFO - Successfully installed blobuploader requests docopt 08:37:33 INFO - Cleaning up... 08:37:34 INFO - Return code: 0 08:37:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b42490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa318d67bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106956d30>, '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 0x10677cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10694eb20>, '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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:37:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:37:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:37:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vjt9VJjTWE/Render', 08:37:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:34 INFO - 'HOME': '/Users/cltbld', 08:37:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:34 INFO - 'LOGNAME': 'cltbld', 08:37:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:34 INFO - 'MOZ_NO_REMOTE': '1', 08:37:34 INFO - 'NO_EM_RESTART': '1', 08:37:34 INFO - 'PAGER': '/bin/cat', 08:37:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:34 INFO - 'PWD': '/builds/slave/test', 08:37:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:34 INFO - 'SHELL': '/bin/bash', 08:37:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U8cmPTxk6o/Listeners', 08:37:34 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 08:37:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:34 INFO - 'USER': 'cltbld', 08:37:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:34 INFO - 'XPC_FLAGS': '0x0', 08:37:34 INFO - 'XPC_SERVICE_NAME': '0', 08:37:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:37:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-BDccU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:37:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-2IcC46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:37:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-LG9doc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:37:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-ssLXtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:37:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-ktWk1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:37:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-5vvS0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:37:34 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-xgk__c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:37:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-QFY8Xs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:37:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-YPdGxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:37:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-1X8FFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:37:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-h_UmXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:37:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-71069i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:37:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Ozoxbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:37:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-VO9x1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-2v4I4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:37:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-NeGADe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:37:36 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-dvG7Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:37:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:37:36 INFO - Running setup.py install for manifestparser 08:37:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:37:36 INFO - Running setup.py install for mozcrash 08:37:36 INFO - Running setup.py install for mozdebug 08:37:36 INFO - Running setup.py install for mozdevice 08:37:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:37:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:37:36 INFO - Running setup.py install for mozfile 08:37:36 INFO - Running setup.py install for mozhttpd 08:37:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:37:37 INFO - Running setup.py install for mozinfo 08:37:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:37:37 INFO - Running setup.py install for mozInstall 08:37:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:37:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:37:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:37:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:37:37 INFO - Running setup.py install for mozleak 08:37:37 INFO - Running setup.py install for mozlog 08:37:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:37:37 INFO - Running setup.py install for moznetwork 08:37:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:37:37 INFO - Running setup.py install for mozprocess 08:37:37 INFO - Running setup.py install for mozprofile 08:37:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:37:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:37:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:37:38 INFO - Running setup.py install for mozrunner 08:37:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:37:38 INFO - Running setup.py install for mozscreenshot 08:37:38 INFO - Running setup.py install for moztest 08:37:38 INFO - Running setup.py install for mozversion 08:37:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:37:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:37:38 INFO - Cleaning up... 08:37:38 INFO - Return code: 0 08:37:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:38 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 0x106b42490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa318d67bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106956d30>, '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 0x10677cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10694eb20>, '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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:37:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:37:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:37:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vjt9VJjTWE/Render', 08:37:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:38 INFO - 'HOME': '/Users/cltbld', 08:37:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:38 INFO - 'LOGNAME': 'cltbld', 08:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:38 INFO - 'MOZ_NO_REMOTE': '1', 08:37:38 INFO - 'NO_EM_RESTART': '1', 08:37:38 INFO - 'PAGER': '/bin/cat', 08:37:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:38 INFO - 'PWD': '/builds/slave/test', 08:37:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:38 INFO - 'SHELL': '/bin/bash', 08:37:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U8cmPTxk6o/Listeners', 08:37:38 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 08:37:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:38 INFO - 'USER': 'cltbld', 08:37:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:38 INFO - 'XPC_FLAGS': '0x0', 08:37:38 INFO - 'XPC_SERVICE_NAME': '0', 08:37:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:37:39 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-SCR8W1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:37:39 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-_CCugN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:37:39 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-8eXgAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:37:39 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-pvWpVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:37:39 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-x4mgP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:37:39 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-fACDp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:37:39 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-HaIAiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:37:39 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-zhb01x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:37:39 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-KCJbxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:37:39 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-9G4up4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:37:40 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-0QB0w7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:37:40 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-w0sdBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:37:40 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-1ZvlVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:37:40 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Oa9eRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:40 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-zprWGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:37:40 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-xy_lkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:37:40 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)) 08:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:37:40 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-jDRmEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:37:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:43 INFO - Downloading blessings-1.6.tar.gz 08:37:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:37:43 INFO - Installing collected packages: blessings 08:37:43 INFO - Running setup.py install for blessings 08:37:43 INFO - Successfully installed blessings 08:37:43 INFO - Cleaning up... 08:37:43 INFO - Return code: 0 08:37:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:37:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:37:43 INFO - Reading from file tmpfile_stdout 08:37:43 INFO - Current package versions: 08:37:43 INFO - blessings == 1.6 08:37:43 INFO - blobuploader == 1.2.4 08:37:43 INFO - docopt == 0.6.1 08:37:43 INFO - functools32 == 3.2.3-2 08:37:43 INFO - jsonschema == 2.5.1 08:37:43 INFO - manifestparser == 1.1 08:37:43 INFO - mozInstall == 1.12 08:37:43 INFO - mozcrash == 0.17 08:37:43 INFO - mozdebug == 0.1 08:37:43 INFO - mozdevice == 0.48 08:37:43 INFO - mozfile == 1.2 08:37:43 INFO - mozhttpd == 0.7 08:37:43 INFO - mozinfo == 0.9 08:37:43 INFO - mozleak == 0.1 08:37:43 INFO - mozlog == 3.4 08:37:43 INFO - moznetwork == 0.27 08:37:43 INFO - mozprocess == 0.23 08:37:43 INFO - mozprofile == 0.28 08:37:43 INFO - mozrunner == 6.12 08:37:43 INFO - mozscreenshot == 0.1 08:37:43 INFO - mozsystemmonitor == 0.3 08:37:43 INFO - moztest == 0.7 08:37:43 INFO - mozversion == 1.4 08:37:43 INFO - psutil == 3.1.1 08:37:43 INFO - requests == 1.2.3 08:37:43 INFO - wsgiref == 0.1.2 08:37:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:37:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37: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')]} 08:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106b42490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa318d67bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106956d30>, '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 0x10677cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10694eb20>, '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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:37:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:37:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vjt9VJjTWE/Render', 08:37:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:44 INFO - 'HOME': '/Users/cltbld', 08:37:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:44 INFO - 'LOGNAME': 'cltbld', 08:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:44 INFO - 'MOZ_NO_REMOTE': '1', 08:37:44 INFO - 'NO_EM_RESTART': '1', 08:37:44 INFO - 'PAGER': '/bin/cat', 08:37:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:44 INFO - 'PWD': '/builds/slave/test', 08:37:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:44 INFO - 'SHELL': '/bin/bash', 08:37:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U8cmPTxk6o/Listeners', 08:37:44 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 08:37:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:44 INFO - 'USER': 'cltbld', 08:37:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:44 INFO - 'XPC_FLAGS': '0x0', 08:37:44 INFO - 'XPC_SERVICE_NAME': '0', 08:37:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:37:44 INFO - Cleaning up... 08:37:45 INFO - Return code: 0 08:37:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:45 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 0x106b42490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa318d67bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106956d30>, '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 0x10677cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10694eb20>, '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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:37:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:37:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:37:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vjt9VJjTWE/Render', 08:37:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:45 INFO - 'HOME': '/Users/cltbld', 08:37:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:45 INFO - 'LOGNAME': 'cltbld', 08:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:45 INFO - 'MOZ_NO_REMOTE': '1', 08:37:45 INFO - 'NO_EM_RESTART': '1', 08:37:45 INFO - 'PAGER': '/bin/cat', 08:37:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:45 INFO - 'PWD': '/builds/slave/test', 08:37:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:45 INFO - 'SHELL': '/bin/bash', 08:37:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U8cmPTxk6o/Listeners', 08:37:45 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 08:37:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:45 INFO - 'USER': 'cltbld', 08:37:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:45 INFO - 'XPC_FLAGS': '0x0', 08:37:45 INFO - 'XPC_SERVICE_NAME': '0', 08:37:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:37:45 INFO - Cleaning up... 08:37:45 INFO - Return code: 0 08:37:45 INFO - Running post-action listener: _resource_record_post_action 08:37:45 INFO - Running post-action listener: _start_resource_monitoring 08:37:45 INFO - Starting resource monitoring. 08:37:45 INFO - [mozharness: 2016-11-08 16:37:45.787120Z] Finished create-virtualenv step (success) 08:37:45 INFO - [mozharness: 2016-11-08 16:37:45.788285Z] Running install step. 08:37:45 INFO - Running pre-action listener: _resource_record_pre_action 08:37:45 INFO - Running main action method: install 08:37:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:37:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:37:46 INFO - Reading from file tmpfile_stdout 08:37:46 INFO - Detecting whether we're running mozinstall >=1.0... 08:37:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:37:46 INFO - Reading from file tmpfile_stdout 08:37:46 INFO - Output received: 08:37:46 INFO - Usage: mozinstall [options] installer 08:37:46 INFO - Options: 08:37:46 INFO - -h, --help show this help message and exit 08:37:46 INFO - -d DEST, --destination=DEST 08:37:46 INFO - Directory to install application into. [default: 08:37:46 INFO - "/builds/slave/test"] 08:37:46 INFO - --app=APP Application being installed. [default: firefox] 08:37:46 INFO - mkdir: /builds/slave/test/build/application 08:37:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:38:20 INFO - Reading from file tmpfile_stdout 08:38:20 INFO - Output received: 08:38:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:38:20 INFO - Running post-action listener: _resource_record_post_action 08:38:20 INFO - [mozharness: 2016-11-08 16:38:20.277885Z] Finished install step (success) 08:38:20 INFO - [mozharness: 2016-11-08 16:38:20.278028Z] Running run-tests step. 08:38:20 INFO - Running pre-action listener: _resource_record_pre_action 08:38:20 INFO - Running main action method: run_tests 08:38:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:38:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:38:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:38:20 INFO - 2016-11-08 08:38:20.292 screenresolution[3549:58468] starting screenresolution argv=screenresolution get 08:38:20 INFO - 2016-11-08 08:38:20.295 screenresolution[3549:58468] Display 0: 1600x1200x32@60 08:38:20 INFO - 2016-11-08 08:38:20.301 screenresolution[3550:58470] starting screenresolution argv=screenresolution list 08:38:20 INFO - Available Modes on Display 0 08:38:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:38:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:38:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:38:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:38:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:38:20 INFO - Intel Iris: 08:38:20 INFO - Chipset Model: Intel Iris 08:38:20 INFO - Type: GPU 08:38:20 INFO - Bus: Built-In 08:38:20 INFO - VRAM (Dynamic, Max): 1536 MB 08:38:20 INFO - Vendor: Intel (0x8086) 08:38:20 INFO - Device ID: 0x0a2e 08:38:20 INFO - Revision ID: 0x0009 08:38:20 INFO - Displays: 08:38:20 INFO - Display: 08:38:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:38:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:38:20 INFO - Display Serial Number: 1600x1200 60 08:38:20 INFO - Main Display: Yes 08:38:20 INFO - Mirror: Off 08:38:20 INFO - Online: Yes 08:38:20 INFO - Rotation: Supported 08:38:20 INFO - Return code: 0 08:38:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:38:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:38:20 INFO - Python 2.7.3 08:38:20 INFO - Return code: 0 08:38:20 INFO - grabbing minidump binary from tooltool 08:38:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:20 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 0x106956d30>, '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 0x10677cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10694eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:38:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:38:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:38:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:38:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:38:20 INFO - Return code: 0 08:38:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:38:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:38:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:38:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:38:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:38:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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 08:38:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0364 --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 08:38:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vjt9VJjTWE/Render', 08:38:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:38:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:38:20 INFO - 'HOME': '/Users/cltbld', 08:38:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:38:20 INFO - 'LOGNAME': 'cltbld', 08:38:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:38:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:38:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:20 INFO - 'MOZ_NO_REMOTE': '1', 08:38:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:38:20 INFO - 'NO_EM_RESTART': '1', 08:38:20 INFO - 'PAGER': '/bin/cat', 08:38:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:38:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:20 INFO - 'PWD': '/builds/slave/test', 08:38:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:38:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:38:20 INFO - 'SHELL': '/bin/bash', 08:38:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U8cmPTxk6o/Listeners', 08:38:20 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 08:38:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:38:20 INFO - 'USER': 'cltbld', 08:38:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:38:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:38:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:20 INFO - 'XPC_FLAGS': '0x0', 08:38:20 INFO - 'XPC_SERVICE_NAME': '0', 08:38:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:38:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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 08:38:20 INFO - mozversion application_buildid: 20161108062935 08:38:20 INFO - mozversion application_changeset: aea5b4c3d165dcde027b3b6551b146a56748e9be 08:38:20 INFO - mozversion application_display_name: Nightly 08:38:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:38:20 INFO - mozversion application_name: Firefox 08:38:20 INFO - mozversion application_remotingname: firefox 08:38:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:38:20 INFO - mozversion application_vendor: Mozilla 08:38:20 INFO - mozversion application_version: 52.0a1 08:38:20 INFO - mozversion platform_buildid: 20161108062935 08:38:20 INFO - mozversion platform_changeset: aea5b4c3d165dcde027b3b6551b146a56748e9be 08:38:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:38:20 INFO - mozversion platform_version: 52.0a1 08:38:20 INFO - using testdate: 1478623100 08:38:20 INFO - actual date: 1478623100 08:38:20 INFO - starting webserver on 'localhost:62461' 08:38:20 INFO - SUITE-START | Running 2 tests 08:38:20 INFO - TEST-START | dromaeo_css 08:38:20 INFO - operating with platform_type : mac_ 08:38:20 INFO - Initialising browser for dromaeo_css test... 08:38:20 INFO - TEST-INFO | started process 3560 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpdcRuQw/profile http://localhost:62461/getInfo.html) 08:38:23 INFO - PROCESS | 3560 | __metrics Screen width/height:1600/1200 08:38:23 INFO - PROCESS | 3560 | colorDepth:24 08:38:23 INFO - PROCESS | 3560 | Browser inner width/height: 1024/689 08:38:23 INFO - PROCESS | 3560 | __metrics 08:38:23 INFO - PROCESS | 3560 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:38:23 INFO - PROCESS | 3560 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:38:24 INFO - TEST-INFO | 3560: exit 0 08:38:24 INFO - Browser initialized. 08:38:24 INFO - Running cycle 1/1 for dromaeo_css test... 08:38:24 INFO - TEST-INFO | started process 3562 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpdcRuQw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:38:36 INFO - PROCESS | 3562 | RSS: Main: 231292928 08:38:36 INFO - PROCESS | 3562 | 08:39:27 INFO - PROCESS | 3562 | 1478623167519 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] 08:41:38 INFO - PROCESS | 3562 | Cycle 1(1): loaded http://localhost:62461/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62461/tests/dromaeo/cssquery-ext.html) 08:41:41 INFO - PROCESS | 3562 | RSS: Main: 528404480 08:41:41 INFO - PROCESS | 3562 | 08:44:44 INFO - PROCESS | 3562 | Cycle 1(1): loaded http://localhost:62461/tests/dromaeo/cssquery-ext.html (next: http://localhost:62461/tests/dromaeo/cssquery-jquery.html) 08:44:45 INFO - PROCESS | 3562 | RSS: Main: 373186560 08:44:45 INFO - PROCESS | 3562 | 08:47:47 INFO - PROCESS | 3562 | Cycle 1(1): loaded http://localhost:62461/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62461/tests/dromaeo/cssquery-mootools.html) 08:47:48 INFO - PROCESS | 3562 | RSS: Main: 345231360 08:47:48 INFO - PROCESS | 3562 | 08:50:50 INFO - PROCESS | 3562 | Cycle 1(1): loaded http://localhost:62461/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62461/tests/dromaeo/cssquery-prototype.html) 08:50:51 INFO - PROCESS | 3562 | RSS: Main: 353120256 08:50:51 INFO - PROCESS | 3562 | 08:53:54 INFO - PROCESS | 3562 | Cycle 1(1): loaded http://localhost:62461/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62461/tests/dromaeo/cssquery-yui.html) 08:53:55 INFO - PROCESS | 3562 | RSS: Main: 333340672 08:53:55 INFO - PROCESS | 3562 | 08:56:58 INFO - PROCESS | 3562 | Cycle 1(1): loaded http://localhost:62461/tests/dromaeo/cssquery-yui.html (next: http://localhost:62461/tests/dromaeo/cssquery-dojo.html) 08:56:58 INFO - PROCESS | 3562 | RSS: Main: 449511424 08:56:58 INFO - PROCESS | 3562 | 08:56:58 INFO - PROCESS | 3562 | __start_tp_report 08:56:58 INFO - PROCESS | 3562 | _x_x_mozilla_page_load 08:56:58 INFO - PROCESS | 3562 | _x_x_mozilla_page_load_details 08:56:58 INFO - PROCESS | 3562 | |i|pagename|runs| 08:56:58 INFO - PROCESS | 3562 | |0;dojo.html;2798.3843554418027;3220.146661134801;3224.7420206383485;3257.4299497587917;3277.7541684373678;5548.611597188198;5625.634333768312;5630.380658127603;5672.801451949188;5687.289088863892;5599.608027438081;5633.661980281176;5637.3235211774645;5660.830175094754;5667.4615911488445;5084.949150508503;5124.256982737501;5154.639175257734;5189.6107791915565;5238.659487133344;5106.106431374495;5187.351581052369;5226.843194704165;5262.842114736564;5270.156774916014;5259.421463639016;5265.473452654723;5272.736363181825;5316.38861530925;5325.973370133144;253.32043650370906;5235.345581802275;5256.290400795881;5263.3947096083675;5280.445553216895;3288.010504553033;5078.847634570969;5161.174212126042;5162.328897243372;5192.71440070796;5561.805336813232;5572.331320241557;5582.553395728332;5626;5631.324241091054;6184.07238914162;6187.288461826857;6197.194364732601;6206.2862770781485;6219.937800621981;6107.175531303262;6119.2350956130485;6130.816075517741;6140.486742244447;6145.279321789906;5662.320521537401;5663.688497132697;5670.659760414306;5685.090385538336;5697.344805347355;6285.811425657238;6291.339409362043;6292.993121100602;6297.622142671455;6318.62088274705;5897.410258974154;5934.13954976526;5945.137954996501;5981.760729570856;6082.908756368658;6122.377813977311;6182.536309776785;6192.411720886508;6210.9068363974575;6217.564770466024;6100.254632272332;6114.9910264806695;6115.969420152871;6140.907886381708;6142.969285153546;6009.06859436788;6111.84720381994;6128.203333566607;6164.075388691643;6168.722407491674;4873.31773551703;4921.040397122527;4928.28539861718;4928.556429921325;4964.081644895634;4404.251277282806;4419.337099435111;4420.557944205554;4434.002349471408;4440.134173836071;5619.971900140473;5759.280089988752;5791.362950075489;5797.130430435468;5854.209681692917;6076.179715738407;6134.467201660938;6139.553468857682;6142.928535732134;6198.566100372931;5776.104703770839;5793.044147715664;5794;5803.274590676166;5804.419558044162;5384.865378365572;5387.488188622075;5390.05674007055;5396.595255355864;5411.945880541223;6131.774283661951;6170.598911070945;6224.315325314303;6250.9062364064575;6261.812145635638;6104.913929256043;6105.520399749632;6131.0708424985305;6140.078988151634;6190.102435146969;6183.628982261079;6263.27972283175;6266.060090986389;6269.874602508015;6272.059191121186;6147.231596050494;6151.538634602333;6154.230721159855;6156.384361563808;6163.7842675506145;5977.103434484862;6125.877482450415;6140.263168419819;6255.40573645503;6259.812205633838;5943.346231914399;5955.940440595538;5956.285245770535;5964.194833697569;5984.581079324405;5895.02732049207;5933.940660593339;5952.523798096228;5954;5967.194428752063;5818.883622327615;5825.126231065374;5830.591858569859;5835.591508594358;5840.386759390372;5920.407959204217;5944.167816505779;5945.64326140433;5949.3753155918;5953.91069133972;5659.179418984307;5771.480566749013;5790.218320526675;5806.65160090396;5843.737031833492 08:56:58 INFO - PROCESS | 3562 | |1;ext.html;502.62554396974235;506.1193523269511;515.9871003224919;521.8773588206768;533.5758072332494;29642.703572964263;36757.26485470289;38700.8389748308;38723.806380968126;40717.185656286856;39216;39705;39721.60278397215;39747;39916.800415997954;7431.665575049135;9155.816883662355;9184.44893306402;9353.906460935406;9358.953205233964;8456.492610443393;9339.859902101474;11336.546538138506;11351.03516201124;11471.483783229774;11162.609308674198;11246.268992515501;11247.662570122868;11253.887461125365;11328.660140195767;8432.915670843275;8670.696525621574;11142.331460112378;11217.66347009591;11221.495032723506;8571.785705357353;8607.18231767981;8617;10841.83737243942;11188.888111118866;38337.80831095854;38497.422538661944;38497.61502384996;38538.80730596357;38685;3233.466478031122;3237.7409807215367;3353.2622822978906;3373.713234375081;3632.691221246197;28178.85910570434;29163.70836291631;29273.70726292731;29522.26194345137;30110.69889301101;2995.9101226963007;3027.2431892026993;3027.8486075696346;3111.5643809866638;3153.8265395403246;466687;467292;467306.9999999966;467933;474381;53298;53670.73164634152;53671.73164134155;53695.46304536983;53786.46213537815;56308.43691563114;56654.716726416926;56683.716581416826;56698.43301566932;56710.7164464175;14827.73964213054;14976.925115374353;15067;15068.849311506963;15086.471973480875;10564.841527377095;10832.079273261703;11760.941515263496;11813.586524471602;11836.93467587904;12231.877681223252;14076.225807580682;14580.125192488485;14587.416503339748;14891;5007.444714252922;5151.613628977767;6077.574569780161;6090.33006369299;6108.5723999319625;829.6722794496261;846.7552697534614;902.4359775140537;911.9726408207764;1036.9792604147874;27396.726032739814;27842.72157278442;28705.856470717514;28730.994415195368;28753.712462875104;1006.2101250518308;1028.6348346337093;1067.4075887882145;1111.377628528027;1113.571275059108;13777.104488208435;14150.575482735534;14592.635184120481;14975.625609359635;15202.391904323704;611514;615795;616465.9176704177;617170;618666;53958.730206348715;54144.72927635337;54198.729006355505;54283.72858135684;54341.1848822268;1327.694630235032;1336.5188532128425;1362.0805955979674;1364.3451143451398;1366.5285476510587;27644.032458864647;28346.866125354754;28940.131796046153;28945.276368090967;29129.56305655459;45836.54163458323;46136.53863461322;46185.53814461813;46258.768706156254;46354.53645463637;564234;565193.6988506499;566081;566488;566694;36233.81883090568;36914.48046286807;37759.244815104095;37821.62178378291;37877.81061094787;43895;44377.55622443734;44710;45283.54716452925;45330.77334613306;35425;36266.456003159445;36313.27373452569;37716.81141594274;37788.81105594454;30834.845825770728;36093.27813443769;36695.34870930761;37222.813885930394;37480.625193747714;35478.8226058868;35792.82103589569;35823.46264805978;35973.820130900225;36359.27281454409;31265.374692506477;32435.513467297536;32449.837750811093;32517.674823251466;32720.50919236261;23479;23648.645270321304;23847.761522384553;24441;24488.632670510313 08:56:58 INFO - PROCESS | 3562 | |2;jquery.html;346.54775517949065;368.7879469305149;370.82756518219037;384.16444233791503;403.3405382200104;14651.487197948074;15012.77480837787;15713.607159821011;19091.904540477277;20090.296839610615;19515.707264391047;19611.21555137796;19975.900120499376;20264.69602955953;20503.589928201465;5568.86077848053;5906.05503119501;5918.852028699274;5922.526197904157;5928.199693041427;4612.100640375128;5865.9413405866035;5909.497692696126;5920.111983202511;5950.5834591578605;5589.692566908819;5889.499392551638;5897.085951677497;5901.23283973085;5902.763889444417;5798.565107616924;5807.680577568253;5852.414758524134;5858.882822343529;5879.0593505039415;5164.664296820694;5169.534741873212;5170;6838.829029274259;7236.986821844946;27074.1877743668;27352.726472735216;27373.726262737317;27536.311592210153;27701.445971080466;1928.2672584417971;2334.509752951882;2493.9501209975697;2681.6513853199153;2848.302165969351;12998.545050923172;16182.352705891868;16215.513534593982;16454.588635284214;17010.48968530946;1891.1489829576608;1940.7768106667772;2026.108512254618;2027.310714357111;2045;62643.49426022999;94052.52973735156;115539.99999999916;130357.04464433041;144147;18926.148323325207;20610.587788244153;20706.757594544073;20731.378058658265;20812;34220.82889585536;34337.313253734785;34410.31179376398;34468;34541.963741087806;8615.913840861636;9887.752806179904;10077.143442807297;10087.092161705481;10364.792704145875;8481.448705834102;8488.151184881586;8491.278241349555;8607.741767746978;8662.913370866338;8392.4544904581;8791.912080879238;9418.246540276761;9550;9913.256505761952;2988.9103326900226;3624.6919011884283;3659.652333028358;3671.596124426311;3740.4202348636004;824.6824972385555;876.7588913048835;889.2441424788877;919.6091661044029;926.7627716997748;18068.457946261635;18258;20687.793122068888;20729.378118656463;21104.366868993955;841.2218697704599;916.9511374679219;919.2508105893676;990.8910019897805;992;9675.709728708009;11113.555457781598;11135.33188008722;11182.384968826793;11249;575082.1245893744;606793;620128;623615;623907;97654.02345976449;126554;127441.36279318544;128573;137350;988.1205726903062;996.2976101848035;1023.4473384372355;1026.2508368890751;1026.2610920137488;18206.906205786505;18434.078296085412;18511.259549617866;18664.066796660383;18775.812241877953;49709;49722.09942830469;50068.24897626466;50554.483365499094;52835;76247.61876190806;78890.6055469719;81208.18791812006;87685;89056;7883.763487095396;7964.761057168294;8038.919610804051;8461.196186362286;8497.87253191215;11545.769084618429;12641.494340226289;12700.745985080062;12996.98546725259;13204;10847.511861966388;11277.32336059839;11420.828687569527;11425.828612570984;11487.36819474937;8967.686130985385;10454.529546170592;10990;11352.978231959163;11431.085513158761;10762.46187690628;11145.554177832795;11421.288081373754;11458.140639452242;11522.193446459003;6187.412195841388;8175.100738918595;8217.424780265324;8232.958835205785;8444.324454043784;8863.734087977371;9233.169014788433;10997.065249453566;11029.007389334998;11341.716457088638 08:56:58 INFO - PROCESS | 3562 | |3;mootools.html;1317.584960737367;1465.406510363303;1486.152892851075;1630.6494103767689;1799.8560115190778;6524;7434.702611895517;7742.380609551236;8317.91682083179;8339.916600834005;8172.305354044914;8232.506049637026;8257.21556452136;8263.91736082639;8273.544955027472;2254.842161048727;2294.5181511882524;2294.5755035318434;2294.598445272079;2297.5060362022164;2285.48576570272;2331.3122628824535;2335.1476711000532;2340.4850932794757;2343.4610039690883;1885.6511545364083;2258.7854153855355;2279.32759835848;2281.9201327953524;2287.142321629389;2195.802377786007;2209.215728416413;2230.7546169921457;2234.7988681018624;2242.831787615919;2283.223703940668;2314.1900334882826;2318.177047148272;2321.2688003278913;2322;8184.386171037196;8256.587170641504;8303.66785328586;8325.125861784547;8328.875066874001;5032.370953630796;5178.197379406198;5188.558972487305;5202.661826981272;5206.76569554371;3117.8775640769304;3380.6450322716005;3483.425234836249;3564.269324788412;3566.2154326048226;1003.1022235099545;1032.2722480651123;1032.3341444768082;1059.1050562274866;1092.9890701092968;10669.733256668567;10803.189760767973;10814.56741730322;10835.241533092687;10839.729006774814;4055.8986025349604;4308.138372325485;4411.316245981878;4417.53615870329;4420.756858372821;8624.965004199536;8651.95674021626;8659.870101948476;8660.696875609323;8664.436811607226;4338.696291259582;5055.69665820052;5059.2916991621205;5066.797328106834;5080.13637681595;4097.590240975938;4154.626083652486;4266.1254442839145;4387.078713470143;4749.145153872267;3536.29274145172;3558.288342331544;3575.3385623659187;3621;3629.9818500907854;1113.5768408004906;1131.7453572946022;1134.5291703942771;1137.852079229695;1144.2390810111349;801.7092481105416;806.4435539477859;835.5780330932962;874.3748220022715;923.5382308845577;5341.626086174008;5514.365847927459;5515.917261241005;5674.659520428705;5698.1452782083015;828.9792755181169;879.4371602174614;893.446063440671;896.2158111652304;896.4128495835238;3538.486919396673;3608.891733248007;3694;3705.4071348584635;3760.7401520490944;9652.083052110178;9900.653477128266;10005.19958403326;10233.43716095607;10244.436555989492;9898.356606820533;9999.800003999879;10198.643047493302;10213.795724085478;10215.795684086277;7599.696012159452;7768.92231077682;7777.766666999886;7794.9220507795335;7797.532148071134;5258.6581872178185;5423.891522169613;5553.72231388437;5583.944160558505;5612.045952188057;7185.928140718526;9299.907000929905;9315.813683726152;9401.388909720847;9509.144177024102;8379.790505237417;8794.780130496789;9360.906390936003;9620.230381569318;9625.229981601595;6137.877242455215;6635.303293154149;6859;7161.319674630901;7170.282971702788;7830.960845195737;7893.763187104396;7944.84110317802;7986.960065199637;8062.717804876801;7086.929130708627;7126.964365178141;7140.571565706075;7199.172095208908;7231.16841563227;6765.830854228684;6782.96608516974;6954.513184077066;7004.089468369283;7147.749828755968;6535.477161827138;6684.0642310075655;6745.460363171032;6745.561538499982;6757.594544327356;3942.8619998299923;4789.856304310876;4810.783514741915;4828.66199366041;4878.170710979213;4726.1729197391005;4733.857984260478;4790.065937142223;4804.159272127294;4909.5090490950615 08:56:58 INFO - PROCESS | 3562 | |4;prototype.html;298.7057748118104;301.1447489130869;308.7113548831842;313.8054406268114;315.4983576113978;4400.647948164144;4950.3069570260195;5201.843944681666;5231.378272735455;5244.501772331627;4079.9796001020027;4340.652747780178;5121.257417674445;5139.331886854713;5184.637075404714;3996.7002474814503;4017.4576432181575;4020.3165461871404;4035.5157381114236;4037.454943582623;3124.0324787519835;3421.853679017532;4037.576054514263;4048.7570745755347;4069.1658210066867;3449.6550344965553;3923.1761330120707;3931.9017024574327;3934.40983852423;3943.3099207638693;3236.417444859924;3919.451276821233;3926.8821935341984;3938.4683067786054;3949.8868105563674;3072.5237588173645;3294.4399452093203;3405.9318813623836;3432.1591210153424;3441.380551500729;4177.895552611209;4396.582324679151;4635.860924172281;5065.290859279704;5065.758889072201;4504.797284122223;4505.52691967342;4510.226210880318;4511.345854851028;4529.230030894756;5292.285541451932;5400.567954563665;5461.344638643388;5462.235287059815;5488.259085023511;3602.783832970004;3705.6294370562996;3732.757370770891;3745.150030710237;3754.2116155607355;5728.484436400702;5736.368999410061;5741.540676745851;5743.942560574341;5802.33273173582;2836.858157092137;3482.495038219444;3496.230829217568;3801.6578507933864;3804.505414296179;3855.267499175148;3878.7478813877005;3882.7187028281164;3884.3979183226643;3894.532656081232;4476.9104617908115;4503.392042074278;4516.796744146522;4532.478764942008;4546.227141385973;4618.399608050931;5282.471752824764;5495.477929596682;5511.559075273968;5536.86157846049;1238.578883179723;1240.5409998300686;1247.762925044257;1250.831137796404;1257.3713143428286;822.1819289806562;845.6067928413318;854.9016863060704;861.7156338408311;866.9769671787351;4303.462067241595;4855.417349918032;4890.070886531547;4938.358013458227;4967.726775027409;4554.973036998504;4632.5830675239395;5319.46805319465;5349.839504814861;5354.678719276778;4176.979115104405;4407.867763967086;4717.466823282447;4899.706017638953;4927.926081108786;2319.93040208794;2593.560573881514;2636.4990651776484;2693.1381957773715;2770.4373454554457;4773.992384797625;4858.562729354375;5521.558275337963;5531.695756733418;5541.9168712469345;5043.798248070109;5117.897642047213;5432.375276843135;5475.945240547623;5493.395726470165;5090.541851233481;5340.91988620171;5469.371022332403;5490.780368785284;5506.834794956158;4754.881127971828;5290.629655924048;5297.636001139227;5306.628536002597;5366.517013468807;4576.3593096967115;4943.332650092335;5009.899802004013;5053.2420136979745;5225.346831646044;5155.422288855572;5403.729813509387;5496.5053145217125;5525.789684126382;5949.345571986992;3346.5816772903386;3596.6583174598372;3644.6902013328104;3762.6237376262156;3785.6782173515558;5257.634064670837;5644.758153206288;5669.886602268014;5679.34687510925;5755.625884317506;4647.62818974488;5563.693996830213;5574.0736113540715;5577.521956681401;5579.6652200868075;4863.635227357891;5218.347706536683;5523.530499907392;5567.081431563746;5577.77688892456;4435.556444355668;4492.73043617384;5379.542738867083;5406.621536492407;5637.661740295595;4666.673332866667;4850.102730994778;5366.597505187048;5562.610617256915;5578.916316255179;4184.727992680466;4594.701344412736;5368.812091576776;5441.2654291670115;5448.400675925565 08:56:58 INFO - PROCESS | 3562 | |5;yui.html;673.521799522339;711.9572825630463;721.8700633885899;756.1644382956832;765.5291995422816;13817.58547243583;15568.377264909404;16241.269142888568;16381.508554743346;16525.008499490024;14407.207603581799;14670;14766.704665906675;14870.33083511242;14923.626909327273;794.3605397654885;815.5881279953616;816.7141500474846;816.8774683797418;819.8852160697508;648.6659370424235;669.4677731203705;673.2358772792891;809.6923169195697;815.6451943404638;736.8784150615143;825.6078362777663;846.8814365988767;856.6659002988839;857.939944203906;818.7543736878913;820.4584973917187;830.6345208108413;838.6058552480324;844.8986121665378;499.0412630425565;510.9616778741609;631.2330518420096;632.2192092765431;633.0472638678805;12074.456649450707;12245.816312755227;12248.203866748632;12259.448324825315;13600.31998400086;13133.737325253442;13493.86506134936;13684.65788355289;13774.724505509834;13801.309934503335;11157.66527004191;11232.157588180919;11270.492827822685;11425.942870285595;11636.534538618445;3637.3270944875017;3652.6164752700847;3676.1912379276782;3694.058015206132;3821.541415030214;87347.99999999936;88188.6771698425;88251.67622485668;88838.5558072212;88900.11099888982;10246.590136394461;10349.120324772479;10401.219908506891;10442.63450779219;10814.621488247874;21222.25722099719;21233.150673973178;21284.36146915595;21304.934753262274;21323.68014479784;7419.29516695913;7440.144383395978;7525.096988361432;7552.546847189186;7583.658735356922;4194.266003449384;4221.451211342506;4251.532331443539;4267.76527291002;4366.934495982563;3382.1206486313736;3394.0496660934978;3430.8970730878113;3500.6149323574386;3503.491993660905;616.7163104971673;617.1267656266423;629.9275583307887;640.7308930249307;642.5213216153902;547.6659237864899;567.1237937386747;601.3475379213514;651.7555916531301;665.7969319357643;7405.370543503755;8651.803875120562;8864.290856731446;8881.777955551162;8944.105589441004;593.365457369279;625.6058683029662;632.4782054804805;648.8021153548214;661.0976017735757;3897.70767192456;4287.3140297552245;4408.184485870124;4411.448568928884;4507.3689683444345;72855.54288914298;93221.5338923301;102795;111822;117279;92792;94010;95239.04760952301;96263;97441.512792437;84191;87038;87303.12696873076;88234.55882720546;90516.09483905208;8276.834463310579;8598.742037738877;8607.870881936651;8778.956105219433;8856;44627.55372446234;54408.455915441926;55499.72250138724;55919.72040139773;57642;75009.6249518749;82994.3401131986;83494.58252708941;84356;85606.71589926032;11537.423128843357;11546.480408381474;11656.18406711522;11667.824982625438;12164.635060948185;11792.41037948082;12035.458404371999;12042.096842737004;12576.937115314366;12602.55891043809;11510.36692981894;11757.47091380907;11773.17587768848;11886.524539018343;11889.28664280146;11606.94196529012;11717;11766.176367654181;11857.762844743229;11984.940075299568;11559.422028898352;11642.301461912313;11680.532778689098;11820;11851.940740296588;9026.774330641787;9546.782530526742;9731.805363892825;9939.602415903282;10197.847032294374;7281;10054.49727513636;10060.69817905464;10111.949440252753;10156.593736250466 08:56:58 INFO - PROCESS | 3562 | __end_tp_report 08:56:58 INFO - PROCESS | 3562 | __start_cc_report 08:56:58 INFO - PROCESS | 3562 | _x_x_mozilla_cycle_collect,3337 08:56:58 INFO - PROCESS | 3562 | __end_cc_report 08:56:58 INFO - PROCESS | 3562 | __startTimestamp1478624218592__endTimestamp 08:56:58 INFO - PROCESS | 3562 | 08:56:58 INFO - PROCESS | 3562 | ------- Summary: start ------- 08:56:58 INFO - PROCESS | 3562 | Number of tests: 6 08:56:58 INFO - PROCESS | 3562 | 08:56:58 INFO - PROCESS | 3562 | [#0] dojo.html Cycles:165 Average:5642.24 Median:5840.39 stddev:781.13 (13.4%) stddev-sans-first:750.99 08:56:58 INFO - PROCESS | 3562 | Values: 2798.4 3220.1 3224.7 3257.4 3277.8 5548.6 5625.6 5630.4 5672.8 5687.3 5599.6 5633.7 5637.3 5660.8 5667.5 5084.9 5124.3 5154.6 5189.6 5238.7 5106.1 5187.4 5226.8 5262.8 5270.2 5259.4 5265.5 5272.7 5316.4 5326.0 253.3 5235.3 5256.3 5263.4 5280.4 3288.0 5078.8 5161.2 5162.3 5192.7 5561.8 5572.3 5582.6 5626.0 5631.3 6184.1 6187.3 6197.2 6206.3 6219.9 6107.2 6119.2 6130.8 6140.5 6145.3 5662.3 5663.7 5670.7 5685.1 5697.3 6285.8 6291.3 6293.0 6297.6 6318.6 5897.4 5934.1 5945.1 5981.8 6082.9 6122.4 6182.5 6192.4 6210.9 6217.6 6100.3 6115.0 6116.0 6140.9 6143.0 6009.1 6111.8 6128.2 6164.1 6168.7 4873.3 4921.0 4928.3 4928.6 4964.1 4404.3 4419.3 4420.6 4434.0 4440.1 5620.0 5759.3 5791.4 5797.1 5854.2 6076.2 6134.5 6139.6 6142.9 6198.6 5776.1 5793.0 5794.0 5803.3 5804.4 5384.9 5387.5 5390.1 5396.6 5411.9 6131.8 6170.6 6224.3 6250.9 6261.8 6104.9 6105.5 6131.1 6140.1 6190.1 6183.6 6263.3 6266.1 6269.9 6272.1 6147.2 6151.5 6154.2 6156.4 6163.8 5977.1 6125.9 6140.3 6255.4 6259.8 5943.3 5955.9 5956.3 5964.2 5984.6 5895.0 5933.9 5952.5 5954.0 5967.2 5818.9 5825.1 5830.6 5835.6 5840.4 5920.4 5944.2 5945.6 5949.4 5953.9 5659.2 5771.5 5790.2 5806.7 5843.7 08:56:58 INFO - PROCESS | 3562 | 08:56:58 INFO - PROCESS | 3562 | [#1] ext.html Cycles:180 Average:67497.22 Median:36527.31 stddev:147931.41 (405.0%) stddev-sans-first:148260.88 08:56:58 INFO - PROCESS | 3562 | Values: 502.6 506.1 516.0 521.9 533.6 29642.7 36757.3 38700.8 38723.8 40717.2 39216.0 39705.0 39721.6 39747.0 39916.8 7431.7 9155.8 9184.4 9353.9 9359.0 8456.5 9339.9 11336.5 11351.0 11471.5 11162.6 11246.3 11247.7 11253.9 11328.7 8432.9 8670.7 11142.3 11217.7 11221.5 8571.8 8607.2 8617.0 10841.8 11188.9 38337.8 38497.4 38497.6 38538.8 38685.0 3233.5 3237.7 3353.3 3373.7 3632.7 28178.9 29163.7 29273.7 29522.3 30110.7 2995.9 3027.2 3027.8 3111.6 3153.8 466687.0 467292.0 467307.0 467933.0 474381.0 53298.0 53670.7 53671.7 53695.5 53786.5 56308.4 56654.7 56683.7 56698.4 56710.7 14827.7 14976.9 15067.0 15068.8 15086.5 10564.8 10832.1 11760.9 11813.6 11836.9 12231.9 14076.2 14580.1 14587.4 14891.0 5007.4 5151.6 6077.6 6090.3 6108.6 829.7 846.8 902.4 912.0 1037.0 27396.7 27842.7 28705.9 28731.0 28753.7 1006.2 1028.6 1067.4 1111.4 1113.6 13777.1 14150.6 14592.6 14975.6 15202.4 611514.0 615795.0 616465.9 617170.0 618666.0 53958.7 54144.7 54198.7 54283.7 54341.2 1327.7 1336.5 1362.1 1364.3 1366.5 27644.0 28346.9 28940.1 28945.3 29129.6 45836.5 46136.5 46185.5 46258.8 46354.5 564234.0 565193.7 566081.0 566488.0 566694.0 36233.8 36914.5 37759.2 37821.6 37877.8 43895.0 44377.6 44710.0 45283.5 45330.8 35425.0 36266.5 36313.3 37716.8 37788.8 30834.8 36093.3 36695.3 37222.8 37480.6 35478.8 35792.8 35823.5 35973.8 36359.3 31265.4 32435.5 32449.8 32517.7 32720.5 23479.0 23648.6 23847.8 24441.0 24488.6 08:56:58 INFO - PROCESS | 3562 | 08:56:58 INFO - PROCESS | 3562 | [#2] jquery.html Cycles:180 Average:36108.62 Median:1985.62 stddev:101438.46 (5108.7%) stddev-sans-first:101687.48 08:56:58 INFO - PROCESS | 3562 | Values: 346.5 368.8 370.8 384.2 403.3 14651.5 15012.8 15713.6 19091.9 20090.3 19515.7 19611.2 19975.9 20264.7 20503.6 5568.9 5906.1 5918.9 5922.5 5928.2 4612.1 5865.9 5909.5 5920.1 5950.6 5589.7 5889.5 5897.1 5901.2 5902.8 5798.6 5807.7 5852.4 5858.9 5879.1 5164.7 5169.5 5170.0 6838.8 7237.0 27074.2 27352.7 27373.7 27536.3 27701.4 1928.3 2334.5 2494.0 2681.7 2848.3 12998.5 16182.4 16215.5 16454.6 17010.5 1891.1 1940.8 2026.1 2027.3 2045.0 62643.5 94052.5 115540.0 130357.0 144147.0 18926.1 20610.6 20706.8 20731.4 20812.0 34220.8 34337.3 34410.3 34468.0 34542.0 8615.9 9887.8 10077.1 10087.1 10364.8 8481.4 8488.2 8491.3 8607.7 8662.9 8392.5 8791.9 9418.2 9550.0 9913.3 2988.9 3624.7 3659.7 3671.6 3740.4 824.7 876.8 889.2 919.6 926.8 18068.5 18258.0 20687.8 20729.4 21104.4 841.2 917.0 919.3 990.9 992.0 9675.7 11113.6 11135.3 11182.4 11249.0 575082.1 606793.0 620128.0 623615.0 623907.0 97654.0 126554.0 127441.4 128573.0 137350.0 988.1 996.3 1023.4 1026.3 1026.3 18206.9 18434.1 18511.3 18664.1 18775.8 49709.0 49722.1 50068.2 50554.5 52835.0 76247.6 78890.6 81208.2 87685.0 89056.0 7883.8 7964.8 8038.9 8461.2 8497.9 11545.8 12641.5 12700.7 12997.0 13204.0 10847.5 11277.3 11420.8 11425.8 11487.4 8967.7 10454.5 10990.0 11353.0 11431.1 10762.5 11145.6 11421.3 11458.1 11522.2 6187.4 8175.1 8217.4 8233.0 8444.3 8863.7 9233.2 10997.1 11029.0 11341.7 08:56:58 INFO - PROCESS | 3562 | 08:56:58 INFO - PROCESS | 3562 | [#3] mootools.html Cycles:180 Average:5327.39 Median:5073.47 stddev:2943.73 (58.0%) stddev-sans-first:2936.56 08:56:58 INFO - PROCESS | 3562 | Values: 1317.6 1465.4 1486.2 1630.6 1799.9 6524.0 7434.7 7742.4 8317.9 8339.9 8172.3 8232.5 8257.2 8263.9 8273.5 2254.8 2294.5 2294.6 2294.6 2297.5 2285.5 2331.3 2335.1 2340.5 2343.5 1885.7 2258.8 2279.3 2281.9 2287.1 2195.8 2209.2 2230.8 2234.8 2242.8 2283.2 2314.2 2318.2 2321.3 2322.0 8184.4 8256.6 8303.7 8325.1 8328.9 5032.4 5178.2 5188.6 5202.7 5206.8 3117.9 3380.6 3483.4 3564.3 3566.2 1003.1 1032.3 1032.3 1059.1 1093.0 10669.7 10803.2 10814.6 10835.2 10839.7 4055.9 4308.1 4411.3 4417.5 4420.8 8625.0 8652.0 8659.9 8660.7 8664.4 4338.7 5055.7 5059.3 5066.8 5080.1 4097.6 4154.6 4266.1 4387.1 4749.1 3536.3 3558.3 3575.3 3621.0 3630.0 1113.6 1131.7 1134.5 1137.9 1144.2 801.7 806.4 835.6 874.4 923.5 5341.6 5514.4 5515.9 5674.7 5698.1 829.0 879.4 893.4 896.2 896.4 3538.5 3608.9 3694.0 3705.4 3760.7 9652.1 9900.7 10005.2 10233.4 10244.4 9898.4 9999.8 10198.6 10213.8 10215.8 7599.7 7768.9 7777.8 7794.9 7797.5 5258.7 5423.9 5553.7 5583.9 5612.0 7185.9 9299.9 9315.8 9401.4 9509.1 8379.8 8794.8 9360.9 9620.2 9625.2 6137.9 6635.3 6859.0 7161.3 7170.3 7831.0 7893.8 7944.8 7987.0 8062.7 7086.9 7127.0 7140.6 7199.2 7231.2 6765.8 6783.0 6954.5 7004.1 7147.7 6535.5 6684.1 6745.5 6745.6 6757.6 3942.9 4789.9 4810.8 4828.7 4878.2 4726.2 4733.9 4790.1 4804.2 4909.5 08:56:58 INFO - PROCESS | 3562 | 08:56:58 INFO - PROCESS | 3562 | [#4] prototype.html Cycles:180 Average:4334.85 Median:4764.44 stddev:1336.03 (28.0%) stddev-sans-first:1304.98 08:56:58 INFO - PROCESS | 3562 | Values: 298.7 301.1 308.7 313.8 315.5 4400.6 4950.3 5201.8 5231.4 5244.5 4080.0 4340.7 5121.3 5139.3 5184.6 3996.7 4017.5 4020.3 4035.5 4037.5 3124.0 3421.9 4037.6 4048.8 4069.2 3449.7 3923.2 3931.9 3934.4 3943.3 3236.4 3919.5 3926.9 3938.5 3949.9 3072.5 3294.4 3405.9 3432.2 3441.4 4177.9 4396.6 4635.9 5065.3 5065.8 4504.8 4505.5 4510.2 4511.3 4529.2 5292.3 5400.6 5461.3 5462.2 5488.3 3602.8 3705.6 3732.8 3745.2 3754.2 5728.5 5736.4 5741.5 5743.9 5802.3 2836.9 3482.5 3496.2 3801.7 3804.5 3855.3 3878.7 3882.7 3884.4 3894.5 4476.9 4503.4 4516.8 4532.5 4546.2 4618.4 5282.5 5495.5 5511.6 5536.9 1238.6 1240.5 1247.8 1250.8 1257.4 822.2 845.6 854.9 861.7 867.0 4303.5 4855.4 4890.1 4938.4 4967.7 4555.0 4632.6 5319.5 5349.8 5354.7 4177.0 4407.9 4717.5 4899.7 4927.9 2319.9 2593.6 2636.5 2693.1 2770.4 4774.0 4858.6 5521.6 5531.7 5541.9 5043.8 5117.9 5432.4 5475.9 5493.4 5090.5 5340.9 5469.4 5490.8 5506.8 4754.9 5290.6 5297.6 5306.6 5366.5 4576.4 4943.3 5009.9 5053.2 5225.3 5155.4 5403.7 5496.5 5525.8 5949.3 3346.6 3596.7 3644.7 3762.6 3785.7 5257.6 5644.8 5669.9 5679.3 5755.6 4647.6 5563.7 5574.1 5577.5 5579.7 4863.6 5218.3 5523.5 5567.1 5577.8 4435.6 4492.7 5379.5 5406.6 5637.7 4666.7 4850.1 5366.6 5562.6 5578.9 4184.7 4594.7 5368.8 5441.3 5448.4 08:56:58 INFO - PROCESS | 3562 | 08:56:58 INFO - PROCESS | 3562 | [#5] yui.html Cycles:180 Average:20309.35 Median:27459.71 stddev:29971.65 (109.1%) stddev-sans-first:30019.46 08:56:58 INFO - PROCESS | 3562 | Values: 673.5 712.0 721.9 756.2 765.5 13817.6 15568.4 16241.3 16381.5 16525.0 14407.2 14670.0 14766.7 14870.3 14923.6 794.4 815.6 816.7 816.9 819.9 648.7 669.5 673.2 809.7 815.6 736.9 825.6 846.9 856.7 857.9 818.8 820.5 830.6 838.6 844.9 499.0 511.0 631.2 632.2 633.0 12074.5 12245.8 12248.2 12259.4 13600.3 13133.7 13493.9 13684.7 13774.7 13801.3 11157.7 11232.2 11270.5 11425.9 11636.5 3637.3 3652.6 3676.2 3694.1 3821.5 87348.0 88188.7 88251.7 88838.6 88900.1 10246.6 10349.1 10401.2 10442.6 10814.6 21222.3 21233.2 21284.4 21304.9 21323.7 7419.3 7440.1 7525.1 7552.5 7583.7 4194.3 4221.5 4251.5 4267.8 4366.9 3382.1 3394.0 3430.9 3500.6 3503.5 616.7 617.1 629.9 640.7 642.5 547.7 567.1 601.3 651.8 665.8 7405.4 8651.8 8864.3 8881.8 8944.1 593.4 625.6 632.5 648.8 661.1 3897.7 4287.3 4408.2 4411.4 4507.4 72855.5 93221.5 102795.0 111822.0 117279.0 92792.0 94010.0 95239.0 96263.0 97441.5 84191.0 87038.0 87303.1 88234.6 90516.1 8276.8 8598.7 8607.9 8779.0 8856.0 44627.6 54408.5 55499.7 55919.7 57642.0 75009.6 82994.3 83494.6 84356.0 85606.7 11537.4 11546.5 11656.2 11667.8 12164.6 11792.4 12035.5 12042.1 12576.9 12602.6 11510.4 11757.5 11773.2 11886.5 11889.3 11606.9 11717.0 11766.2 11857.8 11984.9 11559.4 11642.3 11680.5 11820.0 11851.9 9026.8 9546.8 9731.8 9939.6 10197.8 7281.0 10054.5 10060.7 10111.9 10156.6 08:56:58 INFO - PROCESS | 3562 | -------- Summary: end -------- 08:56:58 INFO - PROCESS | 3562 | 08:56:59 INFO - TEST-INFO | 3562: exit 0 08:56:59 INFO - TEST-OK | dromaeo_css | took 1118528ms 08:56:59 INFO - TEST-START | kraken 08:56:59 INFO - operating with platform_type : mac_ 08:56:59 INFO - Initialising browser for kraken test... 08:56:59 INFO - TEST-INFO | started process 3565 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmp0ErP5u/profile http://localhost:62461/getInfo.html) 08:57:01 INFO - PROCESS | 3565 | __metrics Screen width/height:1600/1200 08:57:01 INFO - PROCESS | 3565 | colorDepth:24 08:57:01 INFO - PROCESS | 3565 | Browser inner width/height: 1024/689 08:57:01 INFO - PROCESS | 3565 | __metrics 08:57:01 INFO - PROCESS | 3565 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:57:01 INFO - PROCESS | 3565 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:57:02 INFO - TEST-INFO | 3565: exit 0 08:57:02 INFO - Browser initialized. 08:57:02 INFO - Running cycle 1/1 for kraken test... 08:57:02 INFO - TEST-INFO | started process 3566 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmp0ErP5u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:57:14 INFO - PROCESS | 3566 | RSS: Main: 231862272 08:57:14 INFO - PROCESS | 3566 | 08:57:17 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62461/tests/kraken/driver.html?testName=audio-beat-detection) 08:57:18 INFO - PROCESS | 3566 | RSS: Main: 290267136 08:57:18 INFO - PROCESS | 3566 | 08:57:21 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62461/tests/kraken/driver.html?testName=audio-dft) 08:57:21 INFO - PROCESS | 3566 | RSS: Main: 291536896 08:57:21 INFO - PROCESS | 3566 | 08:57:25 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62461/tests/kraken/driver.html?testName=audio-fft) 08:57:25 INFO - PROCESS | 3566 | RSS: Main: 383950848 08:57:25 INFO - PROCESS | 3566 | 08:57:27 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62461/tests/kraken/driver.html?testName=audio-oscillator) 08:57:28 INFO - PROCESS | 3566 | RSS: Main: 287088640 08:57:28 INFO - PROCESS | 3566 | 08:57:30 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62461/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:57:31 INFO - PROCESS | 3566 | RSS: Main: 290070528 08:57:31 INFO - PROCESS | 3566 | 08:57:34 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62461/tests/kraken/driver.html?testName=imaging-darkroom) 08:57:34 INFO - PROCESS | 3566 | RSS: Main: 327028736 08:57:34 INFO - PROCESS | 3566 | 08:57:37 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62461/tests/kraken/driver.html?testName=imaging-desaturate) 08:57:38 INFO - PROCESS | 3566 | RSS: Main: 327147520 08:57:38 INFO - PROCESS | 3566 | 08:57:40 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62461/tests/kraken/driver.html?testName=json-parse-financial) 08:57:41 INFO - PROCESS | 3566 | RSS: Main: 325001216 08:57:41 INFO - PROCESS | 3566 | 08:57:42 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62461/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:57:43 INFO - PROCESS | 3566 | RSS: Main: 302665728 08:57:43 INFO - PROCESS | 3566 | 08:57:45 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62461/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:57:46 INFO - PROCESS | 3566 | RSS: Main: 303501312 08:57:46 INFO - PROCESS | 3566 | 08:57:48 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62461/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:57:49 INFO - PROCESS | 3566 | RSS: Main: 309456896 08:57:49 INFO - PROCESS | 3566 | 08:57:52 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62461/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:57:52 INFO - PROCESS | 3566 | RSS: Main: 324603904 08:57:52 INFO - PROCESS | 3566 | 08:57:55 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62461/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:57:56 INFO - PROCESS | 3566 | RSS: Main: 317792256 08:57:56 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | Cycle 1(1): loaded http://localhost:62461/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62461/tests/kraken/driver.html?testName=ai-astar) 08:57:58 INFO - PROCESS | 3566 | RSS: Main: 361291776 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | __start_tp_report 08:57:58 INFO - PROCESS | 3566 | _x_x_mozilla_page_load 08:57:58 INFO - PROCESS | 3566 | _x_x_mozilla_page_load_details 08:57:58 INFO - PROCESS | 3566 | |i|pagename|runs| 08:57:58 INFO - PROCESS | 3566 | |0;ai-astar;105;114;113;113;114;113;113;109;113;113 08:57:58 INFO - PROCESS | 3566 | |1;audio-beat-detection;146;113;113;148;109;105;110;110;146;118 08:57:58 INFO - PROCESS | 3566 | |2;audio-dft;182;185;184;184;201;244;185;181;182;180 08:57:58 INFO - PROCESS | 3566 | |3;audio-fft;80;61;59;90;63;76;81;80;86;58 08:57:58 INFO - PROCESS | 3566 | |4;audio-oscillator;91;73;95;93;53;67;108;96;99;130 08:57:58 INFO - PROCESS | 3566 | |5;imaging-gaussian-blur;86;83;86;84;86;89;132;83;86;83 08:57:58 INFO - PROCESS | 3566 | |6;imaging-darkroom;92;93;92;93;93;91;92;92;93;92 08:57:58 INFO - PROCESS | 3566 | |7;imaging-desaturate;81;81;79;79;81;84;84;86;82;83 08:57:58 INFO - PROCESS | 3566 | |8;json-parse-financial;54;55;54;54;54;56;55;56;55;56 08:57:58 INFO - PROCESS | 3566 | |9;json-stringify-tinderbox;72;68;69;77;73;68;75;101;102;91 08:57:58 INFO - PROCESS | 3566 | |10;stanford-crypto-aes;88;86;87;90;90;89;85;88;78;85 08:57:58 INFO - PROCESS | 3566 | |11;stanford-crypto-ccm;151;146;150;146;147;145;147;175;219;183 08:57:58 INFO - PROCESS | 3566 | |12;stanford-crypto-pbkdf2;145;189;227;175;174;175;179;177;176;174 08:57:58 INFO - PROCESS | 3566 | |13;stanford-crypto-sha256-iterative;68;69;68;68;67;70;67;85;67;67 08:57:58 INFO - PROCESS | 3566 | __end_tp_report 08:57:58 INFO - PROCESS | 3566 | __start_cc_report 08:57:58 INFO - PROCESS | 3566 | _x_x_mozilla_cycle_collect,2049 08:57:58 INFO - PROCESS | 3566 | __end_cc_report 08:57:58 INFO - PROCESS | 3566 | __startTimestamp1478624278477__endTimestamp 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | ------- Summary: start ------- 08:57:58 INFO - PROCESS | 3566 | Number of tests: 14 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:2.83 (2.5%) stddev-sans-first:1.48 08:57:58 INFO - PROCESS | 3566 | Values: 105.0 114.0 113.0 113.0 114.0 113.0 113.0 109.0 113.0 113.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:115.50 stddev:17.49 (15.1%) stddev-sans-first:16.21 08:57:58 INFO - PROCESS | 3566 | Values: 146.0 113.0 113.0 148.0 109.0 105.0 110.0 110.0 146.0 118.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#2] audio-dft Cycles:10 Average:190.80 Median:184.50 stddev:19.61 (10.6%) stddev-sans-first:20.54 08:57:58 INFO - PROCESS | 3566 | Values: 182.0 185.0 184.0 184.0 201.0 244.0 185.0 181.0 182.0 180.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#3] audio-fft Cycles:10 Average:73.40 Median:80.00 stddev:11.98 (15.0%) stddev-sans-first:12.47 08:57:58 INFO - PROCESS | 3566 | Values: 80.0 61.0 59.0 90.0 63.0 76.0 81.0 80.0 86.0 58.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:92.00 stddev:21.76 (23.6%) stddev-sans-first:23.08 08:57:58 INFO - PROCESS | 3566 | Values: 91.0 73.0 95.0 93.0 53.0 67.0 108.0 96.0 99.0 130.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:86.00 stddev:14.95 (17.4%) stddev-sans-first:15.79 08:57:58 INFO - PROCESS | 3566 | Values: 86.0 83.0 86.0 84.0 86.0 89.0 132.0 83.0 86.0 83.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#6] imaging-darkroom Cycles:10 Average:92.30 Median:92.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 08:57:58 INFO - PROCESS | 3566 | Values: 92.0 93.0 92.0 93.0 93.0 91.0 92.0 92.0 93.0 92.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#7] imaging-desaturate Cycles:10 Average:82.00 Median:82.50 stddev:2.26 (2.7%) stddev-sans-first:2.37 08:57:58 INFO - PROCESS | 3566 | Values: 81.0 81.0 79.0 79.0 81.0 84.0 84.0 86.0 82.0 83.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.88 (1.6%) stddev-sans-first:0.87 08:57:58 INFO - PROCESS | 3566 | Values: 54.0 55.0 54.0 54.0 54.0 56.0 55.0 56.0 55.0 56.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#9] json-stringify-tinderbox Cycles:10 Average:79.60 Median:72.50 stddev:13.33 (18.4%) stddev-sans-first:13.86 08:57:58 INFO - PROCESS | 3566 | Values: 72.0 68.0 69.0 77.0 73.0 68.0 75.0 101.0 102.0 91.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:88.00 stddev:3.53 (4.0%) stddev-sans-first:3.71 08:57:58 INFO - PROCESS | 3566 | Values: 88.0 86.0 87.0 90.0 90.0 89.0 85.0 88.0 78.0 85.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#11] stanford-crypto-ccm Cycles:10 Average:160.90 Median:150.50 stddev:24.41 (16.2%) stddev-sans-first:25.63 08:57:58 INFO - PROCESS | 3566 | Values: 151.0 146.0 150.0 146.0 147.0 145.0 147.0 175.0 219.0 183.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:176.50 stddev:20.15 (11.4%) stddev-sans-first:17.19 08:57:58 INFO - PROCESS | 3566 | Values: 145.0 189.0 227.0 175.0 174.0 175.0 179.0 177.0 176.0 174.0 08:57:58 INFO - PROCESS | 3566 | 08:57:58 INFO - PROCESS | 3566 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:68.00 stddev:5.50 (8.1%) stddev-sans-first:5.80 08:57:58 INFO - PROCESS | 3566 | Values: 68.0 69.0 68.0 68.0 67.0 70.0 67.0 85.0 67.0 67.0 08:57:58 INFO - PROCESS | 3566 | -------- Summary: end -------- 08:57:58 INFO - PROCESS | 3566 | 08:57:59 INFO - TEST-INFO | 3566: exit 0 08:57:59 INFO - TEST-OK | kraken | took 60028ms 08:57:59 INFO - SUITE-END | took 1178s 08:57:59 INFO - Completed test suite (00:19:38) 08:57:59 INFO - javascript benchmark 08:57:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2798.3843554418027, 3220.146661134801, 3224.7420206383485, 3257.4299497587917, 3277.7541684373678, 5548.611597188198, 5625.634333768312, 5630.380658127603, 5672.801451949188, 5687.289088863892, 5599.608027438081, 5633.661980281176, 5637.3235211774645, 5660.830175094754, 5667.4615911488445, 5084.949150508503, 5124.256982737501, 5154.639175257734, 5189.6107791915565, 5238.659487133344, 5106.106431374495, 5187.351581052369, 5226.843194704165, 5262.842114736564, 5270.156774916014, 5259.421463639016, 5265.473452654723, 5272.736363181825, 5316.38861530925, 5325.973370133144, 253.32043650370906, 5235.345581802275, 5256.290400795881, 5263.3947096083675, 5280.445553216895, 3288.010504553033, 5078.847634570969, 5161.174212126042, 5162.328897243372, 5192.71440070796, 5561.805336813232, 5572.331320241557, 5582.553395728332, 5626.0, 5631.324241091054, 6184.07238914162, 6187.288461826857, 6197.194364732601, 6206.2862770781485, 6219.937800621981, 6107.175531303262, 6119.2350956130485, 6130.816075517741, 6140.486742244447, 6145.279321789906, 5662.320521537401, 5663.688497132697, 5670.659760414306, 5685.090385538336, 5697.344805347355, 6285.811425657238, 6291.339409362043, 6292.993121100602, 6297.622142671455, 6318.62088274705, 5897.410258974154, 5934.13954976526, 5945.137954996501, 5981.760729570856, 6082.908756368658, 6122.377813977311, 6182.536309776785, 6192.411720886508, 6210.9068363974575, 6217.564770466024, 6100.254632272332, 6114.9910264806695, 6115.969420152871, 6140.907886381708, 6142.969285153546, 6009.06859436788, 6111.84720381994, 6128.203333566607, 6164.075388691643, 6168.722407491674, 4873.31773551703, 4921.040397122527, 4928.28539861718, 4928.556429921325, 4964.081644895634, 4404.251277282806, 4419.337099435111, 4420.557944205554, 4434.002349471408, 4440.134173836071, 5619.971900140473, 5759.280089988752, 5791.362950075489, 5797.130430435468, 5854.209681692917, 6076.179715738407, 6134.467201660938, 6139.553468857682, 6142.928535732134, 6198.566100372931, 5776.104703770839, 5793.044147715664, 5794.0, 5803.274590676166, 5804.419558044162, 5384.865378365572, 5387.488188622075, 5390.05674007055, 5396.595255355864, 5411.945880541223, 6131.774283661951, 6170.598911070945, 6224.315325314303, 6250.9062364064575, 6261.812145635638, 6104.913929256043, 6105.520399749632, 6131.0708424985305, 6140.078988151634, 6190.102435146969, 6183.628982261079, 6263.27972283175, 6266.060090986389, 6269.874602508015, 6272.059191121186, 6147.231596050494, 6151.538634602333, 6154.230721159855, 6156.384361563808, 6163.7842675506145, 5977.103434484862, 6125.877482450415, 6140.263168419819, 6255.40573645503, 6259.812205633838, 5943.346231914399, 5955.940440595538, 5956.285245770535, 5964.194833697569, 5984.581079324405, 5895.02732049207, 5933.940660593339, 5952.523798096228, 5954.0, 5967.194428752063, 5818.883622327615, 5825.126231065374, 5830.591858569859, 5835.591508594358, 5840.386759390372, 5920.407959204217, 5944.167816505779, 5945.64326140433, 5949.3753155918, 5953.91069133972, 5659.179418984307, 5771.480566749013, 5790.218320526675, 5806.65160090396, 5843.737031833492], "value": 5592.233482566553, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.62554396974235, 506.1193523269511, 515.9871003224919, 521.8773588206768, 533.5758072332494, 29642.703572964263, 36757.26485470289, 38700.8389748308, 38723.806380968126, 40717.185656286856, 39216.0, 39705.0, 39721.60278397215, 39747.0, 39916.800415997954, 7431.665575049135, 9155.816883662355, 9184.44893306402, 9353.906460935406, 9358.953205233964, 8456.492610443393, 9339.859902101474, 11336.546538138506, 11351.03516201124, 11471.483783229774, 11162.609308674198, 11246.268992515501, 11247.662570122868, 11253.887461125365, 11328.660140195767, 8432.915670843275, 8670.696525621574, 11142.331460112378, 11217.66347009591, 11221.495032723506, 8571.785705357353, 8607.18231767981, 8617.0, 10841.83737243942, 11188.888111118866, 38337.80831095854, 38497.422538661944, 38497.61502384996, 38538.80730596357, 38685.0, 3233.466478031122, 3237.7409807215367, 3353.2622822978906, 3373.713234375081, 3632.691221246197, 28178.85910570434, 29163.70836291631, 29273.70726292731, 29522.26194345137, 30110.69889301101, 2995.9101226963007, 3027.2431892026993, 3027.8486075696346, 3111.5643809866638, 3153.8265395403246, 466687.0, 467292.0, 467306.9999999966, 467933.0, 474381.0, 53298.0, 53670.73164634152, 53671.73164134155, 53695.46304536983, 53786.46213537815, 56308.43691563114, 56654.716726416926, 56683.716581416826, 56698.43301566932, 56710.7164464175, 14827.73964213054, 14976.925115374353, 15067.0, 15068.849311506963, 15086.471973480875, 10564.841527377095, 10832.079273261703, 11760.941515263496, 11813.586524471602, 11836.93467587904, 12231.877681223252, 14076.225807580682, 14580.125192488485, 14587.416503339748, 14891.0, 5007.444714252922, 5151.613628977767, 6077.574569780161, 6090.33006369299, 6108.5723999319625, 829.6722794496261, 846.7552697534614, 902.4359775140537, 911.9726408207764, 1036.9792604147874, 27396.726032739814, 27842.72157278442, 28705.856470717514, 28730.994415195368, 28753.712462875104, 1006.2101250518308, 1028.6348346337093, 1067.4075887882145, 1111.377628528027, 1113.571275059108, 13777.104488208435, 14150.575482735534, 14592.635184120481, 14975.625609359635, 15202.391904323704, 611514.0, 615795.0, 616465.9176704177, 617170.0, 618666.0, 53958.730206348715, 54144.72927635337, 54198.729006355505, 54283.72858135684, 54341.1848822268, 1327.694630235032, 1336.5188532128425, 1362.0805955979674, 1364.3451143451398, 1366.5285476510587, 27644.032458864647, 28346.866125354754, 28940.131796046153, 28945.276368090967, 29129.56305655459, 45836.54163458323, 46136.53863461322, 46185.53814461813, 46258.768706156254, 46354.53645463637, 564234.0, 565193.6988506499, 566081.0, 566488.0, 566694.0, 36233.81883090568, 36914.48046286807, 37759.244815104095, 37821.62178378291, 37877.81061094787, 43895.0, 44377.55622443734, 44710.0, 45283.54716452925, 45330.77334613306, 35425.0, 36266.456003159445, 36313.27373452569, 37716.81141594274, 37788.81105594454, 30834.845825770728, 36093.27813443769, 36695.34870930761, 37222.813885930394, 37480.625193747714, 35478.8226058868, 35792.82103589569, 35823.46264805978, 35973.820130900225, 36359.27281454409, 31265.374692506477, 32435.513467297536, 32449.837750811093, 32517.674823251466, 32720.50919236261, 23479.0, 23648.645270321304, 23847.761522384553, 24441.0, 24488.632670510313], "value": 19135.39343266896, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.54775517949065, 368.7879469305149, 370.82756518219037, 384.16444233791503, 403.3405382200104, 14651.487197948074, 15012.77480837787, 15713.607159821011, 19091.904540477277, 20090.296839610615, 19515.707264391047, 19611.21555137796, 19975.900120499376, 20264.69602955953, 20503.589928201465, 5568.86077848053, 5906.05503119501, 5918.852028699274, 5922.526197904157, 5928.199693041427, 4612.100640375128, 5865.9413405866035, 5909.497692696126, 5920.111983202511, 5950.5834591578605, 5589.692566908819, 5889.499392551638, 5897.085951677497, 5901.23283973085, 5902.763889444417, 5798.565107616924, 5807.680577568253, 5852.414758524134, 5858.882822343529, 5879.0593505039415, 5164.664296820694, 5169.534741873212, 5170.0, 6838.829029274259, 7236.986821844946, 27074.1877743668, 27352.726472735216, 27373.726262737317, 27536.311592210153, 27701.445971080466, 1928.2672584417971, 2334.509752951882, 2493.9501209975697, 2681.6513853199153, 2848.302165969351, 12998.545050923172, 16182.352705891868, 16215.513534593982, 16454.588635284214, 17010.48968530946, 1891.1489829576608, 1940.7768106667772, 2026.108512254618, 2027.310714357111, 2045.0, 62643.49426022999, 94052.52973735156, 115539.99999999916, 130357.04464433041, 144147.0, 18926.148323325207, 20610.587788244153, 20706.757594544073, 20731.378058658265, 20812.0, 34220.82889585536, 34337.313253734785, 34410.31179376398, 34468.0, 34541.963741087806, 8615.913840861636, 9887.752806179904, 10077.143442807297, 10087.092161705481, 10364.792704145875, 8481.448705834102, 8488.151184881586, 8491.278241349555, 8607.741767746978, 8662.913370866338, 8392.4544904581, 8791.912080879238, 9418.246540276761, 9550.0, 9913.256505761952, 2988.9103326900226, 3624.6919011884283, 3659.652333028358, 3671.596124426311, 3740.4202348636004, 824.6824972385555, 876.7588913048835, 889.2441424788877, 919.6091661044029, 926.7627716997748, 18068.457946261635, 18258.0, 20687.793122068888, 20729.378118656463, 21104.366868993955, 841.2218697704599, 916.9511374679219, 919.2508105893676, 990.8910019897805, 992.0, 9675.709728708009, 11113.555457781598, 11135.33188008722, 11182.384968826793, 11249.0, 575082.1245893744, 606793.0, 620128.0, 623615.0, 623907.0, 97654.02345976449, 126554.0, 127441.36279318544, 128573.0, 137350.0, 988.1205726903062, 996.2976101848035, 1023.4473384372355, 1026.2508368890751, 1026.2610920137488, 18206.906205786505, 18434.078296085412, 18511.259549617866, 18664.066796660383, 18775.812241877953, 49709.0, 49722.09942830469, 50068.24897626466, 50554.483365499094, 52835.0, 76247.61876190806, 78890.6055469719, 81208.18791812006, 87685.0, 89056.0, 7883.763487095396, 7964.761057168294, 8038.919610804051, 8461.196186362286, 8497.87253191215, 11545.769084618429, 12641.494340226289, 12700.745985080062, 12996.98546725259, 13204.0, 10847.511861966388, 11277.32336059839, 11420.828687569527, 11425.828612570984, 11487.36819474937, 8967.686130985385, 10454.529546170592, 10990.0, 11352.978231959163, 11431.085513158761, 10762.46187690628, 11145.554177832795, 11421.288081373754, 11458.140639452242, 11522.193446459003, 6187.412195841388, 8175.100738918595, 8217.424780265324, 8232.958835205785, 8444.324454043784, 8863.734087977371, 9233.169014788433, 10997.065249453566, 11029.007389334998, 11341.716457088638], "value": 10421.829345103833, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.584960737367, 1465.406510363303, 1486.152892851075, 1630.6494103767689, 1799.8560115190778, 6524.0, 7434.702611895517, 7742.380609551236, 8317.91682083179, 8339.916600834005, 8172.305354044914, 8232.506049637026, 8257.21556452136, 8263.91736082639, 8273.544955027472, 2254.842161048727, 2294.5181511882524, 2294.5755035318434, 2294.598445272079, 2297.5060362022164, 2285.48576570272, 2331.3122628824535, 2335.1476711000532, 2340.4850932794757, 2343.4610039690883, 1885.6511545364083, 2258.7854153855355, 2279.32759835848, 2281.9201327953524, 2287.142321629389, 2195.802377786007, 2209.215728416413, 2230.7546169921457, 2234.7988681018624, 2242.831787615919, 2283.223703940668, 2314.1900334882826, 2318.177047148272, 2321.2688003278913, 2322.0, 8184.386171037196, 8256.587170641504, 8303.66785328586, 8325.125861784547, 8328.875066874001, 5032.370953630796, 5178.197379406198, 5188.558972487305, 5202.661826981272, 5206.76569554371, 3117.8775640769304, 3380.6450322716005, 3483.425234836249, 3564.269324788412, 3566.2154326048226, 1003.1022235099545, 1032.2722480651123, 1032.3341444768082, 1059.1050562274866, 1092.9890701092968, 10669.733256668567, 10803.189760767973, 10814.56741730322, 10835.241533092687, 10839.729006774814, 4055.8986025349604, 4308.138372325485, 4411.316245981878, 4417.53615870329, 4420.756858372821, 8624.965004199536, 8651.95674021626, 8659.870101948476, 8660.696875609323, 8664.436811607226, 4338.696291259582, 5055.69665820052, 5059.2916991621205, 5066.797328106834, 5080.13637681595, 4097.590240975938, 4154.626083652486, 4266.1254442839145, 4387.078713470143, 4749.145153872267, 3536.29274145172, 3558.288342331544, 3575.3385623659187, 3621.0, 3629.9818500907854, 1113.5768408004906, 1131.7453572946022, 1134.5291703942771, 1137.852079229695, 1144.2390810111349, 801.7092481105416, 806.4435539477859, 835.5780330932962, 874.3748220022715, 923.5382308845577, 5341.626086174008, 5514.365847927459, 5515.917261241005, 5674.659520428705, 5698.1452782083015, 828.9792755181169, 879.4371602174614, 893.446063440671, 896.2158111652304, 896.4128495835238, 3538.486919396673, 3608.891733248007, 3694.0, 3705.4071348584635, 3760.7401520490944, 9652.083052110178, 9900.653477128266, 10005.19958403326, 10233.43716095607, 10244.436555989492, 9898.356606820533, 9999.800003999879, 10198.643047493302, 10213.795724085478, 10215.795684086277, 7599.696012159452, 7768.92231077682, 7777.766666999886, 7794.9220507795335, 7797.532148071134, 5258.6581872178185, 5423.891522169613, 5553.72231388437, 5583.944160558505, 5612.045952188057, 7185.928140718526, 9299.907000929905, 9315.813683726152, 9401.388909720847, 9509.144177024102, 8379.790505237417, 8794.780130496789, 9360.906390936003, 9620.230381569318, 9625.229981601595, 6137.877242455215, 6635.303293154149, 6859.0, 7161.319674630901, 7170.282971702788, 7830.960845195737, 7893.763187104396, 7944.84110317802, 7986.960065199637, 8062.717804876801, 7086.929130708627, 7126.964365178141, 7140.571565706075, 7199.172095208908, 7231.16841563227, 6765.830854228684, 6782.96608516974, 6954.513184077066, 7004.089468369283, 7147.749828755968, 6535.477161827138, 6684.0642310075655, 6745.460363171032, 6745.561538499982, 6757.594544327356, 3942.8619998299923, 4789.856304310876, 4810.783514741915, 4828.66199366041, 4878.170710979213, 4726.1729197391005, 4733.857984260478, 4790.065937142223, 4804.159272127294, 4909.5090490950615], "value": 4305.177742676394, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.7057748118104, 301.1447489130869, 308.7113548831842, 313.8054406268114, 315.4983576113978, 4400.647948164144, 4950.3069570260195, 5201.843944681666, 5231.378272735455, 5244.501772331627, 4079.9796001020027, 4340.652747780178, 5121.257417674445, 5139.331886854713, 5184.637075404714, 3996.7002474814503, 4017.4576432181575, 4020.3165461871404, 4035.5157381114236, 4037.454943582623, 3124.0324787519835, 3421.853679017532, 4037.576054514263, 4048.7570745755347, 4069.1658210066867, 3449.6550344965553, 3923.1761330120707, 3931.9017024574327, 3934.40983852423, 3943.3099207638693, 3236.417444859924, 3919.451276821233, 3926.8821935341984, 3938.4683067786054, 3949.8868105563674, 3072.5237588173645, 3294.4399452093203, 3405.9318813623836, 3432.1591210153424, 3441.380551500729, 4177.895552611209, 4396.582324679151, 4635.860924172281, 5065.290859279704, 5065.758889072201, 4504.797284122223, 4505.52691967342, 4510.226210880318, 4511.345854851028, 4529.230030894756, 5292.285541451932, 5400.567954563665, 5461.344638643388, 5462.235287059815, 5488.259085023511, 3602.783832970004, 3705.6294370562996, 3732.757370770891, 3745.150030710237, 3754.2116155607355, 5728.484436400702, 5736.368999410061, 5741.540676745851, 5743.942560574341, 5802.33273173582, 2836.858157092137, 3482.495038219444, 3496.230829217568, 3801.6578507933864, 3804.505414296179, 3855.267499175148, 3878.7478813877005, 3882.7187028281164, 3884.3979183226643, 3894.532656081232, 4476.9104617908115, 4503.392042074278, 4516.796744146522, 4532.478764942008, 4546.227141385973, 4618.399608050931, 5282.471752824764, 5495.477929596682, 5511.559075273968, 5536.86157846049, 1238.578883179723, 1240.5409998300686, 1247.762925044257, 1250.831137796404, 1257.3713143428286, 822.1819289806562, 845.6067928413318, 854.9016863060704, 861.7156338408311, 866.9769671787351, 4303.462067241595, 4855.417349918032, 4890.070886531547, 4938.358013458227, 4967.726775027409, 4554.973036998504, 4632.5830675239395, 5319.46805319465, 5349.839504814861, 5354.678719276778, 4176.979115104405, 4407.867763967086, 4717.466823282447, 4899.706017638953, 4927.926081108786, 2319.93040208794, 2593.560573881514, 2636.4990651776484, 2693.1381957773715, 2770.4373454554457, 4773.992384797625, 4858.562729354375, 5521.558275337963, 5531.695756733418, 5541.9168712469345, 5043.798248070109, 5117.897642047213, 5432.375276843135, 5475.945240547623, 5493.395726470165, 5090.541851233481, 5340.91988620171, 5469.371022332403, 5490.780368785284, 5506.834794956158, 4754.881127971828, 5290.629655924048, 5297.636001139227, 5306.628536002597, 5366.517013468807, 4576.3593096967115, 4943.332650092335, 5009.899802004013, 5053.2420136979745, 5225.346831646044, 5155.422288855572, 5403.729813509387, 5496.5053145217125, 5525.789684126382, 5949.345571986992, 3346.5816772903386, 3596.6583174598372, 3644.6902013328104, 3762.6237376262156, 3785.6782173515558, 5257.634064670837, 5644.758153206288, 5669.886602268014, 5679.34687510925, 5755.625884317506, 4647.62818974488, 5563.693996830213, 5574.0736113540715, 5577.521956681401, 5579.6652200868075, 4863.635227357891, 5218.347706536683, 5523.530499907392, 5567.081431563746, 5577.77688892456, 4435.556444355668, 4492.73043617384, 5379.542738867083, 5406.621536492407, 5637.661740295595, 4666.673332866667, 4850.102730994778, 5366.597505187048, 5562.610617256915, 5578.916316255179, 4184.727992680466, 4594.701344412736, 5368.812091576776, 5441.2654291670115, 5448.400675925565], "value": 3913.4844087778993, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.521799522339, 711.9572825630463, 721.8700633885899, 756.1644382956832, 765.5291995422816, 13817.58547243583, 15568.377264909404, 16241.269142888568, 16381.508554743346, 16525.008499490024, 14407.207603581799, 14670.0, 14766.704665906675, 14870.33083511242, 14923.626909327273, 794.3605397654885, 815.5881279953616, 816.7141500474846, 816.8774683797418, 819.8852160697508, 648.6659370424235, 669.4677731203705, 673.2358772792891, 809.6923169195697, 815.6451943404638, 736.8784150615143, 825.6078362777663, 846.8814365988767, 856.6659002988839, 857.939944203906, 818.7543736878913, 820.4584973917187, 830.6345208108413, 838.6058552480324, 844.8986121665378, 499.0412630425565, 510.9616778741609, 631.2330518420096, 632.2192092765431, 633.0472638678805, 12074.456649450707, 12245.816312755227, 12248.203866748632, 12259.448324825315, 13600.31998400086, 13133.737325253442, 13493.86506134936, 13684.65788355289, 13774.724505509834, 13801.309934503335, 11157.66527004191, 11232.157588180919, 11270.492827822685, 11425.942870285595, 11636.534538618445, 3637.3270944875017, 3652.6164752700847, 3676.1912379276782, 3694.058015206132, 3821.541415030214, 87347.99999999936, 88188.6771698425, 88251.67622485668, 88838.5558072212, 88900.11099888982, 10246.590136394461, 10349.120324772479, 10401.219908506891, 10442.63450779219, 10814.621488247874, 21222.25722099719, 21233.150673973178, 21284.36146915595, 21304.934753262274, 21323.68014479784, 7419.29516695913, 7440.144383395978, 7525.096988361432, 7552.546847189186, 7583.658735356922, 4194.266003449384, 4221.451211342506, 4251.532331443539, 4267.76527291002, 4366.934495982563, 3382.1206486313736, 3394.0496660934978, 3430.8970730878113, 3500.6149323574386, 3503.491993660905, 616.7163104971673, 617.1267656266423, 629.9275583307887, 640.7308930249307, 642.5213216153902, 547.6659237864899, 567.1237937386747, 601.3475379213514, 651.7555916531301, 665.7969319357643, 7405.370543503755, 8651.803875120562, 8864.290856731446, 8881.777955551162, 8944.105589441004, 593.365457369279, 625.6058683029662, 632.4782054804805, 648.8021153548214, 661.0976017735757, 3897.70767192456, 4287.3140297552245, 4408.184485870124, 4411.448568928884, 4507.3689683444345, 72855.54288914298, 93221.5338923301, 102795.0, 111822.0, 117279.0, 92792.0, 94010.0, 95239.04760952301, 96263.0, 97441.512792437, 84191.0, 87038.0, 87303.12696873076, 88234.55882720546, 90516.09483905208, 8276.834463310579, 8598.742037738877, 8607.870881936651, 8778.956105219433, 8856.0, 44627.55372446234, 54408.455915441926, 55499.72250138724, 55919.72040139773, 57642.0, 75009.6249518749, 82994.3401131986, 83494.58252708941, 84356.0, 85606.71589926032, 11537.423128843357, 11546.480408381474, 11656.18406711522, 11667.824982625438, 12164.635060948185, 11792.41037948082, 12035.458404371999, 12042.096842737004, 12576.937115314366, 12602.55891043809, 11510.36692981894, 11757.47091380907, 11773.17587768848, 11886.524539018343, 11889.28664280146, 11606.94196529012, 11717.0, 11766.176367654181, 11857.762844743229, 11984.940075299568, 11559.422028898352, 11642.301461912313, 11680.532778689098, 11820.0, 11851.940740296588, 9026.774330641787, 9546.782530526742, 9731.805363892825, 9939.602415903282, 10197.847032294374, 7281.0, 10054.49727513636, 10060.69817905464, 10111.949440252753, 10156.593736250466], "value": 7063.225308556495, "unit": "score"}], "name": "dromaeo_css", "value": 7142.153039274326, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 113.0, 113.0, 114.0, 113.0, 113.0, 109.0, 113.0, 113.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 113.0, 113.0, 148.0, 109.0, 105.0, 110.0, 110.0, 146.0, 118.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 184.0, 184.0, 201.0, 244.0, 185.0, 181.0, 182.0, 180.0], "value": 190.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 59.0, 90.0, 63.0, 76.0, 81.0, 80.0, 86.0, 58.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 73.0, 95.0, 93.0, 53.0, 67.0, 108.0, 96.0, 99.0, 130.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 84.0, 86.0, 89.0, 132.0, 83.0, 86.0, 83.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 93.0, 91.0, 92.0, 92.0, 93.0, 92.0], "value": 92.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 79.0, 79.0, 81.0, 84.0, 84.0, 86.0, 82.0, 83.0], "value": 82.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 54.0, 54.0, 56.0, 55.0, 56.0, 55.0, 56.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 69.0, 77.0, 73.0, 68.0, 75.0, 101.0, 102.0, 91.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 90.0, 90.0, 89.0, 85.0, 88.0, 78.0, 85.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 146.0, 150.0, 146.0, 147.0, 145.0, 147.0, 175.0, 219.0, 183.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 189.0, 227.0, 175.0, 174.0, 175.0, 179.0, 177.0, 176.0, 174.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 68.0, 67.0, 70.0, 67.0, 85.0, 67.0, 67.0], "value": 69.6, "unit": "score"}], "name": "kraken", "value": 1483.2999999999997, "alertThreshold": 2.0}]} 08:57:59 INFO - Return code: 0 08:57:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:57:59 INFO - # TBPL SUCCESS # 08:57:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:57:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:57:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:57:59 INFO - 2016-11-08 08:57:59.804 screenresolution[3570:60573] starting screenresolution argv=screenresolution get 08:57:59 INFO - 2016-11-08 08:57:59.808 screenresolution[3570:60573] Display 0: 1600x1200x32@60 08:57:59 INFO - 2016-11-08 08:57:59.814 screenresolution[3571:60576] starting screenresolution argv=screenresolution list 08:57:59 INFO - Available Modes on Display 0 08:57:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:57:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:57:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:57:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:58:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:58:00 INFO - Intel Iris: 08:58:00 INFO - Chipset Model: Intel Iris 08:58:00 INFO - Type: GPU 08:58:00 INFO - Bus: Built-In 08:58:00 INFO - VRAM (Dynamic, Max): 1536 MB 08:58:00 INFO - Vendor: Intel (0x8086) 08:58:00 INFO - Device ID: 0x0a2e 08:58:00 INFO - Revision ID: 0x0009 08:58:00 INFO - Displays: 08:58:00 INFO - Display: 08:58:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:58:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:58:00 INFO - Display Serial Number: 1600x1200 60 08:58:00 INFO - Main Display: Yes 08:58:00 INFO - Mirror: Off 08:58:00 INFO - Online: Yes 08:58:00 INFO - Rotation: Supported 08:58:00 INFO - Return code: 0 08:58:00 INFO - Running post-action listener: _resource_record_post_action 08:58:00 INFO - [mozharness: 2016-11-08 16:58:00.064542Z] Finished run-tests step (success) 08:58:00 INFO - Running post-run listener: _resource_record_post_run 08:58:00 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 204800; Write bytes: 702284288; Read time: 1; Write time: 1994 08:58:00 INFO - TinderboxPrint: CPU usage
53.6% 08:58:00 INFO - TinderboxPrint: I/O read bytes / time
204,800 / 1 08:58:00 INFO - TinderboxPrint: I/O write bytes / time
702,284,288 / 1,994 08:58:00 INFO - TinderboxPrint: CPU idle
2,250.9 (46.4%) 08:58:00 INFO - TinderboxPrint: CPU system
972.1 (20.0%) 08:58:00 INFO - TinderboxPrint: CPU user
1,630.6 (33.6%) 08:58:00 INFO - TinderboxPrint: Swap in / out
402,849,792 / 0 08:58:00 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 396538368; Write bytes: 388405248; Read time: 29289; Write time: 830 08:58:00 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 204800; Write bytes: 295770624; Read time: 1; Write time: 1113 08:58:00 INFO - Running post-run listener: _upload_blobber_files 08:58:00 INFO - Blob upload gear active. 08:58:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:58:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:58:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:58:01 INFO - (blobuploader) - INFO - Open directory for files ... 08:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:01 INFO - (blobuploader) - INFO - Done attempting. 08:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:58:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:02 INFO - (blobuploader) - INFO - Done attempting. 08:58:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:58:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:58:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:04 INFO - (blobuploader) - INFO - Done attempting. 08:58:04 INFO - (blobuploader) - INFO - Iteration through files over. 08:58:04 INFO - Return code: 0 08:58:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:58:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:58:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eebc799fc6d347850af4de77b53ebf5554817843b4606d09284deb3e05332ff01fdbbceafeb4ce892e81567a43834e2ba8708afbfeb0aeb03f0df62b543e7a53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20da3853621b09e4d7cbf296b66a8b3de63eaa6d408d329cca9339e390938ac16a14d1a75a61c5fb92b8a9dc9b8c8436fa6b8adfbf12906da3045944e95c328a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40df503160231d9f135e97306704232219b241f8e2fee0d6d3e61083dc557624fdc6294c44137af32a72e3651b626046a443250e9f2a6d380fd7085d3ff5c89c"} 08:58:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:58:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:58:04 INFO - Contents: 08:58:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eebc799fc6d347850af4de77b53ebf5554817843b4606d09284deb3e05332ff01fdbbceafeb4ce892e81567a43834e2ba8708afbfeb0aeb03f0df62b543e7a53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20da3853621b09e4d7cbf296b66a8b3de63eaa6d408d329cca9339e390938ac16a14d1a75a61c5fb92b8a9dc9b8c8436fa6b8adfbf12906da3045944e95c328a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40df503160231d9f135e97306704232219b241f8e2fee0d6d3e61083dc557624fdc6294c44137af32a72e3651b626046a443250e9f2a6d380fd7085d3ff5c89c"} 08:58:04 INFO - Running post-run listener: copy_logs_to_upload_dir 08:58:04 INFO - Copying logs to upload dir... 08:58:04 INFO - mkdir: /builds/slave/test/build/upload/logs 08:58:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.694454 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-08 08:58:05.048652) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 08:58:05.051970) ========= 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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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/Firefox/sha512/eebc799fc6d347850af4de77b53ebf5554817843b4606d09284deb3e05332ff01fdbbceafeb4ce892e81567a43834e2ba8708afbfeb0aeb03f0df62b543e7a53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20da3853621b09e4d7cbf296b66a8b3de63eaa6d408d329cca9339e390938ac16a14d1a75a61c5fb92b8a9dc9b8c8436fa6b8adfbf12906da3045944e95c328a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40df503160231d9f135e97306704232219b241f8e2fee0d6d3e61083dc557624fdc6294c44137af32a72e3651b626046a443250e9f2a6d380fd7085d3ff5c89c"} build_url:https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010121 build_url: 'https://queue.taskcluster.net/v1/task/eAsnFN9ySrKGcp3a-isAng/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eebc799fc6d347850af4de77b53ebf5554817843b4606d09284deb3e05332ff01fdbbceafeb4ce892e81567a43834e2ba8708afbfeb0aeb03f0df62b543e7a53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20da3853621b09e4d7cbf296b66a8b3de63eaa6d408d329cca9339e390938ac16a14d1a75a61c5fb92b8a9dc9b8c8436fa6b8adfbf12906da3045944e95c328a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40df503160231d9f135e97306704232219b241f8e2fee0d6d3e61083dc557624fdc6294c44137af32a72e3651b626046a443250e9f2a6d380fd7085d3ff5c89c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 08:58:05.083049) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:58:05.083338) ========= 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.Vjt9VJjTWE/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.U8cmPTxk6o/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.005520 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 08:58:05.131997) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 08:58:05.132286) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 08:58:05.132581) ========= ========= Total master_lag: 0.27 =========