builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0248 starttime: 1483653671.52 results: success (0) buildid: 20170105122013 builduid: e19f4f90c178470ca462574bc9f76c8e revision: dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:11.521999) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:11.522568) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:11.522847) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:11.549675) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:11.550041) ========= 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.P2J9AUInD8/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.tOGuv5gzPX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-05 14:01:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.18M=0.001s 2017-01-05 14:01:12 (9.18 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.588718 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:12.162679) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:12.163190) ========= 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.P2J9AUInD8/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.tOGuv5gzPX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.024953 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:12.222273) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-05 14:01:12.222583) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 --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 dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P2J9AUInD8/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.tOGuv5gzPX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-05 14:01:12,319 truncating revision to first 12 chars 2017-01-05 14:01:12,319 Setting DEBUG logging. 2017-01-05 14:01:12,319 attempt 1/10 2017-01-05 14:01:12,319 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dbbbc4ab2f1b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 14:01:14,110 unpacking tar archive at: mozilla-aurora-dbbbc4ab2f1b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.068542 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-05 14:01:14.314198) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:14.314523) ========= script_repo_revision: dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:14.314897) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:14.315157) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:14.334501) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-05 14:01:14.334799) ========= /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.P2J9AUInD8/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.tOGuv5gzPX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:01:14 INFO - MultiFileLogger online at 20170105 14:01:14 in /builds/slave/test 14:01: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 14:01:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:01:14 INFO - {'append_to_log': False, 14:01:14 INFO - 'base_work_dir': '/builds/slave/test', 14:01:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:01:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:01:14 INFO - 'branch': 'Mozilla-Aurora', 14:01:14 INFO - 'buildbot_json_path': 'buildprops.json', 14:01:14 INFO - 'config_files': ('talos/mac_config.py',), 14:01:14 INFO - 'default_actions': ('clobber', 14:01:14 INFO - 'read-buildbot-config', 14:01:14 INFO - 'download-and-extract', 14:01:14 INFO - 'populate-webroot', 14:01:14 INFO - 'create-virtualenv', 14:01:14 INFO - 'install', 14:01:14 INFO - 'run-tests'), 14:01:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:01:14 INFO - 'download_minidump_stackwalk': True, 14:01:14 INFO - 'download_symbols': 'ondemand', 14:01:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:01:14 INFO - 'tooltool.py': '/tools/tooltool.py', 14:01:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:01:14 INFO - '/tools/misc-python/virtualenv.py')}, 14:01:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:01:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:01:14 INFO - 'installer_path': 'installer.exe', 14:01:14 INFO - 'log_level': 'info', 14:01:14 INFO - 'log_name': 'talos', 14:01:14 INFO - 'log_to_console': True, 14:01:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:01:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:01:14 INFO - 'opt_config_files': (), 14:01:14 INFO - 'pip_index': False, 14:01:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:01:14 INFO - 'cmd': ('bash', 14:01:14 INFO - '-c', 14:01:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:01:14 INFO - 'enabled': True, 14:01:14 INFO - 'halt_on_failure': False, 14:01:14 INFO - 'name': 'check_screen_resolution'},), 14:01:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:01:14 INFO - 'cmd': ('bash', 14:01:14 INFO - '-c', 14:01:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:01:14 INFO - 'enabled': True, 14:01:14 INFO - 'halt_on_failure': False, 14:01:14 INFO - 'name': 'check_screen_resolution'},), 14:01:14 INFO - 'run_cmd_checks_enabled': True, 14:01:14 INFO - 'sps_profile': False, 14:01:14 INFO - 'sps_profile_interval': 0, 14:01:14 INFO - 'suite': 'dromaeojs', 14:01:14 INFO - 'system_bits': '32', 14:01:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:01:14 INFO - 'title': 't-yosemite-r7-0248', 14:01:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:01:14 INFO - 'use_talos_json': True, 14:01:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:01:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:01:14 INFO - 'work_dir': 'build'} 14:01:14 INFO - [mozharness: 2017-01-05 22:01:14.534986Z] Running clobber step. 14:01:14 INFO - Running pre-action listener: _resource_record_pre_action 14:01:14 INFO - Running main action method: clobber 14:01:14 INFO - rmtree: /builds/slave/test/build 14:01:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:01:16 INFO - Running post-action listener: _resource_record_post_action 14:01:16 INFO - [mozharness: 2017-01-05 22:01:16.971159Z] Finished clobber step (success) 14:01:16 INFO - [mozharness: 2017-01-05 22:01:16.971283Z] Running read-buildbot-config step. 14:01:16 INFO - Running pre-action listener: _resource_record_pre_action 14:01:16 INFO - Running main action method: read_buildbot_config 14:01:16 INFO - Using buildbot properties: 14:01:16 INFO - { 14:01:16 INFO - "project": "", 14:01:16 INFO - "product": "firefox", 14:01:16 INFO - "script_repo_revision": "production", 14:01:16 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:01:16 INFO - "repository": "", 14:01:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:01:16 INFO - "buildid": "20170105122013", 14:01:16 INFO - "pgo_build": "False", 14:01:16 INFO - "basedir": "/builds/slave/test", 14:01:16 INFO - "buildnumber": 80, 14:01:16 INFO - "slavename": "t-yosemite-r7-0248", 14:01:16 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:01:16 INFO - "platform": "yosemite_r7", 14:01:16 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:01:16 INFO - "branch": "mozilla-aurora", 14:01:16 INFO - "repo_path": "releases/mozilla-aurora", 14:01:16 INFO - "revision": "dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0", 14:01:16 INFO - "stage_platform": "macosx64", 14:01:16 INFO - "builduid": "e19f4f90c178470ca462574bc9f76c8e", 14:01:16 INFO - "slavebuilddir": "test" 14:01:16 INFO - } 14:01:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 14:01:16 INFO - Running post-action listener: _resource_record_post_action 14:01:16 INFO - [mozharness: 2017-01-05 22:01:16.974578Z] Finished read-buildbot-config step (success) 14:01:16 INFO - [mozharness: 2017-01-05 22:01:16.974677Z] Running download-and-extract step. 14:01:16 INFO - Running pre-action listener: _resource_record_pre_action 14:01:16 INFO - Running main action method: download_and_extract 14:01:16 INFO - mkdir: /builds/slave/test/build/tests 14:01: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')]} 14:01:16 INFO - trying https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 14:01:16 INFO - Downloading https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/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 14:01:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/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 14:01:18 INFO - Downloaded 1199 bytes. 14:01:18 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:01:18 INFO - Using the following test package requirements: 14:01:18 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:01:18 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:18 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 14:01:18 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:18 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 14:01:18 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:18 INFO - u'jsshell-mac.zip'], 14:01:18 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:18 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 14:01:18 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:01:18 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:18 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 14:01:18 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:18 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 14:01:18 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:18 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 14:01:18 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:18 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 14:01:18 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:01:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:01:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:01:18 INFO - Fetch https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:01:18 INFO - Http code: 200 14:01:18 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 14:01:18 INFO - x-amz-cf-id: 7S2tnSRIKkm2lxPVz9qTIzbzaKcLmS5ZBXM4z-XMy_c_qBra5b2r4Q== 14:01:18 INFO - x-amz-version-id: DdnnonK8yj1lGKCEXAi2cs375rvYM7GP 14:01:19 INFO - Expected file size: 16269400 14:01:19 INFO - Obtained file size: 16269400 14:01: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 14:01:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:01:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:01:25 INFO - Fetch https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:01:26 INFO - Http code: 200 14:01:26 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 14:01:26 INFO - x-amz-cf-id: 7YeQzhpbOBl8obyZ_JKFm_Z62p2QtilvvIjTDpCEJU70LiaBKyQEyw== 14:01:26 INFO - x-amz-version-id: DdnnonK8yj1lGKCEXAi2cs375rvYM7GP 14:01:26 INFO - Expected file size: 16269400 14:01:26 INFO - Obtained file size: 16269400 14:01:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 14:01:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:01:32 INFO - Fetch https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 14:01:33 INFO - Http code: 200 14:01:33 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 14:01:33 INFO - x-amz-cf-id: F2C0xbezVsoCSeh1o7Rb0EbzP6JmHWKuY4A2IFPbXqJaB6DthAg_ZQ== 14:01:33 INFO - x-amz-version-id: 6sN6murE149SKWb_aBkJhkzy6gsa9VjG 14:01:34 INFO - Expected file size: 14409038 14:01:34 INFO - Obtained file size: 14409038 14:01: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')]} 14:01:34 INFO - trying https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:01:34 INFO - Downloading https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 14:01:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/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 14:01:37 INFO - Downloaded 109850829 bytes. 14:01:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:01:37 INFO - mkdir: /builds/slave/test/properties 14:01:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:01:37 INFO - Writing to file /builds/slave/test/properties/build_url 14:01:37 INFO - Contents: 14:01:37 INFO - build_url:https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:01:37 INFO - Running post-action listener: _resource_record_post_action 14:01:37 INFO - Running post-action listener: set_extra_try_arguments 14:01:37 INFO - [mozharness: 2017-01-05 22:01:37.939445Z] Finished download-and-extract step (success) 14:01:37 INFO - [mozharness: 2017-01-05 22:01:37.939562Z] Running populate-webroot step. 14:01:37 INFO - Running pre-action listener: _resource_record_pre_action 14:01:37 INFO - Running main action method: populate_webroot 14:01:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:01:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:01:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:01:37 INFO - '--tppagecycles', 14:01:37 INFO - '7'], 14:01:37 INFO - 'tests': ['tsvgm']}}, 14:01:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:01:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:01:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:01:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:01:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:01:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:01:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['damp', 'tps']}, 14:01:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'tests': ['damp', 'tps']}, 14:01:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['dromaeo_dom']}, 14:01:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:01:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['basic_compositor_video']}, 14:01:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:01:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'other-e10s': {'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['tsvgx', 14:01:37 INFO - 'tsvgr_opacity', 14:01:37 INFO - 'tart', 14:01:37 INFO - 'tscrollx', 14:01:37 INFO - 'cart']}, 14:01:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:01:37 INFO - 'tsvgr_opacity', 14:01:37 INFO - 'tart', 14:01:37 INFO - 'tscrollx', 14:01:37 INFO - 'cart']}, 14:01:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['tp5o']}, 14:01:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'tests': ['tp5o']}, 14:01:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'talos_options': ['--disable-e10s', 14:01:37 INFO - '--xperf_path', 14:01:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:01:37 INFO - 'tests': ['tp5n']}, 14:01:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'talos_options': ['--xperf_path', 14:01:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:01:37 INFO - 'tests': ['tp5n']}}, 14:01:37 INFO - 'talos.zip': {'path': '', 14:01:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:01:37 INFO - Running post-action listener: _resource_record_post_action 14:01:37 INFO - [mozharness: 2017-01-05 22:01:37.958630Z] Finished populate-webroot step (success) 14:01:37 INFO - [mozharness: 2017-01-05 22:01:37.958747Z] Running create-virtualenv step. 14:01:37 INFO - Running pre-action listener: _resource_record_pre_action 14:01:37 INFO - Running main action method: create_virtualenv 14:01:37 INFO - Creating virtualenv /builds/slave/test/build/venv 14:01:37 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 14:01:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:01:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:01:38 INFO - Using real prefix '/tools/python27' 14:01:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:01:38 INFO - Installing distribute.............................................................................................................................................................................................done. 14:01:41 INFO - Installing pip.................done. 14:01:41 INFO - Return code: 0 14:01:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:01:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:01:41 INFO - Reading from file tmpfile_stdout 14:01:41 INFO - Output received: 14:01:41 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) 14:01:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:01:41 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')]} 14:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:41 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')]} 14:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:41 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 0x10d1f9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94da564da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d00dd30>, '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 0x10ce73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d005b20>, '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.P2J9AUInD8/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.tOGuv5gzPX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:01:41 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 14:01:41 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 14:01:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2J9AUInD8/Render', 14:01:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:41 INFO - 'HOME': '/Users/cltbld', 14:01:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:41 INFO - 'LOGNAME': 'cltbld', 14:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:41 INFO - 'MOZ_NO_REMOTE': '1', 14:01:41 INFO - 'NO_EM_RESTART': '1', 14:01:41 INFO - 'PAGER': '/bin/cat', 14:01:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:41 INFO - 'PWD': '/builds/slave/test', 14:01:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:41 INFO - 'SHELL': '/bin/bash', 14:01:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tOGuv5gzPX/Listeners', 14:01:41 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:01:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:41 INFO - 'USER': 'cltbld', 14:01:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:41 INFO - 'XPC_FLAGS': '0x0', 14:01:41 INFO - 'XPC_SERVICE_NAME': '0', 14:01:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:42 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 14:01:42 INFO - Cleaning up... 14:01:42 INFO - Return code: 0 14:01:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d1f9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94da564da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d00dd30>, '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 0x10ce73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d005b20>, '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.P2J9AUInD8/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.tOGuv5gzPX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:01:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:01:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2J9AUInD8/Render', 14:01:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:42 INFO - 'HOME': '/Users/cltbld', 14:01:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:42 INFO - 'LOGNAME': 'cltbld', 14:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:42 INFO - 'MOZ_NO_REMOTE': '1', 14:01:42 INFO - 'NO_EM_RESTART': '1', 14:01:42 INFO - 'PAGER': '/bin/cat', 14:01:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:42 INFO - 'PWD': '/builds/slave/test', 14:01:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:42 INFO - 'SHELL': '/bin/bash', 14:01:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tOGuv5gzPX/Listeners', 14:01:42 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:01:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:42 INFO - 'USER': 'cltbld', 14:01:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:42 INFO - 'XPC_FLAGS': '0x0', 14:01:42 INFO - 'XPC_SERVICE_NAME': '0', 14:01:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:42 INFO - Downloading/unpacking psutil>=3.1.1 14:01:42 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 14:01:42 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 14:01:42 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 14:01:42 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 14:01:42 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 14:01:42 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 14:01:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:01:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:01:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:01:46 INFO - Installing collected packages: psutil 14:01:46 INFO - Running setup.py install for psutil 14:01:46 INFO - building 'psutil._psutil_osx' extension 14:01:46 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 14:01:47 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 14:01:47 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 14:01:47 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 14:01:47 INFO - building 'psutil._psutil_posix' extension 14:01:47 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 14:01:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:01:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:01:47 INFO - ^ 14:01:47 INFO - 1 warning generated. 14:01:47 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 14:01:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:01:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:01:47 INFO - Successfully installed psutil 14:01:47 INFO - Cleaning up... 14:01:47 INFO - Return code: 0 14:01:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:47 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 0x10d1f9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94da564da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d00dd30>, '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 0x10ce73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d005b20>, '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.P2J9AUInD8/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.tOGuv5gzPX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:01:47 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 14:01:47 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 14:01:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2J9AUInD8/Render', 14:01:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:47 INFO - 'HOME': '/Users/cltbld', 14:01:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:47 INFO - 'LOGNAME': 'cltbld', 14:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:47 INFO - 'MOZ_NO_REMOTE': '1', 14:01:47 INFO - 'NO_EM_RESTART': '1', 14:01:47 INFO - 'PAGER': '/bin/cat', 14:01:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:47 INFO - 'PWD': '/builds/slave/test', 14:01:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:47 INFO - 'SHELL': '/bin/bash', 14:01:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tOGuv5gzPX/Listeners', 14:01:47 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:01:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:47 INFO - 'USER': 'cltbld', 14:01:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:47 INFO - 'XPC_FLAGS': '0x0', 14:01:47 INFO - 'XPC_SERVICE_NAME': '0', 14:01:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:01:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:01:50 INFO - Installing collected packages: mozsystemmonitor 14:01:50 INFO - Running setup.py install for mozsystemmonitor 14:01:50 INFO - Successfully installed mozsystemmonitor 14:01:50 INFO - Cleaning up... 14:01:50 INFO - Return code: 0 14:01:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:01:50 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')]} 14:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:50 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')]} 14:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:50 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 0x10d1f9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94da564da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d00dd30>, '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 0x10ce73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d005b20>, '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.P2J9AUInD8/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.tOGuv5gzPX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:01:50 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 14:01:50 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 14:01:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2J9AUInD8/Render', 14:01:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:50 INFO - 'HOME': '/Users/cltbld', 14:01:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:50 INFO - 'LOGNAME': 'cltbld', 14:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:50 INFO - 'MOZ_NO_REMOTE': '1', 14:01:50 INFO - 'NO_EM_RESTART': '1', 14:01:50 INFO - 'PAGER': '/bin/cat', 14:01:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:50 INFO - 'PWD': '/builds/slave/test', 14:01:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:50 INFO - 'SHELL': '/bin/bash', 14:01:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tOGuv5gzPX/Listeners', 14:01:50 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:01:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:50 INFO - 'USER': 'cltbld', 14:01:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:50 INFO - 'XPC_FLAGS': '0x0', 14:01:50 INFO - 'XPC_SERVICE_NAME': '0', 14:01:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:51 INFO - Downloading/unpacking jsonschema==2.5.1 14:01:51 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 14:01:51 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 14:01:51 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 14:01:51 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 14:01:51 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 14:01:51 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 14:01:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:01:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:01:53 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 14:01:53 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 14:01:53 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 14:01:53 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 14:01:53 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 14:01:53 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 14:01:54 INFO - Downloading functools32-3.2.3-2.tar.gz 14:01:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:01:54 INFO - warning: no files found matching '*.txt' 14:01:54 INFO - no previously-included directories found matching 'build' 14:01:54 INFO - no previously-included directories found matching 'dist' 14:01:54 INFO - no previously-included directories found matching '.git*' 14:01:54 INFO - Installing collected packages: jsonschema, functools32 14:01:54 INFO - Running setup.py install for functools32 14:01:54 INFO - warning: no files found matching '*.txt' 14:01:54 INFO - no previously-included directories found matching 'build' 14:01:54 INFO - no previously-included directories found matching 'dist' 14:01:54 INFO - no previously-included directories found matching '.git*' 14:01:54 INFO - Successfully installed jsonschema functools32 14:01:54 INFO - Cleaning up... 14:01:54 INFO - Return code: 0 14:01:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:01: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')]} 14:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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')]} 14:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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', '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 0x10d1f9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94da564da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d00dd30>, '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 0x10ce73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d005b20>, '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.P2J9AUInD8/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.tOGuv5gzPX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:01: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:01: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 functools32==3.2.3-2 14:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2J9AUInD8/Render', 14:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:54 INFO - 'HOME': '/Users/cltbld', 14:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:54 INFO - 'LOGNAME': 'cltbld', 14:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:54 INFO - 'MOZ_NO_REMOTE': '1', 14:01:54 INFO - 'NO_EM_RESTART': '1', 14:01:54 INFO - 'PAGER': '/bin/cat', 14:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:54 INFO - 'PWD': '/builds/slave/test', 14:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:54 INFO - 'SHELL': '/bin/bash', 14:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tOGuv5gzPX/Listeners', 14:01:54 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:54 INFO - 'USER': 'cltbld', 14:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:54 INFO - 'XPC_FLAGS': '0x0', 14:01:54 INFO - 'XPC_SERVICE_NAME': '0', 14:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:01:54 INFO - Cleaning up... 14:01:54 INFO - Return code: 0 14:01:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:01: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')]} 14:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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')]} 14:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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', '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 0x10d1f9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94da564da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d00dd30>, '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 0x10ce73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d005b20>, '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.P2J9AUInD8/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.tOGuv5gzPX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:01: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:01: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 blobuploader==1.2.4 14:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2J9AUInD8/Render', 14:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:54 INFO - 'HOME': '/Users/cltbld', 14:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:54 INFO - 'LOGNAME': 'cltbld', 14:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:54 INFO - 'MOZ_NO_REMOTE': '1', 14:01:54 INFO - 'NO_EM_RESTART': '1', 14:01:54 INFO - 'PAGER': '/bin/cat', 14:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:54 INFO - 'PWD': '/builds/slave/test', 14:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:54 INFO - 'SHELL': '/bin/bash', 14:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tOGuv5gzPX/Listeners', 14:01:54 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:54 INFO - 'USER': 'cltbld', 14:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:54 INFO - 'XPC_FLAGS': '0x0', 14:01:54 INFO - 'XPC_SERVICE_NAME': '0', 14:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:55 INFO - Downloading/unpacking blobuploader==1.2.4 14:01:55 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 14:01:55 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 14:01:55 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 14:01:55 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 14:01:55 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 14:01:55 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 14:01:57 INFO - Downloading blobuploader-1.2.4.tar.gz 14:01:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:01:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:01:57 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 14:01:57 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 14:01:57 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 14:01:57 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 14:01:57 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 14:01:57 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 14:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:01:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:01: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 14:01: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 14:01: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 14:01: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 14:01: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 14:01: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 14:01:58 INFO - Downloading docopt-0.6.1.tar.gz 14:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:01:58 INFO - Installing collected packages: blobuploader, requests, docopt 14:01:58 INFO - Running setup.py install for blobuploader 14:01:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:01:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:01:59 INFO - Running setup.py install for requests 14:01:59 INFO - Running setup.py install for docopt 14:01:59 INFO - Successfully installed blobuploader requests docopt 14:01:59 INFO - Cleaning up... 14:01:59 INFO - Return code: 0 14:01:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:59 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 0x10d1f9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94da564da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d00dd30>, '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 0x10ce73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d005b20>, '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.P2J9AUInD8/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.tOGuv5gzPX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:01:59 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 14:01:59 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 14:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2J9AUInD8/Render', 14:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:59 INFO - 'HOME': '/Users/cltbld', 14:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:59 INFO - 'LOGNAME': 'cltbld', 14:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:59 INFO - 'MOZ_NO_REMOTE': '1', 14:01:59 INFO - 'NO_EM_RESTART': '1', 14:01:59 INFO - 'PAGER': '/bin/cat', 14:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:59 INFO - 'PWD': '/builds/slave/test', 14:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:59 INFO - 'SHELL': '/bin/bash', 14:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tOGuv5gzPX/Listeners', 14:01:59 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:59 INFO - 'USER': 'cltbld', 14:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:59 INFO - 'XPC_FLAGS': '0x0', 14:01:59 INFO - 'XPC_SERVICE_NAME': '0', 14:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:02:00 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-U4UEjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:02:00 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-WPw90K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:02:00 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-AuNG0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:02:00 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-jfxEtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:02:00 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-pjXIX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:02:00 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-5O6uzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:02:00 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-URvOBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:02:00 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-Fdaedb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:02:00 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-nYsrWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:02:01 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-eY5oOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:02:01 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-qZ4otP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:02:01 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-ovWHRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:02:01 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-HM5xSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:02:01 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-CwVL3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:02:01 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-fUwPKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:02:01 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-MBoAJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:02:01 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-S79rMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:02:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:02:01 INFO - Running setup.py install for manifestparser 14:02:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Running setup.py install for mozcrash 14:02:02 INFO - Running setup.py install for mozdebug 14:02:02 INFO - Running setup.py install for mozdevice 14:02:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Running setup.py install for mozfile 14:02:02 INFO - Running setup.py install for mozhttpd 14:02:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Running setup.py install for mozinfo 14:02:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Running setup.py install for mozInstall 14:02:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Running setup.py install for mozleak 14:02:03 INFO - Running setup.py install for mozlog 14:02:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Running setup.py install for moznetwork 14:02:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Running setup.py install for mozprocess 14:02:03 INFO - Running setup.py install for mozprofile 14:02:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Running setup.py install for mozrunner 14:02:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Running setup.py install for mozscreenshot 14:02:04 INFO - Running setup.py install for moztest 14:02:04 INFO - Running setup.py install for mozversion 14:02:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:02:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:02:04 INFO - Cleaning up... 14:02:04 INFO - Return code: 0 14:02:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:02:04 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')]} 14:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:04 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')]} 14:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:04 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 0x10d1f9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94da564da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d00dd30>, '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 0x10ce73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d005b20>, '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.P2J9AUInD8/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.tOGuv5gzPX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:02:04 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 14:02:04 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 14:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2J9AUInD8/Render', 14:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:02:04 INFO - 'HOME': '/Users/cltbld', 14:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:02:04 INFO - 'LOGNAME': 'cltbld', 14:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:04 INFO - 'MOZ_NO_REMOTE': '1', 14:02:04 INFO - 'NO_EM_RESTART': '1', 14:02:04 INFO - 'PAGER': '/bin/cat', 14:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:02:04 INFO - 'PWD': '/builds/slave/test', 14:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:02:04 INFO - 'SHELL': '/bin/bash', 14:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tOGuv5gzPX/Listeners', 14:02:04 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:02:04 INFO - 'USER': 'cltbld', 14:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:04 INFO - 'XPC_FLAGS': '0x0', 14:02:04 INFO - 'XPC_SERVICE_NAME': '0', 14:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:02:04 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-yOAK_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:02:04 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)) 14:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:02:04 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-mbs3Nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:02:04 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)) 14:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:02:04 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-xKAjx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:02:05 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)) 14:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:02:05 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-h_mCeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:02:05 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)) 14:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:02:05 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-oU8CgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:02:05 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)) 14:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:02:05 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-ishdcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:02:05 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)) 14:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:02:05 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-MDezMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:02:05 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)) 14:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:02:05 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-tA_7P0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:02:05 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)) 14:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:02:05 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-w8_8UZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:02:05 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)) 14:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:02:05 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-bMjPpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:02:05 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)) 14:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:02:05 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-0JM3Ce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:02:05 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)) 14:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:02:05 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-Xs44rT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:02:05 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)) 14:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:02:05 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-CcqjAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:02:06 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)) 14:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:02:06 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-E_dLCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:02:06 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)) 14:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:02:06 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-SBC69O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:02:06 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)) 14:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:02:06 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-futuPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:02:06 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-d_ARAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:02:06 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)) 14:02:06 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)) 14:02:06 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)) 14:02:06 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)) 14:02:06 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)) 14:02:06 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)) 14:02:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:02:06 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 14:02:06 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 14:02:06 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 14:02:06 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 14:02:06 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 14:02:06 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 14:02:09 INFO - Downloading blessings-1.6.tar.gz 14:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:02:09 INFO - Installing collected packages: blessings 14:02:09 INFO - Running setup.py install for blessings 14:02:09 INFO - Successfully installed blessings 14:02:09 INFO - Cleaning up... 14:02:09 INFO - Return code: 0 14:02:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:02:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:02:09 INFO - Reading from file tmpfile_stdout 14:02:09 INFO - Current package versions: 14:02:09 INFO - blessings == 1.6 14:02:09 INFO - blobuploader == 1.2.4 14:02:09 INFO - docopt == 0.6.1 14:02:09 INFO - functools32 == 3.2.3-2 14:02:09 INFO - jsonschema == 2.5.1 14:02:09 INFO - manifestparser == 1.1 14:02:09 INFO - mozInstall == 1.12 14:02:09 INFO - mozcrash == 1.0 14:02:09 INFO - mozdebug == 0.1 14:02:09 INFO - mozdevice == 0.48 14:02:09 INFO - mozfile == 1.2 14:02:09 INFO - mozhttpd == 0.7 14:02:09 INFO - mozinfo == 0.9 14:02:09 INFO - mozleak == 0.1 14:02:09 INFO - mozlog == 3.4 14:02:09 INFO - moznetwork == 0.27 14:02:09 INFO - mozprocess == 0.23 14:02:09 INFO - mozprofile == 0.28 14:02:09 INFO - mozrunner == 6.13 14:02:09 INFO - mozscreenshot == 0.1 14:02:09 INFO - mozsystemmonitor == 0.3 14:02:09 INFO - moztest == 0.8 14:02:09 INFO - mozversion == 1.4 14:02:09 INFO - psutil == 3.1.1 14:02:09 INFO - requests == 1.2.3 14:02:09 INFO - wsgiref == 0.1.2 14:02:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:09 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 0x10d1f9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94da564da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d00dd30>, '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 0x10ce73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d005b20>, '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.P2J9AUInD8/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.tOGuv5gzPX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:02:09 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 14:02:09 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 14:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2J9AUInD8/Render', 14:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:02:09 INFO - 'HOME': '/Users/cltbld', 14:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:02:09 INFO - 'LOGNAME': 'cltbld', 14:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:09 INFO - 'MOZ_NO_REMOTE': '1', 14:02:09 INFO - 'NO_EM_RESTART': '1', 14:02:09 INFO - 'PAGER': '/bin/cat', 14:02:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:02:09 INFO - 'PWD': '/builds/slave/test', 14:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:02:09 INFO - 'SHELL': '/bin/bash', 14:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tOGuv5gzPX/Listeners', 14:02:09 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:02:09 INFO - 'USER': 'cltbld', 14:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:09 INFO - 'XPC_FLAGS': '0x0', 14:02:09 INFO - 'XPC_SERVICE_NAME': '0', 14:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:09 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)) 14:02:09 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)) 14:02:09 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)) 14:02:09 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)) 14:02:09 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)) 14:02:09 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)) 14:02:09 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)) 14:02:09 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)) 14:02:09 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)) 14:02:09 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)) 14:02:09 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)) 14:02:09 INFO - Cleaning up... 14:02:09 INFO - Return code: 0 14:02:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:09 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 0x10d1f9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d16f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94da564da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d00dd30>, '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 0x10ce73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d005b20>, '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.P2J9AUInD8/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.tOGuv5gzPX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 14:02:09 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 14:02:09 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 14:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2J9AUInD8/Render', 14:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:02:09 INFO - 'HOME': '/Users/cltbld', 14:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:02:09 INFO - 'LOGNAME': 'cltbld', 14:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:09 INFO - 'MOZ_NO_REMOTE': '1', 14:02:09 INFO - 'NO_EM_RESTART': '1', 14:02:09 INFO - 'PAGER': '/bin/cat', 14:02:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:02:09 INFO - 'PWD': '/builds/slave/test', 14:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:02:09 INFO - 'SHELL': '/bin/bash', 14:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tOGuv5gzPX/Listeners', 14:02:09 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:02:09 INFO - 'USER': 'cltbld', 14:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:09 INFO - 'XPC_FLAGS': '0x0', 14:02:09 INFO - 'XPC_SERVICE_NAME': '0', 14:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:02:10 INFO - Cleaning up... 14:02:10 INFO - Return code: 0 14:02:10 INFO - Running post-action listener: _resource_record_post_action 14:02:10 INFO - Running post-action listener: _start_resource_monitoring 14:02:10 INFO - Starting resource monitoring. 14:02:10 INFO - [mozharness: 2017-01-05 22:02:10.258695Z] Finished create-virtualenv step (success) 14:02:10 INFO - [mozharness: 2017-01-05 22:02:10.259678Z] Running install step. 14:02:10 INFO - Running pre-action listener: _resource_record_pre_action 14:02:10 INFO - Running main action method: install 14:02:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:02:10 INFO - Reading from file tmpfile_stdout 14:02:10 INFO - Detecting whether we're running mozinstall >=1.0... 14:02:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:02:10 INFO - Reading from file tmpfile_stdout 14:02:10 INFO - Output received: 14:02:10 INFO - Usage: mozinstall [options] installer 14:02:10 INFO - Options: 14:02:10 INFO - -h, --help show this help message and exit 14:02:10 INFO - -d DEST, --destination=DEST 14:02:10 INFO - Directory to install application into. [default: 14:02:10 INFO - "/builds/slave/test"] 14:02:10 INFO - --app=APP Application being installed. [default: firefox] 14:02:10 INFO - mkdir: /builds/slave/test/build/application 14:02:10 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'] 14:02:10 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 14:02:41 INFO - Reading from file tmpfile_stdout 14:02:41 INFO - Output received: 14:02:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:02:41 INFO - Running post-action listener: _resource_record_post_action 14:02:41 INFO - [mozharness: 2017-01-05 22:02:41.806319Z] Finished install step (success) 14:02:41 INFO - [mozharness: 2017-01-05 22:02:41.806457Z] Running run-tests step. 14:02:41 INFO - Running pre-action listener: _resource_record_pre_action 14:02:41 INFO - Running main action method: run_tests 14:02:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:02:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:02:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:02:41 INFO - 2017-01-05 14:02:41.819 screenresolution[1628:7960] starting screenresolution argv=screenresolution get 14:02:41 INFO - 2017-01-05 14:02:41.821 screenresolution[1628:7960] Display 0: 1600x1200x32@60 14:02:41 INFO - 2017-01-05 14:02:41.826 screenresolution[1629:7964] starting screenresolution argv=screenresolution list 14:02:41 INFO - Available Modes on Display 0 14:02:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:02:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:02:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:02:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:02:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:02:42 INFO - Intel Iris: 14:02:42 INFO - Chipset Model: Intel Iris 14:02:42 INFO - Type: GPU 14:02:42 INFO - Bus: Built-In 14:02:42 INFO - VRAM (Dynamic, Max): 1536 MB 14:02:42 INFO - Vendor: Intel (0x8086) 14:02:42 INFO - Device ID: 0x0a2e 14:02:42 INFO - Revision ID: 0x0009 14:02:42 INFO - Displays: 14:02:42 INFO - Display: 14:02:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:02:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:02:42 INFO - Display Serial Number: 1600x1200 60 14:02:42 INFO - Main Display: Yes 14:02:42 INFO - Mirror: Off 14:02:42 INFO - Online: Yes 14:02:42 INFO - Rotation: Supported 14:02:42 INFO - Return code: 0 14:02:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:02:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:02:42 INFO - Python 2.7.3 14:02:42 INFO - Return code: 0 14:02:42 INFO - grabbing minidump binary from tooltool 14:02:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:02:42 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 0x10d00dd30>, '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 0x10ce73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d005b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:02:42 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 14:02:42 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 14:02:42 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 14:02:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:02:42 INFO - Return code: 0 14:02:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:02:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:02:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:02:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:02:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:02:42 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/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--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 14:02:42 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/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0248 --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 14:02:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P2J9AUInD8/Render', 14:02:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:02:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:02:42 INFO - 'HOME': '/Users/cltbld', 14:02:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:02:42 INFO - 'LOGNAME': 'cltbld', 14:02:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:02:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:42 INFO - 'MOZ_NO_REMOTE': '1', 14:02:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:02:42 INFO - 'NO_EM_RESTART': '1', 14:02:42 INFO - 'PAGER': '/bin/cat', 14:02:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:02:42 INFO - 'PWD': '/builds/slave/test', 14:02:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:02:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:02:42 INFO - 'SHELL': '/bin/bash', 14:02:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tOGuv5gzPX/Listeners', 14:02:42 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 14:02:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:02:42 INFO - 'USER': 'cltbld', 14:02:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:02:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:42 INFO - 'XPC_FLAGS': '0x0', 14:02:42 INFO - 'XPC_SERVICE_NAME': '0', 14:02:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:02:42 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/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--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 14:02:42 INFO - mozversion application_buildid: 20170105122013 14:02:42 INFO - mozversion application_changeset: dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 14:02:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:02:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:02:42 INFO - mozversion application_name: Firefox 14:02:42 INFO - mozversion application_remotingname: firefox-dev 14:02:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:02:42 INFO - mozversion application_vendor: Mozilla 14:02:42 INFO - mozversion application_version: 52.0a2 14:02:42 INFO - mozversion platform_buildid: 20170105122013 14:02:42 INFO - mozversion platform_changeset: dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 14:02:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:02:42 INFO - mozversion platform_version: 52.0a2 14:02:42 INFO - using testdate: 1483653762 14:02:42 INFO - actual date: 1483653762 14:02:42 INFO - starting webserver on 'localhost:49235' 14:02:42 INFO - SUITE-START | Running 2 tests 14:02:42 INFO - TEST-START | dromaeo_css 14:02:42 INFO - operating with platform_type : mac_ 14:02:42 INFO - Initialising browser for dromaeo_css test... 14:02:42 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpNheE86/profile http://localhost:49235/getInfo.html) 14:02:44 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 14:02:44 INFO - PROCESS | 1639 | colorDepth:24 14:02:44 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 14:02:44 INFO - PROCESS | 1639 | __metrics 14:02:45 INFO - PROCESS | 1639 | 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" 14:02:45 INFO - PROCESS | 1639 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:02:45 INFO - TEST-INFO | 1639: exit 0 14:02:45 INFO - Browser initialized. 14:02:45 INFO - Running cycle 1/1 for dromaeo_css test... 14:02:45 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpNheE86/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:02:58 INFO - PROCESS | 1642 | RSS: Main: 223174656 14:02:58 INFO - PROCESS | 1642 | 14:06:02 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 14:06:05 INFO - PROCESS | 1642 | RSS: Main: 308973568 14:06:05 INFO - PROCESS | 1642 | 14:09:08 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 14:09:08 INFO - PROCESS | 1642 | RSS: Main: 348467200 14:09:08 INFO - PROCESS | 1642 | 14:12:11 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 14:12:11 INFO - PROCESS | 1642 | RSS: Main: 327446528 14:12:11 INFO - PROCESS | 1642 | 14:15:14 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 14:15:14 INFO - PROCESS | 1642 | RSS: Main: 316137472 14:15:14 INFO - PROCESS | 1642 | 14:18:17 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 14:18:18 INFO - PROCESS | 1642 | RSS: Main: 307376128 14:18:18 INFO - PROCESS | 1642 | 14:21:21 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 14:21:21 INFO - PROCESS | 1642 | RSS: Main: 354357248 14:21:21 INFO - PROCESS | 1642 | 14:21:21 INFO - PROCESS | 1642 | __start_tp_report 14:21:21 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 14:21:21 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 14:21:21 INFO - PROCESS | 1642 | |i|pagename|runs| 14:21:21 INFO - PROCESS | 1642 | |0;dojo.html;2810.7048759880204;3379.4592865141576;3381.08710648125;3383.475561287998;3434.0899661589683;5715;5800.274965629296;5811.360750317473;5840.299164100308;5855.79504717335;4761.381020467343;5765.481106700396;5795.072788353864;5828.125781132832;5852.326982397015;5220.4779522047875;5242.947570524303;5265.65773224741;5276.841694749163;5302.44324345942;5167.302414174076;5181.792728290883;5185.299984502081;5333.599980001496;5339.599530035243;5218.712970786605;5236;5237.554807841319;5251.081060814361;5254.973725131388;5240.737963101829;5254.15933450646;5254.290670759437;5256.290400795881;5264.657797243186;4310.547392523771;5086.262491938648;5199.818006369796;5209.400918894337;5238;4898.461169271377;5495.752691128909;5775.971120144414;5781.132830075522;5782.074868021096;6170.382961703838;6220.409061139185;6221.004639257742;6221.533384996098;6228.314885362606;6229.314775374705;6274.372562743734;6308.337624549402;6315.336889626569;6324.146240257598;5906.852328691774;5908.084246941731;5911.733971971272;5923.5261179106;5927.436893495154;6213.65824879627;6283.937160628427;6296.527760417987;6299.401556852137;6311.936880631227;6037.909431358533;6108.725107370179;6137.420285946852;6147.600405973598;6204.224471941007;6221.5333849961435;6301.464375528039;6318.936810631927;6328.557001009977;6330.68346582669;6236.906446403307;6243.125962365272;6268.717907694165;6269.0909818076125;6273.18448601688;6132.6627035513475;6141.578063794062;6185.257769067741;6208.689565521706;6224.719887605069;5016.0971025215085;5023.34696489454;5027.069992051409;5030.572401345853;5054.848354549369;4496.730196188174;4509.706869053501;4517.909641807146;4542.977285113553;4550.886227844264;5691.089425691869;5696.914546281809;5724.3130824300515;5778.711064446845;5881.882362352729;5300.44345343741;6193.4425901669065;6250.6874656267;6257.874842503215;6279.560430769802;5749.540036797046;5751.568632352591;5912.793052243151;5922.822315330461;5926.199963005025;5373.677579345252;5419.458054194549;5438.048341540294;5477.123660214346;5503.6147469677535;5400.702961337164;6197.752089916353;6250.3749625037135;6259.937400625936;6334.778282760081;6259.593126446766;6259.874802503833;6276.152719382825;6279.905801412891;6285.654289014148;6255.218097737783;6353.904691429725;6366.808995730135;6370;6393.904091438725;4761.6904901181315;6118.357572455006;6132.263729246545;6148.9692551536955;6259.593126446766;6084.908726369196;6090.69546522681;6227.9377206227355;6250;6254.530910181664;6154.753809847735;6192.659403732658;6206.875862482815;6223.284322302993;6227.50179985591;6053.667048312385;6121.326654068138;6132.969335153296;6155.630662160284;6231.40801623845;6010.1285313628405;6028.2766068072115;6038;6046.27444706638;6090.908636370369;6095.786647467317;6134.4978165938865;6194.628322300677;6213.285472170758;6224.439800417984;6004.609700369462;6038.9396106038375;6039.033754599243;6049.667268300285;6136.549713441429 14:21:21 INFO - PROCESS | 1642 | |1;ext.html;478.2276623253447;506.15724818177733;519.7816916894905;522.7856578802691;557.8159207461536;30509.932152374673;36206.456903146405;40990;41605.58394416055;41679;42294.788526057324;42612.573874261245;42725.14549709004;42889.571104288945;42896.356554651706;9262.490563019031;9419.952900235488;9420.38767480115;9423.575939082757;9438.669646562372;8461.40770146087;8677.175668311533;8687.391882568223;8746.518941458216;8755.3433492488;10993;11214.775704485904;11220.831687524693;11361.886381136206;11384.772304553904;11261.268017578832;11336.603218887381;11353.489092990836;11353.602623908206;11367.4884630191;8411.1588841116;8648;10969.83545246822;11167.609133680364;11263.66209013731;38160.80919595412;39911.60088399137;40096;40115.799421003;40192.19615607701;3239.4654881944684;3266.9673303267136;3504.8422820972864;3681.687056600192;3872.1674839909274;28444.573331400046;29120.9807656731;30507.847460762554;30816.38367232643;31232.21919452032;3177.4916013437737;3220.533022711694;3229.402560526287;3233.288676491192;3239.347932554414;486027;491391;491831;492074;494202;59838.401615984156;60070.39929600735;60089.39910600925;60119.69940150359;60150.09774853381;61546.69226653838;61721.382786172464;61745.382546174864;61808.69095654493;61928;15887.920560397124;16355.509334719978;16361.754573681405;16379.262933168036;16431.424900128677;12681.809772853414;12709.173903696415;12715.173513721578;12718.173318734252;12722.363881805873;14962.625934351729;15382.38470461169;16492.34030638785;16647.916760416363;17054.658906822042;6297.6536290503545;6307.653079080695;6380.521460890452;6411.230652321751;6412.166418365583;843.451756358372;970.834958057132;991.468181659338;1026.7381817636604;1037.8754549454113;30881;31192.376152477278;31810.681893181234;31953.360932781216;32424.83787581047;1026.2610920137488;1046.2362475393006;1048.171944164102;1057.2916146182076;1114.9777004459866;11928.701782455508;14235.572932812032;14847.183404912834;15283.923580382027;15681.13753743555;616573;620613;621686;623096;627397;49278;50381.24428133581;55903.16145257824;57027.714861425426;57292.713536432886;1341.584108926236;1368.6304697731557;1382.5644921849384;1385.3696568061507;1385.9584212473642;29566.260843478223;30576.388472230876;30627.693723062483;30799.692003080578;31347.686523135388;51046.48953510417;51231;51387.22919156141;51526.22710659268;51585.484145158065;785693;791695;799048;799700;800833;24924.252272431855;25136;25291.49417011686;25625.74374256308;25634;44579.325240485676;44659.10681786411;45171.322430162916;45201;45528;36666;37694.62305376911;37804.62195378011;37850.810745947194;37886.86339409822;36427.45358819567;37315.62684373122;37333.6266637341;37469.250614988094;38431.423528647654;36565.26869462649;37433.62566374301;37525;37729.62270377371;37748.62251377451;32253;33361.666383335854;33655.32689346249;33671.66328336783;33912.3217535643;24874;24890.875545622155;24990.62514062327;25135.371615709755;25520.361990950376 14:21:21 INFO - PROCESS | 1642 | |2;jquery.html;336.8837750975913;343.5225037198296;366.6040676069845;371.71563753728384;396.6212267284743;14391.712165756691;14565.12609243446;15535.378584856606;19595.510112247164;20104.899475502603;18645.440636780884;19365.515862103453;19814.60370792583;20054.097565609547;20118.295859644917;5720.570957178218;5954.761809527636;5987.28152621686;5995.040793473046;5998.430149135847;4715.811367545294;5850.239468869062;6509.707063182168;6571.868562628744;6639.800805975829;6291.087792270118;6566.409023187912;6573.638449885254;6585.275619681831;6587.275399706028;6456.289808121103;6490.643014634194;6503.089567460559;6506.1216735740545;6529.444997175246;5536.390996990368;5595.160725891108;5629.268195134607;5664.150377443375;5702.686352250625;24974.37564060913;25030;25098.372540686447;25105;25343.11299104541;2235.4523141830355;2368.9052437902465;2395;2733.494303553849;2964.659064207622;14264.643383915381;15236.314365853452;15337.539873803804;15525.611859703486;15689.372425102982;1522.9847701522954;1709.880308378414;1725.482355293407;1777.5644966983043;1834.6697594432997;100935;117748;119090.99999999913;119578;119758.4012079934;10384.792304154025;10401.063904248655;10534.473276336152;10536.998985096403;10853.674389768319;13466.865331346757;13530.797038044437;13543;13544.59366219015;13571;8295.709650162215;8395.916040839636;8443;8458.365622578209;8581.785455363666;8408.705695300636;8441.902552668114;8611.61247743853;8628.482291062557;8642.783930401665;6309.463695585927;6442.16251887261;6443.097966284724;6444.712127194872;6452.322506136882;1606.9116198608956;1672.5316911264865;1735.7830271216098;1791.3730194432105;1820.2809890093604;810.6068556749972;865.5499140447058;947.5948338239857;951.0156987517886;971.8104969530874;20949.79050209509;21364;21391.57216855654;21423.39324550665;21433.57132857334;918.0910898210723;918.4948278446827;929.177677755178;934.6448349627234;982.621690649091;10515.94742026285;11198.328100314007;11239.381833999209;11308.529891114102;11368.374739389248;582289;583179;592046;596049;596347;74532;110326;119496;120147;121246;879.8108406692592;919.7240827751782;974.88301403832;977.9364341317792;979.1432496565506;19101.331453399063;19739.605207895474;19848.90075549613;19866;19879.503012424804;49263.522094337226;51628;53157.20264195963;54104.458955411515;54166;75241;75472;75656.62171689107;76248.61875690587;76254.61872690602;6613.966930165318;6701.597904125768;6958.756443524655;7153.177384600837;7539.773806785805;8992.235659968714;11710;12395.442205100973;12641.304728240035;12893.29086900192;10923.289986150874;10973.122150228122;10997.725056873642;11336.979671829575;11512.712182195512;10342.32774869631;10932.398718070583;11054.502547385226;11445.713857153638;11499.54001839917;11035.558577657124;11097.056750176325;11454.94272528632;11544.076473881902;11695.005924496512;7281;7325.304096110861;7431.665575049244;7446.478746487694;7563.205863384265;7659.770206893802;9733.270004749813;11023.724406889893;11282;11491.712707182387 14:21:21 INFO - PROCESS | 1642 | |3;mootools.html;1339.363802193958;1468.4052958551788;1612.8387161283872;1786.6516029374277;1835.4677143628348;8116.1478044805335;8495.36284778641;8509.064002959685;8517.574121293941;8550.40147189697;7483.663235154418;7703.614819259043;7709.537427754324;8057.355411567075;8332.50004999699;2311.3528212100637;2354.30547988343;2355.187460326185;2357.870317132557;2358.433975845802;2331.778481044298;2358.3278765551822;2377.429416939939;2378.4886249456363;2383.833131680783;2271.7501074881748;2303;2337.801286890608;2341.2507997440825;2354.2701762453607;2296.0586159674535;2307.8730669813153;2325.953480930372;2326.2439707095177;2328.0338659456306;2334.564242990549;2347.5891718949288;2353.7646235376324;2360.209329874494;2364.9172278970154;8246.793830154234;8447.32421406286;8454.196851299117;8460;8488.363372747068;5056.216286475602;5210.296609957683;5219.765110570033;5222.712750798704;5233.712145831978;3257.6742325767473;3353.2958078803235;3562.1628917204434;3563.3051554946796;3567.4292113262013;1062.718379629399;1066.2482949520647;1091.579741799405;1104.1332553945194;1110.9500072496758;10984.121270298438;11095.778084438267;11133.387663678575;11142.387168705718;11150.052245559138;4493.168763778712;4605.838795642137;4709.340692303081;4711.411073615798;4766.45185803637;9401.200897923616;9417.952910235543;9421.623135074657;9434.90565094354;9436;4454.977725111354;5206.76569554371;5217.791288348423;5222.1383471727495;5233.215017747369;3668.248009158116;4222.662187025031;4701.717896926195;4777.378940737752;4782.306565547976;3776.6223377662013;3782.527184101987;3865.342891708416;3886.397608370707;3889.086064774776;1192.9761404771857;1239.977018959362;1262.5644152767281;1270.8284381608364;1278.1180985120238;764.7476332810271;786.6774622404787;901.1616302742332;904.1049361132432;904.263025634106;5414.052540805422;5581.860453488695;5604.523615492647;5611.7755289788765;5684.6020778545935;818.5743413424985;854.598338780772;915.4781774388667;922.8615707643908;932.2215949681956;3718.6281371862597;3795.392737162096;3799.9430008549894;3850.4994350734223;3909.7849618270698;9732.90267097334;10089.596416143419;10132.493375331203;10274.486275686335;10290.588376465008;10235.641752538626;10240;10244.795104097877;10249.538770755335;10251.58993640246;7485.251474852471;7868.370530357673;7891.565963871927;7896.7236146734585;8042.959785201154;5669.319681638065;5692.943070569407;5694.487496125369;5811.709414529341;5849;9089.272858171462;9111;9507.952460237655;9547.76130596713;9574.856377154209;9459.195968342769;9513.524323783644;9558.522073896416;9656.22750179998;9685.225181985565;6625.635590042594;6825.658717064028;6855.5886646801355;7143.571385716874;7348.77953661391;6830.180378354629;7780.49426787257;8041.597920104088;8049.758507244792;8059.637316320663;7091.361777440055;7173.713051477883;7228.49400541957;7229.240929702306;7348.044754182136;6110.633361998294;6982.718122482425;7062.964685176541;7156.033935418652;7165.996760453437;6448.387403196689;6609.537332386687;6718.596884186964;6774;6795.626240556619;4099.774512401727;4836.298736683091;4871.464138944642;4884.584810291023;4923.532264434873;4727.172744769583;4731.195696731495;4756.9762151189025;4945;5056.418511871182 14:21:21 INFO - PROCESS | 1642 | |4;prototype.html;295.5286318322276;303.1859457356998;309.183754887098;313.82112196048246;315.01243601309886;4637.817356574077;5053.620978426619;5284.128118860383;5352.598555108363;5353.785848566053;4669.929951050737;4979.950200497994;5072.923906141411;5161.561057490555;5259.9474005259935;3497.580290365147;3992.7205095643317;4051.2910240707906;4056.6146216109423;4060.6854093166908;4019.593142400166;4022.5374081980654;4033.415154802552;4034.7780872052026;4082.4080508326274;3191.0426871938444;3988;4016.9397459038137;4020.356742921133;4027.3556231003045;3977.832081515552;4029.1337362466916;4035.3745169498775;4035.5964403559556;4074.470318858544;3502.964970350315;3516.876909308162;3520.612732599412;3530.4704294355797;3534.7879127252445;4459.308807134899;4960.528749768766;5104.846854594368;5136.357955255593;5168.173092305251;4544.454665440136;4588.839390621346;4610.308453731967;4624.3988281523525;4626.1441633297945;5545.500904918536;5578.573320605549;5588.86027849303;5593.692346920918;5628.212050312959;3075.6770539093295;3283.753718471124;3463.3765922134007;3554.2325135356346;3729.974257079325;4307.547707490731;4867.318575399447;5562.694051827148;5775.855603609902;5813.7383817728305;3904.754766009556;3914.119323152268;3924.823382947774;3925.3523168676847;3931.9606803932165;3964.643182113566;3981.8606348777653;3984.940225896614;3985.3025720499377;3991.6607088397814;3744.26986737589;3999.3601023836045;4072.470578824734;4427.29163333865;4635.3278774578175;4524.02733412318;4909.803607855717;5461.8803145355105;5470.6202801781355;5619.381867994517;1168.8597368315686;1217.5373358123857;1236.1285293867802;1255.7802384582662;1308;740.2449501508539;786.6774622404787;836.4019725896013;876.4040452492367;905.9909400906039;4459.710118842265;4501.3667004802555;4662.417197850269;4763.142634325785;4941.654084214142;4537.75042372666;4621.560951709582;4838.467768545457;5137.794488220504;5364.57838672752;4310.7629080400875;4470.173017991682;4836.671179294512;4853.854384368475;4927.408710954636;2532.2150133458317;2571.29289445404;2623.0688105722575;2751.7523422892036;2848.1740295314125;4742.834000810024;5455.09990851513;5474.808381706621;5585.497305242548;5620.85947851307;5012.498750124958;5140.324667862496;5523.475269849358;5562.666240025531;5563.805266815723;4648.465426475998;5047.394312682428;5434.956424402348;5596.832095037155;5637.88434442859;5326.786928522815;5338.919916201183;5428.7557059931605;5432.837014889559;5439.864003399946;4723.952760472352;5304.946950530445;5724.420463628997;5736.913946290725;5785;5004.599632029356;5203.739813009259;5991;6019.608725432833;6039.456448919618;3667.908302292464;3863.3239183142277;3896.3571010782903;3905.7656540607654;3912.2175564886566;4807.495213002584;5693.487586117104;5729.249960019298;5753.626014309057;5765.682887441063;4643.8374656886845;5583.832485025456;5586.245856809279;5600.395815945605;5618.6628802272;4618.529086938472;5288.382415280591;5431.945680543302;5576.546319619517;5602.52378547828;4799.9760001199775;4934.777934992862;5221.477852214748;5468.425815289337;5489.451054894478;4768.520369407958;5346.74351527383;5432.538234249975;5595.832125036255;5618.213450116905;4335.609795118455;4871.903821640102;5198.853135396006;5427.41790418322;5460.6996615186545 14:21:21 INFO - PROCESS | 1642 | |5;yui.html;611.9051547010214;715.7387553542956;732.9413646908249;756.2131706299127;768.316198583261;14233.928830355833;14418.855811441896;14632.634184145401;14715.632109197275;14927.179005154712;12993.285369304684;13331.266780327102;13408.530701425429;13431;13435.529756458525;814.2305521282376;831.7920519870032;832.6045128563944;832.7335252719132;832.8750687396879;657.5200103924138;680.1939701453769;680.6766785776741;808.107041718898;827.0117209934131;840.5209030852417;879.5778026547262;889.7642124836923;889.7731078574955;896.7220161749846;878.5914549734372;880.5245167609484;880.8502554565739;881.7839629290833;884.1644645809712;521.8564894653963;537.2746791830997;631.2771876201723;652.781318258384;666.0375757031092;14261.85738142626;14392.568222953329;14395;14412.774914132311;14413.927930360282;13245.933770331087;13561;13566.321683915765;13618.455261789517;13671.453141874314;11951.88048119525;12031.157818952677;12119.575814846527;12288.938555307166;12387.814182787266;4418.9337159942625;4452.910941781179;4528.43394575678;4548.317752337143;4641.907161856778;74526;77058.84411733829;77981.61009194917;85145.99999999937;88936;8747.825043499095;10452.32059916103;10582.629607963685;10705.036546710833;10748.431916435484;19945.10247038887;20061.398158055283;20172;20253.79746202519;20267.290644827655;7253.637318134072;7470.2903224193615;7549.9245007549225;7622.428317876182;7734.6132693366235;4138.979305103516;4178.686598505064;4301.483821941387;4319.43847299849;4386.276264416336;2904.4917139500503;2923.166897434221;3276.2955964467756;3389.881354152642;3397.8980630581123;613.123233775705;617.7745123030062;624.5253607258517;626.1890851347516;659.1760299625469;483.8185680369861;617.5707883021257;628.8553632664422;630.152444961526;645.3288579876876;8244.958775206085;8337.817102090472;8584.356173287028;8654.048054713976;8723.127687231226;582.4815913836688;597;611.8133969139456;619.6096459230656;647.9611223326616;3421.4867769834227;4058.1680755445063;4086.264472394938;4181.790910454526;4224.66202703783;54433.72783136059;67607.66196168988;81493;106525;106599;65488.34511654918;69428;83772;98429.50785246171;100660;88125.11874881298;88136;88518.55740721255;91046;92206;7924.4056695746895;8106.39202059836;8118.147594502492;8160.224778646019;8201.015878094668;58071.12893306519;58630;59102.70448647729;59936.400635994825;61693.38306617056;65228.67385663231;81669.77495337694;83547.3290534198;84706.15293846982;87946.68079978933;10599.364038157735;11807;11913.631415473941;12475.75048499043;12545.372731363577;13494;13851;14261.286935652968;14786.926065370035;15057.924710376377;13335.399907004403;13360.465581377024;13656.863431365957;13843.307834608431;13949.860501394856;10768.676939691823;12561.24632522015;12726.68183295425;13237.139585926883;13684.65788355299;12710.745785084431;12778.808317875426;12919.418626162033;13034.47862085506;13358.599242022754;9170.358074934691;10619.681409557727;10862.891371086504;11170.944145279222;11613.070954323464;11148.721281968015;11266.66200014001;11373.43132843338;11559.36423496682;11590.188686791844 14:21:21 INFO - PROCESS | 1642 | __end_tp_report 14:21:21 INFO - PROCESS | 1642 | __start_cc_report 14:21:21 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,2171 14:21:21 INFO - PROCESS | 1642 | __end_cc_report 14:21:21 INFO - PROCESS | 1642 | __startTimestamp1483654881839__endTimestamp 14:21:21 INFO - PROCESS | 1642 | 14:21:21 INFO - PROCESS | 1642 | ------- Summary: start ------- 14:21:21 INFO - PROCESS | 1642 | Number of tests: 6 14:21:21 INFO - PROCESS | 1642 | 14:21:21 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5752.80 Median:6038.00 stddev:663.06 (11.0%) stddev-sans-first:623.63 14:21:21 INFO - PROCESS | 1642 | Values: 2810.7 3379.5 3381.1 3383.5 3434.1 5715.0 5800.3 5811.4 5840.3 5855.8 4761.4 5765.5 5795.1 5828.1 5852.3 5220.5 5242.9 5265.7 5276.8 5302.4 5167.3 5181.8 5185.3 5333.6 5339.6 5218.7 5236.0 5237.6 5251.1 5255.0 5240.7 5254.2 5254.3 5256.3 5264.7 4310.5 5086.3 5199.8 5209.4 5238.0 4898.5 5495.8 5776.0 5781.1 5782.1 6170.4 6220.4 6221.0 6221.5 6228.3 6229.3 6274.4 6308.3 6315.3 6324.1 5906.9 5908.1 5911.7 5923.5 5927.4 6213.7 6283.9 6296.5 6299.4 6311.9 6037.9 6108.7 6137.4 6147.6 6204.2 6221.5 6301.5 6318.9 6328.6 6330.7 6236.9 6243.1 6268.7 6269.1 6273.2 6132.7 6141.6 6185.3 6208.7 6224.7 5016.1 5023.3 5027.1 5030.6 5054.8 4496.7 4509.7 4517.9 4543.0 4550.9 5691.1 5696.9 5724.3 5778.7 5881.9 5300.4 6193.4 6250.7 6257.9 6279.6 5749.5 5751.6 5912.8 5922.8 5926.2 5373.7 5419.5 5438.0 5477.1 5503.6 5400.7 6197.8 6250.4 6259.9 6334.8 6259.6 6259.9 6276.2 6279.9 6285.7 6255.2 6353.9 6366.8 6370.0 6393.9 4761.7 6118.4 6132.3 6149.0 6259.6 6084.9 6090.7 6227.9 6250.0 6254.5 6154.8 6192.7 6206.9 6223.3 6227.5 6053.7 6121.3 6133.0 6155.6 6231.4 6010.1 6028.3 6038.0 6046.3 6090.9 6095.8 6134.5 6194.6 6213.3 6224.4 6004.6 6038.9 6039.0 6049.7 6136.5 14:21:21 INFO - PROCESS | 1642 | 14:21:21 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:75465.49 Median:37383.63 stddev:174202.07 (466.0%) stddev-sans-first:174599.77 14:21:21 INFO - PROCESS | 1642 | Values: 478.2 506.2 519.8 522.8 557.8 30509.9 36206.5 40990.0 41605.6 41679.0 42294.8 42612.6 42725.1 42889.6 42896.4 9262.5 9420.0 9420.4 9423.6 9438.7 8461.4 8677.2 8687.4 8746.5 8755.3 10993.0 11214.8 11220.8 11361.9 11384.8 11261.3 11336.6 11353.5 11353.6 11367.5 8411.2 8648.0 10969.8 11167.6 11263.7 38160.8 39911.6 40096.0 40115.8 40192.2 3239.5 3267.0 3504.8 3681.7 3872.2 28444.6 29121.0 30507.8 30816.4 31232.2 3177.5 3220.5 3229.4 3233.3 3239.3 486027.0 491391.0 491831.0 492074.0 494202.0 59838.4 60070.4 60089.4 60119.7 60150.1 61546.7 61721.4 61745.4 61808.7 61928.0 15887.9 16355.5 16361.8 16379.3 16431.4 12681.8 12709.2 12715.2 12718.2 12722.4 14962.6 15382.4 16492.3 16647.9 17054.7 6297.7 6307.7 6380.5 6411.2 6412.2 843.5 970.8 991.5 1026.7 1037.9 30881.0 31192.4 31810.7 31953.4 32424.8 1026.3 1046.2 1048.2 1057.3 1115.0 11928.7 14235.6 14847.2 15283.9 15681.1 616573.0 620613.0 621686.0 623096.0 627397.0 49278.0 50381.2 55903.2 57027.7 57292.7 1341.6 1368.6 1382.6 1385.4 1386.0 29566.3 30576.4 30627.7 30799.7 31347.7 51046.5 51231.0 51387.2 51526.2 51585.5 785693.0 791695.0 799048.0 799700.0 800833.0 24924.3 25136.0 25291.5 25625.7 25634.0 44579.3 44659.1 45171.3 45201.0 45528.0 36666.0 37694.6 37804.6 37850.8 37886.9 36427.5 37315.6 37333.6 37469.3 38431.4 36565.3 37433.6 37525.0 37729.6 37748.6 32253.0 33361.7 33655.3 33671.7 33912.3 24874.0 24890.9 24990.6 25135.4 25520.4 14:21:21 INFO - PROCESS | 1642 | 14:21:21 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:34134.80 Median:1650.77 stddev:98051.28 (5939.7%) stddev-sans-first:98293.49 14:21:21 INFO - PROCESS | 1642 | Values: 336.9 343.5 366.6 371.7 396.6 14391.7 14565.1 15535.4 19595.5 20104.9 18645.4 19365.5 19814.6 20054.1 20118.3 5720.6 5954.8 5987.3 5995.0 5998.4 4715.8 5850.2 6509.7 6571.9 6639.8 6291.1 6566.4 6573.6 6585.3 6587.3 6456.3 6490.6 6503.1 6506.1 6529.4 5536.4 5595.2 5629.3 5664.2 5702.7 24974.4 25030.0 25098.4 25105.0 25343.1 2235.5 2368.9 2395.0 2733.5 2964.7 14264.6 15236.3 15337.5 15525.6 15689.4 1523.0 1709.9 1725.5 1777.6 1834.7 100935.0 117748.0 119091.0 119578.0 119758.4 10384.8 10401.1 10534.5 10537.0 10853.7 13466.9 13530.8 13543.0 13544.6 13571.0 8295.7 8395.9 8443.0 8458.4 8581.8 8408.7 8441.9 8611.6 8628.5 8642.8 6309.5 6442.2 6443.1 6444.7 6452.3 1606.9 1672.5 1735.8 1791.4 1820.3 810.6 865.5 947.6 951.0 971.8 20949.8 21364.0 21391.6 21423.4 21433.6 918.1 918.5 929.2 934.6 982.6 10515.9 11198.3 11239.4 11308.5 11368.4 582289.0 583179.0 592046.0 596049.0 596347.0 74532.0 110326.0 119496.0 120147.0 121246.0 879.8 919.7 974.9 977.9 979.1 19101.3 19739.6 19848.9 19866.0 19879.5 49263.5 51628.0 53157.2 54104.5 54166.0 75241.0 75472.0 75656.6 76248.6 76254.6 6614.0 6701.6 6958.8 7153.2 7539.8 8992.2 11710.0 12395.4 12641.3 12893.3 10923.3 10973.1 10997.7 11337.0 11512.7 10342.3 10932.4 11054.5 11445.7 11499.5 11035.6 11097.1 11454.9 11544.1 11695.0 7281.0 7325.3 7431.7 7446.5 7563.2 7659.8 9733.3 11023.7 11282.0 11491.7 14:21:21 INFO - PROCESS | 1642 | 14:21:21 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5460.91 Median:5131.59 stddev:3004.92 (58.6%) stddev-sans-first:2997.39 14:21:21 INFO - PROCESS | 1642 | Values: 1339.4 1468.4 1612.8 1786.7 1835.5 8116.1 8495.4 8509.1 8517.6 8550.4 7483.7 7703.6 7709.5 8057.4 8332.5 2311.4 2354.3 2355.2 2357.9 2358.4 2331.8 2358.3 2377.4 2378.5 2383.8 2271.8 2303.0 2337.8 2341.3 2354.3 2296.1 2307.9 2326.0 2326.2 2328.0 2334.6 2347.6 2353.8 2360.2 2364.9 8246.8 8447.3 8454.2 8460.0 8488.4 5056.2 5210.3 5219.8 5222.7 5233.7 3257.7 3353.3 3562.2 3563.3 3567.4 1062.7 1066.2 1091.6 1104.1 1111.0 10984.1 11095.8 11133.4 11142.4 11150.1 4493.2 4605.8 4709.3 4711.4 4766.5 9401.2 9418.0 9421.6 9434.9 9436.0 4455.0 5206.8 5217.8 5222.1 5233.2 3668.2 4222.7 4701.7 4777.4 4782.3 3776.6 3782.5 3865.3 3886.4 3889.1 1193.0 1240.0 1262.6 1270.8 1278.1 764.7 786.7 901.2 904.1 904.3 5414.1 5581.9 5604.5 5611.8 5684.6 818.6 854.6 915.5 922.9 932.2 3718.6 3795.4 3799.9 3850.5 3909.8 9732.9 10089.6 10132.5 10274.5 10290.6 10235.6 10240.0 10244.8 10249.5 10251.6 7485.3 7868.4 7891.6 7896.7 8043.0 5669.3 5692.9 5694.5 5811.7 5849.0 9089.3 9111.0 9508.0 9547.8 9574.9 9459.2 9513.5 9558.5 9656.2 9685.2 6625.6 6825.7 6855.6 7143.6 7348.8 6830.2 7780.5 8041.6 8049.8 8059.6 7091.4 7173.7 7228.5 7229.2 7348.0 6110.6 6982.7 7063.0 7156.0 7166.0 6448.4 6609.5 6718.6 6774.0 6795.6 4099.8 4836.3 4871.5 4884.6 4923.5 4727.2 4731.2 4757.0 4945.0 5056.4 14:21:21 INFO - PROCESS | 1642 | 14:21:21 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4392.96 Median:4803.74 stddev:1340.70 (27.9%) stddev-sans-first:1308.71 14:21:21 INFO - PROCESS | 1642 | Values: 295.5 303.2 309.2 313.8 315.0 4637.8 5053.6 5284.1 5352.6 5353.8 4669.9 4980.0 5072.9 5161.6 5259.9 3497.6 3992.7 4051.3 4056.6 4060.7 4019.6 4022.5 4033.4 4034.8 4082.4 3191.0 3988.0 4016.9 4020.4 4027.4 3977.8 4029.1 4035.4 4035.6 4074.5 3503.0 3516.9 3520.6 3530.5 3534.8 4459.3 4960.5 5104.8 5136.4 5168.2 4544.5 4588.8 4610.3 4624.4 4626.1 5545.5 5578.6 5588.9 5593.7 5628.2 3075.7 3283.8 3463.4 3554.2 3730.0 4307.5 4867.3 5562.7 5775.9 5813.7 3904.8 3914.1 3924.8 3925.4 3932.0 3964.6 3981.9 3984.9 3985.3 3991.7 3744.3 3999.4 4072.5 4427.3 4635.3 4524.0 4909.8 5461.9 5470.6 5619.4 1168.9 1217.5 1236.1 1255.8 1308.0 740.2 786.7 836.4 876.4 906.0 4459.7 4501.4 4662.4 4763.1 4941.7 4537.8 4621.6 4838.5 5137.8 5364.6 4310.8 4470.2 4836.7 4853.9 4927.4 2532.2 2571.3 2623.1 2751.8 2848.2 4742.8 5455.1 5474.8 5585.5 5620.9 5012.5 5140.3 5523.5 5562.7 5563.8 4648.5 5047.4 5435.0 5596.8 5637.9 5326.8 5338.9 5428.8 5432.8 5439.9 4724.0 5304.9 5724.4 5736.9 5785.0 5004.6 5203.7 5991.0 6019.6 6039.5 3667.9 3863.3 3896.4 3905.8 3912.2 4807.5 5693.5 5729.2 5753.6 5765.7 4643.8 5583.8 5586.2 5600.4 5618.7 4618.5 5288.4 5431.9 5576.5 5602.5 4800.0 4934.8 5221.5 5468.4 5489.5 4768.5 5346.7 5432.5 5595.8 5618.2 4335.6 4871.9 5198.9 5427.4 5460.7 14:21:21 INFO - PROCESS | 1642 | 14:21:21 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:19713.27 Median:2562.86 stddev:27999.36 (1092.5%) stddev-sans-first:28041.17 14:21:21 INFO - PROCESS | 1642 | Values: 611.9 715.7 732.9 756.2 768.3 14233.9 14418.9 14632.6 14715.6 14927.2 12993.3 13331.3 13408.5 13431.0 13435.5 814.2 831.8 832.6 832.7 832.9 657.5 680.2 680.7 808.1 827.0 840.5 879.6 889.8 889.8 896.7 878.6 880.5 880.9 881.8 884.2 521.9 537.3 631.3 652.8 666.0 14261.9 14392.6 14395.0 14412.8 14413.9 13245.9 13561.0 13566.3 13618.5 13671.5 11951.9 12031.2 12119.6 12288.9 12387.8 4418.9 4452.9 4528.4 4548.3 4641.9 74526.0 77058.8 77981.6 85146.0 88936.0 8747.8 10452.3 10582.6 10705.0 10748.4 19945.1 20061.4 20172.0 20253.8 20267.3 7253.6 7470.3 7549.9 7622.4 7734.6 4139.0 4178.7 4301.5 4319.4 4386.3 2904.5 2923.2 3276.3 3389.9 3397.9 613.1 617.8 624.5 626.2 659.2 483.8 617.6 628.9 630.2 645.3 8245.0 8337.8 8584.4 8654.0 8723.1 582.5 597.0 611.8 619.6 648.0 3421.5 4058.2 4086.3 4181.8 4224.7 54433.7 67607.7 81493.0 106525.0 106599.0 65488.3 69428.0 83772.0 98429.5 100660.0 88125.1 88136.0 88518.6 91046.0 92206.0 7924.4 8106.4 8118.1 8160.2 8201.0 58071.1 58630.0 59102.7 59936.4 61693.4 65228.7 81669.8 83547.3 84706.2 87946.7 10599.4 11807.0 11913.6 12475.8 12545.4 13494.0 13851.0 14261.3 14786.9 15057.9 13335.4 13360.5 13656.9 13843.3 13949.9 10768.7 12561.2 12726.7 13237.1 13684.7 12710.7 12778.8 12919.4 13034.5 13358.6 9170.4 10619.7 10862.9 11170.9 11613.1 11148.7 11266.7 11373.4 11559.4 11590.2 14:21:21 INFO - PROCESS | 1642 | -------- Summary: end -------- 14:21:21 INFO - PROCESS | 1642 | 14:21:22 INFO - TEST-INFO | 1642: exit 0 14:21:22 INFO - TEST-OK | dromaeo_css | took 1120063ms 14:21:22 INFO - TEST-START | kraken 14:21:22 INFO - operating with platform_type : mac_ 14:21:22 INFO - Initialising browser for kraken test... 14:21:22 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpQplWOa/profile http://localhost:49235/getInfo.html) 14:21:25 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 14:21:25 INFO - PROCESS | 1647 | colorDepth:24 14:21:25 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 14:21:25 INFO - PROCESS | 1647 | __metrics 14:21:25 INFO - PROCESS | 1647 | 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" 14:21:25 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:21:25 INFO - TEST-INFO | 1647: exit 0 14:21:25 INFO - Browser initialized. 14:21:25 INFO - Running cycle 1/1 for kraken test... 14:21:25 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpQplWOa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:21:38 INFO - PROCESS | 1648 | RSS: Main: 223027200 14:21:38 INFO - PROCESS | 1648 | 14:21:41 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 14:21:41 INFO - PROCESS | 1648 | RSS: Main: 272506880 14:21:41 INFO - PROCESS | 1648 | 14:21:44 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 14:21:45 INFO - PROCESS | 1648 | RSS: Main: 284274688 14:21:45 INFO - PROCESS | 1648 | 14:21:48 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 14:21:48 INFO - PROCESS | 1648 | RSS: Main: 381837312 14:21:48 INFO - PROCESS | 1648 | 14:21:50 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 14:21:51 INFO - PROCESS | 1648 | RSS: Main: 283488256 14:21:51 INFO - PROCESS | 1648 | 14:21:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:21:54 INFO - PROCESS | 1648 | RSS: Main: 284766208 14:21:54 INFO - PROCESS | 1648 | 14:21:56 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 14:21:57 INFO - PROCESS | 1648 | RSS: Main: 324898816 14:21:57 INFO - PROCESS | 1648 | 14:22:00 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 14:22:01 INFO - PROCESS | 1648 | RSS: Main: 328249344 14:22:01 INFO - PROCESS | 1648 | 14:22:03 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 14:22:04 INFO - PROCESS | 1648 | RSS: Main: 323956736 14:22:04 INFO - PROCESS | 1648 | 14:22:06 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:22:06 INFO - PROCESS | 1648 | RSS: Main: 299438080 14:22:06 INFO - PROCESS | 1648 | 14:22:08 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:22:09 INFO - PROCESS | 1648 | RSS: Main: 301309952 14:22:09 INFO - PROCESS | 1648 | 14:22:11 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:22:12 INFO - PROCESS | 1648 | RSS: Main: 304603136 14:22:12 INFO - PROCESS | 1648 | 14:22:14 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:22:15 INFO - PROCESS | 1648 | RSS: Main: 323764224 14:22:15 INFO - PROCESS | 1648 | 14:22:18 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:22:19 INFO - PROCESS | 1648 | RSS: Main: 317026304 14:22:19 INFO - PROCESS | 1648 | 14:22:20 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 14:22:21 INFO - PROCESS | 1648 | RSS: Main: 361021440 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | __start_tp_report 14:22:21 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 14:22:21 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 14:22:21 INFO - PROCESS | 1648 | |i|pagename|runs| 14:22:21 INFO - PROCESS | 1648 | |0;ai-astar;113;113;114;113;114;114;111;112;113;112 14:22:21 INFO - PROCESS | 1648 | |1;audio-beat-detection;143;110;111;143;106;112;114;110;158;141 14:22:21 INFO - PROCESS | 1648 | |2;audio-dft;183;184;183;181;182;181;183;183;185;183 14:22:21 INFO - PROCESS | 1648 | |3;audio-fft;82;60;57;89;63;58;61;64;110;81 14:22:21 INFO - PROCESS | 1648 | |4;audio-oscillator;89;69;93;69;70;86;127;99;73;92 14:22:21 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;87;83;86;85;86;82;87;84;86;84 14:22:21 INFO - PROCESS | 1648 | |6;imaging-darkroom;96;95;96;96;96;96;96;97;95;97 14:22:21 INFO - PROCESS | 1648 | |7;imaging-desaturate;94;114;79;82;83;82;83;85;81;83 14:22:21 INFO - PROCESS | 1648 | |8;json-parse-financial;57;53;53;52;55;58;59;58;57;57 14:22:21 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;66;65;83;75;72;71;69;71;70;71 14:22:21 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;83;83;82;82;96;102;101;82;83;81 14:22:21 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;138;137;138;145;148;142;138;150;224;136 14:22:21 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;170;163;164;161;164;160;162;161;161;166 14:22:21 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;68;66;65;67;67;67;67;65;65;65 14:22:21 INFO - PROCESS | 1648 | __end_tp_report 14:22:21 INFO - PROCESS | 1648 | __start_cc_report 14:22:21 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1987 14:22:21 INFO - PROCESS | 1648 | __end_cc_report 14:22:21 INFO - PROCESS | 1648 | __startTimestamp1483654941034__endTimestamp 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | ------- Summary: start ------- 14:22:21 INFO - PROCESS | 1648 | Number of tests: 14 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:0.99 (0.9%) stddev-sans-first:1.05 14:22:21 INFO - PROCESS | 1648 | Values: 113.0 113.0 114.0 113.0 114.0 114.0 111.0 112.0 113.0 112.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:127.50 stddev:19.12 (15.0%) stddev-sans-first:19.11 14:22:21 INFO - PROCESS | 1648 | Values: 143.0 110.0 111.0 143.0 106.0 112.0 114.0 110.0 158.0 141.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.00 stddev:1.23 (0.7%) stddev-sans-first:1.30 14:22:21 INFO - PROCESS | 1648 | Values: 183.0 184.0 183.0 181.0 182.0 181.0 183.0 183.0 185.0 183.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:72.50 Median:63.50 stddev:17.46 (27.5%) stddev-sans-first:18.17 14:22:21 INFO - PROCESS | 1648 | Values: 82.0 60.0 57.0 89.0 63.0 58.0 61.0 64.0 110.0 81.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:87.50 stddev:18.08 (20.7%) stddev-sans-first:19.16 14:22:21 INFO - PROCESS | 1648 | Values: 89.0 69.0 93.0 69.0 70.0 86.0 127.0 99.0 73.0 92.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:86.00 stddev:1.70 (2.0%) stddev-sans-first:1.64 14:22:21 INFO - PROCESS | 1648 | Values: 87.0 83.0 86.0 85.0 86.0 82.0 87.0 84.0 86.0 84.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 14:22:21 INFO - PROCESS | 1648 | Values: 96.0 95.0 96.0 96.0 96.0 96.0 96.0 97.0 95.0 97.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:86.60 Median:83.00 stddev:10.43 (12.6%) stddev-sans-first:10.71 14:22:21 INFO - PROCESS | 1648 | Values: 94.0 114.0 79.0 82.0 83.0 82.0 83.0 85.0 81.0 83.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:57.00 stddev:2.47 (4.3%) stddev-sans-first:2.59 14:22:21 INFO - PROCESS | 1648 | Values: 57.0 53.0 53.0 52.0 55.0 58.0 59.0 58.0 57.0 57.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.00 stddev:5.01 (7.1%) stddev-sans-first:4.94 14:22:21 INFO - PROCESS | 1648 | Values: 66.0 65.0 83.0 75.0 72.0 71.0 69.0 71.0 70.0 71.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:82.50 stddev:8.55 (10.4%) stddev-sans-first:8.92 14:22:21 INFO - PROCESS | 1648 | Values: 83.0 83.0 82.0 82.0 96.0 102.0 101.0 82.0 83.0 81.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:143.50 stddev:26.59 (18.5%) stddev-sans-first:27.87 14:22:21 INFO - PROCESS | 1648 | Values: 138.0 137.0 138.0 145.0 148.0 142.0 138.0 150.0 224.0 136.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.20 Median:163.50 stddev:3.01 (1.8%) stddev-sans-first:1.94 14:22:21 INFO - PROCESS | 1648 | Values: 170.0 163.0 164.0 161.0 164.0 160.0 162.0 161.0 161.0 166.0 14:22:21 INFO - PROCESS | 1648 | 14:22:21 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:1.14 (1.7%) stddev-sans-first:1.00 14:22:21 INFO - PROCESS | 1648 | Values: 68.0 66.0 65.0 67.0 67.0 67.0 67.0 65.0 65.0 65.0 14:22:21 INFO - PROCESS | 1648 | -------- Summary: end -------- 14:22:21 INFO - PROCESS | 1648 | 14:22:22 INFO - TEST-INFO | 1648: exit 0 14:22:22 INFO - TEST-OK | kraken | took 59625ms 14:22:22 INFO - SUITE-END | took 1179s 14:22:22 INFO - Completed test suite (00:19:39) 14:22:22 INFO - javascript benchmark 14:22:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2810.7048759880204, 3379.4592865141576, 3381.08710648125, 3383.475561287998, 3434.0899661589683, 5715.0, 5800.274965629296, 5811.360750317473, 5840.299164100308, 5855.79504717335, 4761.381020467343, 5765.481106700396, 5795.072788353864, 5828.125781132832, 5852.326982397015, 5220.4779522047875, 5242.947570524303, 5265.65773224741, 5276.841694749163, 5302.44324345942, 5167.302414174076, 5181.792728290883, 5185.299984502081, 5333.599980001496, 5339.599530035243, 5218.712970786605, 5236.0, 5237.554807841319, 5251.081060814361, 5254.973725131388, 5240.737963101829, 5254.15933450646, 5254.290670759437, 5256.290400795881, 5264.657797243186, 4310.547392523771, 5086.262491938648, 5199.818006369796, 5209.400918894337, 5238.0, 4898.461169271377, 5495.752691128909, 5775.971120144414, 5781.132830075522, 5782.074868021096, 6170.382961703838, 6220.409061139185, 6221.004639257742, 6221.533384996098, 6228.314885362606, 6229.314775374705, 6274.372562743734, 6308.337624549402, 6315.336889626569, 6324.146240257598, 5906.852328691774, 5908.084246941731, 5911.733971971272, 5923.5261179106, 5927.436893495154, 6213.65824879627, 6283.937160628427, 6296.527760417987, 6299.401556852137, 6311.936880631227, 6037.909431358533, 6108.725107370179, 6137.420285946852, 6147.600405973598, 6204.224471941007, 6221.5333849961435, 6301.464375528039, 6318.936810631927, 6328.557001009977, 6330.68346582669, 6236.906446403307, 6243.125962365272, 6268.717907694165, 6269.0909818076125, 6273.18448601688, 6132.6627035513475, 6141.578063794062, 6185.257769067741, 6208.689565521706, 6224.719887605069, 5016.0971025215085, 5023.34696489454, 5027.069992051409, 5030.572401345853, 5054.848354549369, 4496.730196188174, 4509.706869053501, 4517.909641807146, 4542.977285113553, 4550.886227844264, 5691.089425691869, 5696.914546281809, 5724.3130824300515, 5778.711064446845, 5881.882362352729, 5300.44345343741, 6193.4425901669065, 6250.6874656267, 6257.874842503215, 6279.560430769802, 5749.540036797046, 5751.568632352591, 5912.793052243151, 5922.822315330461, 5926.199963005025, 5373.677579345252, 5419.458054194549, 5438.048341540294, 5477.123660214346, 5503.6147469677535, 5400.702961337164, 6197.752089916353, 6250.3749625037135, 6259.937400625936, 6334.778282760081, 6259.593126446766, 6259.874802503833, 6276.152719382825, 6279.905801412891, 6285.654289014148, 6255.218097737783, 6353.904691429725, 6366.808995730135, 6370.0, 6393.904091438725, 4761.6904901181315, 6118.357572455006, 6132.263729246545, 6148.9692551536955, 6259.593126446766, 6084.908726369196, 6090.69546522681, 6227.9377206227355, 6250.0, 6254.530910181664, 6154.753809847735, 6192.659403732658, 6206.875862482815, 6223.284322302993, 6227.50179985591, 6053.667048312385, 6121.326654068138, 6132.969335153296, 6155.630662160284, 6231.40801623845, 6010.1285313628405, 6028.2766068072115, 6038.0, 6046.27444706638, 6090.908636370369, 6095.786647467317, 6134.4978165938865, 6194.628322300677, 6213.285472170758, 6224.439800417984, 6004.609700369462, 6038.9396106038375, 6039.033754599243, 6049.667268300285, 6136.549713441429], "value": 5710.303888691542, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.2276623253447, 506.15724818177733, 519.7816916894905, 522.7856578802691, 557.8159207461536, 30509.932152374673, 36206.456903146405, 40990.0, 41605.58394416055, 41679.0, 42294.788526057324, 42612.573874261245, 42725.14549709004, 42889.571104288945, 42896.356554651706, 9262.490563019031, 9419.952900235488, 9420.38767480115, 9423.575939082757, 9438.669646562372, 8461.40770146087, 8677.175668311533, 8687.391882568223, 8746.518941458216, 8755.3433492488, 10993.0, 11214.775704485904, 11220.831687524693, 11361.886381136206, 11384.772304553904, 11261.268017578832, 11336.603218887381, 11353.489092990836, 11353.602623908206, 11367.4884630191, 8411.1588841116, 8648.0, 10969.83545246822, 11167.609133680364, 11263.66209013731, 38160.80919595412, 39911.60088399137, 40096.0, 40115.799421003, 40192.19615607701, 3239.4654881944684, 3266.9673303267136, 3504.8422820972864, 3681.687056600192, 3872.1674839909274, 28444.573331400046, 29120.9807656731, 30507.847460762554, 30816.38367232643, 31232.21919452032, 3177.4916013437737, 3220.533022711694, 3229.402560526287, 3233.288676491192, 3239.347932554414, 486027.0, 491391.0, 491831.0, 492074.0, 494202.0, 59838.401615984156, 60070.39929600735, 60089.39910600925, 60119.69940150359, 60150.09774853381, 61546.69226653838, 61721.382786172464, 61745.382546174864, 61808.69095654493, 61928.0, 15887.920560397124, 16355.509334719978, 16361.754573681405, 16379.262933168036, 16431.424900128677, 12681.809772853414, 12709.173903696415, 12715.173513721578, 12718.173318734252, 12722.363881805873, 14962.625934351729, 15382.38470461169, 16492.34030638785, 16647.916760416363, 17054.658906822042, 6297.6536290503545, 6307.653079080695, 6380.521460890452, 6411.230652321751, 6412.166418365583, 843.451756358372, 970.834958057132, 991.468181659338, 1026.7381817636604, 1037.8754549454113, 30881.0, 31192.376152477278, 31810.681893181234, 31953.360932781216, 32424.83787581047, 1026.2610920137488, 1046.2362475393006, 1048.171944164102, 1057.2916146182076, 1114.9777004459866, 11928.701782455508, 14235.572932812032, 14847.183404912834, 15283.923580382027, 15681.13753743555, 616573.0, 620613.0, 621686.0, 623096.0, 627397.0, 49278.0, 50381.24428133581, 55903.16145257824, 57027.714861425426, 57292.713536432886, 1341.584108926236, 1368.6304697731557, 1382.5644921849384, 1385.3696568061507, 1385.9584212473642, 29566.260843478223, 30576.388472230876, 30627.693723062483, 30799.692003080578, 31347.686523135388, 51046.48953510417, 51231.0, 51387.22919156141, 51526.22710659268, 51585.484145158065, 785693.0, 791695.0, 799048.0, 799700.0, 800833.0, 24924.252272431855, 25136.0, 25291.49417011686, 25625.74374256308, 25634.0, 44579.325240485676, 44659.10681786411, 45171.322430162916, 45201.0, 45528.0, 36666.0, 37694.62305376911, 37804.62195378011, 37850.810745947194, 37886.86339409822, 36427.45358819567, 37315.62684373122, 37333.6266637341, 37469.250614988094, 38431.423528647654, 36565.26869462649, 37433.62566374301, 37525.0, 37729.62270377371, 37748.62251377451, 32253.0, 33361.666383335854, 33655.32689346249, 33671.66328336783, 33912.3217535643, 24874.0, 24890.875545622155, 24990.62514062327, 25135.371615709755, 25520.361990950376], "value": 19948.14540112933, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.8837750975913, 343.5225037198296, 366.6040676069845, 371.71563753728384, 396.6212267284743, 14391.712165756691, 14565.12609243446, 15535.378584856606, 19595.510112247164, 20104.899475502603, 18645.440636780884, 19365.515862103453, 19814.60370792583, 20054.097565609547, 20118.295859644917, 5720.570957178218, 5954.761809527636, 5987.28152621686, 5995.040793473046, 5998.430149135847, 4715.811367545294, 5850.239468869062, 6509.707063182168, 6571.868562628744, 6639.800805975829, 6291.087792270118, 6566.409023187912, 6573.638449885254, 6585.275619681831, 6587.275399706028, 6456.289808121103, 6490.643014634194, 6503.089567460559, 6506.1216735740545, 6529.444997175246, 5536.390996990368, 5595.160725891108, 5629.268195134607, 5664.150377443375, 5702.686352250625, 24974.37564060913, 25030.0, 25098.372540686447, 25105.0, 25343.11299104541, 2235.4523141830355, 2368.9052437902465, 2395.0, 2733.494303553849, 2964.659064207622, 14264.643383915381, 15236.314365853452, 15337.539873803804, 15525.611859703486, 15689.372425102982, 1522.9847701522954, 1709.880308378414, 1725.482355293407, 1777.5644966983043, 1834.6697594432997, 100935.0, 117748.0, 119090.99999999913, 119578.0, 119758.4012079934, 10384.792304154025, 10401.063904248655, 10534.473276336152, 10536.998985096403, 10853.674389768319, 13466.865331346757, 13530.797038044437, 13543.0, 13544.59366219015, 13571.0, 8295.709650162215, 8395.916040839636, 8443.0, 8458.365622578209, 8581.785455363666, 8408.705695300636, 8441.902552668114, 8611.61247743853, 8628.482291062557, 8642.783930401665, 6309.463695585927, 6442.16251887261, 6443.097966284724, 6444.712127194872, 6452.322506136882, 1606.9116198608956, 1672.5316911264865, 1735.7830271216098, 1791.3730194432105, 1820.2809890093604, 810.6068556749972, 865.5499140447058, 947.5948338239857, 951.0156987517886, 971.8104969530874, 20949.79050209509, 21364.0, 21391.57216855654, 21423.39324550665, 21433.57132857334, 918.0910898210723, 918.4948278446827, 929.177677755178, 934.6448349627234, 982.621690649091, 10515.94742026285, 11198.328100314007, 11239.381833999209, 11308.529891114102, 11368.374739389248, 582289.0, 583179.0, 592046.0, 596049.0, 596347.0, 74532.0, 110326.0, 119496.0, 120147.0, 121246.0, 879.8108406692592, 919.7240827751782, 974.88301403832, 977.9364341317792, 979.1432496565506, 19101.331453399063, 19739.605207895474, 19848.90075549613, 19866.0, 19879.503012424804, 49263.522094337226, 51628.0, 53157.20264195963, 54104.458955411515, 54166.0, 75241.0, 75472.0, 75656.62171689107, 76248.61875690587, 76254.61872690602, 6613.966930165318, 6701.597904125768, 6958.756443524655, 7153.177384600837, 7539.773806785805, 8992.235659968714, 11710.0, 12395.442205100973, 12641.304728240035, 12893.29086900192, 10923.289986150874, 10973.122150228122, 10997.725056873642, 11336.979671829575, 11512.712182195512, 10342.32774869631, 10932.398718070583, 11054.502547385226, 11445.713857153638, 11499.54001839917, 11035.558577657124, 11097.056750176325, 11454.94272528632, 11544.076473881902, 11695.005924496512, 7281.0, 7325.304096110861, 7431.665575049244, 7446.478746487694, 7563.205863384265, 7659.770206893802, 9733.270004749813, 11023.724406889893, 11282.0, 11491.712707182387], "value": 9548.32058449927, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.363802193958, 1468.4052958551788, 1612.8387161283872, 1786.6516029374277, 1835.4677143628348, 8116.1478044805335, 8495.36284778641, 8509.064002959685, 8517.574121293941, 8550.40147189697, 7483.663235154418, 7703.614819259043, 7709.537427754324, 8057.355411567075, 8332.50004999699, 2311.3528212100637, 2354.30547988343, 2355.187460326185, 2357.870317132557, 2358.433975845802, 2331.778481044298, 2358.3278765551822, 2377.429416939939, 2378.4886249456363, 2383.833131680783, 2271.7501074881748, 2303.0, 2337.801286890608, 2341.2507997440825, 2354.2701762453607, 2296.0586159674535, 2307.8730669813153, 2325.953480930372, 2326.2439707095177, 2328.0338659456306, 2334.564242990549, 2347.5891718949288, 2353.7646235376324, 2360.209329874494, 2364.9172278970154, 8246.793830154234, 8447.32421406286, 8454.196851299117, 8460.0, 8488.363372747068, 5056.216286475602, 5210.296609957683, 5219.765110570033, 5222.712750798704, 5233.712145831978, 3257.6742325767473, 3353.2958078803235, 3562.1628917204434, 3563.3051554946796, 3567.4292113262013, 1062.718379629399, 1066.2482949520647, 1091.579741799405, 1104.1332553945194, 1110.9500072496758, 10984.121270298438, 11095.778084438267, 11133.387663678575, 11142.387168705718, 11150.052245559138, 4493.168763778712, 4605.838795642137, 4709.340692303081, 4711.411073615798, 4766.45185803637, 9401.200897923616, 9417.952910235543, 9421.623135074657, 9434.90565094354, 9436.0, 4454.977725111354, 5206.76569554371, 5217.791288348423, 5222.1383471727495, 5233.215017747369, 3668.248009158116, 4222.662187025031, 4701.717896926195, 4777.378940737752, 4782.306565547976, 3776.6223377662013, 3782.527184101987, 3865.342891708416, 3886.397608370707, 3889.086064774776, 1192.9761404771857, 1239.977018959362, 1262.5644152767281, 1270.8284381608364, 1278.1180985120238, 764.7476332810271, 786.6774622404787, 901.1616302742332, 904.1049361132432, 904.263025634106, 5414.052540805422, 5581.860453488695, 5604.523615492647, 5611.7755289788765, 5684.6020778545935, 818.5743413424985, 854.598338780772, 915.4781774388667, 922.8615707643908, 932.2215949681956, 3718.6281371862597, 3795.392737162096, 3799.9430008549894, 3850.4994350734223, 3909.7849618270698, 9732.90267097334, 10089.596416143419, 10132.493375331203, 10274.486275686335, 10290.588376465008, 10235.641752538626, 10240.0, 10244.795104097877, 10249.538770755335, 10251.58993640246, 7485.251474852471, 7868.370530357673, 7891.565963871927, 7896.7236146734585, 8042.959785201154, 5669.319681638065, 5692.943070569407, 5694.487496125369, 5811.709414529341, 5849.0, 9089.272858171462, 9111.0, 9507.952460237655, 9547.76130596713, 9574.856377154209, 9459.195968342769, 9513.524323783644, 9558.522073896416, 9656.22750179998, 9685.225181985565, 6625.635590042594, 6825.658717064028, 6855.5886646801355, 7143.571385716874, 7348.77953661391, 6830.180378354629, 7780.49426787257, 8041.597920104088, 8049.758507244792, 8059.637316320663, 7091.361777440055, 7173.713051477883, 7228.49400541957, 7229.240929702306, 7348.044754182136, 6110.633361998294, 6982.718122482425, 7062.964685176541, 7156.033935418652, 7165.996760453437, 6448.387403196689, 6609.537332386687, 6718.596884186964, 6774.0, 6795.626240556619, 4099.774512401727, 4836.298736683091, 4871.464138944642, 4884.584810291023, 4923.532264434873, 4727.172744769583, 4731.195696731495, 4756.9762151189025, 4945.0, 5056.418511871182], "value": 4424.758831087978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.5286318322276, 303.1859457356998, 309.183754887098, 313.82112196048246, 315.01243601309886, 4637.817356574077, 5053.620978426619, 5284.128118860383, 5352.598555108363, 5353.785848566053, 4669.929951050737, 4979.950200497994, 5072.923906141411, 5161.561057490555, 5259.9474005259935, 3497.580290365147, 3992.7205095643317, 4051.2910240707906, 4056.6146216109423, 4060.6854093166908, 4019.593142400166, 4022.5374081980654, 4033.415154802552, 4034.7780872052026, 4082.4080508326274, 3191.0426871938444, 3988.0, 4016.9397459038137, 4020.356742921133, 4027.3556231003045, 3977.832081515552, 4029.1337362466916, 4035.3745169498775, 4035.5964403559556, 4074.470318858544, 3502.964970350315, 3516.876909308162, 3520.612732599412, 3530.4704294355797, 3534.7879127252445, 4459.308807134899, 4960.528749768766, 5104.846854594368, 5136.357955255593, 5168.173092305251, 4544.454665440136, 4588.839390621346, 4610.308453731967, 4624.3988281523525, 4626.1441633297945, 5545.500904918536, 5578.573320605549, 5588.86027849303, 5593.692346920918, 5628.212050312959, 3075.6770539093295, 3283.753718471124, 3463.3765922134007, 3554.2325135356346, 3729.974257079325, 4307.547707490731, 4867.318575399447, 5562.694051827148, 5775.855603609902, 5813.7383817728305, 3904.754766009556, 3914.119323152268, 3924.823382947774, 3925.3523168676847, 3931.9606803932165, 3964.643182113566, 3981.8606348777653, 3984.940225896614, 3985.3025720499377, 3991.6607088397814, 3744.26986737589, 3999.3601023836045, 4072.470578824734, 4427.29163333865, 4635.3278774578175, 4524.02733412318, 4909.803607855717, 5461.8803145355105, 5470.6202801781355, 5619.381867994517, 1168.8597368315686, 1217.5373358123857, 1236.1285293867802, 1255.7802384582662, 1308.0, 740.2449501508539, 786.6774622404787, 836.4019725896013, 876.4040452492367, 905.9909400906039, 4459.710118842265, 4501.3667004802555, 4662.417197850269, 4763.142634325785, 4941.654084214142, 4537.75042372666, 4621.560951709582, 4838.467768545457, 5137.794488220504, 5364.57838672752, 4310.7629080400875, 4470.173017991682, 4836.671179294512, 4853.854384368475, 4927.408710954636, 2532.2150133458317, 2571.29289445404, 2623.0688105722575, 2751.7523422892036, 2848.1740295314125, 4742.834000810024, 5455.09990851513, 5474.808381706621, 5585.497305242548, 5620.85947851307, 5012.498750124958, 5140.324667862496, 5523.475269849358, 5562.666240025531, 5563.805266815723, 4648.465426475998, 5047.394312682428, 5434.956424402348, 5596.832095037155, 5637.88434442859, 5326.786928522815, 5338.919916201183, 5428.7557059931605, 5432.837014889559, 5439.864003399946, 4723.952760472352, 5304.946950530445, 5724.420463628997, 5736.913946290725, 5785.0, 5004.599632029356, 5203.739813009259, 5991.0, 6019.608725432833, 6039.456448919618, 3667.908302292464, 3863.3239183142277, 3896.3571010782903, 3905.7656540607654, 3912.2175564886566, 4807.495213002584, 5693.487586117104, 5729.249960019298, 5753.626014309057, 5765.682887441063, 4643.8374656886845, 5583.832485025456, 5586.245856809279, 5600.395815945605, 5618.6628802272, 4618.529086938472, 5288.382415280591, 5431.945680543302, 5576.546319619517, 5602.52378547828, 4799.9760001199775, 4934.777934992862, 5221.477852214748, 5468.425815289337, 5489.451054894478, 4768.520369407958, 5346.74351527383, 5432.538234249975, 5595.832125036255, 5618.213450116905, 4335.609795118455, 4871.903821640102, 5198.853135396006, 5427.41790418322, 5460.6996615186545], "value": 3964.244333151793, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.9051547010214, 715.7387553542956, 732.9413646908249, 756.2131706299127, 768.316198583261, 14233.928830355833, 14418.855811441896, 14632.634184145401, 14715.632109197275, 14927.179005154712, 12993.285369304684, 13331.266780327102, 13408.530701425429, 13431.0, 13435.529756458525, 814.2305521282376, 831.7920519870032, 832.6045128563944, 832.7335252719132, 832.8750687396879, 657.5200103924138, 680.1939701453769, 680.6766785776741, 808.107041718898, 827.0117209934131, 840.5209030852417, 879.5778026547262, 889.7642124836923, 889.7731078574955, 896.7220161749846, 878.5914549734372, 880.5245167609484, 880.8502554565739, 881.7839629290833, 884.1644645809712, 521.8564894653963, 537.2746791830997, 631.2771876201723, 652.781318258384, 666.0375757031092, 14261.85738142626, 14392.568222953329, 14395.0, 14412.774914132311, 14413.927930360282, 13245.933770331087, 13561.0, 13566.321683915765, 13618.455261789517, 13671.453141874314, 11951.88048119525, 12031.157818952677, 12119.575814846527, 12288.938555307166, 12387.814182787266, 4418.9337159942625, 4452.910941781179, 4528.43394575678, 4548.317752337143, 4641.907161856778, 74526.0, 77058.84411733829, 77981.61009194917, 85145.99999999937, 88936.0, 8747.825043499095, 10452.32059916103, 10582.629607963685, 10705.036546710833, 10748.431916435484, 19945.10247038887, 20061.398158055283, 20172.0, 20253.79746202519, 20267.290644827655, 7253.637318134072, 7470.2903224193615, 7549.9245007549225, 7622.428317876182, 7734.6132693366235, 4138.979305103516, 4178.686598505064, 4301.483821941387, 4319.43847299849, 4386.276264416336, 2904.4917139500503, 2923.166897434221, 3276.2955964467756, 3389.881354152642, 3397.8980630581123, 613.123233775705, 617.7745123030062, 624.5253607258517, 626.1890851347516, 659.1760299625469, 483.8185680369861, 617.5707883021257, 628.8553632664422, 630.152444961526, 645.3288579876876, 8244.958775206085, 8337.817102090472, 8584.356173287028, 8654.048054713976, 8723.127687231226, 582.4815913836688, 597.0, 611.8133969139456, 619.6096459230656, 647.9611223326616, 3421.4867769834227, 4058.1680755445063, 4086.264472394938, 4181.790910454526, 4224.66202703783, 54433.72783136059, 67607.66196168988, 81493.0, 106525.0, 106599.0, 65488.34511654918, 69428.0, 83772.0, 98429.50785246171, 100660.0, 88125.11874881298, 88136.0, 88518.55740721255, 91046.0, 92206.0, 7924.4056695746895, 8106.39202059836, 8118.147594502492, 8160.224778646019, 8201.015878094668, 58071.12893306519, 58630.0, 59102.70448647729, 59936.400635994825, 61693.38306617056, 65228.67385663231, 81669.77495337694, 83547.3290534198, 84706.15293846982, 87946.68079978933, 10599.364038157735, 11807.0, 11913.631415473941, 12475.75048499043, 12545.372731363577, 13494.0, 13851.0, 14261.286935652968, 14786.926065370035, 15057.924710376377, 13335.399907004403, 13360.465581377024, 13656.863431365957, 13843.307834608431, 13949.860501394856, 10768.676939691823, 12561.24632522015, 12726.68183295425, 13237.139585926883, 13684.65788355299, 12710.745785084431, 12778.808317875426, 12919.418626162033, 13034.47862085506, 13358.599242022754, 9170.358074934691, 10619.681409557727, 10862.891371086504, 11170.944145279222, 11613.070954323464, 11148.721281968015, 11266.66200014001, 11373.43132843338, 11559.36423496682, 11590.188686791844], "value": 7170.624033804976, "unit": "score"}], "name": "dromaeo_css", "value": 7178.34033794374, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 114.0, 114.0, 111.0, 112.0, 113.0, 112.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 111.0, 143.0, 106.0, 112.0, 114.0, 110.0, 158.0, 141.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 183.0, 181.0, 182.0, 181.0, 183.0, 183.0, 185.0, 183.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 57.0, 89.0, 63.0, 58.0, 61.0, 64.0, 110.0, 81.0], "value": 72.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 93.0, 69.0, 70.0, 86.0, 127.0, 99.0, 73.0, 92.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 85.0, 86.0, 82.0, 87.0, 84.0, 86.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 95.0, 97.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 114.0, 79.0, 82.0, 83.0, 82.0, 83.0, 85.0, 81.0, 83.0], "value": 86.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 53.0, 52.0, 55.0, 58.0, 59.0, 58.0, 57.0, 57.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 83.0, 75.0, 72.0, 71.0, 69.0, 71.0, 70.0, 71.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 82.0, 96.0, 102.0, 101.0, 82.0, 83.0, 81.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 138.0, 145.0, 148.0, 142.0, 138.0, 150.0, 224.0, 136.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 163.0, 164.0, 161.0, 164.0, 160.0, 162.0, 161.0, 161.0, 166.0], "value": 163.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 65.0, 67.0, 67.0, 67.0, 67.0, 65.0, 65.0, 65.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1441.0, "alertThreshold": 2.0}]} 14:22:22 INFO - Return code: 0 14:22:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:22:22 INFO - # TBPL SUCCESS # 14:22:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:22:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:22:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:22:22 INFO - 2017-01-05 14:22:22.354 screenresolution[1652:10270] starting screenresolution argv=screenresolution get 14:22:22 INFO - 2017-01-05 14:22:22.357 screenresolution[1652:10270] Display 0: 1600x1200x32@60 14:22:22 INFO - 2017-01-05 14:22:22.363 screenresolution[1653:10273] starting screenresolution argv=screenresolution list 14:22:22 INFO - Available Modes on Display 0 14:22:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:22:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:22:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:22:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:22:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:22:22 INFO - Intel Iris: 14:22:22 INFO - Chipset Model: Intel Iris 14:22:22 INFO - Type: GPU 14:22:22 INFO - Bus: Built-In 14:22:22 INFO - VRAM (Dynamic, Max): 1536 MB 14:22:22 INFO - Vendor: Intel (0x8086) 14:22:22 INFO - Device ID: 0x0a2e 14:22:22 INFO - Revision ID: 0x0009 14:22:22 INFO - Displays: 14:22:22 INFO - Display: 14:22:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:22:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:22:22 INFO - Display Serial Number: 1600x1200 60 14:22:22 INFO - Main Display: Yes 14:22:22 INFO - Mirror: Off 14:22:22 INFO - Online: Yes 14:22:22 INFO - Rotation: Supported 14:22:22 INFO - Return code: 0 14:22:22 INFO - Running post-action listener: _resource_record_post_action 14:22:22 INFO - [mozharness: 2017-01-05 22:22:22.609835Z] Finished run-tests step (success) 14:22:22 INFO - Running post-run listener: _resource_record_post_run 14:22:22 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31673344; Write bytes: 654449664; Read time: 196; Write time: 1864 14:22:22 INFO - TinderboxPrint: CPU usage
53.5% 14:22:22 INFO - TinderboxPrint: I/O read bytes / time
31,673,344 / 196 14:22:22 INFO - TinderboxPrint: I/O write bytes / time
654,449,664 / 1,864 14:22:22 INFO - TinderboxPrint: CPU idle
2,253.3 (46.5%) 14:22:22 INFO - TinderboxPrint: CPU system
970.7 (20.0%) 14:22:22 INFO - TinderboxPrint: CPU user
1,621.8 (33.5%) 14:22:22 INFO - TinderboxPrint: Swap in / out
376,512,512 / 0 14:22:22 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344699392; Write bytes: 345155584; Read time: 26392; Write time: 693 14:22:22 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 29703168; Write bytes: 302416896; Read time: 178; Write time: 1143 14:22:23 INFO - Running post-run listener: _upload_blobber_files 14:22:23 INFO - Blob upload gear active. 14:22:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:22:23 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 14:22:23 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'] 14:22:23 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 14:22:23 INFO - (blobuploader) - INFO - Open directory for files ... 14:22:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:22:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:22:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:22:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:22:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:22:24 INFO - (blobuploader) - INFO - Done attempting. 14:22:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:22:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:22:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:22:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:22:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:22:25 INFO - (blobuploader) - INFO - Done attempting. 14:22:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:22:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:22:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:22:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:22:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:22:27 INFO - (blobuploader) - INFO - Done attempting. 14:22:27 INFO - (blobuploader) - INFO - Iteration through files over. 14:22:27 INFO - Return code: 0 14:22:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:22:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:22:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/408977399c52f548ce503088da02ef778918137cf922b3a64924a1d156a336f4d0bf9ed081e7e32b54313a8292cef17b7947435878d24b9c14cf9bfd87a1bc43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/692faa796b110794fdf0cf5e7ef23054330fcdd48ff56cbaa6c5d87f9b81189c40d2da4abd9c269aa50d69938f7a2461bc9b62df5b1303bcf440dc3f0fd431f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a23ea48089e9dd8f0c9c1225e39e50ea64ec15f7ff1175c961cbeb2f4045ec28d4c2e6848bba0ca85e801fa95ebfac48e34cb57cb94893fb1ca755893af325"} 14:22:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:22:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:22:27 INFO - Contents: 14:22:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/408977399c52f548ce503088da02ef778918137cf922b3a64924a1d156a336f4d0bf9ed081e7e32b54313a8292cef17b7947435878d24b9c14cf9bfd87a1bc43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/692faa796b110794fdf0cf5e7ef23054330fcdd48ff56cbaa6c5d87f9b81189c40d2da4abd9c269aa50d69938f7a2461bc9b62df5b1303bcf440dc3f0fd431f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a23ea48089e9dd8f0c9c1225e39e50ea64ec15f7ff1175c961cbeb2f4045ec28d4c2e6848bba0ca85e801fa95ebfac48e34cb57cb94893fb1ca755893af325"} 14:22:27 INFO - Running post-run listener: copy_logs_to_upload_dir 14:22:27 INFO - Copying logs to upload dir... 14:22:27 INFO - mkdir: /builds/slave/test/build/upload/logs 14:22:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.650464 ========= master_lag: 1.52 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-05 14:22:29.503037) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 14:22:29.506588) ========= 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.P2J9AUInD8/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.tOGuv5gzPX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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/408977399c52f548ce503088da02ef778918137cf922b3a64924a1d156a336f4d0bf9ed081e7e32b54313a8292cef17b7947435878d24b9c14cf9bfd87a1bc43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/692faa796b110794fdf0cf5e7ef23054330fcdd48ff56cbaa6c5d87f9b81189c40d2da4abd9c269aa50d69938f7a2461bc9b62df5b1303bcf440dc3f0fd431f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a23ea48089e9dd8f0c9c1225e39e50ea64ec15f7ff1175c961cbeb2f4045ec28d4c2e6848bba0ca85e801fa95ebfac48e34cb57cb94893fb1ca755893af325"} build_url:https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011081 build_url: 'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/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/408977399c52f548ce503088da02ef778918137cf922b3a64924a1d156a336f4d0bf9ed081e7e32b54313a8292cef17b7947435878d24b9c14cf9bfd87a1bc43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/692faa796b110794fdf0cf5e7ef23054330fcdd48ff56cbaa6c5d87f9b81189c40d2da4abd9c269aa50d69938f7a2461bc9b62df5b1303bcf440dc3f0fd431f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a23ea48089e9dd8f0c9c1225e39e50ea64ec15f7ff1175c961cbeb2f4045ec28d4c2e6848bba0ca85e801fa95ebfac48e34cb57cb94893fb1ca755893af325"}' ========= master_lag: 0.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 14:22:29.587601) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:22:29.587996) ========= 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.P2J9AUInD8/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.tOGuv5gzPX/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.005092 ========= master_lag: 0.34 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:22:29.932297) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 14:22:29.932602) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 14:22:29.938706) ========= ========= Total master_lag: 2.01 =========