builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0382 starttime: 1483006331.08 results: success (0) buildid: 20161229004004 builduid: eee9dec34d4d4c24adb6508f9463f45f revision: 7f4072c726a0f6321320baceb534e288ce8b1e33 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:11.081685) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:11.082196) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:11.082485) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:11.109410) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:11.109733) ========= 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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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-12-29 02:12:11-- 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% 16.1M=0.001s 2016-12-29 02:12:11 (16.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.480028 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:11.647633) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:11.647982) ========= 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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.024816 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:11.696277) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-29 02:12:11.696592) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7f4072c726a0f6321320baceb534e288ce8b1e33 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7f4072c726a0f6321320baceb534e288ce8b1e33 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.POaPDq6cPu/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.LS3yJ7DoZp/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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-12-29 02:12:11,791 truncating revision to first 12 chars 2016-12-29 02:12:11,791 Setting DEBUG logging. 2016-12-29 02:12:11,791 attempt 1/10 2016-12-29 02:12:11,791 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7f4072c726a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 02:12:14,417 unpacking tar archive at: mozilla-aurora-7f4072c726a0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.895994 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-29 02:12:14.616232) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:14.616578) ========= script_repo_revision: 7f4072c726a0f6321320baceb534e288ce8b1e33 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:14.616997) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:14.617284) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 02:12:14.636949) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-12-29 02:12:14.637235) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.POaPDq6cPu/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.LS3yJ7DoZp/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 02:12:14 INFO - MultiFileLogger online at 20161229 02:12:14 in /builds/slave/test 02:12:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:12:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:12:14 INFO - {'append_to_log': False, 02:12:14 INFO - 'base_work_dir': '/builds/slave/test', 02:12:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:12:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:12:14 INFO - 'branch': 'Mozilla-Aurora', 02:12:14 INFO - 'buildbot_json_path': 'buildprops.json', 02:12:14 INFO - 'config_files': ('talos/mac_config.py',), 02:12:14 INFO - 'default_actions': ('clobber', 02:12:14 INFO - 'read-buildbot-config', 02:12:14 INFO - 'download-and-extract', 02:12:14 INFO - 'populate-webroot', 02:12:14 INFO - 'create-virtualenv', 02:12:14 INFO - 'install', 02:12:14 INFO - 'run-tests'), 02:12:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:12:14 INFO - 'download_minidump_stackwalk': True, 02:12:14 INFO - 'download_symbols': 'ondemand', 02:12:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:12:14 INFO - 'tooltool.py': '/tools/tooltool.py', 02:12:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:12:14 INFO - '/tools/misc-python/virtualenv.py')}, 02:12:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:12:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:12:14 INFO - 'installer_path': 'installer.exe', 02:12:14 INFO - 'log_level': 'info', 02:12:14 INFO - 'log_name': 'talos', 02:12:14 INFO - 'log_to_console': True, 02:12:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:12:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:12:14 INFO - 'opt_config_files': (), 02:12:14 INFO - 'pip_index': False, 02:12:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:12:14 INFO - 'cmd': ('bash', 02:12:14 INFO - '-c', 02:12:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:12:14 INFO - 'enabled': True, 02:12:14 INFO - 'halt_on_failure': False, 02:12:14 INFO - 'name': 'check_screen_resolution'},), 02:12:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:12:14 INFO - 'cmd': ('bash', 02:12:14 INFO - '-c', 02:12:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:12:14 INFO - 'enabled': True, 02:12:14 INFO - 'halt_on_failure': False, 02:12:14 INFO - 'name': 'check_screen_resolution'},), 02:12:14 INFO - 'run_cmd_checks_enabled': True, 02:12:14 INFO - 'sps_profile': False, 02:12:14 INFO - 'sps_profile_interval': 0, 02:12:14 INFO - 'suite': 'dromaeojs', 02:12:14 INFO - 'system_bits': '32', 02:12:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:12:14 INFO - 'title': 't-yosemite-r7-0382', 02:12:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:12:14 INFO - 'use_talos_json': True, 02:12:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:12:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:12:14 INFO - 'work_dir': 'build'} 02:12:14 INFO - [mozharness: 2016-12-29 10:12:14.836282Z] Running clobber step. 02:12:14 INFO - Running pre-action listener: _resource_record_pre_action 02:12:14 INFO - Running main action method: clobber 02:12:14 INFO - rmtree: /builds/slave/test/build 02:12:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:12:17 INFO - Running post-action listener: _resource_record_post_action 02:12:17 INFO - [mozharness: 2016-12-29 10:12:17.571512Z] Finished clobber step (success) 02:12:17 INFO - [mozharness: 2016-12-29 10:12:17.571661Z] Running read-buildbot-config step. 02:12:17 INFO - Running pre-action listener: _resource_record_pre_action 02:12:17 INFO - Running main action method: read_buildbot_config 02:12:17 INFO - Using buildbot properties: 02:12:17 INFO - { 02:12:17 INFO - "project": "", 02:12:17 INFO - "product": "firefox", 02:12:17 INFO - "script_repo_revision": "production", 02:12:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:12:17 INFO - "repository": "", 02:12:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:12:17 INFO - "buildid": "20161229004004", 02:12:17 INFO - "stage_platform": "macosx64", 02:12:17 INFO - "basedir": "/builds/slave/test", 02:12:17 INFO - "nightly_build": "True", 02:12:17 INFO - "buildnumber": 52, 02:12:17 INFO - "slavename": "t-yosemite-r7-0382", 02:12:17 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:12:17 INFO - "platform": "yosemite_r7", 02:12:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:12:17 INFO - "branch": "mozilla-aurora", 02:12:17 INFO - "repo_path": "releases/mozilla-aurora", 02:12:17 INFO - "revision": "7f4072c726a0f6321320baceb534e288ce8b1e33", 02:12:17 INFO - "pgo_build": "False", 02:12:17 INFO - "builduid": "eee9dec34d4d4c24adb6508f9463f45f", 02:12:17 INFO - "slavebuilddir": "test" 02:12:17 INFO - } 02:12:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:12:17 INFO - Running post-action listener: _resource_record_post_action 02:12:17 INFO - [mozharness: 2016-12-29 10:12:17.574946Z] Finished read-buildbot-config step (success) 02:12:17 INFO - [mozharness: 2016-12-29 10:12:17.575051Z] Running download-and-extract step. 02:12:17 INFO - Running pre-action listener: _resource_record_pre_action 02:12:17 INFO - Running main action method: download_and_extract 02:12:17 INFO - mkdir: /builds/slave/test/build/tests 02:12: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')]} 02:12:17 INFO - trying https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:12:17 INFO - Downloading https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:12:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:12:18 INFO - Downloaded 1199 bytes. 02:12:18 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:12:18 INFO - Using the following test package requirements: 02:12:18 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:12:18 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:12:18 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:12:18 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:12:18 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:12:18 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:12:18 INFO - u'jsshell-mac.zip'], 02:12:18 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:12:18 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:12:18 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:12:18 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:12:18 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:12:18 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:12:18 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:12:18 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:12:18 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:12:18 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:12:18 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:12:18 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:12:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:12:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:12:18 INFO - Fetch https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:12:19 INFO - Http code: 200 02:12:19 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 02:12:19 INFO - x-amz-cf-id: faM3iiEOD27Nr0HG1Gi8PUwua5Pf6KQF-yzxpK5L8fwP8J6Y31gcpg== 02:12:19 INFO - x-amz-version-id: v_BUtgXLJcgPDTRpZEnvEoxBul.i_Woe 02:12:19 INFO - Expected file size: 16268368 02:12:19 INFO - Obtained file size: 16268368 02:12:25 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:12:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:12:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:12:25 INFO - Fetch https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:12:26 INFO - Http code: 200 02:12:26 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 02:12:26 INFO - x-amz-cf-id: LKmuxrvUjC5if1tr_eGhF3X-AO34VEAU6wbhI_0StB9abC8G0x3Vcw== 02:12:26 INFO - x-amz-version-id: v_BUtgXLJcgPDTRpZEnvEoxBul.i_Woe 02:12:26 INFO - Expected file size: 16268368 02:12:26 INFO - Obtained file size: 16268368 02:12:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:12:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:12:32 INFO - Fetch https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:12:33 INFO - Http code: 200 02:12:33 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 02:12:33 INFO - x-amz-cf-id: H3dIpCJ1ewiOWZPtmSldO2HXqjYFyDckg1IIfhkedFDUOL1RLUFTtw== 02:12:33 INFO - x-amz-version-id: mRI44Rvz6pesmD7i9AOpppTss371voPy 02:12:34 INFO - Expected file size: 14409038 02:12:34 INFO - Obtained file size: 14409038 02:12: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')]} 02:12:34 INFO - trying https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:12:34 INFO - Downloading https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:12:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:12:44 INFO - Downloaded 109949694 bytes. 02:12:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:12:44 INFO - mkdir: /builds/slave/test/properties 02:12:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:12:44 INFO - Writing to file /builds/slave/test/properties/build_url 02:12:44 INFO - Contents: 02:12:44 INFO - build_url:https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:12:44 INFO - Running post-action listener: _resource_record_post_action 02:12:44 INFO - Running post-action listener: set_extra_try_arguments 02:12:44 INFO - [mozharness: 2016-12-29 10:12:44.946225Z] Finished download-and-extract step (success) 02:12:44 INFO - [mozharness: 2016-12-29 10:12:44.946342Z] Running populate-webroot step. 02:12:44 INFO - Running pre-action listener: _resource_record_pre_action 02:12:44 INFO - Running main action method: populate_webroot 02:12:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:12:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:12:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:12:44 INFO - '--tppagecycles', 02:12:44 INFO - '7'], 02:12:44 INFO - 'tests': ['tsvgm']}}, 02:12:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:12:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:12:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:12:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:12:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:12:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:12:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:12:44 INFO - 'talos_options': ['--disable-e10s'], 02:12:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:12:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:12:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:12:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:12:44 INFO - 'talos_options': ['--disable-e10s'], 02:12:44 INFO - 'tests': ['damp', 'tps']}, 02:12:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:12:44 INFO - 'tests': ['damp', 'tps']}, 02:12:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:12:44 INFO - 'tests': ['dromaeo_dom']}, 02:12:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:12:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:12:44 INFO - 'tests': ['basic_compositor_video']}, 02:12:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:12:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:12:44 INFO - 'tests': ['a11yr', 02:12:44 INFO - 'ts_paint', 02:12:44 INFO - 'tpaint', 02:12:44 INFO - 'sessionrestore', 02:12:44 INFO - 'sessionrestore_no_auto_restore', 02:12:44 INFO - 'tabpaint']}, 02:12:44 INFO - 'other-e10s': {'tests': ['a11yr', 02:12:44 INFO - 'ts_paint', 02:12:44 INFO - 'tpaint', 02:12:44 INFO - 'sessionrestore', 02:12:44 INFO - 'sessionrestore_no_auto_restore', 02:12:44 INFO - 'tabpaint']}, 02:12:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:12:44 INFO - 'ts_paint', 02:12:44 INFO - 'tpaint', 02:12:44 INFO - 'sessionrestore', 02:12:44 INFO - 'sessionrestore_no_auto_restore', 02:12:44 INFO - 'tabpaint']}, 02:12:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:12:44 INFO - 'ts_paint', 02:12:44 INFO - 'tpaint', 02:12:44 INFO - 'sessionrestore', 02:12:44 INFO - 'sessionrestore_no_auto_restore', 02:12:44 INFO - 'tabpaint']}, 02:12:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:12:44 INFO - 'tests': ['a11yr', 02:12:44 INFO - 'ts_paint', 02:12:44 INFO - 'tpaint', 02:12:44 INFO - 'sessionrestore', 02:12:44 INFO - 'sessionrestore_no_auto_restore', 02:12:44 INFO - 'tabpaint']}, 02:12:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:12:44 INFO - 'tests': ['a11yr', 02:12:44 INFO - 'ts_paint', 02:12:44 INFO - 'tpaint', 02:12:44 INFO - 'sessionrestore', 02:12:44 INFO - 'sessionrestore_no_auto_restore', 02:12:44 INFO - 'tabpaint']}, 02:12:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:12:44 INFO - 'tests': ['tsvgx', 02:12:44 INFO - 'tsvgr_opacity', 02:12:44 INFO - 'tart', 02:12:44 INFO - 'tscrollx', 02:12:44 INFO - 'cart']}, 02:12:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:12:44 INFO - 'tsvgr_opacity', 02:12:44 INFO - 'tart', 02:12:44 INFO - 'tscrollx', 02:12:44 INFO - 'cart']}, 02:12:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:12:44 INFO - 'talos_options': ['--disable-e10s'], 02:12:44 INFO - 'tests': ['tp5o']}, 02:12:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:12:44 INFO - 'tests': ['tp5o']}, 02:12:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:12:44 INFO - 'talos_options': ['--disable-e10s', 02:12:44 INFO - '--xperf_path', 02:12:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:12:44 INFO - 'tests': ['tp5n']}, 02:12:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:12:44 INFO - 'talos_options': ['--xperf_path', 02:12:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:12:44 INFO - 'tests': ['tp5n']}}, 02:12:44 INFO - 'talos.zip': {'path': '', 02:12:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:12:44 INFO - Running post-action listener: _resource_record_post_action 02:12:44 INFO - [mozharness: 2016-12-29 10:12:44.964906Z] Finished populate-webroot step (success) 02:12:44 INFO - [mozharness: 2016-12-29 10:12:44.965035Z] Running create-virtualenv step. 02:12:44 INFO - Running pre-action listener: _resource_record_pre_action 02:12:44 INFO - Running main action method: create_virtualenv 02:12:44 INFO - Creating virtualenv /builds/slave/test/build/venv 02:12:44 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 02:12:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:12:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:12:45 INFO - Using real prefix '/tools/python27' 02:12:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:12:45 INFO - Installing distribute.............................................................................................................................................................................................done. 02:12:48 INFO - Installing pip.................done. 02:12:48 INFO - Return code: 0 02:12:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:12:48 INFO - Reading from file tmpfile_stdout 02:12:48 INFO - Output received: 02:12:48 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) 02:12:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106356490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e3a496fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10616ad30>, '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 0x105fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106162b20>, '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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 02:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:12:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POaPDq6cPu/Render', 02:12:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:48 INFO - 'HOME': '/Users/cltbld', 02:12:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:48 INFO - 'LOGNAME': 'cltbld', 02:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:48 INFO - 'MOZ_NO_REMOTE': '1', 02:12:48 INFO - 'NO_EM_RESTART': '1', 02:12:48 INFO - 'PAGER': '/bin/cat', 02:12:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:48 INFO - 'PWD': '/builds/slave/test', 02:12:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:48 INFO - 'SHELL': '/bin/bash', 02:12:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LS3yJ7DoZp/Listeners', 02:12:48 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 02:12:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:48 INFO - 'USER': 'cltbld', 02:12:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:48 INFO - 'XPC_FLAGS': '0x0', 02:12:48 INFO - 'XPC_SERVICE_NAME': '0', 02:12:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:49 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 02:12:49 INFO - Cleaning up... 02:12:49 INFO - Return code: 0 02:12:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:49 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 0x106356490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e3a496fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10616ad30>, '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 0x105fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106162b20>, '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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 02:12:49 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 02:12:49 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 02:12:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POaPDq6cPu/Render', 02:12:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:49 INFO - 'HOME': '/Users/cltbld', 02:12:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:49 INFO - 'LOGNAME': 'cltbld', 02:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:49 INFO - 'MOZ_NO_REMOTE': '1', 02:12:49 INFO - 'NO_EM_RESTART': '1', 02:12:49 INFO - 'PAGER': '/bin/cat', 02:12:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:49 INFO - 'PWD': '/builds/slave/test', 02:12:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:49 INFO - 'SHELL': '/bin/bash', 02:12:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LS3yJ7DoZp/Listeners', 02:12:49 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 02:12:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:49 INFO - 'USER': 'cltbld', 02:12:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:49 INFO - 'XPC_FLAGS': '0x0', 02:12:49 INFO - 'XPC_SERVICE_NAME': '0', 02:12:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:49 INFO - Downloading/unpacking psutil>=3.1.1 02:12:49 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 02:12:49 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 02:12:49 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 02:12:49 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 02:12:49 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 02:12:49 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 02:12:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:12:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:12:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:12:53 INFO - Installing collected packages: psutil 02:12:53 INFO - Running setup.py install for psutil 02:12:53 INFO - building 'psutil._psutil_osx' extension 02:12:53 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 02:12:54 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 02:12:54 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 02:12:54 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 02:12:54 INFO - building 'psutil._psutil_posix' extension 02:12:54 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 02:12:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:12:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:12:54 INFO - ^ 02:12:54 INFO - 1 warning generated. 02:12:54 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 02:12:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:12:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:12:54 INFO - Successfully installed psutil 02:12:54 INFO - Cleaning up... 02:12:54 INFO - Return code: 0 02:12:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:54 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 0x106356490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e3a496fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10616ad30>, '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 0x105fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106162b20>, '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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 02:12:54 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 02:12:54 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 02:12:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POaPDq6cPu/Render', 02:12:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:54 INFO - 'HOME': '/Users/cltbld', 02:12:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:54 INFO - 'LOGNAME': 'cltbld', 02:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:54 INFO - 'MOZ_NO_REMOTE': '1', 02:12:54 INFO - 'NO_EM_RESTART': '1', 02:12:54 INFO - 'PAGER': '/bin/cat', 02:12:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:54 INFO - 'PWD': '/builds/slave/test', 02:12:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:54 INFO - 'SHELL': '/bin/bash', 02:12:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LS3yJ7DoZp/Listeners', 02:12:54 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 02:12:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:54 INFO - 'USER': 'cltbld', 02:12:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:54 INFO - 'XPC_FLAGS': '0x0', 02:12:54 INFO - 'XPC_SERVICE_NAME': '0', 02:12:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:12:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:12:57 INFO - Installing collected packages: mozsystemmonitor 02:12:57 INFO - Running setup.py install for mozsystemmonitor 02:12:57 INFO - Successfully installed mozsystemmonitor 02:12:57 INFO - Cleaning up... 02:12:57 INFO - Return code: 0 02:12:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:57 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 0x106356490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e3a496fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10616ad30>, '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 0x105fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106162b20>, '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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 02:12:57 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 02:12:57 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 02:12:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POaPDq6cPu/Render', 02:12:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:57 INFO - 'HOME': '/Users/cltbld', 02:12:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:57 INFO - 'LOGNAME': 'cltbld', 02:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:57 INFO - 'MOZ_NO_REMOTE': '1', 02:12:57 INFO - 'NO_EM_RESTART': '1', 02:12:57 INFO - 'PAGER': '/bin/cat', 02:12:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:57 INFO - 'PWD': '/builds/slave/test', 02:12:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:57 INFO - 'SHELL': '/bin/bash', 02:12:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LS3yJ7DoZp/Listeners', 02:12:57 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 02:12:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:57 INFO - 'USER': 'cltbld', 02:12:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:57 INFO - 'XPC_FLAGS': '0x0', 02:12:57 INFO - 'XPC_SERVICE_NAME': '0', 02:12:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:58 INFO - Downloading/unpacking jsonschema==2.5.1 02:12:58 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 02:12:58 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 02:12:58 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 02:12:58 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 02:12:58 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 02:12:58 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 02:13:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:13:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:13:00 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 02:13:00 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 02:13:00 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 02:13:00 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 02:13:00 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 02:13:00 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 02:13:01 INFO - Downloading functools32-3.2.3-2.tar.gz 02:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:13:01 INFO - warning: no files found matching '*.txt' 02:13:01 INFO - no previously-included directories found matching 'build' 02:13:01 INFO - no previously-included directories found matching 'dist' 02:13:01 INFO - no previously-included directories found matching '.git*' 02:13:01 INFO - Installing collected packages: jsonschema, functools32 02:13:01 INFO - Running setup.py install for functools32 02:13:01 INFO - warning: no files found matching '*.txt' 02:13:01 INFO - no previously-included directories found matching 'build' 02:13:01 INFO - no previously-included directories found matching 'dist' 02:13:01 INFO - no previously-included directories found matching '.git*' 02:13:01 INFO - Successfully installed jsonschema functools32 02:13:01 INFO - Cleaning up... 02:13:01 INFO - Return code: 0 02:13:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:13:01 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')]} 02:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:01 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')]} 02:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:01 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 0x106356490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e3a496fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10616ad30>, '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 0x105fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106162b20>, '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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 02:13:01 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 02:13:01 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 02:13:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POaPDq6cPu/Render', 02:13:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:01 INFO - 'HOME': '/Users/cltbld', 02:13:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:01 INFO - 'LOGNAME': 'cltbld', 02:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:01 INFO - 'MOZ_NO_REMOTE': '1', 02:13:01 INFO - 'NO_EM_RESTART': '1', 02:13:01 INFO - 'PAGER': '/bin/cat', 02:13:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:01 INFO - 'PWD': '/builds/slave/test', 02:13:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:01 INFO - 'SHELL': '/bin/bash', 02:13:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LS3yJ7DoZp/Listeners', 02:13:01 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 02:13:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:01 INFO - 'USER': 'cltbld', 02:13:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:01 INFO - 'XPC_FLAGS': '0x0', 02:13:01 INFO - 'XPC_SERVICE_NAME': '0', 02:13:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:13:01 INFO - Cleaning up... 02:13:01 INFO - Return code: 0 02:13:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:13:01 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')]} 02:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:01 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')]} 02:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:01 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 0x106356490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e3a496fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10616ad30>, '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 0x105fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106162b20>, '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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 02:13:01 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 02:13:01 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 02:13:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POaPDq6cPu/Render', 02:13:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:01 INFO - 'HOME': '/Users/cltbld', 02:13:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:01 INFO - 'LOGNAME': 'cltbld', 02:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:01 INFO - 'MOZ_NO_REMOTE': '1', 02:13:01 INFO - 'NO_EM_RESTART': '1', 02:13:01 INFO - 'PAGER': '/bin/cat', 02:13:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:01 INFO - 'PWD': '/builds/slave/test', 02:13:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:01 INFO - 'SHELL': '/bin/bash', 02:13:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LS3yJ7DoZp/Listeners', 02:13:01 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 02:13:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:01 INFO - 'USER': 'cltbld', 02:13:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:01 INFO - 'XPC_FLAGS': '0x0', 02:13:01 INFO - 'XPC_SERVICE_NAME': '0', 02:13:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:02 INFO - Downloading/unpacking blobuploader==1.2.4 02:13:02 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 02:13:02 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 02:13:02 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 02:13:02 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 02:13:02 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 02:13:02 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 02:13:04 INFO - Downloading blobuploader-1.2.4.tar.gz 02:13:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:13:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:13:04 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 02:13:04 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 02:13:04 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 02:13:04 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 02:13:04 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 02:13:04 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 02:13:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:13:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:13:05 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 02:13:05 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 02:13:05 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 02:13:05 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 02:13:05 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 02:13:05 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 02:13:05 INFO - Downloading docopt-0.6.1.tar.gz 02:13:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:13:05 INFO - Installing collected packages: blobuploader, requests, docopt 02:13:05 INFO - Running setup.py install for blobuploader 02:13:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:13:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:13:06 INFO - Running setup.py install for requests 02:13:06 INFO - Running setup.py install for docopt 02:13:06 INFO - Successfully installed blobuploader requests docopt 02:13:06 INFO - Cleaning up... 02:13:06 INFO - Return code: 0 02:13:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:13:06 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')]} 02:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:06 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')]} 02:13:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:06 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 0x106356490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e3a496fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10616ad30>, '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 0x105fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106162b20>, '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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 02:13:06 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 02:13:06 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 02:13:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POaPDq6cPu/Render', 02:13:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:06 INFO - 'HOME': '/Users/cltbld', 02:13:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:06 INFO - 'LOGNAME': 'cltbld', 02:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:06 INFO - 'MOZ_NO_REMOTE': '1', 02:13:06 INFO - 'NO_EM_RESTART': '1', 02:13:06 INFO - 'PAGER': '/bin/cat', 02:13:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:06 INFO - 'PWD': '/builds/slave/test', 02:13:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:06 INFO - 'SHELL': '/bin/bash', 02:13:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LS3yJ7DoZp/Listeners', 02:13:06 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 02:13:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:06 INFO - 'USER': 'cltbld', 02:13:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:06 INFO - 'XPC_FLAGS': '0x0', 02:13:06 INFO - 'XPC_SERVICE_NAME': '0', 02:13:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:13:07 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-XQfvqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:13:07 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-q4abos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:13:07 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-mD7Py5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:13:07 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-bX0mmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:13:07 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-GuIz3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:13:07 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-YEygWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:13:07 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-F9R29y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:13:07 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-el9X3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:13:07 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-uC9YpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:13:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-LfNqaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:13:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-XzCqXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:13:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-rcVQTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:13:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-zWYL0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:13:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-hKu0jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:13:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-EUHIMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:13:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-egHXfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:13:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-O1v9ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:13:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:13:08 INFO - Running setup.py install for manifestparser 02:13:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:13:08 INFO - Running setup.py install for mozcrash 02:13:09 INFO - Running setup.py install for mozdebug 02:13:09 INFO - Running setup.py install for mozdevice 02:13:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:13:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:13:09 INFO - Running setup.py install for mozfile 02:13:09 INFO - Running setup.py install for mozhttpd 02:13:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:13:09 INFO - Running setup.py install for mozinfo 02:13:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:13:09 INFO - Running setup.py install for mozInstall 02:13:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:13:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:13:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:13:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:13:10 INFO - Running setup.py install for mozleak 02:13:10 INFO - Running setup.py install for mozlog 02:13:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:13:10 INFO - Running setup.py install for moznetwork 02:13:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:13:10 INFO - Running setup.py install for mozprocess 02:13:10 INFO - Running setup.py install for mozprofile 02:13:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:13:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:13:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:13:10 INFO - Running setup.py install for mozrunner 02:13:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:13:10 INFO - Running setup.py install for mozscreenshot 02:13:11 INFO - Running setup.py install for moztest 02:13:11 INFO - Running setup.py install for mozversion 02:13:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:13:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:13:11 INFO - Cleaning up... 02:13:11 INFO - Return code: 0 02:13:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:13:11 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')]} 02:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:11 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')]} 02:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:11 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 0x106356490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e3a496fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10616ad30>, '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 0x105fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106162b20>, '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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 02:13:11 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 02:13:11 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 02:13:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POaPDq6cPu/Render', 02:13:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:11 INFO - 'HOME': '/Users/cltbld', 02:13:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:11 INFO - 'LOGNAME': 'cltbld', 02:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:11 INFO - 'MOZ_NO_REMOTE': '1', 02:13:11 INFO - 'NO_EM_RESTART': '1', 02:13:11 INFO - 'PAGER': '/bin/cat', 02:13:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:11 INFO - 'PWD': '/builds/slave/test', 02:13:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:11 INFO - 'SHELL': '/bin/bash', 02:13:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LS3yJ7DoZp/Listeners', 02:13:11 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 02:13:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:11 INFO - 'USER': 'cltbld', 02:13:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:11 INFO - 'XPC_FLAGS': '0x0', 02:13:11 INFO - 'XPC_SERVICE_NAME': '0', 02:13:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:13:11 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-PdYYXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:13:11 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)) 02:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:13:11 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-iraNfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:13:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:13:11 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-eQJVmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:13:12 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)) 02:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:13:12 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-FdxLu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:13:12 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)) 02:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:13:12 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-pDkOyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:13:12 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)) 02:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:13:12 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-0jgTnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:13:12 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)) 02:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:13:12 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-om5fI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:13:12 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)) 02:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:13:12 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-L9o9LF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:13:12 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)) 02:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:13:12 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-g5RCmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:13:12 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)) 02:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:13:12 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-W4FM5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:13:12 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)) 02:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:13:12 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-2il2WS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:13:12 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)) 02:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:13:12 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-ktb3ZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:13:12 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)) 02:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:13:12 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-smpVe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:13:13 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)) 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:13:13 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-QVeUuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:13:13 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-qgWyNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:13:13 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)) 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:13:13 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-mw8OVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:13:13 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)) 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:13:13 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-4LPOVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:13:13 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)) 02:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:13:13 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)) 02:13:13 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)) 02:13:13 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)) 02:13:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:13:13 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 02:13:13 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 02:13:13 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 02:13:13 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 02:13:13 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 02:13:13 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 02:13:16 INFO - Downloading blessings-1.6.tar.gz 02:13:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:13:16 INFO - Installing collected packages: blessings 02:13:16 INFO - Running setup.py install for blessings 02:13:16 INFO - Successfully installed blessings 02:13:16 INFO - Cleaning up... 02:13:16 INFO - Return code: 0 02:13:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:13:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:13:16 INFO - Reading from file tmpfile_stdout 02:13:16 INFO - Current package versions: 02:13:16 INFO - blessings == 1.6 02:13:16 INFO - blobuploader == 1.2.4 02:13:16 INFO - docopt == 0.6.1 02:13:16 INFO - functools32 == 3.2.3-2 02:13:16 INFO - jsonschema == 2.5.1 02:13:16 INFO - manifestparser == 1.1 02:13:16 INFO - mozInstall == 1.12 02:13:16 INFO - mozcrash == 1.0 02:13:16 INFO - mozdebug == 0.1 02:13:16 INFO - mozdevice == 0.48 02:13:16 INFO - mozfile == 1.2 02:13:16 INFO - mozhttpd == 0.7 02:13:16 INFO - mozinfo == 0.9 02:13:16 INFO - mozleak == 0.1 02:13:16 INFO - mozlog == 3.4 02:13:16 INFO - moznetwork == 0.27 02:13:16 INFO - mozprocess == 0.23 02:13:16 INFO - mozprofile == 0.28 02:13:16 INFO - mozrunner == 6.13 02:13:16 INFO - mozscreenshot == 0.1 02:13:16 INFO - mozsystemmonitor == 0.3 02:13:16 INFO - moztest == 0.7 02:13:16 INFO - mozversion == 1.4 02:13:16 INFO - psutil == 3.1.1 02:13:16 INFO - requests == 1.2.3 02:13:16 INFO - wsgiref == 0.1.2 02:13:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:13:16 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')]} 02:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:16 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')]} 02:13:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:16 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 0x106356490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e3a496fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10616ad30>, '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 0x105fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106162b20>, '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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 02:13:16 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 02:13:16 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 02:13:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POaPDq6cPu/Render', 02:13:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:16 INFO - 'HOME': '/Users/cltbld', 02:13:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:16 INFO - 'LOGNAME': 'cltbld', 02:13:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:16 INFO - 'MOZ_NO_REMOTE': '1', 02:13:16 INFO - 'NO_EM_RESTART': '1', 02:13:16 INFO - 'PAGER': '/bin/cat', 02:13:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:16 INFO - 'PWD': '/builds/slave/test', 02:13:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:16 INFO - 'SHELL': '/bin/bash', 02:13:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LS3yJ7DoZp/Listeners', 02:13:16 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 02:13:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:16 INFO - 'USER': 'cltbld', 02:13:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:16 INFO - 'XPC_FLAGS': '0x0', 02:13:16 INFO - 'XPC_SERVICE_NAME': '0', 02:13:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:17 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)) 02:13:17 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)) 02:13:17 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)) 02:13:17 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)) 02:13:17 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)) 02:13:17 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)) 02:13:17 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)) 02:13:17 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)) 02:13:17 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)) 02:13:17 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)) 02:13:17 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)) 02:13:17 INFO - Cleaning up... 02:13:17 INFO - Return code: 0 02:13:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:13: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')]} 02:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13: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')]} 02:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106356490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e3a496fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10616ad30>, '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 0x105fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106162b20>, '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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 02:13: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', 'jsonschema'] in /builds/slave/test/build 02:13: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 jsonschema 02:13:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POaPDq6cPu/Render', 02:13:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:17 INFO - 'HOME': '/Users/cltbld', 02:13:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:17 INFO - 'LOGNAME': 'cltbld', 02:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:17 INFO - 'MOZ_NO_REMOTE': '1', 02:13:17 INFO - 'NO_EM_RESTART': '1', 02:13:17 INFO - 'PAGER': '/bin/cat', 02:13:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:17 INFO - 'PWD': '/builds/slave/test', 02:13:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:17 INFO - 'SHELL': '/bin/bash', 02:13:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LS3yJ7DoZp/Listeners', 02:13:17 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 02:13:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:17 INFO - 'USER': 'cltbld', 02:13:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:17 INFO - 'XPC_FLAGS': '0x0', 02:13:17 INFO - 'XPC_SERVICE_NAME': '0', 02:13:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:13:17 INFO - Cleaning up... 02:13:17 INFO - Return code: 0 02:13:17 INFO - Running post-action listener: _resource_record_post_action 02:13:17 INFO - Running post-action listener: _start_resource_monitoring 02:13:17 INFO - Starting resource monitoring. 02:13:17 INFO - [mozharness: 2016-12-29 10:13:17.354803Z] Finished create-virtualenv step (success) 02:13:17 INFO - [mozharness: 2016-12-29 10:13:17.355785Z] Running install step. 02:13:17 INFO - Running pre-action listener: _resource_record_pre_action 02:13:17 INFO - Running main action method: install 02:13:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:13:17 INFO - Reading from file tmpfile_stdout 02:13:17 INFO - Detecting whether we're running mozinstall >=1.0... 02:13:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:13:17 INFO - Reading from file tmpfile_stdout 02:13:17 INFO - Output received: 02:13:17 INFO - Usage: mozinstall [options] installer 02:13:17 INFO - Options: 02:13:17 INFO - -h, --help show this help message and exit 02:13:17 INFO - -d DEST, --destination=DEST 02:13:17 INFO - Directory to install application into. [default: 02:13:17 INFO - "/builds/slave/test"] 02:13:17 INFO - --app=APP Application being installed. [default: firefox] 02:13:17 INFO - mkdir: /builds/slave/test/build/application 02:13:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:13:48 INFO - Reading from file tmpfile_stdout 02:13:48 INFO - Output received: 02:13:48 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:13:48 INFO - Running post-action listener: _resource_record_post_action 02:13:48 INFO - [mozharness: 2016-12-29 10:13:48.881150Z] Finished install step (success) 02:13:48 INFO - [mozharness: 2016-12-29 10:13:48.881290Z] Running run-tests step. 02:13:48 INFO - Running pre-action listener: _resource_record_pre_action 02:13:48 INFO - Running main action method: run_tests 02:13:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:13:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:13:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:13:48 INFO - 2016-12-29 02:13:48.896 screenresolution[1650:8706] starting screenresolution argv=screenresolution get 02:13:48 INFO - 2016-12-29 02:13:48.898 screenresolution[1650:8706] Display 0: 1600x1200x32@60 02:13:48 INFO - 2016-12-29 02:13:48.905 screenresolution[1651:8715] starting screenresolution argv=screenresolution list 02:13:48 INFO - Available Modes on Display 0 02:13:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:13:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:13:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:13:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:13:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:13:49 INFO - Intel Iris: 02:13:49 INFO - Chipset Model: Intel Iris 02:13:49 INFO - Type: GPU 02:13:49 INFO - Bus: Built-In 02:13:49 INFO - VRAM (Dynamic, Max): 1536 MB 02:13:49 INFO - Vendor: Intel (0x8086) 02:13:49 INFO - Device ID: 0x0a2e 02:13:49 INFO - Revision ID: 0x0009 02:13:49 INFO - Displays: 02:13:49 INFO - Display: 02:13:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:13:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:13:49 INFO - Display Serial Number: 1600x1200 60 02:13:49 INFO - Main Display: Yes 02:13:49 INFO - Mirror: Off 02:13:49 INFO - Online: Yes 02:13:49 INFO - Rotation: Supported 02:13:49 INFO - Return code: 0 02:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:13:49 INFO - Python 2.7.3 02:13:49 INFO - Return code: 0 02:13:49 INFO - grabbing minidump binary from tooltool 02:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:49 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 0x10616ad30>, '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 0x105fd0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106162b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:13:49 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 02:13:49 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 02:13:49 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 02:13:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:13:49 INFO - Return code: 0 02:13:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:13:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:13:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:13:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:13:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 02:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0382 --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 02:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POaPDq6cPu/Render', 02:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:49 INFO - 'HOME': '/Users/cltbld', 02:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:49 INFO - 'LOGNAME': 'cltbld', 02:13:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:13:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:49 INFO - 'MOZ_NO_REMOTE': '1', 02:13:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:13:49 INFO - 'NO_EM_RESTART': '1', 02:13:49 INFO - 'PAGER': '/bin/cat', 02:13:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:49 INFO - 'PWD': '/builds/slave/test', 02:13:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:49 INFO - 'SHELL': '/bin/bash', 02:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LS3yJ7DoZp/Listeners', 02:13:49 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 02:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:49 INFO - 'USER': 'cltbld', 02:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:49 INFO - 'XPC_FLAGS': '0x0', 02:13:49 INFO - 'XPC_SERVICE_NAME': '0', 02:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 02:13:49 INFO - mozversion application_buildid: 20161229004004 02:13:49 INFO - mozversion application_changeset: 7f4072c726a0f6321320baceb534e288ce8b1e33 02:13:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:13:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:13:49 INFO - mozversion application_name: Firefox 02:13:49 INFO - mozversion application_remotingname: firefox-dev 02:13:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:13:49 INFO - mozversion application_vendor: Mozilla 02:13:49 INFO - mozversion application_version: 52.0a2 02:13:49 INFO - mozversion platform_buildid: 20161229004004 02:13:49 INFO - mozversion platform_changeset: 7f4072c726a0f6321320baceb534e288ce8b1e33 02:13:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:13:49 INFO - mozversion platform_version: 52.0a2 02:13:49 INFO - using testdate: 1483006429 02:13:49 INFO - actual date: 1483006429 02:13:49 INFO - starting webserver on 'localhost:49244' 02:13:49 INFO - SUITE-START | Running 2 tests 02:13:49 INFO - TEST-START | dromaeo_css 02:13:49 INFO - operating with platform_type : mac_ 02:13:49 INFO - Initialising browser for dromaeo_css test... 02:13:49 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmp1oGYf4/profile http://localhost:49244/getInfo.html) 02:13:52 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 02:13:52 INFO - PROCESS | 1661 | colorDepth:24 02:13:52 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 02:13:52 INFO - PROCESS | 1661 | __metrics 02:13:52 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:13:52 INFO - PROCESS | 1661 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:13:53 INFO - TEST-INFO | 1661: exit 0 02:13:53 INFO - Browser initialized. 02:13:53 INFO - Running cycle 1/1 for dromaeo_css test... 02:13:53 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmp1oGYf4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:14:05 INFO - PROCESS | 1664 | RSS: Main: 222638080 02:14:05 INFO - PROCESS | 1664 | 02:14:56 INFO - PROCESS | 1664 | 1483006496952 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] 02:17:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 02:17:11 INFO - PROCESS | 1664 | RSS: Main: 380919808 02:17:11 INFO - PROCESS | 1664 | 02:20:14 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 02:20:15 INFO - PROCESS | 1664 | RSS: Main: 349253632 02:20:15 INFO - PROCESS | 1664 | 02:23:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 02:23:18 INFO - PROCESS | 1664 | RSS: Main: 306081792 02:23:18 INFO - PROCESS | 1664 | 02:26:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 02:26:21 INFO - PROCESS | 1664 | RSS: Main: 316112896 02:26:21 INFO - PROCESS | 1664 | 02:29:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 02:29:25 INFO - PROCESS | 1664 | RSS: Main: 309641216 02:29:25 INFO - PROCESS | 1664 | 02:32:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 02:32:28 INFO - PROCESS | 1664 | RSS: Main: 424734720 02:32:28 INFO - PROCESS | 1664 | 02:32:28 INFO - PROCESS | 1664 | __start_tp_report 02:32:28 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 02:32:28 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 02:32:28 INFO - PROCESS | 1664 | |i|pagename|runs| 02:32:28 INFO - PROCESS | 1664 | |0;dojo.html;3290.5557749703785;3308.702216800486;3319.5186697928793;3325.8004519728834;3363;5600.859978500534;5646.407127251641;5698.971505142474;5700.144978253254;5743.798967036149;5588.161775733642;5615.859603509914;5616.5787565932515;5640.74616642251;5766.077427611583;4257.787110644455;5125.948740512594;5172;5203.219517072451;5221.660592061523;4446.3997360356425;5125.410577783547;5158.045761534109;5234.241035049916;5267.86830329241;5170.069387510247;5183.481651834805;5201.713905735183;5209.296744939423;5220.190870415092;5203;5216.3218781558535;5267.420583735786;5275.57795376369;5294.629375943686;5063.1898896176435;5127.769335359524;5188.688678679254;5197.376314842205;5223.973880130613;5527.2538207341895;5570.693611851347;5597.720113994284;5609.354924183689;5638.35158956717;5065.366829146356;6081.726322315506;6138.355472675349;6194.783182588588;6200.255969283669;6245.812625621239;6253.53098517613;6267.4672652824565;6269.62382257066;6272.435480806749;5504.834854954358;5542.196381524656;5549.750261238173;5684.914726279109;5834.912476312858;6232.470240029557;6250.062490626442;6273.247210334789;6275;6283.5287353448675;6095.116208149793;6109.725062372204;6114.205153330038;6138.386161383826;6139.6009259398;6039.033754599243;6102.389761023862;6107.408898231393;6126.571140020245;6148.938510614926;6062.42406971337;6109.116942259659;6139.201903752573;6155;6161.44546990773;6135.447809697149;6171.783987560414;6178.598391104564;6182.845428864314;6190.288116866618;4833.758312084382;4889.951100488949;4891.461939186686;4895.265710143507;4917.9016419672125;4259;4264.0832221072615;4285.571442855689;4342.413774140514;4379.562043795595;5664.688442135722;5684.891446167958;5702.942970570324;5724.227229323988;5761.9135712964335;4916.606671466346;6173.351798061229;6212.844678883064;6223.782167624111;6251.78118765841;5550.72246387679;5627.831165065055;5667.40492248308;5676.403977582377;5688.544916406678;5288.286081378965;5325.653832500952;5343.171808369709;5361.249425080492;5386.192071189353;6277.529185311028;6281.15204447412;6289.685515724287;6299.748010079728;6307.306196318493;5904.439078287556;6025.517958563217;6171.957367923837;6227.501799856092;6232.75068997235;5262.736863156751;6117.309816958451;6219.346968568235;6232.875342493216;6264.029075493306;6058.57589968698;6115.908261376172;6122.672617327995;6139.2114150962325;6144.126041557368;6179.969100154471;6210.285817131088;6220.066989951543;6221.720022599085;6229.781957631461;5844.649321040752;5953.732082056405;6007.3992600739575;6033.42682445167;6049.697515124314;6010.729517171825;6037.456628903244;6047.909281360695;6047.971844786309;6080.513558915364;5893.499052580407;5903.940960590339;5907.497862681548;5907.911381329198;5909.113632955091;5816;5919.082542205792;5932.1694962706115;6013.158157857816;6027.939720602738;5807.245058142415;5831.35855055952;5840.795572154954;5848.239728835224;5853.443922827325 02:32:28 INFO - PROCESS | 1664 | |1;ext.html;505.29511331692265;507.9796808127675;517.6402400331771;519.6752029981262;545.0624925128778;30250.54624180636;37795.62204377956;39124.02189945249;39300.80349598255;40758.388624170664;40124.19751604966;40618.79690601543;40626.98432539188;40692.59307406932;40707.389389159114;7145.464090193231;9255.352125351228;9417.764555886124;9429.245660347156;9448.574814133346;8384.371172162082;11045.226834121615;11280.210385273034;11309.264897781659;11464.598739044135;11132.220744547885;11199.888001119965;11200.719982000475;11216.990470857621;11226.438678066104;11164.218504704673;11231.494582743797;11243.831342529827;11253.943730281297;11268.943655281753;11037.448127593669;11134.16493762971;11135.443227838576;11142.220044596881;11142.387168705718;31763.841180794177;38788.612113878786;38790.418143727584;38826.805865970775;38898.41652375217;3169.8732050717945;3171.9849648112613;3266.3304022675534;3304.438245498247;3441.174118211636;27942.580861287097;28263.01079462209;29006;30220.395592088254;30511.542326864892;3112.9221769455944;3173.7778355515125;3177.777555571111;3239.72462340699;3260.2012506935716;461586;461905;462169;464924;469559;31972.520412193837;32401.027969160492;32760.508592371134;56048.439515604325;56421.43578564162;33913;34099.65900341015;34116.48825267623;34123.31753364919;34144.82927585396;14181.14913105217;14309.713805723828;14314.570562883131;14327.928360358132;14358.641033974234;11013.229073964745;11250.549978000952;12095.939520302518;12176.391180440942;12176.512939482498;7210.927890721131;7497.325240728361;7702.075750909815;7733.304002639789;7757.379409647215;3222.355528894184;3223.0975326908033;3226.2902161524426;3228.2090887732656;3249.5938007749032;897.5243121145803;967.670991862756;985.5663508056432;991.6727479931606;1048.02010120536;23642.4089397763;28893.133206003855;29226.661867050585;29693.554596681068;30762;1042.08296698906;1088.1077516436446;1088.4231357380602;1109.877913429522;1125.6285425809463;14786.556403307917;14857.885658575435;15007.924960375347;15023.94832361746;15263.771043434357;569861;669702.6514867394;671568;672043;672131;55253;55526.72236638791;55593.88812223733;55634.72182639061;55643.721781390836;1227.5826219085554;1267.3916520070418;1346.2999240395136;1373.615387691448;1388.625071230742;28979.130626081253;29560.704392955795;29727.10818675443;29780.70219297779;30475.39049218959;45996.540034599224;46486.070278593565;46492.767536163454;46508;46517.53482465268;501839;504276;505298;505937;862508;34835.25823708855;37416.6258337424;38279.617203827605;39051.02372440598;39473;42083.7895810519;42637.786811065744;42774.78612606917;42958.57041429546;43084;35072;35419.82290088533;35669.28661426809;35960.64039359573;36035;35522.644773552965;35751.64248357483;35781.28437431289;36020.63979360173;36115.81942090273;34823.4776478348;35194.12014699653;35866.82066589651;35948;36070.27859442744;29415.705842941297;31440.52839207368;31509.369812604076;31612.525812112377;31855.840720797176;24086.518269634158;24134.637980429958;24266.63600046036;24516.019359225433;24634 02:32:28 INFO - PROCESS | 1664 | |2;jquery.html;349.37636319170286;369.0920335973505;372.59759459783436;378.86550274652495;397.3741357362155;14600.342984565694;14866.108033517983;15466.845331546696;19038.61922761544;20214.393568192976;19028.334008309714;19527.511812204702;20107.698384524243;20137.194512219492;20373.49066273344;5539;5960;5996.22049133612;6005.12925625784;6006.759729610832;4636.933505293784;5846.23998880145;5886.705664716768;5888.558358123158;5895.35151133375;5558.499735023847;5831.620944638585;5834;5844.503217226541;5864.618799778023;5677.801276955287;5857.384974577693;5860.706964651793;5861.941380586182;5863.296404431453;5049.570786483153;5117.769700363493;5124.538791508745;5130.871728206825;5327.813526526553;11760.294382337002;12615.810762838473;12742.872571274262;12755;12775.74448511034;2153.8384621153477;2228.6545585434283;2298.092253559851;2477.4054226985572;2850.914472565826;9972.252081093948;10142.391456512632;13176.934115329457;13952.790708139284;14389.856101438956;1645.7778199942975;1722.0872937343229;1736.6179440523065;1794.7577077094556;1829.0945981739123;87637.23913325825;90843.54578227132;105389.47305263425;107940;122049.38975305155;10907.727306817234;10913.345199288067;11410.65768026944;11470.770584588261;11661.533538658528;14348.928255358865;14378.928105359615;14398.496052638317;14400.919931005217;14407.567772966828;6991.23096459389;8087.716929907425;8499.107593702696;8526.57367131641;8558.529280889486;8383.371247156487;8412.032616249087;8424.705135320235;8576.914230857736;8587.742367728979;4318.3738357938555;4344.326629372453;4445.088756804847;4487.147441986012;4588.128255631419;1842.603840174369;1900.296890150653;1903.4391798724912;1903.4670292318174;1904.523869032742;801.4029547986671;863.87905693203;878.3149143667949;905.9365844390829;955.7945041815904;19733;20344.49138771513;20393;20423;20502.76983381002;914.3919293669768;924.6671198368582;926.416357694648;941.6280569175137;987.132380434838;9836.016398360107;10699.34724940642;10823.719450787232;10888.891111088946;11865.644030679094;564819;577390;579265;584890;590679;87901.5604921984;104018.47990759998;109622;121821.39089304674;123574;951.7335146159087;996.9800603988025;1011.2264117949775;1011.6964910526813;1012;17691.469255922344;17840.3755868544;18601.813981860007;18616.627667446846;18925.810741892405;36651.45022824713;39645.405318919664;44086;50845.491545085555;52310;69161.96257056047;69903.60192796217;71906.88791452785;72853;74603.62698186474;6932.757353492603;7971.641276142704;8003.559804210824;8004.119546849725;8012.719554815787;12171;12621.432035558239;12798.872011279767;12853.742925141632;13010.869891300967;10379.636712715019;10703.892961070289;10898.291611045257;10924.890751092706;11098.94888365757;8613.48319100856;10763.569457221623;10877.782444351227;11035.172362072715;11302.94348528285;11106.000459958643;11154.944225278821;11192.608258711232;11678.372366217533;11735.706607334885;6892.172939247122;7962;7976.699300280915;8185.918140818516;8336.29141522953;7751;9151.542422878963;10838.837417438903;10994.065504432212;11096.667099987013 02:32:28 INFO - PROCESS | 1664 | |3;mootools.html;1435.5406269993607;1477.645365112373;1604.9438269660563;1770.3803668715943;1794.3091909614802;7822.882656760146;8405.159484051592;8409.201125893038;8417.579121043938;8417.663293468255;6282.780102696395;7415.110186777581;7528.774136775892;7997.840043199147;8236.91763082369;2272.4432514034042;2320.1299512682745;2322.059565875823;2325.988370058147;2326.325365643961;2327.650852372141;2361.0437772702085;2362.350353652742;2383.4398916254668;2387.5583017141885;2325.0815927708545;2325.337278875521;2356.45801465663;2362.126013375054;2363.9881800590974;2299.72403311602;2310.630299152136;2341.1220792202926;2342.6763878408647;2345.1323010486144;1958.9030143119755;2328.429534763977;2355.8939847706893;2360.45709486819;2365.4795944892267;8178.754637360888;8339;8355.498670079754;8369.707060252862;8375;5030.170021946375;5208.453112423179;5211.088059589557;5215.582753379758;5231.346081739783;3231.7737758356925;3319;3566.3045706087323;3569.446735755962;3576.8032758198287;1048.6434612231878;1048.669669054253;1077.805994920914;1089.5750657243682;1100.6092837042816;10888.836667449994;11002.33985960837;11032.22774405792;11033.117350611932;11061.944690276578;4507.549245075532;4534.795934182904;4736.526347365305;4742.075295317449;4745.715257084586;9371.062893710574;9376.109269619375;9380.765480863032;9391.953040234755;9403.482808445464;5225.5819534437915;5227.947720522746;5230.692326918271;5231.215317702299;5238.76425560843;3964.5837187095517;4175.227582897173;4723.267893476516;4792.113459010095;4793.448753393404;3778.414345776409;3798.3922572388287;3865.1110244643887;3876.3604005339366;3908.5895980922164;1239.5227837282712;1246.5512415530402;1250.93745312734;1258.3205069262497;1260.1431026901612;760.2587477209677;788.5150632361057;795.7891158842912;853.5945425922637;899.6536333511515;5514.007478653881;5522.337319521603;5632.295963004624;5783.392743761929;5823.30120385548;840.4663038970298;868.2966796894535;884.4869975414244;891.2379915172435;912.8402529557301;3699.5375578052744;3705.814709264526;3724.981375093107;3779.6598306152027;3820.9110403535415;9719.125278724949;10155.136813370798;10227.43749093807;10229.079382855578;10235.078842904175;8938.955305223431;9585.712428627152;9716.61133554664;9904.851427228597;9924.106830385155;7225.89161162583;7433.516821406592;7670.501417407851;7898.407619428451;7899.84200315979;5529.557635389239;5548.805791797267;5553.416891226525;5663.9716801415725;5706.172604972338;7784.260495252942;9453.669121580711;9487;9498.19265362452;9510.429374237568;9302.255819534286;9491.430514168896;9599.32804703664;9676.612935482503;9680.612775488902;6944.4791640626145;7020.6489675514995;7122.252163522756;7130.964345178241;7327.047483827068;7930.206979302255;8015.599220038858;8023.277904988579;8030.5181689098845;8062.798430039296;6840.213375461885;6865.313468653095;6906.075975777535;7084.36240738336;7177.425805935408;6879;6956.686949087202;6997.1253593300835;7098.29017098286;7125.82185445368;6498.837529061811;6676.332366763285;6687.1975362956755;6687.899681504879;6689.899651505329;4716.8113275468595;4750.406199225097;4768.666193366431;4799.0401919615515;4810.446798618063;3673.2286219893053;4041.5352234492234;4764.8332308369045;4766.952330476651;4809 02:32:28 INFO - PROCESS | 1664 | |4;prototype.html;297.9925501862453;305.28410876494627;305.8455479982608;311.8129122526483;315.41962788469215;4068.8372465101434;4481;4883.2430973199125;5256.658317209379;5306.646805064713;4920.892799120196;5162.432132465426;5224.4775522447735;5247.711375874325;5260.684358938457;3381.3406385754847;3852.499175107246;3943.6450719435247;3982.3230050891416;4042.3532234842432;3981.686043605603;4014.3577027675433;4015.518137823465;4053.5946405359377;4100.1594673092095;3610.6750392464673;3951.170254246612;3957.248122856648;3965.385365268388;3973.87397742208;3552.3250582389264;3627.455881617752;3738.6822120119823;3999.740016898907;4011.83952641894;3452.723782097426;3466.907924003956;3469.8785542506143;3478.4608385700258;3488.5116083748294;4250.404943307908;4940.912999140183;5031.547160755549;5038.722870242135;5062.152314405215;4407.808173445148;4431.955680443187;4511.345854851061;4538.773061346952;4552.474920901502;5057.0897238497055;5184.963007398535;5397.163439666819;5421.620486565942;5467.480589344006;2942.3963939652726;3836.5779764225913;3840.231953609289;3841.154945911895;3841.9039524012123;5269.473052694738;5411;5686.891056244034;5710.514606258473;5773.826785196451;3001.8198908065588;3881.7670939743707;3882.145927895858;3883.1457079442475;3891.105045839473;3924.8822535323984;3940.566537680853;3943.659155886955;3946.3883098119836;3976.5029371328583;3881.787381192693;3964.623360780721;4058.6347228749555;4406.20688276107;4560.931586026212;4566.725996440225;4908.950910490921;5118.436971933084;5534;5579.38626751057;1076.9461526923621;1246.765701955058;1247.3887794980394;1250.2560976219136;1255.8681338459512;760.1638197982176;796.2714116583261;812.3135950122137;835.9456635318685;852.799592095857;4729.385179926657;4832.371791667061;4845.152098382769;4956.4547899731;5127.87180320495;5481.643693159932;5497.312835895513;5507.0637991541535;5545.085060964976;5545.140503222007;4295.055087880662;4474.418325617649;5063.797448102035;5108.182690769533;5267.552258058108;2613.8890971337105;2625.9474810503684;2840.9204502289;2843.9619538868164;2851.657801063844;5081.4918508148885;5577.912307100158;5622.07652020955;5632.943670563324;5640.0693885508435;4897.608191344684;5222.268882356473;5292.867678307996;5478.835634930959;5500.75455481268;4595.908081838412;4823.14308991034;5272.499112584375;5308.548773354308;5630.40880707528;5338;5383.273258110262;5420.349558053059;5421.322334708161;5433.750237445489;4320.4383430153875;4423.496011356155;4610.677252592421;4980.701157930391;5267.683938963674;5017.8243761468175;5110.821121260738;5217.878156196436;5538.750756216061;5673.120666296731;3172.96827031736;3419.136570568996;3533.982330088436;3553.307105114478;3560.643935606522;5035.773390197377;5331.600129990189;5333.493318134771;5365.382980957083;5375.242910024578;5162.380514338303;5172.818951336836;5210.993767399655;5229.660072095302;5274.841754747364;4480.193565158303;4850.102730994778;5156.767945442539;5171.276021357088;5228.712420816892;4667.673262871566;4826.710397376169;4977.875553111056;5100.668456550461;5171.431142574389;4698.412698412699;5181.430042695376;5227.686338819683;5346.3535549903045;5372.280870121524;4712.363830882924;5385.838424847104;5399.596105012646;5399.83800485986;5403.378611459732 02:32:28 INFO - PROCESS | 1664 | |5;yui.html;706.6855249414012;715.2918610575532;728.6684558525872;734.6657270941722;769.4229328003997;13608.387622556984;14002.92998535006;14013.509527166563;14022.789658155136;14022.859771402284;12895.806562901564;12900.22598644082;12905.870941290585;12948.611541653765;13063.673408164801;638.7476946606088;659.1332397896774;660.3561527510686;662.4799532367093;797.1231645190302;652.8302641313269;655.0501772429972;783.7492002559184;815.5269943432825;817.9836403271931;864.0365991918997;870.9896520036758;876.329607849992;877.3814460805149;881.9901213110984;828.5484410996022;828.8300898315831;828.9875651865227;843.228445971941;865.4417900454234;499.5104797298631;511.05709965114306;646.0277282689569;648.45205801098;649.6264647827518;8850.380473366802;11310.60412885545;11332.206745527816;11478;11516.366599837005;13260.3369831509;13562.728745425135;13686.794698079537;13718.65703357414;13747.931260343734;10430.16558675304;10755.139588832875;10918.454077296103;10946.233763636548;11004;3557.7509574329806;3613.7109031277437;3809.847606095753;3833.7891415972113;3844.442555829389;77286;81500;81837.59081204615;88447.55776221078;89648.31055068359;10185.94907025475;10408.739781505372;10605.25763196569;10746.086582640562;10869.728256793485;20010.69983950242;20358.28745993883;20457.2839950601;20460.283890063776;20469.692954605693;7388.667509962064;7679.539227646359;7766.534007959541;7767.300942915052;7867.803304917423;3814.809259537012;4038.0510580013674;4211.157768446323;4299.828006879689;4397.626201772821;3314.5691060162508;3376.0884561168837;3432.1076520104452;3437.6562343765922;3447.4311738562856;630.4357599948021;639.68015992004;646.2729429391934;646.570030929436;653.389081209071;596.9910451343234;614.9600275982046;634.1534052040578;639.3478651775164;659.5152959872845;8661.220490155916;8864.512451815082;8939.650872817954;9018.053104424072;9208.217301529312;600.618607184441;629.9338569450233;643.5688088980395;654.2672207128047;661.9304972977768;4192.937105943413;4401.141777353385;4456.866294011184;4459.665525085632;4468.402225755128;63225.683871580346;68147.65926170337;98054;102395;103855.44216836753;60005.399946000856;77419;91251;93319;95927;76865.61567192129;78675.21324786793;81175;81563;82492;7327.523710958771;8505.128871648227;8740.562971851255;8833.469991800512;8950.731478055668;52667.47332526779;53669.463305366444;54648.72675636596;55454.72272638611;57082.429175709374;64839.84021517948;67478.32521674721;73502;76062;76209;10275.84586231222;10470.371777693363;10521.368717876952;10568.524416401127;10796.784064318826;11796.76406471883;11799.35103569312;12131.393430328624;12378.442970066188;12606.432710527864;11726.237794543327;11812.93273677086;12161.939190303992;12216.328101954477;12227.02183825274;9841.95079024629;11639.185257032264;11932.343721095423;12008.819867702166;12370.13409061393;11966.22219555726;12060.155789094679;12137.878621213675;12381.195222310522;12501.56244531437;10439.37363758177;10877.945610271898;10905.672829815117;10976.341419514854;11104.5558177672;9331.72004839856;9686.176674982706;9830.606775728891;9916.603335866484;10111.03945125212 02:32:28 INFO - PROCESS | 1664 | __end_tp_report 02:32:28 INFO - PROCESS | 1664 | __start_cc_report 02:32:28 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1947 02:32:28 INFO - PROCESS | 1664 | __end_cc_report 02:32:28 INFO - PROCESS | 1664 | __startTimestamp1483007548796__endTimestamp 02:32:28 INFO - PROCESS | 1664 | 02:32:28 INFO - PROCESS | 1664 | ------- Summary: start ------- 02:32:28 INFO - PROCESS | 1664 | Number of tests: 6 02:32:28 INFO - PROCESS | 1664 | 02:32:28 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5660.15 Median:5844.65 stddev:648.85 (11.1%) stddev-sans-first:623.64 02:32:28 INFO - PROCESS | 1664 | Values: 3290.6 3308.7 3319.5 3325.8 3363.0 5600.9 5646.4 5699.0 5700.1 5743.8 5588.2 5615.9 5616.6 5640.7 5766.1 4257.8 5125.9 5172.0 5203.2 5221.7 4446.4 5125.4 5158.0 5234.2 5267.9 5170.1 5183.5 5201.7 5209.3 5220.2 5203.0 5216.3 5267.4 5275.6 5294.6 5063.2 5127.8 5188.7 5197.4 5224.0 5527.3 5570.7 5597.7 5609.4 5638.4 5065.4 6081.7 6138.4 6194.8 6200.3 6245.8 6253.5 6267.5 6269.6 6272.4 5504.8 5542.2 5549.8 5684.9 5834.9 6232.5 6250.1 6273.2 6275.0 6283.5 6095.1 6109.7 6114.2 6138.4 6139.6 6039.0 6102.4 6107.4 6126.6 6148.9 6062.4 6109.1 6139.2 6155.0 6161.4 6135.4 6171.8 6178.6 6182.8 6190.3 4833.8 4890.0 4891.5 4895.3 4917.9 4259.0 4264.1 4285.6 4342.4 4379.6 5664.7 5684.9 5702.9 5724.2 5761.9 4916.6 6173.4 6212.8 6223.8 6251.8 5550.7 5627.8 5667.4 5676.4 5688.5 5288.3 5325.7 5343.2 5361.2 5386.2 6277.5 6281.2 6289.7 6299.7 6307.3 5904.4 6025.5 6172.0 6227.5 6232.8 5262.7 6117.3 6219.3 6232.9 6264.0 6058.6 6115.9 6122.7 6139.2 6144.1 6180.0 6210.3 6220.1 6221.7 6229.8 5844.6 5953.7 6007.4 6033.4 6049.7 6010.7 6037.5 6047.9 6048.0 6080.5 5893.5 5903.9 5907.5 5907.9 5909.1 5816.0 5919.1 5932.2 6013.2 6027.9 5807.2 5831.4 5840.8 5848.2 5853.4 02:32:28 INFO - PROCESS | 1664 | 02:32:28 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:67505.54 Median:34108.07 stddev:154473.74 (452.9%) stddev-sans-first:154825.17 02:32:28 INFO - PROCESS | 1664 | Values: 505.3 508.0 517.6 519.7 545.1 30250.5 37795.6 39124.0 39300.8 40758.4 40124.2 40618.8 40627.0 40692.6 40707.4 7145.5 9255.4 9417.8 9429.2 9448.6 8384.4 11045.2 11280.2 11309.3 11464.6 11132.2 11199.9 11200.7 11217.0 11226.4 11164.2 11231.5 11243.8 11253.9 11268.9 11037.4 11134.2 11135.4 11142.2 11142.4 31763.8 38788.6 38790.4 38826.8 38898.4 3169.9 3172.0 3266.3 3304.4 3441.2 27942.6 28263.0 29006.0 30220.4 30511.5 3112.9 3173.8 3177.8 3239.7 3260.2 461586.0 461905.0 462169.0 464924.0 469559.0 31972.5 32401.0 32760.5 56048.4 56421.4 33913.0 34099.7 34116.5 34123.3 34144.8 14181.1 14309.7 14314.6 14327.9 14358.6 11013.2 11250.5 12095.9 12176.4 12176.5 7210.9 7497.3 7702.1 7733.3 7757.4 3222.4 3223.1 3226.3 3228.2 3249.6 897.5 967.7 985.6 991.7 1048.0 23642.4 28893.1 29226.7 29693.6 30762.0 1042.1 1088.1 1088.4 1109.9 1125.6 14786.6 14857.9 15007.9 15023.9 15263.8 569861.0 669702.7 671568.0 672043.0 672131.0 55253.0 55526.7 55593.9 55634.7 55643.7 1227.6 1267.4 1346.3 1373.6 1388.6 28979.1 29560.7 29727.1 29780.7 30475.4 45996.5 46486.1 46492.8 46508.0 46517.5 501839.0 504276.0 505298.0 505937.0 862508.0 34835.3 37416.6 38279.6 39051.0 39473.0 42083.8 42637.8 42774.8 42958.6 43084.0 35072.0 35419.8 35669.3 35960.6 36035.0 35522.6 35751.6 35781.3 36020.6 36115.8 34823.5 35194.1 35866.8 35948.0 36070.3 29415.7 31440.5 31509.4 31612.5 31855.8 24086.5 24134.6 24266.6 24516.0 24634.0 02:32:28 INFO - PROCESS | 1664 | 02:32:28 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:32615.60 Median:18500.41 stddev:96125.49 (519.6%) stddev-sans-first:96364.61 02:32:28 INFO - PROCESS | 1664 | Values: 349.4 369.1 372.6 378.9 397.4 14600.3 14866.1 15466.8 19038.6 20214.4 19028.3 19527.5 20107.7 20137.2 20373.5 5539.0 5960.0 5996.2 6005.1 6006.8 4636.9 5846.2 5886.7 5888.6 5895.4 5558.5 5831.6 5834.0 5844.5 5864.6 5677.8 5857.4 5860.7 5861.9 5863.3 5049.6 5117.8 5124.5 5130.9 5327.8 11760.3 12615.8 12742.9 12755.0 12775.7 2153.8 2228.7 2298.1 2477.4 2850.9 9972.3 10142.4 13176.9 13952.8 14389.9 1645.8 1722.1 1736.6 1794.8 1829.1 87637.2 90843.5 105389.5 107940.0 122049.4 10907.7 10913.3 11410.7 11470.8 11661.5 14348.9 14378.9 14398.5 14400.9 14407.6 6991.2 8087.7 8499.1 8526.6 8558.5 8383.4 8412.0 8424.7 8576.9 8587.7 4318.4 4344.3 4445.1 4487.1 4588.1 1842.6 1900.3 1903.4 1903.5 1904.5 801.4 863.9 878.3 905.9 955.8 19733.0 20344.5 20393.0 20423.0 20502.8 914.4 924.7 926.4 941.6 987.1 9836.0 10699.3 10823.7 10888.9 11865.6 564819.0 577390.0 579265.0 584890.0 590679.0 87901.6 104018.5 109622.0 121821.4 123574.0 951.7 997.0 1011.2 1011.7 1012.0 17691.5 17840.4 18601.8 18616.6 18925.8 36651.5 39645.4 44086.0 50845.5 52310.0 69162.0 69903.6 71906.9 72853.0 74603.6 6932.8 7971.6 8003.6 8004.1 8012.7 12171.0 12621.4 12798.9 12853.7 13010.9 10379.6 10703.9 10898.3 10924.9 11098.9 8613.5 10763.6 10877.8 11035.2 11302.9 11106.0 11154.9 11192.6 11678.4 11735.7 6892.2 7962.0 7976.7 8185.9 8336.3 7751.0 9151.5 10838.8 10994.1 11096.7 02:32:28 INFO - PROCESS | 1664 | 02:32:28 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5405.64 Median:5230.95 stddev:2960.31 (56.6%) stddev-sans-first:2953.58 02:32:28 INFO - PROCESS | 1664 | Values: 1435.5 1477.6 1604.9 1770.4 1794.3 7822.9 8405.2 8409.2 8417.6 8417.7 6282.8 7415.1 7528.8 7997.8 8236.9 2272.4 2320.1 2322.1 2326.0 2326.3 2327.7 2361.0 2362.4 2383.4 2387.6 2325.1 2325.3 2356.5 2362.1 2364.0 2299.7 2310.6 2341.1 2342.7 2345.1 1958.9 2328.4 2355.9 2360.5 2365.5 8178.8 8339.0 8355.5 8369.7 8375.0 5030.2 5208.5 5211.1 5215.6 5231.3 3231.8 3319.0 3566.3 3569.4 3576.8 1048.6 1048.7 1077.8 1089.6 1100.6 10888.8 11002.3 11032.2 11033.1 11061.9 4507.5 4534.8 4736.5 4742.1 4745.7 9371.1 9376.1 9380.8 9392.0 9403.5 5225.6 5227.9 5230.7 5231.2 5238.8 3964.6 4175.2 4723.3 4792.1 4793.4 3778.4 3798.4 3865.1 3876.4 3908.6 1239.5 1246.6 1250.9 1258.3 1260.1 760.3 788.5 795.8 853.6 899.7 5514.0 5522.3 5632.3 5783.4 5823.3 840.5 868.3 884.5 891.2 912.8 3699.5 3705.8 3725.0 3779.7 3820.9 9719.1 10155.1 10227.4 10229.1 10235.1 8939.0 9585.7 9716.6 9904.9 9924.1 7225.9 7433.5 7670.5 7898.4 7899.8 5529.6 5548.8 5553.4 5664.0 5706.2 7784.3 9453.7 9487.0 9498.2 9510.4 9302.3 9491.4 9599.3 9676.6 9680.6 6944.5 7020.6 7122.3 7131.0 7327.0 7930.2 8015.6 8023.3 8030.5 8062.8 6840.2 6865.3 6906.1 7084.4 7177.4 6879.0 6956.7 6997.1 7098.3 7125.8 6498.8 6676.3 6687.2 6687.9 6689.9 4716.8 4750.4 4768.7 4799.0 4810.4 3673.2 4041.5 4764.8 4767.0 4809.0 02:32:28 INFO - PROCESS | 1664 | 02:32:28 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4328.75 Median:4890.43 stddev:1311.37 (26.8%) stddev-sans-first:1279.67 02:32:28 INFO - PROCESS | 1664 | Values: 298.0 305.3 305.8 311.8 315.4 4068.8 4481.0 4883.2 5256.7 5306.6 4920.9 5162.4 5224.5 5247.7 5260.7 3381.3 3852.5 3943.6 3982.3 4042.4 3981.7 4014.4 4015.5 4053.6 4100.2 3610.7 3951.2 3957.2 3965.4 3973.9 3552.3 3627.5 3738.7 3999.7 4011.8 3452.7 3466.9 3469.9 3478.5 3488.5 4250.4 4940.9 5031.5 5038.7 5062.2 4407.8 4432.0 4511.3 4538.8 4552.5 5057.1 5185.0 5397.2 5421.6 5467.5 2942.4 3836.6 3840.2 3841.2 3841.9 5269.5 5411.0 5686.9 5710.5 5773.8 3001.8 3881.8 3882.1 3883.1 3891.1 3924.9 3940.6 3943.7 3946.4 3976.5 3881.8 3964.6 4058.6 4406.2 4560.9 4566.7 4909.0 5118.4 5534.0 5579.4 1076.9 1246.8 1247.4 1250.3 1255.9 760.2 796.3 812.3 835.9 852.8 4729.4 4832.4 4845.2 4956.5 5127.9 5481.6 5497.3 5507.1 5545.1 5545.1 4295.1 4474.4 5063.8 5108.2 5267.6 2613.9 2625.9 2840.9 2844.0 2851.7 5081.5 5577.9 5622.1 5632.9 5640.1 4897.6 5222.3 5292.9 5478.8 5500.8 4595.9 4823.1 5272.5 5308.5 5630.4 5338.0 5383.3 5420.3 5421.3 5433.8 4320.4 4423.5 4610.7 4980.7 5267.7 5017.8 5110.8 5217.9 5538.8 5673.1 3173.0 3419.1 3534.0 3553.3 3560.6 5035.8 5331.6 5333.5 5365.4 5375.2 5162.4 5172.8 5211.0 5229.7 5274.8 4480.2 4850.1 5156.8 5171.3 5228.7 4667.7 4826.7 4977.9 5100.7 5171.4 4698.4 5181.4 5227.7 5346.4 5372.3 4712.4 5385.8 5399.6 5399.8 5403.4 02:32:28 INFO - PROCESS | 1664 | 02:32:28 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18897.13 Median:55051.72 stddev:27110.60 (49.2%) stddev-sans-first:27152.25 02:32:28 INFO - PROCESS | 1664 | Values: 706.7 715.3 728.7 734.7 769.4 13608.4 14002.9 14013.5 14022.8 14022.9 12895.8 12900.2 12905.9 12948.6 13063.7 638.7 659.1 660.4 662.5 797.1 652.8 655.1 783.7 815.5 818.0 864.0 871.0 876.3 877.4 882.0 828.5 828.8 829.0 843.2 865.4 499.5 511.1 646.0 648.5 649.6 8850.4 11310.6 11332.2 11478.0 11516.4 13260.3 13562.7 13686.8 13718.7 13747.9 10430.2 10755.1 10918.5 10946.2 11004.0 3557.8 3613.7 3809.8 3833.8 3844.4 77286.0 81500.0 81837.6 88447.6 89648.3 10185.9 10408.7 10605.3 10746.1 10869.7 20010.7 20358.3 20457.3 20460.3 20469.7 7388.7 7679.5 7766.5 7767.3 7867.8 3814.8 4038.1 4211.2 4299.8 4397.6 3314.6 3376.1 3432.1 3437.7 3447.4 630.4 639.7 646.3 646.6 653.4 597.0 615.0 634.2 639.3 659.5 8661.2 8864.5 8939.7 9018.1 9208.2 600.6 629.9 643.6 654.3 661.9 4192.9 4401.1 4456.9 4459.7 4468.4 63225.7 68147.7 98054.0 102395.0 103855.4 60005.4 77419.0 91251.0 93319.0 95927.0 76865.6 78675.2 81175.0 81563.0 82492.0 7327.5 8505.1 8740.6 8833.5 8950.7 52667.5 53669.5 54648.7 55454.7 57082.4 64839.8 67478.3 73502.0 76062.0 76209.0 10275.8 10470.4 10521.4 10568.5 10796.8 11796.8 11799.4 12131.4 12378.4 12606.4 11726.2 11812.9 12161.9 12216.3 12227.0 9842.0 11639.2 11932.3 12008.8 12370.1 11966.2 12060.2 12137.9 12381.2 12501.6 10439.4 10877.9 10905.7 10976.3 11104.6 9331.7 9686.2 9830.6 9916.6 10111.0 02:32:28 INFO - PROCESS | 1664 | -------- Summary: end -------- 02:32:28 INFO - PROCESS | 1664 | 02:32:29 INFO - TEST-INFO | 1664: exit 0 02:32:29 INFO - TEST-OK | dromaeo_css | took 1119984ms 02:32:29 INFO - TEST-START | kraken 02:32:29 INFO - operating with platform_type : mac_ 02:32:29 INFO - Initialising browser for kraken test... 02:32:29 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpHbmezm/profile http://localhost:49244/getInfo.html) 02:32:32 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 02:32:32 INFO - PROCESS | 1672 | colorDepth:24 02:32:32 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 02:32:32 INFO - PROCESS | 1672 | __metrics 02:32:32 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:32:32 INFO - PROCESS | 1672 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:32:33 INFO - TEST-INFO | 1672: exit 0 02:32:33 INFO - Browser initialized. 02:32:33 INFO - Running cycle 1/1 for kraken test... 02:32:33 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpHbmezm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:32:45 INFO - PROCESS | 1674 | RSS: Main: 222539776 02:32:45 INFO - PROCESS | 1674 | 02:32:48 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 02:32:49 INFO - PROCESS | 1674 | RSS: Main: 272764928 02:32:49 INFO - PROCESS | 1674 | 02:32:51 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 02:32:52 INFO - PROCESS | 1674 | RSS: Main: 283488256 02:32:52 INFO - PROCESS | 1674 | 02:32:55 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 02:32:56 INFO - PROCESS | 1674 | RSS: Main: 382124032 02:32:56 INFO - PROCESS | 1674 | 02:32:58 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 02:32:58 INFO - PROCESS | 1674 | RSS: Main: 283275264 02:32:58 INFO - PROCESS | 1674 | 02:33:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:33:01 INFO - PROCESS | 1674 | RSS: Main: 284983296 02:33:01 INFO - PROCESS | 1674 | 02:33:04 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 02:33:04 INFO - PROCESS | 1674 | RSS: Main: 327729152 02:33:04 INFO - PROCESS | 1674 | 02:33:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 02:33:08 INFO - PROCESS | 1674 | RSS: Main: 326844416 02:33:08 INFO - PROCESS | 1674 | 02:33:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 02:33:11 INFO - PROCESS | 1674 | RSS: Main: 325611520 02:33:11 INFO - PROCESS | 1674 | 02:33:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:33:13 INFO - PROCESS | 1674 | RSS: Main: 300851200 02:33:13 INFO - PROCESS | 1674 | 02:33:15 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:33:16 INFO - PROCESS | 1674 | RSS: Main: 300650496 02:33:16 INFO - PROCESS | 1674 | 02:33:18 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:33:19 INFO - PROCESS | 1674 | RSS: Main: 302989312 02:33:19 INFO - PROCESS | 1674 | 02:33:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:33:22 INFO - PROCESS | 1674 | RSS: Main: 311259136 02:33:22 INFO - PROCESS | 1674 | 02:33:25 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:33:26 INFO - PROCESS | 1674 | RSS: Main: 311287808 02:33:26 INFO - PROCESS | 1674 | 02:33:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 02:33:28 INFO - PROCESS | 1674 | RSS: Main: 355123200 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | __start_tp_report 02:33:28 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 02:33:28 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 02:33:28 INFO - PROCESS | 1674 | |i|pagename|runs| 02:33:28 INFO - PROCESS | 1674 | |0;ai-astar;115;115;115;114;114;115;115;115;115;114 02:33:28 INFO - PROCESS | 1674 | |1;audio-beat-detection;144;113;110;152;140;137;127;109;137;110 02:33:28 INFO - PROCESS | 1674 | |2;audio-dft;184;186;185;186;179;184;184;185;184;184 02:33:28 INFO - PROCESS | 1674 | |3;audio-fft;82;60;59;89;62;61;60;60;85;58 02:33:28 INFO - PROCESS | 1674 | |4;audio-oscillator;82;71;93;69;70;72;102;96;95;121 02:33:28 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;87;88;86;84;86;83;88;84;87;85 02:33:28 INFO - PROCESS | 1674 | |6;imaging-darkroom;94;94;94;93;94;94;93;94;94;94 02:33:28 INFO - PROCESS | 1674 | |7;imaging-desaturate;80;84;80;83;83;83;86;86;83;81 02:33:28 INFO - PROCESS | 1674 | |8;json-parse-financial;56;56;56;56;59;58;58;57;57;57 02:33:28 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;66;65;75;80;78;75;70;73;71;72 02:33:28 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;85;88;85;84;83;83;82;84;81;81 02:33:28 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;140;139;140;141;149;151;151;137;212;188 02:33:28 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;165;164;165;164;165;164;171;165;163;164 02:33:28 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;69;67;67;68;67;67;66;67;68;67 02:33:28 INFO - PROCESS | 1674 | __end_tp_report 02:33:28 INFO - PROCESS | 1674 | __start_cc_report 02:33:28 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1965 02:33:28 INFO - PROCESS | 1674 | __end_cc_report 02:33:28 INFO - PROCESS | 1674 | __startTimestamp1483007608226__endTimestamp 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | ------- Summary: start ------- 02:33:28 INFO - PROCESS | 1674 | Number of tests: 14 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 02:33:28 INFO - PROCESS | 1674 | Values: 115.0 115.0 115.0 114.0 114.0 115.0 115.0 115.0 115.0 114.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:137.00 stddev:16.24 (11.9%) stddev-sans-first:16.14 02:33:28 INFO - PROCESS | 1674 | Values: 144.0 113.0 110.0 152.0 140.0 137.0 127.0 109.0 137.0 110.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:1.97 (1.1%) stddev-sans-first:2.09 02:33:28 INFO - PROCESS | 1674 | Values: 184.0 186.0 185.0 186.0 179.0 184.0 184.0 185.0 184.0 184.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:67.60 Median:61.50 stddev:12.39 (20.2%) stddev-sans-first:12.00 02:33:28 INFO - PROCESS | 1674 | Values: 82.0 60.0 59.0 89.0 62.0 61.0 60.0 60.0 85.0 58.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:77.00 stddev:17.26 (22.4%) stddev-sans-first:18.21 02:33:28 INFO - PROCESS | 1674 | Values: 82.0 71.0 93.0 69.0 70.0 72.0 102.0 96.0 95.0 121.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:86.50 stddev:1.75 (2.0%) stddev-sans-first:1.80 02:33:28 INFO - PROCESS | 1674 | Values: 87.0 88.0 86.0 84.0 86.0 83.0 88.0 84.0 87.0 85.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:33:28 INFO - PROCESS | 1674 | Values: 94.0 94.0 94.0 93.0 94.0 94.0 93.0 94.0 94.0 94.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:82.90 Median:83.00 stddev:2.13 (2.6%) stddev-sans-first:1.99 02:33:28 INFO - PROCESS | 1674 | Values: 80.0 84.0 80.0 83.0 83.0 83.0 86.0 86.0 83.0 81.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:1.05 (1.8%) stddev-sans-first:1.05 02:33:28 INFO - PROCESS | 1674 | Values: 56.0 56.0 56.0 56.0 59.0 58.0 58.0 57.0 57.0 57.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:74.00 stddev:4.79 (6.5%) stddev-sans-first:4.47 02:33:28 INFO - PROCESS | 1674 | Values: 66.0 65.0 75.0 80.0 78.0 75.0 70.0 73.0 71.0 72.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:2.12 (2.5%) stddev-sans-first:2.19 02:33:28 INFO - PROCESS | 1674 | Values: 85.0 88.0 85.0 84.0 83.0 83.0 82.0 84.0 81.0 81.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:150.00 stddev:25.01 (16.7%) stddev-sans-first:25.95 02:33:28 INFO - PROCESS | 1674 | Values: 140.0 139.0 140.0 141.0 149.0 151.0 151.0 137.0 212.0 188.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:165.00 stddev:2.21 (1.3%) stddev-sans-first:2.35 02:33:28 INFO - PROCESS | 1674 | Values: 165.0 164.0 165.0 164.0 165.0 164.0 171.0 165.0 163.0 164.0 02:33:28 INFO - PROCESS | 1674 | 02:33:28 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.60 02:33:28 INFO - PROCESS | 1674 | Values: 69.0 67.0 67.0 68.0 67.0 67.0 66.0 67.0 68.0 67.0 02:33:28 INFO - PROCESS | 1674 | -------- Summary: end -------- 02:33:28 INFO - PROCESS | 1674 | 02:33:29 INFO - TEST-INFO | 1674: exit 0 02:33:29 INFO - TEST-OK | kraken | took 59844ms 02:33:29 INFO - SUITE-END | took 1179s 02:33:29 INFO - Completed test suite (00:19:39) 02:33:29 INFO - javascript benchmark 02:33:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3290.5557749703785, 3308.702216800486, 3319.5186697928793, 3325.8004519728834, 3363.0, 5600.859978500534, 5646.407127251641, 5698.971505142474, 5700.144978253254, 5743.798967036149, 5588.161775733642, 5615.859603509914, 5616.5787565932515, 5640.74616642251, 5766.077427611583, 4257.787110644455, 5125.948740512594, 5172.0, 5203.219517072451, 5221.660592061523, 4446.3997360356425, 5125.410577783547, 5158.045761534109, 5234.241035049916, 5267.86830329241, 5170.069387510247, 5183.481651834805, 5201.713905735183, 5209.296744939423, 5220.190870415092, 5203.0, 5216.3218781558535, 5267.420583735786, 5275.57795376369, 5294.629375943686, 5063.1898896176435, 5127.769335359524, 5188.688678679254, 5197.376314842205, 5223.973880130613, 5527.2538207341895, 5570.693611851347, 5597.720113994284, 5609.354924183689, 5638.35158956717, 5065.366829146356, 6081.726322315506, 6138.355472675349, 6194.783182588588, 6200.255969283669, 6245.812625621239, 6253.53098517613, 6267.4672652824565, 6269.62382257066, 6272.435480806749, 5504.834854954358, 5542.196381524656, 5549.750261238173, 5684.914726279109, 5834.912476312858, 6232.470240029557, 6250.062490626442, 6273.247210334789, 6275.0, 6283.5287353448675, 6095.116208149793, 6109.725062372204, 6114.205153330038, 6138.386161383826, 6139.6009259398, 6039.033754599243, 6102.389761023862, 6107.408898231393, 6126.571140020245, 6148.938510614926, 6062.42406971337, 6109.116942259659, 6139.201903752573, 6155.0, 6161.44546990773, 6135.447809697149, 6171.783987560414, 6178.598391104564, 6182.845428864314, 6190.288116866618, 4833.758312084382, 4889.951100488949, 4891.461939186686, 4895.265710143507, 4917.9016419672125, 4259.0, 4264.0832221072615, 4285.571442855689, 4342.413774140514, 4379.562043795595, 5664.688442135722, 5684.891446167958, 5702.942970570324, 5724.227229323988, 5761.9135712964335, 4916.606671466346, 6173.351798061229, 6212.844678883064, 6223.782167624111, 6251.78118765841, 5550.72246387679, 5627.831165065055, 5667.40492248308, 5676.403977582377, 5688.544916406678, 5288.286081378965, 5325.653832500952, 5343.171808369709, 5361.249425080492, 5386.192071189353, 6277.529185311028, 6281.15204447412, 6289.685515724287, 6299.748010079728, 6307.306196318493, 5904.439078287556, 6025.517958563217, 6171.957367923837, 6227.501799856092, 6232.75068997235, 5262.736863156751, 6117.309816958451, 6219.346968568235, 6232.875342493216, 6264.029075493306, 6058.57589968698, 6115.908261376172, 6122.672617327995, 6139.2114150962325, 6144.126041557368, 6179.969100154471, 6210.285817131088, 6220.066989951543, 6221.720022599085, 6229.781957631461, 5844.649321040752, 5953.732082056405, 6007.3992600739575, 6033.42682445167, 6049.697515124314, 6010.729517171825, 6037.456628903244, 6047.909281360695, 6047.971844786309, 6080.513558915364, 5893.499052580407, 5903.940960590339, 5907.497862681548, 5907.911381329198, 5909.113632955091, 5816.0, 5919.082542205792, 5932.1694962706115, 6013.158157857816, 6027.939720602738, 5807.245058142415, 5831.35855055952, 5840.795572154954, 5848.239728835224, 5853.443922827325], "value": 5618.94053209943, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.29511331692265, 507.9796808127675, 517.6402400331771, 519.6752029981262, 545.0624925128778, 30250.54624180636, 37795.62204377956, 39124.02189945249, 39300.80349598255, 40758.388624170664, 40124.19751604966, 40618.79690601543, 40626.98432539188, 40692.59307406932, 40707.389389159114, 7145.464090193231, 9255.352125351228, 9417.764555886124, 9429.245660347156, 9448.574814133346, 8384.371172162082, 11045.226834121615, 11280.210385273034, 11309.264897781659, 11464.598739044135, 11132.220744547885, 11199.888001119965, 11200.719982000475, 11216.990470857621, 11226.438678066104, 11164.218504704673, 11231.494582743797, 11243.831342529827, 11253.943730281297, 11268.943655281753, 11037.448127593669, 11134.16493762971, 11135.443227838576, 11142.220044596881, 11142.387168705718, 31763.841180794177, 38788.612113878786, 38790.418143727584, 38826.805865970775, 38898.41652375217, 3169.8732050717945, 3171.9849648112613, 3266.3304022675534, 3304.438245498247, 3441.174118211636, 27942.580861287097, 28263.01079462209, 29006.0, 30220.395592088254, 30511.542326864892, 3112.9221769455944, 3173.7778355515125, 3177.777555571111, 3239.72462340699, 3260.2012506935716, 461586.0, 461905.0, 462169.0, 464924.0, 469559.0, 31972.520412193837, 32401.027969160492, 32760.508592371134, 56048.439515604325, 56421.43578564162, 33913.0, 34099.65900341015, 34116.48825267623, 34123.31753364919, 34144.82927585396, 14181.14913105217, 14309.713805723828, 14314.570562883131, 14327.928360358132, 14358.641033974234, 11013.229073964745, 11250.549978000952, 12095.939520302518, 12176.391180440942, 12176.512939482498, 7210.927890721131, 7497.325240728361, 7702.075750909815, 7733.304002639789, 7757.379409647215, 3222.355528894184, 3223.0975326908033, 3226.2902161524426, 3228.2090887732656, 3249.5938007749032, 897.5243121145803, 967.670991862756, 985.5663508056432, 991.6727479931606, 1048.02010120536, 23642.4089397763, 28893.133206003855, 29226.661867050585, 29693.554596681068, 30762.0, 1042.08296698906, 1088.1077516436446, 1088.4231357380602, 1109.877913429522, 1125.6285425809463, 14786.556403307917, 14857.885658575435, 15007.924960375347, 15023.94832361746, 15263.771043434357, 569861.0, 669702.6514867394, 671568.0, 672043.0, 672131.0, 55253.0, 55526.72236638791, 55593.88812223733, 55634.72182639061, 55643.721781390836, 1227.5826219085554, 1267.3916520070418, 1346.2999240395136, 1373.615387691448, 1388.625071230742, 28979.130626081253, 29560.704392955795, 29727.10818675443, 29780.70219297779, 30475.39049218959, 45996.540034599224, 46486.070278593565, 46492.767536163454, 46508.0, 46517.53482465268, 501839.0, 504276.0, 505298.0, 505937.0, 862508.0, 34835.25823708855, 37416.6258337424, 38279.617203827605, 39051.02372440598, 39473.0, 42083.7895810519, 42637.786811065744, 42774.78612606917, 42958.57041429546, 43084.0, 35072.0, 35419.82290088533, 35669.28661426809, 35960.64039359573, 36035.0, 35522.644773552965, 35751.64248357483, 35781.28437431289, 36020.63979360173, 36115.81942090273, 34823.4776478348, 35194.12014699653, 35866.82066589651, 35948.0, 36070.27859442744, 29415.705842941297, 31440.52839207368, 31509.369812604076, 31612.525812112377, 31855.840720797176, 24086.518269634158, 24134.637980429958, 24266.63600046036, 24516.019359225433, 24634.0], "value": 18359.844197992796, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.37636319170286, 369.0920335973505, 372.59759459783436, 378.86550274652495, 397.3741357362155, 14600.342984565694, 14866.108033517983, 15466.845331546696, 19038.61922761544, 20214.393568192976, 19028.334008309714, 19527.511812204702, 20107.698384524243, 20137.194512219492, 20373.49066273344, 5539.0, 5960.0, 5996.22049133612, 6005.12925625784, 6006.759729610832, 4636.933505293784, 5846.23998880145, 5886.705664716768, 5888.558358123158, 5895.35151133375, 5558.499735023847, 5831.620944638585, 5834.0, 5844.503217226541, 5864.618799778023, 5677.801276955287, 5857.384974577693, 5860.706964651793, 5861.941380586182, 5863.296404431453, 5049.570786483153, 5117.769700363493, 5124.538791508745, 5130.871728206825, 5327.813526526553, 11760.294382337002, 12615.810762838473, 12742.872571274262, 12755.0, 12775.74448511034, 2153.8384621153477, 2228.6545585434283, 2298.092253559851, 2477.4054226985572, 2850.914472565826, 9972.252081093948, 10142.391456512632, 13176.934115329457, 13952.790708139284, 14389.856101438956, 1645.7778199942975, 1722.0872937343229, 1736.6179440523065, 1794.7577077094556, 1829.0945981739123, 87637.23913325825, 90843.54578227132, 105389.47305263425, 107940.0, 122049.38975305155, 10907.727306817234, 10913.345199288067, 11410.65768026944, 11470.770584588261, 11661.533538658528, 14348.928255358865, 14378.928105359615, 14398.496052638317, 14400.919931005217, 14407.567772966828, 6991.23096459389, 8087.716929907425, 8499.107593702696, 8526.57367131641, 8558.529280889486, 8383.371247156487, 8412.032616249087, 8424.705135320235, 8576.914230857736, 8587.742367728979, 4318.3738357938555, 4344.326629372453, 4445.088756804847, 4487.147441986012, 4588.128255631419, 1842.603840174369, 1900.296890150653, 1903.4391798724912, 1903.4670292318174, 1904.523869032742, 801.4029547986671, 863.87905693203, 878.3149143667949, 905.9365844390829, 955.7945041815904, 19733.0, 20344.49138771513, 20393.0, 20423.0, 20502.76983381002, 914.3919293669768, 924.6671198368582, 926.416357694648, 941.6280569175137, 987.132380434838, 9836.016398360107, 10699.34724940642, 10823.719450787232, 10888.891111088946, 11865.644030679094, 564819.0, 577390.0, 579265.0, 584890.0, 590679.0, 87901.5604921984, 104018.47990759998, 109622.0, 121821.39089304674, 123574.0, 951.7335146159087, 996.9800603988025, 1011.2264117949775, 1011.6964910526813, 1012.0, 17691.469255922344, 17840.3755868544, 18601.813981860007, 18616.627667446846, 18925.810741892405, 36651.45022824713, 39645.405318919664, 44086.0, 50845.491545085555, 52310.0, 69161.96257056047, 69903.60192796217, 71906.88791452785, 72853.0, 74603.62698186474, 6932.757353492603, 7971.641276142704, 8003.559804210824, 8004.119546849725, 8012.719554815787, 12171.0, 12621.432035558239, 12798.872011279767, 12853.742925141632, 13010.869891300967, 10379.636712715019, 10703.892961070289, 10898.291611045257, 10924.890751092706, 11098.94888365757, 8613.48319100856, 10763.569457221623, 10877.782444351227, 11035.172362072715, 11302.94348528285, 11106.000459958643, 11154.944225278821, 11192.608258711232, 11678.372366217533, 11735.706607334885, 6892.172939247122, 7962.0, 7976.699300280915, 8185.918140818516, 8336.29141522953, 7751.0, 9151.542422878963, 10838.837417438903, 10994.065504432212, 11096.667099987013], "value": 9076.020531734333, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.5406269993607, 1477.645365112373, 1604.9438269660563, 1770.3803668715943, 1794.3091909614802, 7822.882656760146, 8405.159484051592, 8409.201125893038, 8417.579121043938, 8417.663293468255, 6282.780102696395, 7415.110186777581, 7528.774136775892, 7997.840043199147, 8236.91763082369, 2272.4432514034042, 2320.1299512682745, 2322.059565875823, 2325.988370058147, 2326.325365643961, 2327.650852372141, 2361.0437772702085, 2362.350353652742, 2383.4398916254668, 2387.5583017141885, 2325.0815927708545, 2325.337278875521, 2356.45801465663, 2362.126013375054, 2363.9881800590974, 2299.72403311602, 2310.630299152136, 2341.1220792202926, 2342.6763878408647, 2345.1323010486144, 1958.9030143119755, 2328.429534763977, 2355.8939847706893, 2360.45709486819, 2365.4795944892267, 8178.754637360888, 8339.0, 8355.498670079754, 8369.707060252862, 8375.0, 5030.170021946375, 5208.453112423179, 5211.088059589557, 5215.582753379758, 5231.346081739783, 3231.7737758356925, 3319.0, 3566.3045706087323, 3569.446735755962, 3576.8032758198287, 1048.6434612231878, 1048.669669054253, 1077.805994920914, 1089.5750657243682, 1100.6092837042816, 10888.836667449994, 11002.33985960837, 11032.22774405792, 11033.117350611932, 11061.944690276578, 4507.549245075532, 4534.795934182904, 4736.526347365305, 4742.075295317449, 4745.715257084586, 9371.062893710574, 9376.109269619375, 9380.765480863032, 9391.953040234755, 9403.482808445464, 5225.5819534437915, 5227.947720522746, 5230.692326918271, 5231.215317702299, 5238.76425560843, 3964.5837187095517, 4175.227582897173, 4723.267893476516, 4792.113459010095, 4793.448753393404, 3778.414345776409, 3798.3922572388287, 3865.1110244643887, 3876.3604005339366, 3908.5895980922164, 1239.5227837282712, 1246.5512415530402, 1250.93745312734, 1258.3205069262497, 1260.1431026901612, 760.2587477209677, 788.5150632361057, 795.7891158842912, 853.5945425922637, 899.6536333511515, 5514.007478653881, 5522.337319521603, 5632.295963004624, 5783.392743761929, 5823.30120385548, 840.4663038970298, 868.2966796894535, 884.4869975414244, 891.2379915172435, 912.8402529557301, 3699.5375578052744, 3705.814709264526, 3724.981375093107, 3779.6598306152027, 3820.9110403535415, 9719.125278724949, 10155.136813370798, 10227.43749093807, 10229.079382855578, 10235.078842904175, 8938.955305223431, 9585.712428627152, 9716.61133554664, 9904.851427228597, 9924.106830385155, 7225.89161162583, 7433.516821406592, 7670.501417407851, 7898.407619428451, 7899.84200315979, 5529.557635389239, 5548.805791797267, 5553.416891226525, 5663.9716801415725, 5706.172604972338, 7784.260495252942, 9453.669121580711, 9487.0, 9498.19265362452, 9510.429374237568, 9302.255819534286, 9491.430514168896, 9599.32804703664, 9676.612935482503, 9680.612775488902, 6944.4791640626145, 7020.6489675514995, 7122.252163522756, 7130.964345178241, 7327.047483827068, 7930.206979302255, 8015.599220038858, 8023.277904988579, 8030.5181689098845, 8062.798430039296, 6840.213375461885, 6865.313468653095, 6906.075975777535, 7084.36240738336, 7177.425805935408, 6879.0, 6956.686949087202, 6997.1253593300835, 7098.29017098286, 7125.82185445368, 6498.837529061811, 6676.332366763285, 6687.1975362956755, 6687.899681504879, 6689.899651505329, 4716.8113275468595, 4750.406199225097, 4768.666193366431, 4799.0401919615515, 4810.446798618063, 3673.2286219893053, 4041.5352234492234, 4764.8332308369045, 4766.952330476651, 4809.0], "value": 4384.031858604038, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.9925501862453, 305.28410876494627, 305.8455479982608, 311.8129122526483, 315.41962788469215, 4068.8372465101434, 4481.0, 4883.2430973199125, 5256.658317209379, 5306.646805064713, 4920.892799120196, 5162.432132465426, 5224.4775522447735, 5247.711375874325, 5260.684358938457, 3381.3406385754847, 3852.499175107246, 3943.6450719435247, 3982.3230050891416, 4042.3532234842432, 3981.686043605603, 4014.3577027675433, 4015.518137823465, 4053.5946405359377, 4100.1594673092095, 3610.6750392464673, 3951.170254246612, 3957.248122856648, 3965.385365268388, 3973.87397742208, 3552.3250582389264, 3627.455881617752, 3738.6822120119823, 3999.740016898907, 4011.83952641894, 3452.723782097426, 3466.907924003956, 3469.8785542506143, 3478.4608385700258, 3488.5116083748294, 4250.404943307908, 4940.912999140183, 5031.547160755549, 5038.722870242135, 5062.152314405215, 4407.808173445148, 4431.955680443187, 4511.345854851061, 4538.773061346952, 4552.474920901502, 5057.0897238497055, 5184.963007398535, 5397.163439666819, 5421.620486565942, 5467.480589344006, 2942.3963939652726, 3836.5779764225913, 3840.231953609289, 3841.154945911895, 3841.9039524012123, 5269.473052694738, 5411.0, 5686.891056244034, 5710.514606258473, 5773.826785196451, 3001.8198908065588, 3881.7670939743707, 3882.145927895858, 3883.1457079442475, 3891.105045839473, 3924.8822535323984, 3940.566537680853, 3943.659155886955, 3946.3883098119836, 3976.5029371328583, 3881.787381192693, 3964.623360780721, 4058.6347228749555, 4406.20688276107, 4560.931586026212, 4566.725996440225, 4908.950910490921, 5118.436971933084, 5534.0, 5579.38626751057, 1076.9461526923621, 1246.765701955058, 1247.3887794980394, 1250.2560976219136, 1255.8681338459512, 760.1638197982176, 796.2714116583261, 812.3135950122137, 835.9456635318685, 852.799592095857, 4729.385179926657, 4832.371791667061, 4845.152098382769, 4956.4547899731, 5127.87180320495, 5481.643693159932, 5497.312835895513, 5507.0637991541535, 5545.085060964976, 5545.140503222007, 4295.055087880662, 4474.418325617649, 5063.797448102035, 5108.182690769533, 5267.552258058108, 2613.8890971337105, 2625.9474810503684, 2840.9204502289, 2843.9619538868164, 2851.657801063844, 5081.4918508148885, 5577.912307100158, 5622.07652020955, 5632.943670563324, 5640.0693885508435, 4897.608191344684, 5222.268882356473, 5292.867678307996, 5478.835634930959, 5500.75455481268, 4595.908081838412, 4823.14308991034, 5272.499112584375, 5308.548773354308, 5630.40880707528, 5338.0, 5383.273258110262, 5420.349558053059, 5421.322334708161, 5433.750237445489, 4320.4383430153875, 4423.496011356155, 4610.677252592421, 4980.701157930391, 5267.683938963674, 5017.8243761468175, 5110.821121260738, 5217.878156196436, 5538.750756216061, 5673.120666296731, 3172.96827031736, 3419.136570568996, 3533.982330088436, 3553.307105114478, 3560.643935606522, 5035.773390197377, 5331.600129990189, 5333.493318134771, 5365.382980957083, 5375.242910024578, 5162.380514338303, 5172.818951336836, 5210.993767399655, 5229.660072095302, 5274.841754747364, 4480.193565158303, 4850.102730994778, 5156.767945442539, 5171.276021357088, 5228.712420816892, 4667.673262871566, 4826.710397376169, 4977.875553111056, 5100.668456550461, 5171.431142574389, 4698.412698412699, 5181.430042695376, 5227.686338819683, 5346.3535549903045, 5372.280870121524, 4712.363830882924, 5385.838424847104, 5399.596105012646, 5399.83800485986, 5403.378611459732], "value": 3908.281128680167, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.6855249414012, 715.2918610575532, 728.6684558525872, 734.6657270941722, 769.4229328003997, 13608.387622556984, 14002.92998535006, 14013.509527166563, 14022.789658155136, 14022.859771402284, 12895.806562901564, 12900.22598644082, 12905.870941290585, 12948.611541653765, 13063.673408164801, 638.7476946606088, 659.1332397896774, 660.3561527510686, 662.4799532367093, 797.1231645190302, 652.8302641313269, 655.0501772429972, 783.7492002559184, 815.5269943432825, 817.9836403271931, 864.0365991918997, 870.9896520036758, 876.329607849992, 877.3814460805149, 881.9901213110984, 828.5484410996022, 828.8300898315831, 828.9875651865227, 843.228445971941, 865.4417900454234, 499.5104797298631, 511.05709965114306, 646.0277282689569, 648.45205801098, 649.6264647827518, 8850.380473366802, 11310.60412885545, 11332.206745527816, 11478.0, 11516.366599837005, 13260.3369831509, 13562.728745425135, 13686.794698079537, 13718.65703357414, 13747.931260343734, 10430.16558675304, 10755.139588832875, 10918.454077296103, 10946.233763636548, 11004.0, 3557.7509574329806, 3613.7109031277437, 3809.847606095753, 3833.7891415972113, 3844.442555829389, 77286.0, 81500.0, 81837.59081204615, 88447.55776221078, 89648.31055068359, 10185.94907025475, 10408.739781505372, 10605.25763196569, 10746.086582640562, 10869.728256793485, 20010.69983950242, 20358.28745993883, 20457.2839950601, 20460.283890063776, 20469.692954605693, 7388.667509962064, 7679.539227646359, 7766.534007959541, 7767.300942915052, 7867.803304917423, 3814.809259537012, 4038.0510580013674, 4211.157768446323, 4299.828006879689, 4397.626201772821, 3314.5691060162508, 3376.0884561168837, 3432.1076520104452, 3437.6562343765922, 3447.4311738562856, 630.4357599948021, 639.68015992004, 646.2729429391934, 646.570030929436, 653.389081209071, 596.9910451343234, 614.9600275982046, 634.1534052040578, 639.3478651775164, 659.5152959872845, 8661.220490155916, 8864.512451815082, 8939.650872817954, 9018.053104424072, 9208.217301529312, 600.618607184441, 629.9338569450233, 643.5688088980395, 654.2672207128047, 661.9304972977768, 4192.937105943413, 4401.141777353385, 4456.866294011184, 4459.665525085632, 4468.402225755128, 63225.683871580346, 68147.65926170337, 98054.0, 102395.0, 103855.44216836753, 60005.399946000856, 77419.0, 91251.0, 93319.0, 95927.0, 76865.61567192129, 78675.21324786793, 81175.0, 81563.0, 82492.0, 7327.523710958771, 8505.128871648227, 8740.562971851255, 8833.469991800512, 8950.731478055668, 52667.47332526779, 53669.463305366444, 54648.72675636596, 55454.72272638611, 57082.429175709374, 64839.84021517948, 67478.32521674721, 73502.0, 76062.0, 76209.0, 10275.84586231222, 10470.371777693363, 10521.368717876952, 10568.524416401127, 10796.784064318826, 11796.76406471883, 11799.35103569312, 12131.393430328624, 12378.442970066188, 12606.432710527864, 11726.237794543327, 11812.93273677086, 12161.939190303992, 12216.328101954477, 12227.02183825274, 9841.95079024629, 11639.185257032264, 11932.343721095423, 12008.819867702166, 12370.13409061393, 11966.22219555726, 12060.155789094679, 12137.878621213675, 12381.195222310522, 12501.56244531437, 10439.37363758177, 10877.945610271898, 10905.672829815117, 10976.341419514854, 11104.5558177672, 9331.72004839856, 9686.176674982706, 9830.606775728891, 9916.603335866484, 10111.03945125212], "value": 6897.162935987497, "unit": "score"}], "name": "dromaeo_css", "value": 6928.923885642194, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 114.0, 114.0, 115.0, 115.0, 115.0, 115.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 110.0, 152.0, 140.0, 137.0, 127.0, 109.0, 137.0, 110.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 185.0, 186.0, 179.0, 184.0, 184.0, 185.0, 184.0, 184.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 59.0, 89.0, 62.0, 61.0, 60.0, 60.0, 85.0, 58.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 93.0, 69.0, 70.0, 72.0, 102.0, 96.0, 95.0, 121.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 86.0, 84.0, 86.0, 83.0, 88.0, 84.0, 87.0, 85.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 80.0, 83.0, 83.0, 83.0, 86.0, 86.0, 83.0, 81.0], "value": 82.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 56.0, 59.0, 58.0, 58.0, 57.0, 57.0, 57.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 75.0, 80.0, 78.0, 75.0, 70.0, 73.0, 71.0, 72.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 85.0, 84.0, 83.0, 83.0, 82.0, 84.0, 81.0, 81.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 140.0, 141.0, 149.0, 151.0, 151.0, 137.0, 212.0, 188.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 165.0, 164.0, 165.0, 164.0, 171.0, 165.0, 163.0, 164.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 68.0, 67.0, 67.0, 66.0, 67.0, 68.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1444.1, "alertThreshold": 2.0}]} 02:33:29 INFO - Return code: 0 02:33:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:33:29 INFO - # TBPL SUCCESS # 02:33:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:33:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:33:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:33:29 INFO - 2016-12-29 02:33:29.527 screenresolution[1678:10978] starting screenresolution argv=screenresolution get 02:33:29 INFO - 2016-12-29 02:33:29.530 screenresolution[1678:10978] Display 0: 1600x1200x32@60 02:33:29 INFO - 2016-12-29 02:33:29.536 screenresolution[1679:10981] starting screenresolution argv=screenresolution list 02:33:29 INFO - Available Modes on Display 0 02:33:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:33:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:33:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:33:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:33:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:33:29 INFO - Intel Iris: 02:33:29 INFO - Chipset Model: Intel Iris 02:33:29 INFO - Type: GPU 02:33:29 INFO - Bus: Built-In 02:33:29 INFO - VRAM (Dynamic, Max): 1536 MB 02:33:29 INFO - Vendor: Intel (0x8086) 02:33:29 INFO - Device ID: 0x0a2e 02:33:29 INFO - Revision ID: 0x0009 02:33:29 INFO - Displays: 02:33:29 INFO - Display: 02:33:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:33:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:33:29 INFO - Display Serial Number: 1600x1200 60 02:33:29 INFO - Main Display: Yes 02:33:29 INFO - Mirror: Off 02:33:29 INFO - Online: Yes 02:33:29 INFO - Rotation: Supported 02:33:29 INFO - Return code: 0 02:33:29 INFO - Running post-action listener: _resource_record_post_action 02:33:29 INFO - [mozharness: 2016-12-29 10:33:29.790616Z] Finished run-tests step (success) 02:33:29 INFO - Running post-run listener: _resource_record_post_run 02:33:29 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32439296; Write bytes: 656256000; Read time: 201; Write time: 1893 02:33:29 INFO - TinderboxPrint: CPU usage
53.4% 02:33:29 INFO - TinderboxPrint: I/O read bytes / time
32,439,296 / 201 02:33:29 INFO - TinderboxPrint: I/O write bytes / time
656,256,000 / 1,893 02:33:29 INFO - TinderboxPrint: CPU idle
2,256.7 (46.6%) 02:33:29 INFO - TinderboxPrint: CPU system
967.4 (20.0%) 02:33:29 INFO - TinderboxPrint: CPU user
1,621.7 (33.5%) 02:33:29 INFO - TinderboxPrint: Swap in / out
377,720,832 / 0 02:33:29 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 345125376; Write bytes: 345179648; Read time: 26941; Write time: 741 02:33:29 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 30465024; Write bytes: 304199168; Read time: 185; Write time: 1124 02:33:30 INFO - Running post-run listener: _upload_blobber_files 02:33:30 INFO - Blob upload gear active. 02:33:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:33:30 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 02:33:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:33:30 INFO - (blobuploader) - INFO - Open directory for files ... 02:33:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:33:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:33:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:33:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:33:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:33:31 INFO - (blobuploader) - INFO - Done attempting. 02:33:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:33:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:33:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:33:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:33:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:33:32 INFO - (blobuploader) - INFO - Done attempting. 02:33:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:33:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:33:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:33:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:33:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:33:34 INFO - (blobuploader) - INFO - Done attempting. 02:33:34 INFO - (blobuploader) - INFO - Iteration through files over. 02:33:34 INFO - Return code: 0 02:33:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:33:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:33:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93fadf960b9a5fd58b4f39475c9976f26d740ed3e48c15bf4ee29e0ebc1e7fc53f7900e50b88e60a3370c9cccbf6596685c074037c6527d44937a0db25147cc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c991a63574e83acc4d70dde21ff2f6d66af985a4848eb2830965657aa8c3c7a9a1d7b2984a501ad5f5babfb6696f0a12bb467763899f6a7d170e98458df8c0d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69637a17e6d890672caab182eaa185fb59e9628d60b34750b343d5410af1431f1f8f4afeb8539a4380f546acb9cacb543ab21dd0944603c28f5359b9536b80c7"} 02:33:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:33:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:33:34 INFO - Contents: 02:33:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93fadf960b9a5fd58b4f39475c9976f26d740ed3e48c15bf4ee29e0ebc1e7fc53f7900e50b88e60a3370c9cccbf6596685c074037c6527d44937a0db25147cc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c991a63574e83acc4d70dde21ff2f6d66af985a4848eb2830965657aa8c3c7a9a1d7b2984a501ad5f5babfb6696f0a12bb467763899f6a7d170e98458df8c0d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69637a17e6d890672caab182eaa185fb59e9628d60b34750b343d5410af1431f1f8f4afeb8539a4380f546acb9cacb543ab21dd0944603c28f5359b9536b80c7"} 02:33:34 INFO - Running post-run listener: copy_logs_to_upload_dir 02:33:34 INFO - Copying logs to upload dir... 02:33:34 INFO - mkdir: /builds/slave/test/build/upload/logs 02:33:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.027562 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-12-29 02:33:34.701168) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 02:33:34.705187) ========= 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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93fadf960b9a5fd58b4f39475c9976f26d740ed3e48c15bf4ee29e0ebc1e7fc53f7900e50b88e60a3370c9cccbf6596685c074037c6527d44937a0db25147cc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c991a63574e83acc4d70dde21ff2f6d66af985a4848eb2830965657aa8c3c7a9a1d7b2984a501ad5f5babfb6696f0a12bb467763899f6a7d170e98458df8c0d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69637a17e6d890672caab182eaa185fb59e9628d60b34750b343d5410af1431f1f8f4afeb8539a4380f546acb9cacb543ab21dd0944603c28f5359b9536b80c7"} build_url:https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010911 build_url: 'https://queue.taskcluster.net/v1/task/LhQYXYP5SIKvNsMO1yawug/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93fadf960b9a5fd58b4f39475c9976f26d740ed3e48c15bf4ee29e0ebc1e7fc53f7900e50b88e60a3370c9cccbf6596685c074037c6527d44937a0db25147cc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c991a63574e83acc4d70dde21ff2f6d66af985a4848eb2830965657aa8c3c7a9a1d7b2984a501ad5f5babfb6696f0a12bb467763899f6a7d170e98458df8c0d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69637a17e6d890672caab182eaa185fb59e9628d60b34750b343d5410af1431f1f8f4afeb8539a4380f546acb9cacb543ab21dd0944603c28f5359b9536b80c7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 02:33:34.738846) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:33:34.739165) ========= 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.POaPDq6cPu/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.LS3yJ7DoZp/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.005288 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:33:34.787668) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 02:33:34.787990) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 02:33:34.788312) ========= ========= Total master_lag: 0.21 =========