builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0232 starttime: 1484260906.48 results: success (0) buildid: 20170112123541 builduid: dea6d35996314dbb9632b89c473d34c8 revision: 98ecf719163d1c0cf19359186ab1cac6f34bb621 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:46.477138) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:46.477578) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:46.477864) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:46.504123) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:46.504504) ========= 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.dD6DsdP90j/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.1K7QUUUcnK/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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-12 14:41:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2017-01-12 14:41:46 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147973 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:46.676640) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:46.677043) ========= 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.dD6DsdP90j/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.1K7QUUUcnK/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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.025137 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:46.725756) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 14:41:46.726128) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 98ecf719163d1c0cf19359186ab1cac6f34bb621 --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 98ecf719163d1c0cf19359186ab1cac6f34bb621 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dD6DsdP90j/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.1K7QUUUcnK/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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-12 14:41:46,825 truncating revision to first 12 chars 2017-01-12 14:41:46,825 Setting DEBUG logging. 2017-01-12 14:41:46,825 attempt 1/10 2017-01-12 14:41:46,826 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/98ecf719163d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 14:41:47,948 unpacking tar archive at: mozilla-aurora-98ecf719163d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.376017 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 14:41:48.125994) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:48.126408) ========= script_repo_revision: 98ecf719163d1c0cf19359186ab1cac6f34bb621 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:48.126854) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:48.127190) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 14:41:48.147281) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-12 14:41:48.147599) ========= /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.dD6DsdP90j/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.1K7QUUUcnK/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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:41:48 INFO - MultiFileLogger online at 20170112 14:41:48 in /builds/slave/test 14:41:48 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:41:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:41:48 INFO - {'append_to_log': False, 14:41:48 INFO - 'base_work_dir': '/builds/slave/test', 14:41:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:41:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:41:48 INFO - 'branch': 'Mozilla-Aurora', 14:41:48 INFO - 'buildbot_json_path': 'buildprops.json', 14:41:48 INFO - 'config_files': ('talos/mac_config.py',), 14:41:48 INFO - 'default_actions': ('clobber', 14:41:48 INFO - 'read-buildbot-config', 14:41:48 INFO - 'download-and-extract', 14:41:48 INFO - 'populate-webroot', 14:41:48 INFO - 'create-virtualenv', 14:41:48 INFO - 'install', 14:41:48 INFO - 'run-tests'), 14:41:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:41:48 INFO - 'download_minidump_stackwalk': True, 14:41:48 INFO - 'download_symbols': 'ondemand', 14:41:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:41:48 INFO - 'tooltool.py': '/tools/tooltool.py', 14:41:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:41:48 INFO - '/tools/misc-python/virtualenv.py')}, 14:41:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:41:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:41:48 INFO - 'installer_path': 'installer.exe', 14:41:48 INFO - 'log_level': 'info', 14:41:48 INFO - 'log_name': 'talos', 14:41:48 INFO - 'log_to_console': True, 14:41:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:41:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:41:48 INFO - 'opt_config_files': (), 14:41:48 INFO - 'pip_index': False, 14:41:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:41:48 INFO - 'cmd': ('bash', 14:41:48 INFO - '-c', 14:41:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:41:48 INFO - 'enabled': True, 14:41:48 INFO - 'halt_on_failure': False, 14:41:48 INFO - 'name': 'check_screen_resolution'},), 14:41:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:41:48 INFO - 'cmd': ('bash', 14:41:48 INFO - '-c', 14:41:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:41:48 INFO - 'enabled': True, 14:41:48 INFO - 'halt_on_failure': False, 14:41:48 INFO - 'name': 'check_screen_resolution'},), 14:41:48 INFO - 'run_cmd_checks_enabled': True, 14:41:48 INFO - 'sps_profile': False, 14:41:48 INFO - 'sps_profile_interval': 0, 14:41:48 INFO - 'suite': 'dromaeojs', 14:41:48 INFO - 'system_bits': '32', 14:41:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:41:48 INFO - 'title': 't-yosemite-r7-0232', 14:41:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:41:48 INFO - 'use_talos_json': True, 14:41:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:41:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:41:48 INFO - 'work_dir': 'build'} 14:41:48 INFO - [mozharness: 2017-01-12 22:41:48.354993Z] Running clobber step. 14:41:48 INFO - Running pre-action listener: _resource_record_pre_action 14:41:48 INFO - Running main action method: clobber 14:41:48 INFO - rmtree: /builds/slave/test/build 14:41:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:41:50 INFO - Running post-action listener: _resource_record_post_action 14:41:50 INFO - [mozharness: 2017-01-12 22:41:50.731641Z] Finished clobber step (success) 14:41:50 INFO - [mozharness: 2017-01-12 22:41:50.731782Z] Running read-buildbot-config step. 14:41:50 INFO - Running pre-action listener: _resource_record_pre_action 14:41:50 INFO - Running main action method: read_buildbot_config 14:41:50 INFO - Using buildbot properties: 14:41:50 INFO - { 14:41:50 INFO - "project": "", 14:41:50 INFO - "product": "firefox", 14:41:50 INFO - "script_repo_revision": "production", 14:41:50 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:41:50 INFO - "repository": "", 14:41:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:41:50 INFO - "buildid": "20170112123541", 14:41:50 INFO - "pgo_build": "False", 14:41:50 INFO - "basedir": "/builds/slave/test", 14:41:50 INFO - "buildnumber": 71, 14:41:50 INFO - "slavename": "t-yosemite-r7-0232", 14:41:50 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 14:41:50 INFO - "platform": "yosemite_r7", 14:41:50 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:41:50 INFO - "branch": "mozilla-aurora", 14:41:50 INFO - "repo_path": "releases/mozilla-aurora", 14:41:50 INFO - "revision": "98ecf719163d1c0cf19359186ab1cac6f34bb621", 14:41:50 INFO - "stage_platform": "macosx64", 14:41:50 INFO - "builduid": "dea6d35996314dbb9632b89c473d34c8", 14:41:50 INFO - "slavebuilddir": "test" 14:41:50 INFO - } 14:41:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 14:41:50 INFO - Running post-action listener: _resource_record_post_action 14:41:50 INFO - [mozharness: 2017-01-12 22:41:50.734967Z] Finished read-buildbot-config step (success) 14:41:50 INFO - [mozharness: 2017-01-12 22:41:50.735101Z] Running download-and-extract step. 14:41:50 INFO - Running pre-action listener: _resource_record_pre_action 14:41:50 INFO - Running main action method: download_and_extract 14:41:50 INFO - mkdir: /builds/slave/test/build/tests 14:41: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:41:50 INFO - trying https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 14:41:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/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:41:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/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:41:51 INFO - Downloaded 1199 bytes. 14:41:51 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:41:51 INFO - Using the following test package requirements: 14:41:51 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:41:51 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:41:51 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 14:41:51 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:41:51 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 14:41:51 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:41:51 INFO - u'jsshell-mac.zip'], 14:41:51 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:41:51 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 14:41:51 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:41:51 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:41:51 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 14:41:51 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:41:51 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 14:41:51 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:41:51 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 14:41:51 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:41:51 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 14:41:51 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:41:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:41:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:41:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:41:52 INFO - Http code: 200 14:41:52 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 14:41:52 INFO - x-amz-cf-id: OdMkrQhfF7YLz6HiwYZBOYO68mGhHQaJKz0frxdP-ssS6VfaGbFmeQ== 14:41:52 INFO - x-amz-version-id: VVMPkHZhgxzjd8VwONySscbIZCBL1gLa 14:41:52 INFO - Expected file size: 16275723 14:41:52 INFO - Obtained file size: 16275723 14:41:59 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:41:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:41:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:41:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:41:59 INFO - Http code: 200 14:41:59 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 14:41:59 INFO - x-amz-cf-id: t1FYjPvBbgUkdd_FTam3nru2Wk0kJ4XU2X_y7YF452dM4n2eqp-1zg== 14:41:59 INFO - x-amz-version-id: VVMPkHZhgxzjd8VwONySscbIZCBL1gLa 14:42:00 INFO - Expected file size: 16275723 14:42:00 INFO - Obtained file size: 16275723 14:42:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 14:42:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:42:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 14:42:07 INFO - Http code: 200 14:42:07 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 14:42:07 INFO - x-amz-cf-id: HqmQrll3MENHN2qxkRojlnuj22bRCW-m7ls-loQtTtp7Q85Yub_nCg== 14:42:07 INFO - x-amz-version-id: NPOrcPXeayTB9g9pWVc7madc20k7uAoL 14:42:07 INFO - Expected file size: 14409038 14:42:07 INFO - Obtained file size: 14409038 14:42:07 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:42:07 INFO - trying https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:42:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 14:42:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/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:42:10 INFO - Downloaded 109997009 bytes. 14:42:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:42:10 INFO - mkdir: /builds/slave/test/properties 14:42:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:42:10 INFO - Writing to file /builds/slave/test/properties/build_url 14:42:10 INFO - Contents: 14:42:10 INFO - build_url:https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:42:11 INFO - Running post-action listener: _resource_record_post_action 14:42:11 INFO - Running post-action listener: set_extra_try_arguments 14:42:11 INFO - [mozharness: 2017-01-12 22:42:11.511318Z] Finished download-and-extract step (success) 14:42:11 INFO - [mozharness: 2017-01-12 22:42:11.511461Z] Running populate-webroot step. 14:42:11 INFO - Running pre-action listener: _resource_record_pre_action 14:42:11 INFO - Running main action method: populate_webroot 14:42:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:42:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:42:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:42:11 INFO - '--tppagecycles', 14:42:11 INFO - '7'], 14:42:11 INFO - 'tests': ['tsvgm']}}, 14:42:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:42:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:42:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:42:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:42:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:42:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:42:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:42:11 INFO - 'talos_options': ['--disable-e10s'], 14:42:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:42:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:42:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:42:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:42:11 INFO - 'talos_options': ['--disable-e10s'], 14:42:11 INFO - 'tests': ['damp', 'tps']}, 14:42:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:42:11 INFO - 'tests': ['damp', 'tps']}, 14:42:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:42:11 INFO - 'tests': ['dromaeo_dom']}, 14:42:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:42:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:42:11 INFO - 'tests': ['basic_compositor_video']}, 14:42:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:42:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:42:11 INFO - 'tests': ['a11yr', 14:42:11 INFO - 'ts_paint', 14:42:11 INFO - 'tpaint', 14:42:11 INFO - 'sessionrestore', 14:42:11 INFO - 'sessionrestore_no_auto_restore', 14:42:11 INFO - 'tabpaint']}, 14:42:11 INFO - 'other-e10s': {'tests': ['a11yr', 14:42:11 INFO - 'ts_paint', 14:42:11 INFO - 'tpaint', 14:42:11 INFO - 'sessionrestore', 14:42:11 INFO - 'sessionrestore_no_auto_restore', 14:42:11 INFO - 'tabpaint']}, 14:42:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:42:11 INFO - 'ts_paint', 14:42:11 INFO - 'tpaint', 14:42:11 INFO - 'sessionrestore', 14:42:11 INFO - 'sessionrestore_no_auto_restore', 14:42:11 INFO - 'tabpaint']}, 14:42:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:42:11 INFO - 'ts_paint', 14:42:11 INFO - 'tpaint', 14:42:11 INFO - 'sessionrestore', 14:42:11 INFO - 'sessionrestore_no_auto_restore', 14:42:11 INFO - 'tabpaint']}, 14:42:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:42:11 INFO - 'tests': ['a11yr', 14:42:11 INFO - 'ts_paint', 14:42:11 INFO - 'tpaint', 14:42:11 INFO - 'sessionrestore', 14:42:11 INFO - 'sessionrestore_no_auto_restore', 14:42:11 INFO - 'tabpaint']}, 14:42:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:42:11 INFO - 'tests': ['a11yr', 14:42:11 INFO - 'ts_paint', 14:42:11 INFO - 'tpaint', 14:42:11 INFO - 'sessionrestore', 14:42:11 INFO - 'sessionrestore_no_auto_restore', 14:42:11 INFO - 'tabpaint']}, 14:42:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:42:11 INFO - 'tests': ['tsvgx', 14:42:11 INFO - 'tsvgr_opacity', 14:42:11 INFO - 'tart', 14:42:11 INFO - 'tscrollx', 14:42:11 INFO - 'cart']}, 14:42:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:42:11 INFO - 'tsvgr_opacity', 14:42:11 INFO - 'tart', 14:42:11 INFO - 'tscrollx', 14:42:11 INFO - 'cart']}, 14:42:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:42:11 INFO - 'talos_options': ['--disable-e10s'], 14:42:11 INFO - 'tests': ['tp5o']}, 14:42:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:42:11 INFO - 'tests': ['tp5o']}, 14:42:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:42:11 INFO - 'talos_options': ['--disable-e10s', 14:42:11 INFO - '--xperf_path', 14:42:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:42:11 INFO - 'tests': ['tp5n']}, 14:42:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:42:11 INFO - 'talos_options': ['--xperf_path', 14:42:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:42:11 INFO - 'tests': ['tp5n']}}, 14:42:11 INFO - 'talos.zip': {'path': '', 14:42:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:42:11 INFO - Running post-action listener: _resource_record_post_action 14:42:11 INFO - [mozharness: 2017-01-12 22:42:11.530472Z] Finished populate-webroot step (success) 14:42:11 INFO - [mozharness: 2017-01-12 22:42:11.530586Z] Running create-virtualenv step. 14:42:11 INFO - Running pre-action listener: _resource_record_pre_action 14:42:11 INFO - Running main action method: create_virtualenv 14:42:11 INFO - Creating virtualenv /builds/slave/test/build/venv 14:42:11 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:42:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:42:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:42:11 INFO - Using real prefix '/tools/python27' 14:42:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:42:12 INFO - Installing distribute.............................................................................................................................................................................................done. 14:42:15 INFO - Installing pip.................done. 14:42:15 INFO - Return code: 0 14:42:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:42:15 INFO - Reading from file tmpfile_stdout 14:42:15 INFO - Output received: 14:42:15 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:42:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:42:15 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:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:15 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:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:15 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 0x10476d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2a4d67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104581d30>, '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 0x1043e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104579b20>, '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.dD6DsdP90j/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.1K7QUUUcnK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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:42:15 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:42:15 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:42:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dD6DsdP90j/Render', 14:42:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:15 INFO - 'HOME': '/Users/cltbld', 14:42:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:15 INFO - 'LOGNAME': 'cltbld', 14:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:15 INFO - 'MOZ_NO_REMOTE': '1', 14:42:15 INFO - 'NO_EM_RESTART': '1', 14:42:15 INFO - 'PAGER': '/bin/cat', 14:42:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:15 INFO - 'PWD': '/builds/slave/test', 14:42:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:15 INFO - 'SHELL': '/bin/bash', 14:42:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1K7QUUUcnK/Listeners', 14:42:15 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 14:42:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:15 INFO - 'USER': 'cltbld', 14:42:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:15 INFO - 'XPC_FLAGS': '0x0', 14:42:15 INFO - 'XPC_SERVICE_NAME': '0', 14:42:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:15 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:42:15 INFO - Cleaning up... 14:42:15 INFO - Return code: 0 14:42:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:42:15 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:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:15 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:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:15 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 0x10476d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2a4d67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104581d30>, '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 0x1043e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104579b20>, '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.dD6DsdP90j/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.1K7QUUUcnK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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:42:15 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:42:15 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:42:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dD6DsdP90j/Render', 14:42:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:15 INFO - 'HOME': '/Users/cltbld', 14:42:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:15 INFO - 'LOGNAME': 'cltbld', 14:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:15 INFO - 'MOZ_NO_REMOTE': '1', 14:42:15 INFO - 'NO_EM_RESTART': '1', 14:42:15 INFO - 'PAGER': '/bin/cat', 14:42:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:15 INFO - 'PWD': '/builds/slave/test', 14:42:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:15 INFO - 'SHELL': '/bin/bash', 14:42:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1K7QUUUcnK/Listeners', 14:42:15 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 14:42:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:15 INFO - 'USER': 'cltbld', 14:42:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:15 INFO - 'XPC_FLAGS': '0x0', 14:42:15 INFO - 'XPC_SERVICE_NAME': '0', 14:42:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:15 INFO - Downloading/unpacking psutil>=3.1.1 14:42:15 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:42:16 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:42:16 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:42:16 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:42:16 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:42:16 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:42:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:42:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:42:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:42:19 INFO - Installing collected packages: psutil 14:42:19 INFO - Running setup.py install for psutil 14:42:19 INFO - building 'psutil._psutil_osx' extension 14:42:19 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:42:20 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:42:20 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:42:20 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:42:20 INFO - building 'psutil._psutil_posix' extension 14:42:20 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:42:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:42:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:42:20 INFO - ^ 14:42:20 INFO - 1 warning generated. 14:42:20 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:42:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:42:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:42:20 INFO - Successfully installed psutil 14:42:20 INFO - Cleaning up... 14:42:20 INFO - Return code: 0 14:42:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:20 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 0x10476d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2a4d67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104581d30>, '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 0x1043e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104579b20>, '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.dD6DsdP90j/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.1K7QUUUcnK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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:42:20 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:42:20 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:42:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dD6DsdP90j/Render', 14:42:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:20 INFO - 'HOME': '/Users/cltbld', 14:42:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:20 INFO - 'LOGNAME': 'cltbld', 14:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:20 INFO - 'MOZ_NO_REMOTE': '1', 14:42:20 INFO - 'NO_EM_RESTART': '1', 14:42:20 INFO - 'PAGER': '/bin/cat', 14:42:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:20 INFO - 'PWD': '/builds/slave/test', 14:42:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:20 INFO - 'SHELL': '/bin/bash', 14:42:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1K7QUUUcnK/Listeners', 14:42:20 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 14:42:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:20 INFO - 'USER': 'cltbld', 14:42:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:20 INFO - 'XPC_FLAGS': '0x0', 14:42:20 INFO - 'XPC_SERVICE_NAME': '0', 14:42:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:42:20 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:42:20 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:42:20 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:42:20 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:42:20 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:42:20 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:42:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:42:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:42:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:42:23 INFO - Installing collected packages: mozsystemmonitor 14:42:23 INFO - Running setup.py install for mozsystemmonitor 14:42:23 INFO - Successfully installed mozsystemmonitor 14:42:23 INFO - Cleaning up... 14:42:23 INFO - Return code: 0 14:42:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:42:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:23 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 0x10476d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2a4d67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104581d30>, '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 0x1043e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104579b20>, '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.dD6DsdP90j/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.1K7QUUUcnK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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:42:23 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:42:23 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:42:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dD6DsdP90j/Render', 14:42:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:23 INFO - 'HOME': '/Users/cltbld', 14:42:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:23 INFO - 'LOGNAME': 'cltbld', 14:42:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:23 INFO - 'MOZ_NO_REMOTE': '1', 14:42:23 INFO - 'NO_EM_RESTART': '1', 14:42:23 INFO - 'PAGER': '/bin/cat', 14:42:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:23 INFO - 'PWD': '/builds/slave/test', 14:42:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:23 INFO - 'SHELL': '/bin/bash', 14:42:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1K7QUUUcnK/Listeners', 14:42:23 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 14:42:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:23 INFO - 'USER': 'cltbld', 14:42:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:23 INFO - 'XPC_FLAGS': '0x0', 14:42:23 INFO - 'XPC_SERVICE_NAME': '0', 14:42:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:24 INFO - Downloading/unpacking jsonschema==2.5.1 14:42:24 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:42:24 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:42:24 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:42:24 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:42:24 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:42:24 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:42:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:42:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:42:26 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:42:26 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:42:26 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:42:26 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:42:26 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:42:26 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:42:26 INFO - Downloading functools32-3.2.3-2.tar.gz 14:42:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:42:27 INFO - warning: no files found matching '*.txt' 14:42:27 INFO - no previously-included directories found matching 'build' 14:42:27 INFO - no previously-included directories found matching 'dist' 14:42:27 INFO - no previously-included directories found matching '.git*' 14:42:27 INFO - Installing collected packages: jsonschema, functools32 14:42:27 INFO - Running setup.py install for functools32 14:42:27 INFO - warning: no files found matching '*.txt' 14:42:27 INFO - no previously-included directories found matching 'build' 14:42:27 INFO - no previously-included directories found matching 'dist' 14:42:27 INFO - no previously-included directories found matching '.git*' 14:42:27 INFO - Successfully installed jsonschema functools32 14:42:27 INFO - Cleaning up... 14:42:27 INFO - Return code: 0 14:42:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:42:27 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:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:27 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:42:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:27 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 0x10476d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2a4d67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104581d30>, '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 0x1043e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104579b20>, '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.dD6DsdP90j/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.1K7QUUUcnK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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:42:27 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:42:27 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:42:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dD6DsdP90j/Render', 14:42:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:27 INFO - 'HOME': '/Users/cltbld', 14:42:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:27 INFO - 'LOGNAME': 'cltbld', 14:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:27 INFO - 'MOZ_NO_REMOTE': '1', 14:42:27 INFO - 'NO_EM_RESTART': '1', 14:42:27 INFO - 'PAGER': '/bin/cat', 14:42:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:27 INFO - 'PWD': '/builds/slave/test', 14:42:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:27 INFO - 'SHELL': '/bin/bash', 14:42:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1K7QUUUcnK/Listeners', 14:42:27 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 14:42:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:27 INFO - 'USER': 'cltbld', 14:42:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:27 INFO - 'XPC_FLAGS': '0x0', 14:42:27 INFO - 'XPC_SERVICE_NAME': '0', 14:42:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:42:27 INFO - Cleaning up... 14:42:27 INFO - Return code: 0 14:42:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:42:27 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:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:27 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:42:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:27 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 0x10476d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2a4d67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104581d30>, '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 0x1043e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104579b20>, '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.dD6DsdP90j/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.1K7QUUUcnK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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:42:27 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:42:27 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:42:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dD6DsdP90j/Render', 14:42:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:27 INFO - 'HOME': '/Users/cltbld', 14:42:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:27 INFO - 'LOGNAME': 'cltbld', 14:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:27 INFO - 'MOZ_NO_REMOTE': '1', 14:42:27 INFO - 'NO_EM_RESTART': '1', 14:42:27 INFO - 'PAGER': '/bin/cat', 14:42:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:27 INFO - 'PWD': '/builds/slave/test', 14:42:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:27 INFO - 'SHELL': '/bin/bash', 14:42:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1K7QUUUcnK/Listeners', 14:42:27 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 14:42:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:27 INFO - 'USER': 'cltbld', 14:42:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:27 INFO - 'XPC_FLAGS': '0x0', 14:42:27 INFO - 'XPC_SERVICE_NAME': '0', 14:42:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:27 INFO - Downloading/unpacking blobuploader==1.2.4 14:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:30 INFO - Downloading blobuploader-1.2.4.tar.gz 14:42:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:42:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:42:30 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:42:30 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:42:30 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:42:30 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:42:30 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:42:30 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:42:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:42:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:31 INFO - Downloading docopt-0.6.1.tar.gz 14:42:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:42:31 INFO - Installing collected packages: blobuploader, requests, docopt 14:42:31 INFO - Running setup.py install for blobuploader 14:42:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:42:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:42:31 INFO - Running setup.py install for requests 14:42:32 INFO - Running setup.py install for docopt 14:42:32 INFO - Successfully installed blobuploader requests docopt 14:42:32 INFO - Cleaning up... 14:42:32 INFO - Return code: 0 14:42:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:42:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:32 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 0x10476d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2a4d67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104581d30>, '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 0x1043e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104579b20>, '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.dD6DsdP90j/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.1K7QUUUcnK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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:42:32 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:42:32 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:42:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dD6DsdP90j/Render', 14:42:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:32 INFO - 'HOME': '/Users/cltbld', 14:42:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:32 INFO - 'LOGNAME': 'cltbld', 14:42:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:32 INFO - 'MOZ_NO_REMOTE': '1', 14:42:32 INFO - 'NO_EM_RESTART': '1', 14:42:32 INFO - 'PAGER': '/bin/cat', 14:42:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:32 INFO - 'PWD': '/builds/slave/test', 14:42:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:32 INFO - 'SHELL': '/bin/bash', 14:42:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1K7QUUUcnK/Listeners', 14:42:32 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 14:42:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:32 INFO - 'USER': 'cltbld', 14:42:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:32 INFO - 'XPC_FLAGS': '0x0', 14:42:32 INFO - 'XPC_SERVICE_NAME': '0', 14:42:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:42:32 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-ZbV34n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:42:33 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-01LDWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:42:33 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-8eXzAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:42:33 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-wvryDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:42:33 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-DgoFzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:42:33 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-oFTyw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:42:33 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-IOljZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:42:33 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-w8Sjzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:42:33 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-HR357r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:42:33 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-vdIcIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:42:33 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-J5Ozk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:42:34 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-LLGVP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:42:34 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-0uQSWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:42:34 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-tpZFjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:42:34 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-QnY5vB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:42:34 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-zA2L4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:42:34 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-VRxQtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:42:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:42:34 INFO - Running setup.py install for manifestparser 14:42:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:42:34 INFO - Running setup.py install for mozcrash 14:42:35 INFO - Running setup.py install for mozdebug 14:42:35 INFO - Running setup.py install for mozdevice 14:42:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:42:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:42:35 INFO - Running setup.py install for mozfile 14:42:35 INFO - Running setup.py install for mozhttpd 14:42:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:42:35 INFO - Running setup.py install for mozinfo 14:42:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:42:35 INFO - Running setup.py install for mozInstall 14:42:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:42:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:42:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:42:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:42:35 INFO - Running setup.py install for mozleak 14:42:36 INFO - Running setup.py install for mozlog 14:42:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:42:36 INFO - Running setup.py install for moznetwork 14:42:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:42:36 INFO - Running setup.py install for mozprocess 14:42:36 INFO - Running setup.py install for mozprofile 14:42:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:42:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:42:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:42:36 INFO - Running setup.py install for mozrunner 14:42:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:42:36 INFO - Running setup.py install for mozscreenshot 14:42:36 INFO - Running setup.py install for moztest 14:42:37 INFO - Running setup.py install for mozversion 14:42:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:42:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:42:37 INFO - Cleaning up... 14:42:37 INFO - Return code: 0 14:42:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:37 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 0x10476d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2a4d67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104581d30>, '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 0x1043e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104579b20>, '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.dD6DsdP90j/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.1K7QUUUcnK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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:42:37 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:42:37 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:42:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dD6DsdP90j/Render', 14:42:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:37 INFO - 'HOME': '/Users/cltbld', 14:42:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:37 INFO - 'LOGNAME': 'cltbld', 14:42:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:37 INFO - 'MOZ_NO_REMOTE': '1', 14:42:37 INFO - 'NO_EM_RESTART': '1', 14:42:37 INFO - 'PAGER': '/bin/cat', 14:42:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:37 INFO - 'PWD': '/builds/slave/test', 14:42:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:37 INFO - 'SHELL': '/bin/bash', 14:42:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1K7QUUUcnK/Listeners', 14:42:37 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 14:42:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:37 INFO - 'USER': 'cltbld', 14:42:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:37 INFO - 'XPC_FLAGS': '0x0', 14:42:37 INFO - 'XPC_SERVICE_NAME': '0', 14:42:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:42:37 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-vmDOcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:42:37 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:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:42:37 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-Mp9HIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:42:37 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:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:42:37 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-zmBG5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:42:37 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:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:42:37 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-XtytWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:42:38 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-h_JkP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:42:38 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-xGaOj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:42:38 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-nnpiG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:42:38 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-mmpAtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:42:38 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-kGv4dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:42:38 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-XH5Okw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:42:38 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-FWoOso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:42:38 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-VPJDfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:42:38 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-mm98Xs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:42:38 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:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:42:38 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-vXY07_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:42:39 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-Mg3PqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:42:39 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-izAHkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:42:39 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:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:42:39 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-mzDRrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:42:39 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:42:39 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:42:39 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:42:39 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:42:39 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:42:39 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:42:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:41 INFO - Downloading blessings-1.6.tar.gz 14:42:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:42:42 INFO - Installing collected packages: blessings 14:42:42 INFO - Running setup.py install for blessings 14:42:42 INFO - Successfully installed blessings 14:42:42 INFO - Cleaning up... 14:42:42 INFO - Return code: 0 14:42:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:42:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:42:42 INFO - Reading from file tmpfile_stdout 14:42:42 INFO - Current package versions: 14:42:42 INFO - blessings == 1.6 14:42:42 INFO - blobuploader == 1.2.4 14:42:42 INFO - docopt == 0.6.1 14:42:42 INFO - functools32 == 3.2.3-2 14:42:42 INFO - jsonschema == 2.5.1 14:42:42 INFO - manifestparser == 1.1 14:42:42 INFO - mozInstall == 1.12 14:42:42 INFO - mozcrash == 1.0 14:42:42 INFO - mozdebug == 0.1 14:42:42 INFO - mozdevice == 0.48 14:42:42 INFO - mozfile == 1.2 14:42:42 INFO - mozhttpd == 0.7 14:42:42 INFO - mozinfo == 0.9 14:42:42 INFO - mozleak == 0.1 14:42:42 INFO - mozlog == 3.4 14:42:42 INFO - moznetwork == 0.27 14:42:42 INFO - mozprocess == 0.23 14:42:42 INFO - mozprofile == 0.28 14:42:42 INFO - mozrunner == 6.13 14:42:42 INFO - mozscreenshot == 0.1 14:42:42 INFO - mozsystemmonitor == 0.3 14:42:42 INFO - moztest == 0.8 14:42:42 INFO - mozversion == 1.4 14:42:42 INFO - psutil == 3.1.1 14:42:42 INFO - requests == 1.2.3 14:42:42 INFO - wsgiref == 0.1.2 14:42:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:42: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:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:43 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 0x10476d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2a4d67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104581d30>, '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 0x1043e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104579b20>, '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.dD6DsdP90j/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.1K7QUUUcnK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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:42:43 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:42:43 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:42:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dD6DsdP90j/Render', 14:42:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:43 INFO - 'HOME': '/Users/cltbld', 14:42:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:43 INFO - 'LOGNAME': 'cltbld', 14:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:43 INFO - 'MOZ_NO_REMOTE': '1', 14:42:43 INFO - 'NO_EM_RESTART': '1', 14:42:43 INFO - 'PAGER': '/bin/cat', 14:42:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:43 INFO - 'PWD': '/builds/slave/test', 14:42:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:43 INFO - 'SHELL': '/bin/bash', 14:42:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1K7QUUUcnK/Listeners', 14:42:43 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 14:42:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:43 INFO - 'USER': 'cltbld', 14:42:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:43 INFO - 'XPC_FLAGS': '0x0', 14:42:43 INFO - 'XPC_SERVICE_NAME': '0', 14:42:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:42:44 INFO - Cleaning up... 14:42:44 INFO - Return code: 0 14:42:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10476d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2a4d67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104581d30>, '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 0x1043e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104579b20>, '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.dD6DsdP90j/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.1K7QUUUcnK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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:42:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:42:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:42:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dD6DsdP90j/Render', 14:42:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:44 INFO - 'HOME': '/Users/cltbld', 14:42:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:44 INFO - 'LOGNAME': 'cltbld', 14:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:44 INFO - 'MOZ_NO_REMOTE': '1', 14:42:44 INFO - 'NO_EM_RESTART': '1', 14:42:44 INFO - 'PAGER': '/bin/cat', 14:42:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:44 INFO - 'PWD': '/builds/slave/test', 14:42:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:44 INFO - 'SHELL': '/bin/bash', 14:42:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1K7QUUUcnK/Listeners', 14:42:44 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 14:42:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:44 INFO - 'USER': 'cltbld', 14:42:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:44 INFO - 'XPC_FLAGS': '0x0', 14:42:44 INFO - 'XPC_SERVICE_NAME': '0', 14:42:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:42:44 INFO - Cleaning up... 14:42:44 INFO - Return code: 0 14:42:44 INFO - Running post-action listener: _resource_record_post_action 14:42:44 INFO - Running post-action listener: _start_resource_monitoring 14:42:44 INFO - Starting resource monitoring. 14:42:44 INFO - [mozharness: 2017-01-12 22:42:44.777998Z] Finished create-virtualenv step (success) 14:42:44 INFO - [mozharness: 2017-01-12 22:42:44.779018Z] Running install step. 14:42:44 INFO - Running pre-action listener: _resource_record_pre_action 14:42:44 INFO - Running main action method: install 14:42:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:42:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:42:45 INFO - Reading from file tmpfile_stdout 14:42:45 INFO - Detecting whether we're running mozinstall >=1.0... 14:42:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:42:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:42:45 INFO - Reading from file tmpfile_stdout 14:42:45 INFO - Output received: 14:42:45 INFO - Usage: mozinstall [options] installer 14:42:45 INFO - Options: 14:42:45 INFO - -h, --help show this help message and exit 14:42:45 INFO - -d DEST, --destination=DEST 14:42:45 INFO - Directory to install application into. [default: 14:42:45 INFO - "/builds/slave/test"] 14:42:45 INFO - --app=APP Application being installed. [default: firefox] 14:42:45 INFO - mkdir: /builds/slave/test/build/application 14:42:45 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:42:45 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:43:17 INFO - Reading from file tmpfile_stdout 14:43:17 INFO - Output received: 14:43:17 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:43:17 INFO - Running post-action listener: _resource_record_post_action 14:43:17 INFO - [mozharness: 2017-01-12 22:43:17.230214Z] Finished install step (success) 14:43:17 INFO - [mozharness: 2017-01-12 22:43:17.230358Z] Running run-tests step. 14:43:17 INFO - Running pre-action listener: _resource_record_pre_action 14:43:17 INFO - Running main action method: run_tests 14:43:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:43:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:43:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:43:17 INFO - 2017-01-12 14:43:17.245 screenresolution[1623:7455] starting screenresolution argv=screenresolution get 14:43:17 INFO - 2017-01-12 14:43:17.247 screenresolution[1623:7455] Display 0: 1600x1200x32@60 14:43:17 INFO - 2017-01-12 14:43:17.253 screenresolution[1624:7457] starting screenresolution argv=screenresolution list 14:43:17 INFO - Available Modes on Display 0 14:43:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:43:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:43:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:43:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:43:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:43:17 INFO - Intel Iris: 14:43:17 INFO - Chipset Model: Intel Iris 14:43:17 INFO - Type: GPU 14:43:17 INFO - Bus: Built-In 14:43:17 INFO - VRAM (Dynamic, Max): 1536 MB 14:43:17 INFO - Vendor: Intel (0x8086) 14:43:17 INFO - Device ID: 0x0a2e 14:43:17 INFO - Revision ID: 0x0009 14:43:17 INFO - Displays: 14:43:17 INFO - Display: 14:43:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:43:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:43:17 INFO - Display Serial Number: 1600x1200 60 14:43:17 INFO - Main Display: Yes 14:43:17 INFO - Mirror: Off 14:43:17 INFO - Online: Yes 14:43:17 INFO - Rotation: Supported 14:43:17 INFO - Return code: 0 14:43:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:43:17 INFO - Python 2.7.3 14:43:17 INFO - Return code: 0 14:43:17 INFO - grabbing minidump binary from tooltool 14:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:17 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 0x104581d30>, '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 0x1043e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104579b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:43:17 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:43:17 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:43:17 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:43:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:43:17 INFO - Return code: 0 14:43:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:43:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:43:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:43:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:43:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:43:17 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/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0232', '--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:43:17 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/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0232 --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:43:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dD6DsdP90j/Render', 14:43:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:43:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:43:17 INFO - 'HOME': '/Users/cltbld', 14:43:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:43:17 INFO - 'LOGNAME': 'cltbld', 14:43:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:43:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:17 INFO - 'MOZ_NO_REMOTE': '1', 14:43:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:43:17 INFO - 'NO_EM_RESTART': '1', 14:43:17 INFO - 'PAGER': '/bin/cat', 14:43:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:17 INFO - 'PWD': '/builds/slave/test', 14:43:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:43:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:43:17 INFO - 'SHELL': '/bin/bash', 14:43:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1K7QUUUcnK/Listeners', 14:43:17 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 14:43:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:43:17 INFO - 'USER': 'cltbld', 14:43:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:43:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:17 INFO - 'XPC_FLAGS': '0x0', 14:43:17 INFO - 'XPC_SERVICE_NAME': '0', 14:43:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:43:17 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/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0232', '--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:43:17 INFO - mozversion application_buildid: 20170112123541 14:43:17 INFO - mozversion application_changeset: 98ecf719163d1c0cf19359186ab1cac6f34bb621 14:43:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:43:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:43:17 INFO - mozversion application_name: Firefox 14:43:17 INFO - mozversion application_remotingname: firefox-dev 14:43:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:43:17 INFO - mozversion application_vendor: Mozilla 14:43:17 INFO - mozversion application_version: 52.0a2 14:43:17 INFO - mozversion platform_buildid: 20170112123541 14:43:17 INFO - mozversion platform_changeset: 98ecf719163d1c0cf19359186ab1cac6f34bb621 14:43:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:43:17 INFO - mozversion platform_version: 52.0a2 14:43:17 INFO - using testdate: 1484260997 14:43:17 INFO - actual date: 1484260997 14:43:17 INFO - starting webserver on 'localhost:49236' 14:43:17 INFO - SUITE-START | Running 2 tests 14:43:17 INFO - TEST-START | dromaeo_css 14:43:17 INFO - operating with platform_type : mac_ 14:43:17 INFO - Initialising browser for dromaeo_css test... 14:43:17 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/tmp48CDaF/profile http://localhost:49236/getInfo.html) 14:43:20 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 14:43:20 INFO - PROCESS | 1634 | colorDepth:24 14:43:20 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 14:43:20 INFO - PROCESS | 1634 | __metrics 14:43:20 INFO - PROCESS | 1634 | 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:43:20 INFO - PROCESS | 1634 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:43:21 INFO - TEST-INFO | 1634: exit 0 14:43:21 INFO - Browser initialized. 14:43:21 INFO - Running cycle 1/1 for dromaeo_css test... 14:43:21 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/tmp48CDaF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:43:33 INFO - PROCESS | 1636 | RSS: Main: 221794304 14:43:33 INFO - PROCESS | 1636 | 14:46:37 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 14:46:39 INFO - PROCESS | 1636 | RSS: Main: 372453376 14:46:39 INFO - PROCESS | 1636 | 14:49:42 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 14:49:42 INFO - PROCESS | 1636 | RSS: Main: 349773824 14:49:42 INFO - PROCESS | 1636 | 14:52:45 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 14:52:45 INFO - PROCESS | 1636 | RSS: Main: 326373376 14:52:45 INFO - PROCESS | 1636 | 14:55:48 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 14:55:48 INFO - PROCESS | 1636 | RSS: Main: 329900032 14:55:48 INFO - PROCESS | 1636 | 14:58:51 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 14:58:52 INFO - PROCESS | 1636 | RSS: Main: 307716096 14:58:52 INFO - PROCESS | 1636 | 15:01:55 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 15:01:55 INFO - PROCESS | 1636 | RSS: Main: 434663424 15:01:55 INFO - PROCESS | 1636 | 15:01:55 INFO - PROCESS | 1636 | __start_tp_report 15:01:55 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 15:01:55 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 15:01:55 INFO - PROCESS | 1636 | |i|pagename|runs| 15:01:55 INFO - PROCESS | 1636 | |0;dojo.html;3294.390537750516;3312.271300313931;3321.3357328534285;3338.1988322802536;3364.865405383785;5561.91657125143;5590.413006634296;5637.267155269814;5651.491365777079;5705.144228365747;5622.072358060926;5681.375048744635;5682.971585142069;5705.25831641886;5726.31284245891;5120.411152717429;5138.383393992726;5162.535371816536;5167.844964651066;5200.193969934648;5083.822066227683;5098.184290513518;5113.948860511385;5241.659292146017;5282.128448805943;5113.437521872591;5156.767945442445;5164.896702065957;5170.560502357304;5171.922421163686;5153.66501177426;5201.349831271091;5226.843194704127;5227.712475813829;5270.894582108336;5038.992201559666;5077.949220507785;5167.121589329823;5170.767315470813;5171.663841850305;5539.002979463695;5563.415841336682;5572.331320241557;5581.162825576155;5623.437656234385;6089.238845144357;6106.542009349317;6128.601640893372;6152.3847615238565;6177.69111544421;6242.157308763304;6254.0618907163835;6258.812235632939;6267.373262673742;6271.372862713738;5576.776928922879;5633.690147041952;5643.887122257532;5728.656280623176;5760.798372056958;6266.310705822356;6293.024581189923;6315.684215789192;6321.146645202931;6339.651319177397;6009.128676341906;6047.546434017467;6057.030875059969;6061.999770037894;6067.54493412987;5891.086881533369;5960.165576819249;5965.522758179335;6075.2405949256345;6132.969335153385;6058.727357268934;6080.0575910733905;6080.483158931452;6106.114613381035;6106.900757863538;6021.789237376671;6031.758729650853;6090.20827292458;6100.54245931548;6101.694915254219;4699.224627936353;4807.4471435784635;4809.422869255712;4820.71075735457;4834.734089625034;3697.8705745298785;4319.654427645781;4344.109457561192;4357.629601483909;4425.690201685851;5712.285964254468;5713.600047996683;5714.628549144375;5720.8283751487525;5727.570432217517;6002.949483840311;6196.504279657616;6199.318075011745;6217.658028808459;6227.968860155761;5548.361938377057;5608;5661.886762264731;5670.773169073191;5672.971635141798;5277.337638643815;5308.840734777963;5314.92027619586;5317.946820531745;5346.4118946915805;6298.7480500780375;6303.9684801575695;6313.589616674902;6320.399562041599;6321.873562528632;4784.593309568587;5989.1615173874825;6049.395060494091;6096.542759292982;6191.84520386994;6209.316975132822;6288.151099601495;6289.308176100717;6289.622622642656;6296.370362963667;6066.3630318815885;6072.696365181811;6089.756409743561;6107.358727333566;6116.143739876332;6023.608465449732;6094.603850749687;6150.384961503813;6151.138840562415;6164.780678955662;5069.797208111634;5622.409646987007;5840;5912.556558258062;5918.556108291809;5970.253718285215;5972.8805423892145;5985.551083668829;5992.43071908168;6017.849553761191;5699.344575373885;5812.883742325045;5828.708564571839;5831.475167234969;5842.912356314574;5814.680192589448;5863.882722345615;5911.822645320647;5920.911186332122;5961.046232602763;5808.912866306924;5826.737796799239;5830.921279563295;5831.212786273968;5858.179854820414 15:01:55 INFO - PROCESS | 1636 | |1;ext.html;500.3070747015383;511.00864323212966;513.0892665518705;523.5837509180201;526.5234962359065;29920.850395748017;31629.683703162962;31683.049508514723;31751.841240793794;31865.203369915766;32546.511802322984;34618;41593.37609935846;42986.785066074626;44446;9345.299102567318;9455.959844417108;9461.006594307602;9461.053894610519;9468.573914173845;9428.434293942386;9523.142917137457;9530.475823829687;9531.428114313128;9544.902336231318;8428.410011299211;8646.610902509403;9595.23238140947;11489.942550287236;11617.593384231554;11301.49143288554;11377.089832813355;11380.886191138065;11390.430478476042;11402.372869492174;7067.611281379522;11126.61056863014;11190.888091119066;11209.775804483865;11220.719482012933;30809.691903080908;39281.60718392808;39842.80078599617;39851.605193818075;40581.797091014654;3213.5340375645633;3267.4118658641437;3309.2885029718727;3402.5576675032335;3805.7335986480957;28734.856325718447;29242.70757292421;30185.849070754724;31092.37815243705;31118.844405777825;3070.7850450468477;3195.680431956786;3226;3233.9489665858455;3251.252211991255;499813;502847.48576256883;504074;504282;520908;51491.227631585556;52632.73683631634;60033.69983150056;62222.377776221656;62934;62053;64176;64831.67584162049;64871.35128648747;65020;13266.53567125146;15374.461893833663;15833.683326333408;15901.920490397473;15961.521154365155;12040.036797056215;12160.574379896838;12168.391580420943;12173.75652486963;12178.512859485521;16175.676486470205;16544.6691066178;18681.15934782938;18713.53216169607;18821.15304811287;6632.436242919406;6640.335966403418;7680.423968202407;7725.1459649683675;7728.420368472387;851.4550687559966;938.010399029031;1007.8085163818997;1014.5333146752577;1055.2516655984934;29383.853080734458;29581.964631237803;29608.963686270876;29804;30293.69706302953;927.679950417105;948.8329354893519;993.9453330066916;1058.4866339825178;1122.3602546548389;12606.180598261084;14592.343344549521;14947.626309342137;15136.167510787012;15386.692266154838;658040.4195958074;662670;662845;663266;663280;49174;49590.75204623954;50650.24024639634;58172.545686357334;58851.11723324154;1351.8512963573999;1361.8706222908809;1375.24361601121;1377.7520046391546;1380.2063813307589;29209.415811683222;29945.401091978474;30483.237919051313;30495.237619059702;31098.22254443657;50790.49209508005;51335;51391.74304128456;51540.742296289776;51566.74216628893;582824;687853;713133;713288;873285;40090;40572.594274058065;40787.59212407838;41102.79448602738;41438.79280603577;43742;44705.77647111744;44762.77618611886;44776.77611611921;45563.54436455593;36442.8177859109;36944.63055369412;37737.62262377341;38766;39059;30512.932047378235;35496;37044.814775925945;37158.44262336121;37339.25321493609;33581;37055.62944370522;37602.43596346112;37659.246815062994;38814;32669.509957351132;34197.80307689219;34761.65238347584;34800.82599586986;34855;21609.243676471247;25362;25763.098291559705;25942.962281508528;26337.07820226283 15:01:55 INFO - PROCESS | 1636 | |2;jquery.html;342.93860501747116;360.97112231021515;367.47451144862833;376.6515972725229;389.5111634898202;14505.854941450596;14567.417303307868;15631.92184039081;19830.90084549575;20289.695654565156;19728.112234949425;20423.978801059926;20500.69248961267;20734.89632551835;20743.377698669025;5708.800191993281;6032.457078862902;6045.697715114226;6054.152418661391;6055.364186760393;4241.915161696764;5386.218998245252;5969.403059694039;6017.247844019497;6027.367126451725;4988.226824842155;5957.642541447505;5979.402059794008;5984.102384642317;5985.5810093293485;5624.3250809902665;5826.708664566776;5884.588078834483;5887.264091988502;5888.676122813244;4936.777844996948;5133.820316288912;5160;5160.6129540284255;5162.380514338266;24900.50198996028;25044.624330635055;25184.8666809994;25218.495630087295;25267.62098568523;2261.3894248552965;2493.850368977867;2711.0375816585024;2721.727827217282;2736.220177249494;7943.4836735612;13935.930320348434;15031.924840375837;15405.306761195772;15698.136602486858;1772.4948389709;1782.295993082725;1817.9272829086822;1852.397970659541;1864.254298280685;106934.93065069328;115829.42085289604;125299.37350313281;127862.36068819596;133677.33161334132;8802.339824513187;9431.528423578793;11239.494222759995;11746.765064698659;11873.28760274369;14882.330295136744;14894.03188792725;14907.70184596302;14924.70150596982;14947.775783363259;8210.343172546302;8474.025487069066;8482.957585212158;8592.5703714814;8603.440776349516;6739.359760822714;7227.638618069075;8102.594870256581;8300.916990830136;8450.957745211357;4490.483594386622;4499.460064792246;4501.257292546693;4512.210363186429;4568.314752787042;1739.8086210516833;1770.6281680847167;1823.9270429182684;1848.7504186934862;1890.905454727258;850.9872351914727;869.3827382558454;974.878140232471;990.6978371596588;1005.9446730429751;20601.89699051495;21233.15067397287;21245;21339.039743211593;21425.357239282846;855.5979485546827;938.6057855700623;947.3368641950663;953;958.496789185683;10474.842877356845;10502.947485262524;11066.336019838835;11163.888361116286;11214.327140371604;603469;640435;642704;650215;655779;114283;124173;127119;128193.35903320424;129085.06372404422;901.9729408117767;947.6114792934864;965.652365148546;994.6021591363368;998.7652901568125;16193.75709364337;18930.33743819015;19533.902330488258;19546.218151273788;19560.706589400885;47768;51990.48009520008;52647.73676131595;54631;55677.164842528204;75656.62171689107;76604.61697691475;77083.74995000636;79251;80076.59961700154;8042.3968202383885;8136.023677158779;8193.631286592237;8306.833863322821;8337.916620833714;12252;12760.74478510443;12952.510461297072;13094.934525327313;13148.211107333591;10963.725906852393;11027.503762330874;11098.944505277423;11116.722081947693;11230.66308010761;9124.49815260167;10756.46217689128;10841.837372439577;10852.782944340912;11271.380074096003;10344.431056291976;11075;11229.382383968634;11372.772544549229;11420.771584568429;7582.582957937146;7920.5247685139075;8001.3598912088055;8213.79465513367;8224.835503289782;8205.630746616298;9858.605655773976;10011.199104071511;10533.10468610146;10939.398333091756 15:01:55 INFO - PROCESS | 1636 | |3;mootools.html;1461.9561413157605;1488.3153749275332;1646.8929519581227;1773.2109211400932;1828.4331857124284;7991.200879912006;8341.62462689179;8346.74959751207;8351.791205219872;8362.832743345129;6947.478939079564;7406.259374062604;7642.694292228304;7672.501287416314;7974.880376794353;2310.896009679564;2351.8471299365488;2353.8587684738886;2355.882205889707;2358.1864256831363;2338.1348900906687;2366.136360228513;2375.2161786610377;2379.0008196557487;2381.833271670975;2311.3297143828268;2315.988420057906;2331.976680233193;2343.3321503371544;2351.6472529120597;2302.4474126209757;2305.020366344297;2325.3837733000682;2327.49958758866;2328.5924963131383;2331.463763334443;2350.083467447697;2353.0940587873793;2355.729091154522;2361.515889242701;8305;8316.833663326699;8413.032501262373;8458.78853028673;8465.153484651548;4990.65065445419;5159.613029022801;5161;5162.74186290684;5163.3545806774155;3247.626522949867;3342.2145795737983;3577.159367548624;3584.910377240564;3588.9641103588892;940.8776859008285;1052.6157952347414;1075.8166017380818;1079.0773888325448;1092.6558134187785;10935.56257749689;11059.391733454657;11069.889301106967;11078.224524283303;11084.722881927935;4478.216312145361;4608.239640459354;4699.741514216751;4720.929186062212;4725.716457012591;9369.297302702325;9373.390729602554;9382.671606493877;9396.483193424427;9402.529873506297;5170.405403378584;5174.43081261061;5177.948220517822;5180.8963820723375;5188.92216616751;4147.564505726916;4463.66522510813;4687.7656117194;4748.928766068511;4752.691075080109;3350.8324583770714;3665.2303016366327;3747.569029561581;3818.4654148419245;3853.3834586466032;1234.6974991127051;1243.6828608704723;1244.6888277930518;1246.1962034487706;1252.6743046807712;764.7782143178528;865.5326123893153;908.5230254116509;909.0591238068567;915.1626261970356;5454.972725136349;5614.297465041469;5656.660600363992;5831.4168583141345;5974.462298393166;899.3434792601438;901.2474583722665;930.5765876526165;957.8371676814958;976.3702411944256;3725.720570957135;3873.4383514390256;3898.8050597470015;3987.501562304712;4007.338789099824;9821.508924553744;10114.443705596115;10239.180865530889;10249.077583017564;10264.024917632962;9920.305578609428;10104.343217690826;10228.335158214692;10228.64199753005;10244.231682623833;7364.410847132217;7723.111842138113;7723.9227607723205;7744.535327880345;7760.68957241704;5228.607854410859;5280.445553216857;5624.212610234652;5727.427257274239;5828.679422631796;9109.590068446803;9153.908460915305;9180.311476639412;9439.811203776024;9508.714738557854;9265.258779297505;9412.764680883032;9471.384360016853;9536.618535258789;9538.523073846418;6668.899966500409;6848.178218613599;6855.691493882887;7082.504224704221;7308.524945878501;7494;7581.924180758122;7616.7334143304715;7726.729564465217;7973.8405231896195;7053.858922821618;7204.459665525002;7243.420526357773;7264.67308971087;7333.779986600411;7068;7069.469789765685;7103.396211322096;7128.251533588898;7225.385842203262;6655.866882662222;6688.431483323778;6726.596404215763;6739.966300168468;6744.831379215362;4897.877553061202;4919.7540122993;4927.876803079952;4943.802247910044;4956.207006878882;4836.177849765619;4837.2260438329695;4857.489963553918;5001.8499445016705;5035.949640503548 15:01:55 INFO - PROCESS | 1636 | |4;prototype.html;293.2346575438106;298.8879170311133;307.4788233943466;311.4098782806582;312.0280326782072;5002.624803139765;5091.338126043614;5103.413107492639;5127.820526281576;5151.716655583941;4198.8530401435955;4749.335093086962;5019.899602007958;5025.798968041274;5026.246063090529;3461.809600471973;3661.359262129117;3857.209272099227;3884.1162036412316;3890.396988466785;3232.6120865496173;3859.8842034739005;3908.863189788358;3911.7457365271307;3953.7627742335553;3552.4546822132456;3729.9067523311865;3794.5067141271734;3847.634474724897;3893.299206142893;3440.4323286657673;3862.208247309295;3869.6130386961217;3874.612538746131;3881.941770873439;3341.2315167511365;3355.8154301513405;3363.310521343143;3363.863946394033;3364.536426654389;4279.315309550489;4688.530487439304;4958.181899986498;4961.3798275215595;4971;3404.8127352995575;3776.546814382264;3873.133880928121;4201.668074218807;4468.240399132155;4880.707157570521;5263.289455923439;5297.311349524575;5299.973500132513;5308.761105750251;3269;3519.8768043118625;3672.6143754905615;3695.1131728384994;3706.703463722896;4758.881662809237;4806.3511425957395;4839.7096174229655;5209.400918894337;5341.919871201935;3696.353138200804;3700.703943684499;3708.1841994761107;3723.94414083789;3735.645113714247;3198.360327934376;3357.7649564530248;3426.623071462137;3487.779277252941;3776.2825063237897;4005.2990726623307;4335.655946656547;4352.978235108804;4359.38968544404;4401.581849724271;4200.663946884242;5070.518300761421;5267.762950667229;5306.257124002643;5331.946680533145;1078.5870509632407;1119.656412305237;1148.7070796946725;1179.9203728588245;1185.7806305833449;766.9148155360215;793.6983946100561;833.2584000239791;839.8152406470567;840.2102024097329;4069.491313585802;4570.88572785683;4645.745648674839;4652.674312798072;4666.60333871618;4755.476897541268;5138.2806407103035;5139.357580302462;5139.91833307511;5141.871453213699;3868.09322584192;4399.64802815774;4666.883327916829;4674.859754207379;4694.6244300456565;2345.8827058647;2659.205036600326;2677.839329640228;2685.60980727153;2707.4043710383685;5146.845594632167;5263.868403289948;5274.973625131926;5280.471952804689;5284.640171858232;4700.87179077019;4856.39295088114;5209.270702101709;5267.2889159962915;5279.18172683227;5363.597730170253;5488.1340344282335;5499;5534.588679886684;5667.744951477111;5433.592480563894;5471.835844924659;5478.369987451494;5481.945180548303;5483.424240454854;5120.975804838972;5382.354117505768;5395.838124856261;5409.459054094559;5411.242426060433;4773.880652983565;4835.8065677372515;5556.749946252347;5589.55283577321;5639.295088113986;3150.574672419194;3635.963750331249;3722.571904231048;3735.58908520068;3736.7757934524016;4583.335416364675;5164.535191832753;5517.386112096583;5519.834404967857;5562.276904002454;4142.730722503027;4800.35195248651;5306.6550674206055;5356.78572857097;5370.677759334452;5104.41299250591;5266.710330931835;5309.628326017141;5315.681059136464;5327.733613319241;4140.441040459499;4627.028324051987;4890.559849613552;4927.768057985503;5240.816571419982;4505.800881374261;5208.5051920067535;5346.358436987586;5383.973080134574;5395.86510337229;5248.632595718415;5263.6578622389425;5267.130168790024;5287.656302340336;5299.788008479772 15:01:55 INFO - PROCESS | 1636 | |5;yui.html;659.2583343738295;673.8974399872399;690.3786592067142;695.2456584605702;733.8441953922571;15565.455209067684;16201.91899040503;16209.83790162098;16591.087490188034;16643.583910402245;14998.625034374147;15053.322600482978;15056.774148387756;15079.472218472356;15112.773308400356;635.7012204264012;653.4902775834832;812.6221307092216;837.7528629054414;838.0069617503278;637.5154882289447;803.4697099914058;819.1685439279137;834.7454026521908;836.0719601242616;835.3526017336552;876;877.4472082587961;879.0594064351147;880.6565439478609;665.3978149774414;867.5445391169654;869.1438932651333;869.1786261982429;869.9347548933855;509.33023074656705;619.1672200889841;639.402158981354;643.1606753187089;644.0661041489858;14243.64390890236;14267.357968891321;14283;14289.928550357286;14290.785638215435;12578.308193049379;12793.424295906707;12867.420966056456;12874.87125128746;12932.935335323356;12043.757596805035;12196.939015304955;12265.034783821988;12275.324857132855;12327.568535101318;4240.469941257343;4240.533541310453;4320.719153255029;4362.323839804835;4498.887527811831;80107.59946200231;82597.93505162359;84270.57864710699;84591.57704211501;84781.15218847794;9786.95106524477;9966.900330996743;10035.849462258071;10056.044675755791;10289.279750417549;19117.426477205707;19247.13387897548;19280.61438771217;19325.323613673452;20008.59982800365;7742.961285193651;7761.379089672812;7774.067111946602;7870.645820938072;7977.7207797726805;4107.301758701028;4223.5142958559545;4272.188284225987;4377.4528183977;4403.955960440355;2798.944021119566;3473.218525831718;3521.4893840393;3524.3479956208184;3532.576090869061;571.0577547047405;644.1851058411114;648.4099469482842;654.165938428498;665.9167604049494;530.9203619457035;541.8401571536406;543.5977376741183;576.8183022347947;601.9819405417844;8963.686270980485;9028.503432311287;9053.230475409533;9204.493752843659;9245.72262832116;604.685563506974;638.3648269971408;661.3578752882266;668.4518694670323;676.0400231671039;3877.991722152204;4202.957970420318;4535.9773201133785;4606.539346065434;4696.88257793551;114943;118381;120057.39971300088;123129;124731;99570;104106;105277;107180.46409767901;108594;67620.6618966902;92573.61139582911;93461;93676.53161734148;96875;9373.062693730571;9496.335256531976;9560.904390956279;9623.615055397706;9634.566444510154;57208.85582288414;58642;58771.70614146902;59238.70380648069;60433.79132417415;75319.49361012859;78616;80876.19123808686;83779.16220837958;88067.11932880589;12057.397130143634;12157.331346776013;12239.816402754144;12288.1398302118;12451.626451206479;13340.399682014528;13587.52443664467;13987.510437135059;14180.361883715465;14300.856991429953;12169.878301217228;12716.809247861105;12771.489140434278;13477.865221348053;13673.974451916345;12656.303903285409;13039.869601303866;13165.407556660164;13345.199288042748;13818.378172982038;12167.2699638022;12827;12856.228626282453;12964.00045612992;13413.798793018308;10305.53625086888;11179.105671546095;11182.161337899528;11261.268017578832;11318.434078296217;7155.749548765768;8869.802576652328;9831.459269739948;10864.891351086704;10918.344899306067 15:01:55 INFO - PROCESS | 1636 | __end_tp_report 15:01:55 INFO - PROCESS | 1636 | __start_cc_report 15:01:55 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,1740 15:01:55 INFO - PROCESS | 1636 | __end_cc_report 15:01:55 INFO - PROCESS | 1636 | __startTimestamp1484262115707__endTimestamp 15:01:55 INFO - PROCESS | 1636 | 15:01:55 INFO - PROCESS | 1636 | ------- Summary: start ------- 15:01:55 INFO - PROCESS | 1636 | Number of tests: 6 15:01:55 INFO - PROCESS | 1636 | 15:01:55 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5644.52 Median:5826.74 stddev:639.66 (11.0%) stddev-sans-first:614.48 15:01:55 INFO - PROCESS | 1636 | Values: 3294.4 3312.3 3321.3 3338.2 3364.9 5561.9 5590.4 5637.3 5651.5 5705.1 5622.1 5681.4 5683.0 5705.3 5726.3 5120.4 5138.4 5162.5 5167.8 5200.2 5083.8 5098.2 5113.9 5241.7 5282.1 5113.4 5156.8 5164.9 5170.6 5171.9 5153.7 5201.3 5226.8 5227.7 5270.9 5039.0 5077.9 5167.1 5170.8 5171.7 5539.0 5563.4 5572.3 5581.2 5623.4 6089.2 6106.5 6128.6 6152.4 6177.7 6242.2 6254.1 6258.8 6267.4 6271.4 5576.8 5633.7 5643.9 5728.7 5760.8 6266.3 6293.0 6315.7 6321.1 6339.7 6009.1 6047.5 6057.0 6062.0 6067.5 5891.1 5960.2 5965.5 6075.2 6133.0 6058.7 6080.1 6080.5 6106.1 6106.9 6021.8 6031.8 6090.2 6100.5 6101.7 4699.2 4807.4 4809.4 4820.7 4834.7 3697.9 4319.7 4344.1 4357.6 4425.7 5712.3 5713.6 5714.6 5720.8 5727.6 6002.9 6196.5 6199.3 6217.7 6228.0 5548.4 5608.0 5661.9 5670.8 5673.0 5277.3 5308.8 5314.9 5317.9 5346.4 6298.7 6304.0 6313.6 6320.4 6321.9 4784.6 5989.2 6049.4 6096.5 6191.8 6209.3 6288.2 6289.3 6289.6 6296.4 6066.4 6072.7 6089.8 6107.4 6116.1 6023.6 6094.6 6150.4 6151.1 6164.8 5069.8 5622.4 5840.0 5912.6 5918.6 5970.3 5972.9 5985.6 5992.4 6017.8 5699.3 5812.9 5828.7 5831.5 5842.9 5814.7 5863.9 5911.8 5920.9 5961.0 5808.9 5826.7 5830.9 5831.2 5858.2 15:01:55 INFO - PROCESS | 1636 | 15:01:55 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:74750.36 Median:37248.85 stddev:170679.05 (458.2%) stddev-sans-first:171066.80 15:01:55 INFO - PROCESS | 1636 | Values: 500.3 511.0 513.1 523.6 526.5 29920.9 31629.7 31683.0 31751.8 31865.2 32546.5 34618.0 41593.4 42986.8 44446.0 9345.3 9456.0 9461.0 9461.1 9468.6 9428.4 9523.1 9530.5 9531.4 9544.9 8428.4 8646.6 9595.2 11489.9 11617.6 11301.5 11377.1 11380.9 11390.4 11402.4 7067.6 11126.6 11190.9 11209.8 11220.7 30809.7 39281.6 39842.8 39851.6 40581.8 3213.5 3267.4 3309.3 3402.6 3805.7 28734.9 29242.7 30185.8 31092.4 31118.8 3070.8 3195.7 3226.0 3233.9 3251.3 499813.0 502847.5 504074.0 504282.0 520908.0 51491.2 52632.7 60033.7 62222.4 62934.0 62053.0 64176.0 64831.7 64871.4 65020.0 13266.5 15374.5 15833.7 15901.9 15961.5 12040.0 12160.6 12168.4 12173.8 12178.5 16175.7 16544.7 18681.2 18713.5 18821.2 6632.4 6640.3 7680.4 7725.1 7728.4 851.5 938.0 1007.8 1014.5 1055.3 29383.9 29582.0 29609.0 29804.0 30293.7 927.7 948.8 993.9 1058.5 1122.4 12606.2 14592.3 14947.6 15136.2 15386.7 658040.4 662670.0 662845.0 663266.0 663280.0 49174.0 49590.8 50650.2 58172.5 58851.1 1351.9 1361.9 1375.2 1377.8 1380.2 29209.4 29945.4 30483.2 30495.2 31098.2 50790.5 51335.0 51391.7 51540.7 51566.7 582824.0 687853.0 713133.0 713288.0 873285.0 40090.0 40572.6 40787.6 41102.8 41438.8 43742.0 44705.8 44762.8 44776.8 45563.5 36442.8 36944.6 37737.6 38766.0 39059.0 30512.9 35496.0 37044.8 37158.4 37339.3 33581.0 37055.6 37602.4 37659.2 38814.0 32669.5 34197.8 34761.7 34800.8 34855.0 21609.2 25362.0 25763.1 25943.0 26337.1 15:01:55 INFO - PROCESS | 1636 | 15:01:55 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:36050.51 Median:351.95 stddev:106289.51 (30199.8%) stddev-sans-first:106553.86 15:01:55 INFO - PROCESS | 1636 | Values: 342.9 361.0 367.5 376.7 389.5 14505.9 14567.4 15631.9 19830.9 20289.7 19728.1 20424.0 20500.7 20734.9 20743.4 5708.8 6032.5 6045.7 6054.2 6055.4 4241.9 5386.2 5969.4 6017.2 6027.4 4988.2 5957.6 5979.4 5984.1 5985.6 5624.3 5826.7 5884.6 5887.3 5888.7 4936.8 5133.8 5160.0 5160.6 5162.4 24900.5 25044.6 25184.9 25218.5 25267.6 2261.4 2493.9 2711.0 2721.7 2736.2 7943.5 13935.9 15031.9 15405.3 15698.1 1772.5 1782.3 1817.9 1852.4 1864.3 106934.9 115829.4 125299.4 127862.4 133677.3 8802.3 9431.5 11239.5 11746.8 11873.3 14882.3 14894.0 14907.7 14924.7 14947.8 8210.3 8474.0 8483.0 8592.6 8603.4 6739.4 7227.6 8102.6 8300.9 8451.0 4490.5 4499.5 4501.3 4512.2 4568.3 1739.8 1770.6 1823.9 1848.8 1890.9 851.0 869.4 974.9 990.7 1005.9 20601.9 21233.2 21245.0 21339.0 21425.4 855.6 938.6 947.3 953.0 958.5 10474.8 10502.9 11066.3 11163.9 11214.3 603469.0 640435.0 642704.0 650215.0 655779.0 114283.0 124173.0 127119.0 128193.4 129085.1 902.0 947.6 965.7 994.6 998.8 16193.8 18930.3 19533.9 19546.2 19560.7 47768.0 51990.5 52647.7 54631.0 55677.2 75656.6 76604.6 77083.7 79251.0 80076.6 8042.4 8136.0 8193.6 8306.8 8337.9 12252.0 12760.7 12952.5 13094.9 13148.2 10963.7 11027.5 11098.9 11116.7 11230.7 9124.5 10756.5 10841.8 10852.8 11271.4 10344.4 11075.0 11229.4 11372.8 11420.8 7582.6 7920.5 8001.4 8213.8 8224.8 8205.6 9858.6 10011.2 10533.1 10939.4 15:01:55 INFO - PROCESS | 1636 | 15:01:55 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5446.82 Median:5163.05 stddev:2968.84 (57.5%) stddev-sans-first:2962.06 15:01:55 INFO - PROCESS | 1636 | Values: 1462.0 1488.3 1646.9 1773.2 1828.4 7991.2 8341.6 8346.7 8351.8 8362.8 6947.5 7406.3 7642.7 7672.5 7974.9 2310.9 2351.8 2353.9 2355.9 2358.2 2338.1 2366.1 2375.2 2379.0 2381.8 2311.3 2316.0 2332.0 2343.3 2351.6 2302.4 2305.0 2325.4 2327.5 2328.6 2331.5 2350.1 2353.1 2355.7 2361.5 8305.0 8316.8 8413.0 8458.8 8465.2 4990.7 5159.6 5161.0 5162.7 5163.4 3247.6 3342.2 3577.2 3584.9 3589.0 940.9 1052.6 1075.8 1079.1 1092.7 10935.6 11059.4 11069.9 11078.2 11084.7 4478.2 4608.2 4699.7 4720.9 4725.7 9369.3 9373.4 9382.7 9396.5 9402.5 5170.4 5174.4 5177.9 5180.9 5188.9 4147.6 4463.7 4687.8 4748.9 4752.7 3350.8 3665.2 3747.6 3818.5 3853.4 1234.7 1243.7 1244.7 1246.2 1252.7 764.8 865.5 908.5 909.1 915.2 5455.0 5614.3 5656.7 5831.4 5974.5 899.3 901.2 930.6 957.8 976.4 3725.7 3873.4 3898.8 3987.5 4007.3 9821.5 10114.4 10239.2 10249.1 10264.0 9920.3 10104.3 10228.3 10228.6 10244.2 7364.4 7723.1 7723.9 7744.5 7760.7 5228.6 5280.4 5624.2 5727.4 5828.7 9109.6 9153.9 9180.3 9439.8 9508.7 9265.3 9412.8 9471.4 9536.6 9538.5 6668.9 6848.2 6855.7 7082.5 7308.5 7494.0 7581.9 7616.7 7726.7 7973.8 7053.9 7204.5 7243.4 7264.7 7333.8 7068.0 7069.5 7103.4 7128.3 7225.4 6655.9 6688.4 6726.6 6740.0 6744.8 4897.9 4919.8 4927.9 4943.8 4956.2 4836.2 4837.2 4857.5 5001.8 5035.9 15:01:55 INFO - PROCESS | 1636 | 15:01:55 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4235.59 Median:4752.41 stddev:1307.13 (27.5%) stddev-sans-first:1276.86 15:01:55 INFO - PROCESS | 1636 | Values: 293.2 298.9 307.5 311.4 312.0 5002.6 5091.3 5103.4 5127.8 5151.7 4198.9 4749.3 5019.9 5025.8 5026.2 3461.8 3661.4 3857.2 3884.1 3890.4 3232.6 3859.9 3908.9 3911.7 3953.8 3552.5 3729.9 3794.5 3847.6 3893.3 3440.4 3862.2 3869.6 3874.6 3881.9 3341.2 3355.8 3363.3 3363.9 3364.5 4279.3 4688.5 4958.2 4961.4 4971.0 3404.8 3776.5 3873.1 4201.7 4468.2 4880.7 5263.3 5297.3 5300.0 5308.8 3269.0 3519.9 3672.6 3695.1 3706.7 4758.9 4806.4 4839.7 5209.4 5341.9 3696.4 3700.7 3708.2 3723.9 3735.6 3198.4 3357.8 3426.6 3487.8 3776.3 4005.3 4335.7 4353.0 4359.4 4401.6 4200.7 5070.5 5267.8 5306.3 5331.9 1078.6 1119.7 1148.7 1179.9 1185.8 766.9 793.7 833.3 839.8 840.2 4069.5 4570.9 4645.7 4652.7 4666.6 4755.5 5138.3 5139.4 5139.9 5141.9 3868.1 4399.6 4666.9 4674.9 4694.6 2345.9 2659.2 2677.8 2685.6 2707.4 5146.8 5263.9 5275.0 5280.5 5284.6 4700.9 4856.4 5209.3 5267.3 5279.2 5363.6 5488.1 5499.0 5534.6 5667.7 5433.6 5471.8 5478.4 5481.9 5483.4 5121.0 5382.4 5395.8 5409.5 5411.2 4773.9 4835.8 5556.7 5589.6 5639.3 3150.6 3636.0 3722.6 3735.6 3736.8 4583.3 5164.5 5517.4 5519.8 5562.3 4142.7 4800.4 5306.7 5356.8 5370.7 5104.4 5266.7 5309.6 5315.7 5327.7 4140.4 4627.0 4890.6 4927.8 5240.8 4505.8 5208.5 5346.4 5384.0 5395.9 5248.6 5263.7 5267.1 5287.7 5299.8 15:01:55 INFO - PROCESS | 1636 | 15:01:55 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:21456.88 Median:4517.43 stddev:32071.07 (709.9%) stddev-sans-first:32123.02 15:01:55 INFO - PROCESS | 1636 | Values: 659.3 673.9 690.4 695.2 733.8 15565.5 16201.9 16209.8 16591.1 16643.6 14998.6 15053.3 15056.8 15079.5 15112.8 635.7 653.5 812.6 837.8 838.0 637.5 803.5 819.2 834.7 836.1 835.4 876.0 877.4 879.1 880.7 665.4 867.5 869.1 869.2 869.9 509.3 619.2 639.4 643.2 644.1 14243.6 14267.4 14283.0 14289.9 14290.8 12578.3 12793.4 12867.4 12874.9 12932.9 12043.8 12196.9 12265.0 12275.3 12327.6 4240.5 4240.5 4320.7 4362.3 4498.9 80107.6 82597.9 84270.6 84591.6 84781.2 9787.0 9966.9 10035.8 10056.0 10289.3 19117.4 19247.1 19280.6 19325.3 20008.6 7743.0 7761.4 7774.1 7870.6 7977.7 4107.3 4223.5 4272.2 4377.5 4404.0 2798.9 3473.2 3521.5 3524.3 3532.6 571.1 644.2 648.4 654.2 665.9 530.9 541.8 543.6 576.8 602.0 8963.7 9028.5 9053.2 9204.5 9245.7 604.7 638.4 661.4 668.5 676.0 3878.0 4203.0 4536.0 4606.5 4696.9 114943.0 118381.0 120057.4 123129.0 124731.0 99570.0 104106.0 105277.0 107180.5 108594.0 67620.7 92573.6 93461.0 93676.5 96875.0 9373.1 9496.3 9560.9 9623.6 9634.6 57208.9 58642.0 58771.7 59238.7 60433.8 75319.5 78616.0 80876.2 83779.2 88067.1 12057.4 12157.3 12239.8 12288.1 12451.6 13340.4 13587.5 13987.5 14180.4 14300.9 12169.9 12716.8 12771.5 13477.9 13674.0 12656.3 13039.9 13165.4 13345.2 13818.4 12167.3 12827.0 12856.2 12964.0 13413.8 10305.5 11179.1 11182.2 11261.3 11318.4 7155.7 8869.8 9831.5 10864.9 10918.3 15:01:55 INFO - PROCESS | 1636 | -------- Summary: end -------- 15:01:55 INFO - PROCESS | 1636 | 15:01:56 INFO - TEST-INFO | 1636: exit 0 15:01:56 INFO - TEST-OK | dromaeo_css | took 1118537ms 15:01:56 INFO - TEST-START | kraken 15:01:56 INFO - operating with platform_type : mac_ 15:01:56 INFO - Initialising browser for kraken test... 15:01:56 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/tmpbO0JY7/profile http://localhost:49236/getInfo.html) 15:01:59 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 15:01:59 INFO - PROCESS | 1642 | colorDepth:24 15:01:59 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 15:01:59 INFO - PROCESS | 1642 | __metrics 15:01:59 INFO - PROCESS | 1642 | 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" 15:01:59 INFO - PROCESS | 1642 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:02:00 INFO - TEST-INFO | 1642: exit 0 15:02:00 INFO - Browser initialized. 15:02:00 INFO - Running cycle 1/1 for kraken test... 15:02:00 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/tmpbO0JY7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:02:12 INFO - PROCESS | 1643 | RSS: Main: 222216192 15:02:12 INFO - PROCESS | 1643 | 15:02:15 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 15:02:16 INFO - PROCESS | 1643 | RSS: Main: 272150528 15:02:16 INFO - PROCESS | 1643 | 15:02:19 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 15:02:19 INFO - PROCESS | 1643 | RSS: Main: 283222016 15:02:19 INFO - PROCESS | 1643 | 15:02:23 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 15:02:23 INFO - PROCESS | 1643 | RSS: Main: 379633664 15:02:23 INFO - PROCESS | 1643 | 15:02:25 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 15:02:26 INFO - PROCESS | 1643 | RSS: Main: 282771456 15:02:26 INFO - PROCESS | 1643 | 15:02:28 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:02:29 INFO - PROCESS | 1643 | RSS: Main: 283488256 15:02:29 INFO - PROCESS | 1643 | 15:02:31 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 15:02:32 INFO - PROCESS | 1643 | RSS: Main: 325533696 15:02:32 INFO - PROCESS | 1643 | 15:02:35 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 15:02:36 INFO - PROCESS | 1643 | RSS: Main: 325832704 15:02:36 INFO - PROCESS | 1643 | 15:02:38 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 15:02:39 INFO - PROCESS | 1643 | RSS: Main: 323252224 15:02:39 INFO - PROCESS | 1643 | 15:02:41 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:02:41 INFO - PROCESS | 1643 | RSS: Main: 298885120 15:02:41 INFO - PROCESS | 1643 | 15:02:43 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:02:44 INFO - PROCESS | 1643 | RSS: Main: 301596672 15:02:44 INFO - PROCESS | 1643 | 15:02:46 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:02:46 INFO - PROCESS | 1643 | RSS: Main: 303202304 15:02:46 INFO - PROCESS | 1643 | 15:02:49 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:02:50 INFO - PROCESS | 1643 | RSS: Main: 321896448 15:02:50 INFO - PROCESS | 1643 | 15:02:53 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:02:54 INFO - PROCESS | 1643 | RSS: Main: 315469824 15:02:54 INFO - PROCESS | 1643 | 15:02:55 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 15:02:55 INFO - PROCESS | 1643 | RSS: Main: 360026112 15:02:55 INFO - PROCESS | 1643 | 15:02:55 INFO - PROCESS | 1643 | __start_tp_report 15:02:55 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 15:02:55 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 15:02:55 INFO - PROCESS | 1643 | |i|pagename|runs| 15:02:55 INFO - PROCESS | 1643 | |0;ai-astar;116;114;113;114;114;115;162;168;114;112 15:02:55 INFO - PROCESS | 1643 | |1;audio-beat-detection;149;178;134;155;100;133;136;132;155;139 15:02:55 INFO - PROCESS | 1643 | |2;audio-dft;183;183;182;184;190;256;207;180;182;184 15:02:55 INFO - PROCESS | 1643 | |3;audio-fft;80;61;73;114;85;84;83;60;80;69 15:02:55 INFO - PROCESS | 1643 | |4;audio-oscillator;87;73;119;101;98;68;88;67;69;90 15:02:55 INFO - PROCESS | 1643 | |5;imaging-gaussian-blur;87;86;88;85;87;84;87;84;87;83 15:02:55 INFO - PROCESS | 1643 | |6;imaging-darkroom;106;143;93;95;94;94;93;94;93;108 15:02:55 INFO - PROCESS | 1643 | |7;imaging-desaturate;83;81;79;80;83;83;86;85;80;80 15:02:55 INFO - PROCESS | 1643 | |8;json-parse-financial;56;56;54;53;57;57;59;58;56;57 15:02:55 INFO - PROCESS | 1643 | |9;json-stringify-tinderbox;73;74;77;73;78;70;70;72;70;72 15:02:56 INFO - PROCESS | 1643 | |10;stanford-crypto-aes;83;83;82;83;83;83;83;81;82;84 15:02:56 INFO - PROCESS | 1643 | |11;stanford-crypto-ccm;138;139;141;147;133;145;141;145;195;147 15:02:56 INFO - PROCESS | 1643 | |12;stanford-crypto-pbkdf2;169;164;164;166;164;165;164;162;163;176 15:02:56 INFO - PROCESS | 1643 | |13;stanford-crypto-sha256-iterative;66;68;67;67;67;66;67;68;66;65 15:02:56 INFO - PROCESS | 1643 | __end_tp_report 15:02:56 INFO - PROCESS | 1643 | __start_cc_report 15:02:56 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,2032 15:02:56 INFO - PROCESS | 1643 | __end_cc_report 15:02:56 INFO - PROCESS | 1643 | __startTimestamp1484262175993__endTimestamp 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | ------- Summary: start ------- 15:02:56 INFO - PROCESS | 1643 | Number of tests: 14 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#0] ai-astar Cycles:10 Average:124.20 Median:114.50 stddev:21.58 (18.8%) stddev-sans-first:22.68 15:02:56 INFO - PROCESS | 1643 | Values: 116.0 114.0 113.0 114.0 114.0 115.0 162.0 168.0 114.0 112.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:144.00 stddev:20.35 (14.1%) stddev-sans-first:21.39 15:02:56 INFO - PROCESS | 1643 | Values: 149.0 178.0 134.0 155.0 100.0 133.0 136.0 132.0 155.0 139.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#2] audio-dft Cycles:10 Average:193.10 Median:184.00 stddev:23.44 (12.7%) stddev-sans-first:24.58 15:02:56 INFO - PROCESS | 1643 | Values: 183.0 183.0 182.0 184.0 190.0 256.0 207.0 180.0 182.0 184.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#3] audio-fft Cycles:10 Average:78.90 Median:80.00 stddev:15.37 (19.2%) stddev-sans-first:16.29 15:02:56 INFO - PROCESS | 1643 | Values: 80.0 61.0 73.0 114.0 85.0 84.0 83.0 60.0 80.0 69.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:80.00 stddev:17.07 (21.3%) stddev-sans-first:18.10 15:02:56 INFO - PROCESS | 1643 | Values: 87.0 73.0 119.0 101.0 98.0 68.0 88.0 67.0 69.0 90.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.69 (1.9%) stddev-sans-first:1.73 15:02:56 INFO - PROCESS | 1643 | Values: 87.0 86.0 88.0 85.0 87.0 84.0 87.0 84.0 87.0 83.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:93.50 stddev:15.68 (16.8%) stddev-sans-first:16.54 15:02:56 INFO - PROCESS | 1643 | Values: 106.0 143.0 93.0 95.0 94.0 94.0 93.0 94.0 93.0 108.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#7] imaging-desaturate Cycles:10 Average:82.00 Median:83.00 stddev:2.36 (2.8%) stddev-sans-first:2.47 15:02:56 INFO - PROCESS | 1643 | Values: 83.0 81.0 79.0 80.0 83.0 83.0 86.0 85.0 80.0 80.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.77 (3.1%) stddev-sans-first:1.87 15:02:56 INFO - PROCESS | 1643 | Values: 56.0 56.0 54.0 53.0 57.0 57.0 59.0 58.0 56.0 57.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:73.00 stddev:2.81 (3.8%) stddev-sans-first:2.98 15:02:56 INFO - PROCESS | 1643 | Values: 73.0 74.0 77.0 73.0 78.0 70.0 70.0 72.0 70.0 72.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 15:02:56 INFO - PROCESS | 1643 | Values: 83.0 83.0 82.0 83.0 83.0 83.0 83.0 81.0 82.0 84.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:145.00 stddev:17.40 (12.0%) stddev-sans-first:18.14 15:02:56 INFO - PROCESS | 1643 | Values: 138.0 139.0 141.0 147.0 133.0 145.0 141.0 145.0 195.0 147.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.70 Median:164.50 stddev:4.08 (2.5%) stddev-sans-first:4.15 15:02:56 INFO - PROCESS | 1643 | Values: 169.0 164.0 164.0 166.0 164.0 165.0 164.0 162.0 163.0 176.0 15:02:56 INFO - PROCESS | 1643 | 15:02:56 INFO - PROCESS | 1643 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.95 (1.4%) stddev-sans-first:0.97 15:02:56 INFO - PROCESS | 1643 | Values: 66.0 68.0 67.0 67.0 67.0 66.0 67.0 68.0 66.0 65.0 15:02:56 INFO - PROCESS | 1643 | -------- Summary: end -------- 15:02:56 INFO - PROCESS | 1643 | 15:02:57 INFO - TEST-INFO | 1643: exit 0 15:02:57 INFO - TEST-OK | kraken | took 60687ms 15:02:57 INFO - SUITE-END | took 1179s 15:02:57 INFO - Completed test suite (00:19:39) 15:02:57 INFO - javascript benchmark 15:02:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3294.390537750516, 3312.271300313931, 3321.3357328534285, 3338.1988322802536, 3364.865405383785, 5561.91657125143, 5590.413006634296, 5637.267155269814, 5651.491365777079, 5705.144228365747, 5622.072358060926, 5681.375048744635, 5682.971585142069, 5705.25831641886, 5726.31284245891, 5120.411152717429, 5138.383393992726, 5162.535371816536, 5167.844964651066, 5200.193969934648, 5083.822066227683, 5098.184290513518, 5113.948860511385, 5241.659292146017, 5282.128448805943, 5113.437521872591, 5156.767945442445, 5164.896702065957, 5170.560502357304, 5171.922421163686, 5153.66501177426, 5201.349831271091, 5226.843194704127, 5227.712475813829, 5270.894582108336, 5038.992201559666, 5077.949220507785, 5167.121589329823, 5170.767315470813, 5171.663841850305, 5539.002979463695, 5563.415841336682, 5572.331320241557, 5581.162825576155, 5623.437656234385, 6089.238845144357, 6106.542009349317, 6128.601640893372, 6152.3847615238565, 6177.69111544421, 6242.157308763304, 6254.0618907163835, 6258.812235632939, 6267.373262673742, 6271.372862713738, 5576.776928922879, 5633.690147041952, 5643.887122257532, 5728.656280623176, 5760.798372056958, 6266.310705822356, 6293.024581189923, 6315.684215789192, 6321.146645202931, 6339.651319177397, 6009.128676341906, 6047.546434017467, 6057.030875059969, 6061.999770037894, 6067.54493412987, 5891.086881533369, 5960.165576819249, 5965.522758179335, 6075.2405949256345, 6132.969335153385, 6058.727357268934, 6080.0575910733905, 6080.483158931452, 6106.114613381035, 6106.900757863538, 6021.789237376671, 6031.758729650853, 6090.20827292458, 6100.54245931548, 6101.694915254219, 4699.224627936353, 4807.4471435784635, 4809.422869255712, 4820.71075735457, 4834.734089625034, 3697.8705745298785, 4319.654427645781, 4344.109457561192, 4357.629601483909, 4425.690201685851, 5712.285964254468, 5713.600047996683, 5714.628549144375, 5720.8283751487525, 5727.570432217517, 6002.949483840311, 6196.504279657616, 6199.318075011745, 6217.658028808459, 6227.968860155761, 5548.361938377057, 5608.0, 5661.886762264731, 5670.773169073191, 5672.971635141798, 5277.337638643815, 5308.840734777963, 5314.92027619586, 5317.946820531745, 5346.4118946915805, 6298.7480500780375, 6303.9684801575695, 6313.589616674902, 6320.399562041599, 6321.873562528632, 4784.593309568587, 5989.1615173874825, 6049.395060494091, 6096.542759292982, 6191.84520386994, 6209.316975132822, 6288.151099601495, 6289.308176100717, 6289.622622642656, 6296.370362963667, 6066.3630318815885, 6072.696365181811, 6089.756409743561, 6107.358727333566, 6116.143739876332, 6023.608465449732, 6094.603850749687, 6150.384961503813, 6151.138840562415, 6164.780678955662, 5069.797208111634, 5622.409646987007, 5840.0, 5912.556558258062, 5918.556108291809, 5970.253718285215, 5972.8805423892145, 5985.551083668829, 5992.43071908168, 6017.849553761191, 5699.344575373885, 5812.883742325045, 5828.708564571839, 5831.475167234969, 5842.912356314574, 5814.680192589448, 5863.882722345615, 5911.822645320647, 5920.911186332122, 5961.046232602763, 5808.912866306924, 5826.737796799239, 5830.921279563295, 5831.212786273968, 5858.179854820414], "value": 5603.245208101403, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.3070747015383, 511.00864323212966, 513.0892665518705, 523.5837509180201, 526.5234962359065, 29920.850395748017, 31629.683703162962, 31683.049508514723, 31751.841240793794, 31865.203369915766, 32546.511802322984, 34618.0, 41593.37609935846, 42986.785066074626, 44446.0, 9345.299102567318, 9455.959844417108, 9461.006594307602, 9461.053894610519, 9468.573914173845, 9428.434293942386, 9523.142917137457, 9530.475823829687, 9531.428114313128, 9544.902336231318, 8428.410011299211, 8646.610902509403, 9595.23238140947, 11489.942550287236, 11617.593384231554, 11301.49143288554, 11377.089832813355, 11380.886191138065, 11390.430478476042, 11402.372869492174, 7067.611281379522, 11126.61056863014, 11190.888091119066, 11209.775804483865, 11220.719482012933, 30809.691903080908, 39281.60718392808, 39842.80078599617, 39851.605193818075, 40581.797091014654, 3213.5340375645633, 3267.4118658641437, 3309.2885029718727, 3402.5576675032335, 3805.7335986480957, 28734.856325718447, 29242.70757292421, 30185.849070754724, 31092.37815243705, 31118.844405777825, 3070.7850450468477, 3195.680431956786, 3226.0, 3233.9489665858455, 3251.252211991255, 499813.0, 502847.48576256883, 504074.0, 504282.0, 520908.0, 51491.227631585556, 52632.73683631634, 60033.69983150056, 62222.377776221656, 62934.0, 62053.0, 64176.0, 64831.67584162049, 64871.35128648747, 65020.0, 13266.53567125146, 15374.461893833663, 15833.683326333408, 15901.920490397473, 15961.521154365155, 12040.036797056215, 12160.574379896838, 12168.391580420943, 12173.75652486963, 12178.512859485521, 16175.676486470205, 16544.6691066178, 18681.15934782938, 18713.53216169607, 18821.15304811287, 6632.436242919406, 6640.335966403418, 7680.423968202407, 7725.1459649683675, 7728.420368472387, 851.4550687559966, 938.010399029031, 1007.8085163818997, 1014.5333146752577, 1055.2516655984934, 29383.853080734458, 29581.964631237803, 29608.963686270876, 29804.0, 30293.69706302953, 927.679950417105, 948.8329354893519, 993.9453330066916, 1058.4866339825178, 1122.3602546548389, 12606.180598261084, 14592.343344549521, 14947.626309342137, 15136.167510787012, 15386.692266154838, 658040.4195958074, 662670.0, 662845.0, 663266.0, 663280.0, 49174.0, 49590.75204623954, 50650.24024639634, 58172.545686357334, 58851.11723324154, 1351.8512963573999, 1361.8706222908809, 1375.24361601121, 1377.7520046391546, 1380.2063813307589, 29209.415811683222, 29945.401091978474, 30483.237919051313, 30495.237619059702, 31098.22254443657, 50790.49209508005, 51335.0, 51391.74304128456, 51540.742296289776, 51566.74216628893, 582824.0, 687853.0, 713133.0, 713288.0, 873285.0, 40090.0, 40572.594274058065, 40787.59212407838, 41102.79448602738, 41438.79280603577, 43742.0, 44705.77647111744, 44762.77618611886, 44776.77611611921, 45563.54436455593, 36442.8177859109, 36944.63055369412, 37737.62262377341, 38766.0, 39059.0, 30512.932047378235, 35496.0, 37044.814775925945, 37158.44262336121, 37339.25321493609, 33581.0, 37055.62944370522, 37602.43596346112, 37659.246815062994, 38814.0, 32669.509957351132, 34197.80307689219, 34761.65238347584, 34800.82599586986, 34855.0, 21609.243676471247, 25362.0, 25763.098291559705, 25942.962281508528, 26337.07820226283], "value": 19981.174389075615, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.93860501747116, 360.97112231021515, 367.47451144862833, 376.6515972725229, 389.5111634898202, 14505.854941450596, 14567.417303307868, 15631.92184039081, 19830.90084549575, 20289.695654565156, 19728.112234949425, 20423.978801059926, 20500.69248961267, 20734.89632551835, 20743.377698669025, 5708.800191993281, 6032.457078862902, 6045.697715114226, 6054.152418661391, 6055.364186760393, 4241.915161696764, 5386.218998245252, 5969.403059694039, 6017.247844019497, 6027.367126451725, 4988.226824842155, 5957.642541447505, 5979.402059794008, 5984.102384642317, 5985.5810093293485, 5624.3250809902665, 5826.708664566776, 5884.588078834483, 5887.264091988502, 5888.676122813244, 4936.777844996948, 5133.820316288912, 5160.0, 5160.6129540284255, 5162.380514338266, 24900.50198996028, 25044.624330635055, 25184.8666809994, 25218.495630087295, 25267.62098568523, 2261.3894248552965, 2493.850368977867, 2711.0375816585024, 2721.727827217282, 2736.220177249494, 7943.4836735612, 13935.930320348434, 15031.924840375837, 15405.306761195772, 15698.136602486858, 1772.4948389709, 1782.295993082725, 1817.9272829086822, 1852.397970659541, 1864.254298280685, 106934.93065069328, 115829.42085289604, 125299.37350313281, 127862.36068819596, 133677.33161334132, 8802.339824513187, 9431.528423578793, 11239.494222759995, 11746.765064698659, 11873.28760274369, 14882.330295136744, 14894.03188792725, 14907.70184596302, 14924.70150596982, 14947.775783363259, 8210.343172546302, 8474.025487069066, 8482.957585212158, 8592.5703714814, 8603.440776349516, 6739.359760822714, 7227.638618069075, 8102.594870256581, 8300.916990830136, 8450.957745211357, 4490.483594386622, 4499.460064792246, 4501.257292546693, 4512.210363186429, 4568.314752787042, 1739.8086210516833, 1770.6281680847167, 1823.9270429182684, 1848.7504186934862, 1890.905454727258, 850.9872351914727, 869.3827382558454, 974.878140232471, 990.6978371596588, 1005.9446730429751, 20601.89699051495, 21233.15067397287, 21245.0, 21339.039743211593, 21425.357239282846, 855.5979485546827, 938.6057855700623, 947.3368641950663, 953.0, 958.496789185683, 10474.842877356845, 10502.947485262524, 11066.336019838835, 11163.888361116286, 11214.327140371604, 603469.0, 640435.0, 642704.0, 650215.0, 655779.0, 114283.0, 124173.0, 127119.0, 128193.35903320424, 129085.06372404422, 901.9729408117767, 947.6114792934864, 965.652365148546, 994.6021591363368, 998.7652901568125, 16193.75709364337, 18930.33743819015, 19533.902330488258, 19546.218151273788, 19560.706589400885, 47768.0, 51990.48009520008, 52647.73676131595, 54631.0, 55677.164842528204, 75656.62171689107, 76604.61697691475, 77083.74995000636, 79251.0, 80076.59961700154, 8042.3968202383885, 8136.023677158779, 8193.631286592237, 8306.833863322821, 8337.916620833714, 12252.0, 12760.74478510443, 12952.510461297072, 13094.934525327313, 13148.211107333591, 10963.725906852393, 11027.503762330874, 11098.944505277423, 11116.722081947693, 11230.66308010761, 9124.49815260167, 10756.46217689128, 10841.837372439577, 10852.782944340912, 11271.380074096003, 10344.431056291976, 11075.0, 11229.382383968634, 11372.772544549229, 11420.771584568429, 7582.582957937146, 7920.5247685139075, 8001.3598912088055, 8213.79465513367, 8224.835503289782, 8205.630746616298, 9858.605655773976, 10011.199104071511, 10533.10468610146, 10939.398333091756], "value": 9496.155900429829, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1461.9561413157605, 1488.3153749275332, 1646.8929519581227, 1773.2109211400932, 1828.4331857124284, 7991.200879912006, 8341.62462689179, 8346.74959751207, 8351.791205219872, 8362.832743345129, 6947.478939079564, 7406.259374062604, 7642.694292228304, 7672.501287416314, 7974.880376794353, 2310.896009679564, 2351.8471299365488, 2353.8587684738886, 2355.882205889707, 2358.1864256831363, 2338.1348900906687, 2366.136360228513, 2375.2161786610377, 2379.0008196557487, 2381.833271670975, 2311.3297143828268, 2315.988420057906, 2331.976680233193, 2343.3321503371544, 2351.6472529120597, 2302.4474126209757, 2305.020366344297, 2325.3837733000682, 2327.49958758866, 2328.5924963131383, 2331.463763334443, 2350.083467447697, 2353.0940587873793, 2355.729091154522, 2361.515889242701, 8305.0, 8316.833663326699, 8413.032501262373, 8458.78853028673, 8465.153484651548, 4990.65065445419, 5159.613029022801, 5161.0, 5162.74186290684, 5163.3545806774155, 3247.626522949867, 3342.2145795737983, 3577.159367548624, 3584.910377240564, 3588.9641103588892, 940.8776859008285, 1052.6157952347414, 1075.8166017380818, 1079.0773888325448, 1092.6558134187785, 10935.56257749689, 11059.391733454657, 11069.889301106967, 11078.224524283303, 11084.722881927935, 4478.216312145361, 4608.239640459354, 4699.741514216751, 4720.929186062212, 4725.716457012591, 9369.297302702325, 9373.390729602554, 9382.671606493877, 9396.483193424427, 9402.529873506297, 5170.405403378584, 5174.43081261061, 5177.948220517822, 5180.8963820723375, 5188.92216616751, 4147.564505726916, 4463.66522510813, 4687.7656117194, 4748.928766068511, 4752.691075080109, 3350.8324583770714, 3665.2303016366327, 3747.569029561581, 3818.4654148419245, 3853.3834586466032, 1234.6974991127051, 1243.6828608704723, 1244.6888277930518, 1246.1962034487706, 1252.6743046807712, 764.7782143178528, 865.5326123893153, 908.5230254116509, 909.0591238068567, 915.1626261970356, 5454.972725136349, 5614.297465041469, 5656.660600363992, 5831.4168583141345, 5974.462298393166, 899.3434792601438, 901.2474583722665, 930.5765876526165, 957.8371676814958, 976.3702411944256, 3725.720570957135, 3873.4383514390256, 3898.8050597470015, 3987.501562304712, 4007.338789099824, 9821.508924553744, 10114.443705596115, 10239.180865530889, 10249.077583017564, 10264.024917632962, 9920.305578609428, 10104.343217690826, 10228.335158214692, 10228.64199753005, 10244.231682623833, 7364.410847132217, 7723.111842138113, 7723.9227607723205, 7744.535327880345, 7760.68957241704, 5228.607854410859, 5280.445553216857, 5624.212610234652, 5727.427257274239, 5828.679422631796, 9109.590068446803, 9153.908460915305, 9180.311476639412, 9439.811203776024, 9508.714738557854, 9265.258779297505, 9412.764680883032, 9471.384360016853, 9536.618535258789, 9538.523073846418, 6668.899966500409, 6848.178218613599, 6855.691493882887, 7082.504224704221, 7308.524945878501, 7494.0, 7581.924180758122, 7616.7334143304715, 7726.729564465217, 7973.8405231896195, 7053.858922821618, 7204.459665525002, 7243.420526357773, 7264.67308971087, 7333.779986600411, 7068.0, 7069.469789765685, 7103.396211322096, 7128.251533588898, 7225.385842203262, 6655.866882662222, 6688.431483323778, 6726.596404215763, 6739.966300168468, 6744.831379215362, 4897.877553061202, 4919.7540122993, 4927.876803079952, 4943.802247910044, 4956.207006878882, 4836.177849765619, 4837.2260438329695, 4857.489963553918, 5001.8499445016705, 5035.949640503548], "value": 4427.311281998961, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.2346575438106, 298.8879170311133, 307.4788233943466, 311.4098782806582, 312.0280326782072, 5002.624803139765, 5091.338126043614, 5103.413107492639, 5127.820526281576, 5151.716655583941, 4198.8530401435955, 4749.335093086962, 5019.899602007958, 5025.798968041274, 5026.246063090529, 3461.809600471973, 3661.359262129117, 3857.209272099227, 3884.1162036412316, 3890.396988466785, 3232.6120865496173, 3859.8842034739005, 3908.863189788358, 3911.7457365271307, 3953.7627742335553, 3552.4546822132456, 3729.9067523311865, 3794.5067141271734, 3847.634474724897, 3893.299206142893, 3440.4323286657673, 3862.208247309295, 3869.6130386961217, 3874.612538746131, 3881.941770873439, 3341.2315167511365, 3355.8154301513405, 3363.310521343143, 3363.863946394033, 3364.536426654389, 4279.315309550489, 4688.530487439304, 4958.181899986498, 4961.3798275215595, 4971.0, 3404.8127352995575, 3776.546814382264, 3873.133880928121, 4201.668074218807, 4468.240399132155, 4880.707157570521, 5263.289455923439, 5297.311349524575, 5299.973500132513, 5308.761105750251, 3269.0, 3519.8768043118625, 3672.6143754905615, 3695.1131728384994, 3706.703463722896, 4758.881662809237, 4806.3511425957395, 4839.7096174229655, 5209.400918894337, 5341.919871201935, 3696.353138200804, 3700.703943684499, 3708.1841994761107, 3723.94414083789, 3735.645113714247, 3198.360327934376, 3357.7649564530248, 3426.623071462137, 3487.779277252941, 3776.2825063237897, 4005.2990726623307, 4335.655946656547, 4352.978235108804, 4359.38968544404, 4401.581849724271, 4200.663946884242, 5070.518300761421, 5267.762950667229, 5306.257124002643, 5331.946680533145, 1078.5870509632407, 1119.656412305237, 1148.7070796946725, 1179.9203728588245, 1185.7806305833449, 766.9148155360215, 793.6983946100561, 833.2584000239791, 839.8152406470567, 840.2102024097329, 4069.491313585802, 4570.88572785683, 4645.745648674839, 4652.674312798072, 4666.60333871618, 4755.476897541268, 5138.2806407103035, 5139.357580302462, 5139.91833307511, 5141.871453213699, 3868.09322584192, 4399.64802815774, 4666.883327916829, 4674.859754207379, 4694.6244300456565, 2345.8827058647, 2659.205036600326, 2677.839329640228, 2685.60980727153, 2707.4043710383685, 5146.845594632167, 5263.868403289948, 5274.973625131926, 5280.471952804689, 5284.640171858232, 4700.87179077019, 4856.39295088114, 5209.270702101709, 5267.2889159962915, 5279.18172683227, 5363.597730170253, 5488.1340344282335, 5499.0, 5534.588679886684, 5667.744951477111, 5433.592480563894, 5471.835844924659, 5478.369987451494, 5481.945180548303, 5483.424240454854, 5120.975804838972, 5382.354117505768, 5395.838124856261, 5409.459054094559, 5411.242426060433, 4773.880652983565, 4835.8065677372515, 5556.749946252347, 5589.55283577321, 5639.295088113986, 3150.574672419194, 3635.963750331249, 3722.571904231048, 3735.58908520068, 3736.7757934524016, 4583.335416364675, 5164.535191832753, 5517.386112096583, 5519.834404967857, 5562.276904002454, 4142.730722503027, 4800.35195248651, 5306.6550674206055, 5356.78572857097, 5370.677759334452, 5104.41299250591, 5266.710330931835, 5309.628326017141, 5315.681059136464, 5327.733613319241, 4140.441040459499, 4627.028324051987, 4890.559849613552, 4927.768057985503, 5240.816571419982, 4505.800881374261, 5208.5051920067535, 5346.358436987586, 5383.973080134574, 5395.86510337229, 5248.632595718415, 5263.6578622389425, 5267.130168790024, 5287.656302340336, 5299.788008479772], "value": 3819.0033639282356, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.2583343738295, 673.8974399872399, 690.3786592067142, 695.2456584605702, 733.8441953922571, 15565.455209067684, 16201.91899040503, 16209.83790162098, 16591.087490188034, 16643.583910402245, 14998.625034374147, 15053.322600482978, 15056.774148387756, 15079.472218472356, 15112.773308400356, 635.7012204264012, 653.4902775834832, 812.6221307092216, 837.7528629054414, 838.0069617503278, 637.5154882289447, 803.4697099914058, 819.1685439279137, 834.7454026521908, 836.0719601242616, 835.3526017336552, 876.0, 877.4472082587961, 879.0594064351147, 880.6565439478609, 665.3978149774414, 867.5445391169654, 869.1438932651333, 869.1786261982429, 869.9347548933855, 509.33023074656705, 619.1672200889841, 639.402158981354, 643.1606753187089, 644.0661041489858, 14243.64390890236, 14267.357968891321, 14283.0, 14289.928550357286, 14290.785638215435, 12578.308193049379, 12793.424295906707, 12867.420966056456, 12874.87125128746, 12932.935335323356, 12043.757596805035, 12196.939015304955, 12265.034783821988, 12275.324857132855, 12327.568535101318, 4240.469941257343, 4240.533541310453, 4320.719153255029, 4362.323839804835, 4498.887527811831, 80107.59946200231, 82597.93505162359, 84270.57864710699, 84591.57704211501, 84781.15218847794, 9786.95106524477, 9966.900330996743, 10035.849462258071, 10056.044675755791, 10289.279750417549, 19117.426477205707, 19247.13387897548, 19280.61438771217, 19325.323613673452, 20008.59982800365, 7742.961285193651, 7761.379089672812, 7774.067111946602, 7870.645820938072, 7977.7207797726805, 4107.301758701028, 4223.5142958559545, 4272.188284225987, 4377.4528183977, 4403.955960440355, 2798.944021119566, 3473.218525831718, 3521.4893840393, 3524.3479956208184, 3532.576090869061, 571.0577547047405, 644.1851058411114, 648.4099469482842, 654.165938428498, 665.9167604049494, 530.9203619457035, 541.8401571536406, 543.5977376741183, 576.8183022347947, 601.9819405417844, 8963.686270980485, 9028.503432311287, 9053.230475409533, 9204.493752843659, 9245.72262832116, 604.685563506974, 638.3648269971408, 661.3578752882266, 668.4518694670323, 676.0400231671039, 3877.991722152204, 4202.957970420318, 4535.9773201133785, 4606.539346065434, 4696.88257793551, 114943.0, 118381.0, 120057.39971300088, 123129.0, 124731.0, 99570.0, 104106.0, 105277.0, 107180.46409767901, 108594.0, 67620.6618966902, 92573.61139582911, 93461.0, 93676.53161734148, 96875.0, 9373.062693730571, 9496.335256531976, 9560.904390956279, 9623.615055397706, 9634.566444510154, 57208.85582288414, 58642.0, 58771.70614146902, 59238.70380648069, 60433.79132417415, 75319.49361012859, 78616.0, 80876.19123808686, 83779.16220837958, 88067.11932880589, 12057.397130143634, 12157.331346776013, 12239.816402754144, 12288.1398302118, 12451.626451206479, 13340.399682014528, 13587.52443664467, 13987.510437135059, 14180.361883715465, 14300.856991429953, 12169.878301217228, 12716.809247861105, 12771.489140434278, 13477.865221348053, 13673.974451916345, 12656.303903285409, 13039.869601303866, 13165.407556660164, 13345.199288042748, 13818.378172982038, 12167.2699638022, 12827.0, 12856.228626282453, 12964.00045612992, 13413.798793018308, 10305.53625086888, 11179.105671546095, 11182.161337899528, 11261.268017578832, 11318.434078296217, 7155.749548765768, 8869.802576652328, 9831.459269739948, 10864.891351086704, 10918.344899306067], "value": 7340.464297217715, "unit": "score"}], "name": "dromaeo_css", "value": 7135.298427387258, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 113.0, 114.0, 114.0, 115.0, 162.0, 168.0, 114.0, 112.0], "value": 124.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 178.0, 134.0, 155.0, 100.0, 133.0, 136.0, 132.0, 155.0, 139.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 182.0, 184.0, 190.0, 256.0, 207.0, 180.0, 182.0, 184.0], "value": 193.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 73.0, 114.0, 85.0, 84.0, 83.0, 60.0, 80.0, 69.0], "value": 78.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 119.0, 101.0, 98.0, 68.0, 88.0, 67.0, 69.0, 90.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 85.0, 87.0, 84.0, 87.0, 84.0, 87.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 143.0, 93.0, 95.0, 94.0, 94.0, 93.0, 94.0, 93.0, 108.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 79.0, 80.0, 83.0, 83.0, 86.0, 85.0, 80.0, 80.0], "value": 82.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 53.0, 57.0, 57.0, 59.0, 58.0, 56.0, 57.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 77.0, 73.0, 78.0, 70.0, 70.0, 72.0, 70.0, 72.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 83.0, 83.0, 83.0, 83.0, 81.0, 82.0, 84.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 141.0, 147.0, 133.0, 145.0, 141.0, 145.0, 195.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 164.0, 164.0, 166.0, 164.0, 165.0, 164.0, 162.0, 163.0, 176.0], "value": 165.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 67.0, 67.0, 66.0, 67.0, 68.0, 66.0, 65.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1483.7999999999997, "alertThreshold": 2.0}]} 15:02:57 INFO - Return code: 0 15:02:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:02:57 INFO - # TBPL SUCCESS # 15:02:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:02:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:02:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:02:57 INFO - 2017-01-12 15:02:57.311 screenresolution[1651:10028] starting screenresolution argv=screenresolution get 15:02:57 INFO - 2017-01-12 15:02:57.314 screenresolution[1651:10028] Display 0: 1600x1200x32@60 15:02:57 INFO - 2017-01-12 15:02:57.320 screenresolution[1652:10030] starting screenresolution argv=screenresolution list 15:02:57 INFO - Available Modes on Display 0 15:02:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:02:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:02:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:02:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:02:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:02:57 INFO - Intel Iris: 15:02:57 INFO - Chipset Model: Intel Iris 15:02:57 INFO - Type: GPU 15:02:57 INFO - Bus: Built-In 15:02:57 INFO - VRAM (Dynamic, Max): 1536 MB 15:02:57 INFO - Vendor: Intel (0x8086) 15:02:57 INFO - Device ID: 0x0a2e 15:02:57 INFO - Revision ID: 0x0009 15:02:57 INFO - Displays: 15:02:57 INFO - Display: 15:02:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:02:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:02:57 INFO - Display Serial Number: 1600x1200 60 15:02:57 INFO - Main Display: Yes 15:02:57 INFO - Mirror: Off 15:02:57 INFO - Online: Yes 15:02:57 INFO - Rotation: Supported 15:02:57 INFO - Return code: 0 15:02:57 INFO - Running post-action listener: _resource_record_post_action 15:02:57 INFO - [mozharness: 2017-01-12 23:02:57.566413Z] Finished run-tests step (success) 15:02:57 INFO - Running post-run listener: _resource_record_post_run 15:02:57 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37835776; Write bytes: 659474944; Read time: 240; Write time: 1921 15:02:57 INFO - TinderboxPrint: CPU usage
53.3% 15:02:57 INFO - TinderboxPrint: I/O read bytes / time
37,835,776 / 240 15:02:57 INFO - TinderboxPrint: I/O write bytes / time
659,474,944 / 1,921 15:02:57 INFO - TinderboxPrint: CPU idle
2,263.8 (46.7%) 15:02:57 INFO - TinderboxPrint: CPU system
967.3 (19.9%) 15:02:57 INFO - TinderboxPrint: CPU user
1,618.5 (33.4%) 15:02:57 INFO - TinderboxPrint: Swap in / out
387,072,000 / 0 15:02:57 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346452480; Write bytes: 345089536; Read time: 28259; Write time: 729 15:02:57 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 35836928; Write bytes: 307414016; Read time: 222; Write time: 1164 15:02:58 INFO - Running post-run listener: _upload_blobber_files 15:02:58 INFO - Blob upload gear active. 15:02:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:02:58 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 15:02:58 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'] 15:02:58 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 15:02:58 INFO - (blobuploader) - INFO - Open directory for files ... 15:02:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:02:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:02:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:02:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:02:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:02:59 INFO - (blobuploader) - INFO - Done attempting. 15:02:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:02:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:02:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:03:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:00 INFO - (blobuploader) - INFO - Done attempting. 15:03:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:03:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:03:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:02 INFO - (blobuploader) - INFO - Done attempting. 15:03:02 INFO - (blobuploader) - INFO - Iteration through files over. 15:03:02 INFO - Return code: 0 15:03:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:03:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:03:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ead65201bd27b353ad5bb631c248317d6d07345586270ca4496fe401a45c444a85e29ff54b4d1d920f63ee451aeaeb25366f8ba11cbf2cdb53fb8a0f0084a31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/288ae88041c81d8656c73f7d8f447630b2f0a1d861857b126121f292b3f44588955f42a5443efcb479a796112f355e150181f79f00a6e30ac435e5d8ed31a8fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f2bba60383f8914353a4825195e57a003afece1ab44013be6197a00bc6261fa681cbe619a13294d694ea3392a4ee62717a88aa826c0e8e5c5886033cb9b9015"} 15:03:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:03:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:03:02 INFO - Contents: 15:03:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ead65201bd27b353ad5bb631c248317d6d07345586270ca4496fe401a45c444a85e29ff54b4d1d920f63ee451aeaeb25366f8ba11cbf2cdb53fb8a0f0084a31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/288ae88041c81d8656c73f7d8f447630b2f0a1d861857b126121f292b3f44588955f42a5443efcb479a796112f355e150181f79f00a6e30ac435e5d8ed31a8fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f2bba60383f8914353a4825195e57a003afece1ab44013be6197a00bc6261fa681cbe619a13294d694ea3392a4ee62717a88aa826c0e8e5c5886033cb9b9015"} 15:03:02 INFO - Running post-run listener: copy_logs_to_upload_dir 15:03:02 INFO - Copying logs to upload dir... 15:03:02 INFO - mkdir: /builds/slave/test/build/upload/logs 15:03:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.652381 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-12 15:03:02.847808) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 15:03:02.851631) ========= 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.dD6DsdP90j/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.1K7QUUUcnK/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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/9ead65201bd27b353ad5bb631c248317d6d07345586270ca4496fe401a45c444a85e29ff54b4d1d920f63ee451aeaeb25366f8ba11cbf2cdb53fb8a0f0084a31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/288ae88041c81d8656c73f7d8f447630b2f0a1d861857b126121f292b3f44588955f42a5443efcb479a796112f355e150181f79f00a6e30ac435e5d8ed31a8fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f2bba60383f8914353a4825195e57a003afece1ab44013be6197a00bc6261fa681cbe619a13294d694ea3392a4ee62717a88aa826c0e8e5c5886033cb9b9015"} build_url:https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011085 build_url: 'https://queue.taskcluster.net/v1/task/Y7ejASGgTKmbT3NK2Ja_wQ/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/9ead65201bd27b353ad5bb631c248317d6d07345586270ca4496fe401a45c444a85e29ff54b4d1d920f63ee451aeaeb25366f8ba11cbf2cdb53fb8a0f0084a31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/288ae88041c81d8656c73f7d8f447630b2f0a1d861857b126121f292b3f44588955f42a5443efcb479a796112f355e150181f79f00a6e30ac435e5d8ed31a8fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f2bba60383f8914353a4825195e57a003afece1ab44013be6197a00bc6261fa681cbe619a13294d694ea3392a4ee62717a88aa826c0e8e5c5886033cb9b9015"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 15:03:02.890794) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 15:03:02.891155) ========= 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.dD6DsdP90j/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.1K7QUUUcnK/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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.005085 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 15:03:02.939966) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 15:03:02.940288) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 15:03:02.940605) ========= ========= Total master_lag: 0.19 =========