builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0312 starttime: 1479486373.56 results: success (0) buildid: 20161118060928 builduid: 6c0ad3f43e204c4f8622f086657268d7 revision: 48f5a1870ec3974ddf717576e6e54d345e476677 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:13.557883) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:13.558383) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:13.558665) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:13.583435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:13.583768) ========= 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.NScDpMLbik/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.qyG6EosauB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-18 08:26:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 65.6M=0s 2016-11-18 08:26:13 (65.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120806 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:13.727160) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:13.727493) ========= 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.NScDpMLbik/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.qyG6EosauB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.026149 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:13.776149) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-18 08:26:13.776520) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 48f5a1870ec3974ddf717576e6e54d345e476677 --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 48f5a1870ec3974ddf717576e6e54d345e476677 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NScDpMLbik/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.qyG6EosauB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-18 08:26:13,874 truncating revision to first 12 chars 2016-11-18 08:26:13,874 Setting DEBUG logging. 2016-11-18 08:26:13,874 attempt 1/10 2016-11-18 08:26:13,874 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/48f5a1870ec3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-18 08:26:14,708 unpacking tar archive at: mozilla-aurora-48f5a1870ec3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.100374 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-18 08:26:14.898891) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:14.899227) ========= script_repo_revision: 48f5a1870ec3974ddf717576e6e54d345e476677 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:14.899629) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:14.899896) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 08:26:14.918028) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-18 08:26:14.918322) ========= /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.NScDpMLbik/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.qyG6EosauB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:26:15 INFO - MultiFileLogger online at 20161118 08:26:15 in /builds/slave/test 08:26:15 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 08:26:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:26:15 INFO - {'append_to_log': False, 08:26:15 INFO - 'base_work_dir': '/builds/slave/test', 08:26:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:26:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:26:15 INFO - 'branch': 'Mozilla-Aurora', 08:26:15 INFO - 'buildbot_json_path': 'buildprops.json', 08:26:15 INFO - 'config_files': ('talos/mac_config.py',), 08:26:15 INFO - 'default_actions': ('clobber', 08:26:15 INFO - 'read-buildbot-config', 08:26:15 INFO - 'download-and-extract', 08:26:15 INFO - 'populate-webroot', 08:26:15 INFO - 'create-virtualenv', 08:26:15 INFO - 'install', 08:26:15 INFO - 'run-tests'), 08:26:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:26:15 INFO - 'download_minidump_stackwalk': True, 08:26:15 INFO - 'download_symbols': 'ondemand', 08:26:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:26:15 INFO - 'tooltool.py': '/tools/tooltool.py', 08:26:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:26:15 INFO - '/tools/misc-python/virtualenv.py')}, 08:26:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:26:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:26:15 INFO - 'installer_path': 'installer.exe', 08:26:15 INFO - 'log_level': 'info', 08:26:15 INFO - 'log_name': 'talos', 08:26:15 INFO - 'log_to_console': True, 08:26:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:26:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:26:15 INFO - 'opt_config_files': (), 08:26:15 INFO - 'pip_index': False, 08:26:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:26:15 INFO - 'cmd': ('bash', 08:26:15 INFO - '-c', 08:26:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:26:15 INFO - 'enabled': True, 08:26:15 INFO - 'halt_on_failure': False, 08:26:15 INFO - 'name': 'check_screen_resolution'},), 08:26:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:26:15 INFO - 'cmd': ('bash', 08:26:15 INFO - '-c', 08:26:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:26:15 INFO - 'enabled': True, 08:26:15 INFO - 'halt_on_failure': False, 08:26:15 INFO - 'name': 'check_screen_resolution'},), 08:26:15 INFO - 'run_cmd_checks_enabled': True, 08:26:15 INFO - 'sps_profile': False, 08:26:15 INFO - 'sps_profile_interval': 0, 08:26:15 INFO - 'suite': 'dromaeojs', 08:26:15 INFO - 'system_bits': '32', 08:26:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:26:15 INFO - 'title': 't-yosemite-r7-0312', 08:26:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:26:15 INFO - 'use_talos_json': True, 08:26:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:26:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:26:15 INFO - 'work_dir': 'build'} 08:26:15 INFO - [mozharness: 2016-11-18 16:26:15.121645Z] Running clobber step. 08:26:15 INFO - Running pre-action listener: _resource_record_pre_action 08:26:15 INFO - Running main action method: clobber 08:26:15 INFO - rmtree: /builds/slave/test/build 08:26:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:26:17 INFO - Running post-action listener: _resource_record_post_action 08:26:17 INFO - [mozharness: 2016-11-18 16:26:17.550549Z] Finished clobber step (success) 08:26:17 INFO - [mozharness: 2016-11-18 16:26:17.550699Z] Running read-buildbot-config step. 08:26:17 INFO - Running pre-action listener: _resource_record_pre_action 08:26:17 INFO - Running main action method: read_buildbot_config 08:26:17 INFO - Using buildbot properties: 08:26:17 INFO - { 08:26:17 INFO - "project": "", 08:26:17 INFO - "product": "firefox", 08:26:17 INFO - "script_repo_revision": "production", 08:26:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:26:17 INFO - "repository": "", 08:26:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:26:17 INFO - "buildid": "20161118060928", 08:26:17 INFO - "pgo_build": "False", 08:26:17 INFO - "basedir": "/builds/slave/test", 08:26:17 INFO - "buildnumber": 25, 08:26:17 INFO - "slavename": "t-yosemite-r7-0312", 08:26:17 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:26:17 INFO - "platform": "yosemite_r7", 08:26:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:26:17 INFO - "branch": "mozilla-aurora", 08:26:17 INFO - "repo_path": "releases/mozilla-aurora", 08:26:17 INFO - "revision": "48f5a1870ec3974ddf717576e6e54d345e476677", 08:26:17 INFO - "stage_platform": "macosx64", 08:26:17 INFO - "builduid": "6c0ad3f43e204c4f8622f086657268d7", 08:26:17 INFO - "slavebuilddir": "test" 08:26:17 INFO - } 08:26:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:26:17 INFO - Running post-action listener: _resource_record_post_action 08:26:17 INFO - [mozharness: 2016-11-18 16:26:17.554015Z] Finished read-buildbot-config step (success) 08:26:17 INFO - [mozharness: 2016-11-18 16:26:17.554110Z] Running download-and-extract step. 08:26:17 INFO - Running pre-action listener: _resource_record_pre_action 08:26:17 INFO - Running main action method: download_and_extract 08:26:17 INFO - mkdir: /builds/slave/test/build/tests 08:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:17 INFO - trying https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:26:17 INFO - Downloading https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/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 08:26:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/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 08:26:18 INFO - Downloaded 1199 bytes. 08:26:18 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:26:18 INFO - Using the following test package requirements: 08:26:18 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:26:18 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:26:18 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:26:18 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:26:18 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:26:18 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:26:18 INFO - u'jsshell-mac.zip'], 08:26:18 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:26:18 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:26:18 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:26:18 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:26:18 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:26:18 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:26:18 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:26:18 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:26:18 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:26:18 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:26:18 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:26:18 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:26:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:26:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:26:18 INFO - Fetch https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:26:19 INFO - Http code: 200 08:26:19 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 08:26:19 INFO - x-amz-cf-id: vaWTWcycfFAYPGhtTaEce0b5uxG_5NxvzK9wJXrYuSCOQzZjtXz2EA== 08:26:19 INFO - x-amz-version-id: _Ytz6t173q9Sqky9B0Au8cEs0Ddpkoat 08:26:19 INFO - Expected file size: 16274875 08:26:19 INFO - Obtained file size: 16274875 08:26:26 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 08:26:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:26:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:26:26 INFO - Fetch https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:26:26 INFO - Http code: 200 08:26:26 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 08:26:26 INFO - x-amz-cf-id: LHtUP98M3d0QMjkY6GRDzZaySzXRqmZSrho2EM5I1528fpg1M5MptA== 08:26:26 INFO - x-amz-version-id: _Ytz6t173q9Sqky9B0Au8cEs0Ddpkoat 08:26:27 INFO - Expected file size: 16274875 08:26:27 INFO - Obtained file size: 16274875 08:26:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:26:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:26:33 INFO - Fetch https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:26:34 INFO - Http code: 200 08:26:34 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 08:26:34 INFO - x-amz-cf-id: ZWkh5mxpgxToGYwRDDYh1lh8QWI4so6qkbojm29_xVl08sAx2RRwUg== 08:26:34 INFO - x-amz-version-id: 5.ZmqO3qmRFWm_J2WzOfqREoz2h6fsM1 08:26:35 INFO - Expected file size: 14409038 08:26:35 INFO - Obtained file size: 14409038 08:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:35 INFO - trying https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:26:35 INFO - Downloading https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:26:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/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 08:26:38 INFO - Downloaded 114500549 bytes. 08:26:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:26:38 INFO - mkdir: /builds/slave/test/properties 08:26:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:26:38 INFO - Writing to file /builds/slave/test/properties/build_url 08:26:38 INFO - Contents: 08:26:38 INFO - build_url:https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:26:39 INFO - Running post-action listener: _resource_record_post_action 08:26:39 INFO - Running post-action listener: set_extra_try_arguments 08:26:39 INFO - [mozharness: 2016-11-18 16:26:39.488544Z] Finished download-and-extract step (success) 08:26:39 INFO - [mozharness: 2016-11-18 16:26:39.488689Z] Running populate-webroot step. 08:26:39 INFO - Running pre-action listener: _resource_record_pre_action 08:26:39 INFO - Running main action method: populate_webroot 08:26:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:26:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:26:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:26:39 INFO - '--tppagecycles', 08:26:39 INFO - '7'], 08:26:39 INFO - 'tests': ['tsvgm']}}, 08:26:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:26:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:26:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:26:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:26:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:26:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:26:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:39 INFO - 'talos_options': ['--disable-e10s'], 08:26:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:26:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:26:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:39 INFO - 'talos_options': ['--disable-e10s'], 08:26:39 INFO - 'tests': ['damp', 'tps']}, 08:26:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:39 INFO - 'tests': ['damp', 'tps']}, 08:26:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:26:39 INFO - 'tests': ['dromaeo_dom']}, 08:26:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:26:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:26:39 INFO - 'tests': ['basic_compositor_video']}, 08:26:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:26:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:26:39 INFO - 'tests': ['a11yr', 08:26:39 INFO - 'ts_paint', 08:26:39 INFO - 'tpaint', 08:26:39 INFO - 'sessionrestore', 08:26:39 INFO - 'sessionrestore_no_auto_restore', 08:26:39 INFO - 'tabpaint']}, 08:26:39 INFO - 'other-e10s': {'tests': ['a11yr', 08:26:39 INFO - 'ts_paint', 08:26:39 INFO - 'tpaint', 08:26:39 INFO - 'sessionrestore', 08:26:39 INFO - 'sessionrestore_no_auto_restore', 08:26:39 INFO - 'tabpaint']}, 08:26:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:26:39 INFO - 'ts_paint', 08:26:39 INFO - 'tpaint', 08:26:39 INFO - 'sessionrestore', 08:26:39 INFO - 'sessionrestore_no_auto_restore', 08:26:39 INFO - 'tabpaint']}, 08:26:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:26:39 INFO - 'ts_paint', 08:26:39 INFO - 'tpaint', 08:26:39 INFO - 'sessionrestore', 08:26:39 INFO - 'sessionrestore_no_auto_restore', 08:26:39 INFO - 'tabpaint']}, 08:26:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:26:39 INFO - 'tests': ['a11yr', 08:26:39 INFO - 'ts_paint', 08:26:39 INFO - 'tpaint', 08:26:39 INFO - 'sessionrestore', 08:26:39 INFO - 'sessionrestore_no_auto_restore', 08:26:39 INFO - 'tabpaint']}, 08:26:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:26:39 INFO - 'tests': ['a11yr', 08:26:39 INFO - 'ts_paint', 08:26:39 INFO - 'tpaint', 08:26:39 INFO - 'sessionrestore', 08:26:39 INFO - 'sessionrestore_no_auto_restore', 08:26:39 INFO - 'tabpaint']}, 08:26:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:26:39 INFO - 'tests': ['tsvgx', 08:26:39 INFO - 'tsvgr_opacity', 08:26:39 INFO - 'tart', 08:26:39 INFO - 'tscrollx', 08:26:39 INFO - 'cart']}, 08:26:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:26:39 INFO - 'tsvgr_opacity', 08:26:39 INFO - 'tart', 08:26:39 INFO - 'tscrollx', 08:26:39 INFO - 'cart']}, 08:26:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:39 INFO - 'talos_options': ['--disable-e10s'], 08:26:39 INFO - 'tests': ['tp5o']}, 08:26:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:39 INFO - 'tests': ['tp5o']}, 08:26:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:39 INFO - 'talos_options': ['--disable-e10s', 08:26:39 INFO - '--xperf_path', 08:26:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:26:39 INFO - 'tests': ['tp5n']}, 08:26:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:39 INFO - 'talos_options': ['--xperf_path', 08:26:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:26:39 INFO - 'tests': ['tp5n']}}, 08:26:39 INFO - 'talos.zip': {'path': '', 08:26:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:26:39 INFO - Running post-action listener: _resource_record_post_action 08:26:39 INFO - [mozharness: 2016-11-18 16:26:39.507352Z] Finished populate-webroot step (success) 08:26:39 INFO - [mozharness: 2016-11-18 16:26:39.507463Z] Running create-virtualenv step. 08:26:39 INFO - Running pre-action listener: _resource_record_pre_action 08:26:39 INFO - Running main action method: create_virtualenv 08:26:39 INFO - Creating virtualenv /builds/slave/test/build/venv 08:26:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:26:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:26:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:26:39 INFO - Using real prefix '/tools/python27' 08:26:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:26:40 INFO - Installing distribute.............................................................................................................................................................................................done. 08:26:43 INFO - Installing pip.................done. 08:26:43 INFO - Return code: 0 08:26:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:26:43 INFO - Reading from file tmpfile_stdout 08:26:43 INFO - Output received: 08:26:43 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:26:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:43 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 0x105b7d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8d57730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105991d30>, '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 0x1057f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105989b20>, '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.NScDpMLbik/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.qyG6EosauB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:26:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:26:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NScDpMLbik/Render', 08:26:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:43 INFO - 'HOME': '/Users/cltbld', 08:26:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:43 INFO - 'LOGNAME': 'cltbld', 08:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:43 INFO - 'MOZ_NO_REMOTE': '1', 08:26:43 INFO - 'NO_EM_RESTART': '1', 08:26:43 INFO - 'PAGER': '/bin/cat', 08:26:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:43 INFO - 'PWD': '/builds/slave/test', 08:26:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:43 INFO - 'SHELL': '/bin/bash', 08:26:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qyG6EosauB/Listeners', 08:26:43 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 08:26:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:43 INFO - 'USER': 'cltbld', 08:26:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:43 INFO - 'XPC_FLAGS': '0x0', 08:26:43 INFO - 'XPC_SERVICE_NAME': '0', 08:26:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:26:43 INFO - Cleaning up... 08:26:43 INFO - Return code: 0 08:26:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:43 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 0x105b7d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8d57730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105991d30>, '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 0x1057f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105989b20>, '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.NScDpMLbik/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.qyG6EosauB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:26:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:26:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NScDpMLbik/Render', 08:26:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:43 INFO - 'HOME': '/Users/cltbld', 08:26:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:43 INFO - 'LOGNAME': 'cltbld', 08:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:43 INFO - 'MOZ_NO_REMOTE': '1', 08:26:43 INFO - 'NO_EM_RESTART': '1', 08:26:43 INFO - 'PAGER': '/bin/cat', 08:26:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:43 INFO - 'PWD': '/builds/slave/test', 08:26:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:43 INFO - 'SHELL': '/bin/bash', 08:26:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qyG6EosauB/Listeners', 08:26:43 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 08:26:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:43 INFO - 'USER': 'cltbld', 08:26:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:43 INFO - 'XPC_FLAGS': '0x0', 08:26:43 INFO - 'XPC_SERVICE_NAME': '0', 08:26:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:43 INFO - Downloading/unpacking psutil>=3.1.1 08:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:26:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:26:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:26:47 INFO - Installing collected packages: psutil 08:26:47 INFO - Running setup.py install for psutil 08:26:47 INFO - building 'psutil._psutil_osx' extension 08:26:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:26:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:26:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:26:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:26:48 INFO - building 'psutil._psutil_posix' extension 08:26:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:26:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:26:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:26:48 INFO - ^ 08:26:48 INFO - 1 warning generated. 08:26:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:26:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:26:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:26:48 INFO - Successfully installed psutil 08:26:48 INFO - Cleaning up... 08:26:48 INFO - Return code: 0 08:26:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105b7d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8d57730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105991d30>, '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 0x1057f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105989b20>, '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.NScDpMLbik/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.qyG6EosauB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:26:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:26:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:26:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NScDpMLbik/Render', 08:26:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:48 INFO - 'HOME': '/Users/cltbld', 08:26:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:48 INFO - 'LOGNAME': 'cltbld', 08:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:48 INFO - 'MOZ_NO_REMOTE': '1', 08:26:48 INFO - 'NO_EM_RESTART': '1', 08:26:48 INFO - 'PAGER': '/bin/cat', 08:26:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:48 INFO - 'PWD': '/builds/slave/test', 08:26:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:48 INFO - 'SHELL': '/bin/bash', 08:26:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qyG6EosauB/Listeners', 08:26:48 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 08:26:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:48 INFO - 'USER': 'cltbld', 08:26:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:48 INFO - 'XPC_FLAGS': '0x0', 08:26:48 INFO - 'XPC_SERVICE_NAME': '0', 08:26:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:26:51 INFO - Installing collected packages: mozsystemmonitor 08:26:51 INFO - Running setup.py install for mozsystemmonitor 08:26:51 INFO - Successfully installed mozsystemmonitor 08:26:51 INFO - Cleaning up... 08:26:51 INFO - Return code: 0 08:26:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:51 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 0x105b7d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8d57730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105991d30>, '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 0x1057f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105989b20>, '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.NScDpMLbik/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.qyG6EosauB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:26:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:26:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:26:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NScDpMLbik/Render', 08:26:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:51 INFO - 'HOME': '/Users/cltbld', 08:26:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:51 INFO - 'LOGNAME': 'cltbld', 08:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:51 INFO - 'MOZ_NO_REMOTE': '1', 08:26:51 INFO - 'NO_EM_RESTART': '1', 08:26:51 INFO - 'PAGER': '/bin/cat', 08:26:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:51 INFO - 'PWD': '/builds/slave/test', 08:26:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:51 INFO - 'SHELL': '/bin/bash', 08:26:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qyG6EosauB/Listeners', 08:26:51 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 08:26:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:51 INFO - 'USER': 'cltbld', 08:26:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:51 INFO - 'XPC_FLAGS': '0x0', 08:26:51 INFO - 'XPC_SERVICE_NAME': '0', 08:26:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:51 INFO - Downloading/unpacking jsonschema==2.5.1 08:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:26:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:54 INFO - Downloading functools32-3.2.3-2.tar.gz 08:26:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:26:54 INFO - warning: no files found matching '*.txt' 08:26:54 INFO - no previously-included directories found matching 'build' 08:26:54 INFO - no previously-included directories found matching 'dist' 08:26:54 INFO - no previously-included directories found matching '.git*' 08:26:54 INFO - Installing collected packages: jsonschema, functools32 08:26:55 INFO - Running setup.py install for functools32 08:26:55 INFO - warning: no files found matching '*.txt' 08:26:55 INFO - no previously-included directories found matching 'build' 08:26:55 INFO - no previously-included directories found matching 'dist' 08:26:55 INFO - no previously-included directories found matching '.git*' 08:26:55 INFO - Successfully installed jsonschema functools32 08:26:55 INFO - Cleaning up... 08:26:55 INFO - Return code: 0 08:26:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:55 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 0x105b7d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8d57730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105991d30>, '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 0x1057f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105989b20>, '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.NScDpMLbik/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.qyG6EosauB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:26:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:26:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:26:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NScDpMLbik/Render', 08:26:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:55 INFO - 'HOME': '/Users/cltbld', 08:26:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:55 INFO - 'LOGNAME': 'cltbld', 08:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:55 INFO - 'MOZ_NO_REMOTE': '1', 08:26:55 INFO - 'NO_EM_RESTART': '1', 08:26:55 INFO - 'PAGER': '/bin/cat', 08:26:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:55 INFO - 'PWD': '/builds/slave/test', 08:26:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:55 INFO - 'SHELL': '/bin/bash', 08:26:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qyG6EosauB/Listeners', 08:26:55 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 08:26:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:55 INFO - 'USER': 'cltbld', 08:26:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:55 INFO - 'XPC_FLAGS': '0x0', 08:26:55 INFO - 'XPC_SERVICE_NAME': '0', 08:26:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:26:55 INFO - Cleaning up... 08:26:55 INFO - Return code: 0 08:26:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:55 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 0x105b7d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8d57730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105991d30>, '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 0x1057f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105989b20>, '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.NScDpMLbik/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.qyG6EosauB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:26:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:26:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:26:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NScDpMLbik/Render', 08:26:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:55 INFO - 'HOME': '/Users/cltbld', 08:26:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:55 INFO - 'LOGNAME': 'cltbld', 08:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:55 INFO - 'MOZ_NO_REMOTE': '1', 08:26:55 INFO - 'NO_EM_RESTART': '1', 08:26:55 INFO - 'PAGER': '/bin/cat', 08:26:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:55 INFO - 'PWD': '/builds/slave/test', 08:26:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:55 INFO - 'SHELL': '/bin/bash', 08:26:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qyG6EosauB/Listeners', 08:26:55 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 08:26:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:55 INFO - 'USER': 'cltbld', 08:26:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:55 INFO - 'XPC_FLAGS': '0x0', 08:26:55 INFO - 'XPC_SERVICE_NAME': '0', 08:26:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:55 INFO - Downloading/unpacking blobuploader==1.2.4 08:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:58 INFO - Downloading blobuploader-1.2.4.tar.gz 08:26:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:26:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:26:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:59 INFO - Downloading docopt-0.6.1.tar.gz 08:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:26:59 INFO - Installing collected packages: blobuploader, requests, docopt 08:26:59 INFO - Running setup.py install for blobuploader 08:26:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:26:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:26:59 INFO - Running setup.py install for requests 08:27:00 INFO - Running setup.py install for docopt 08:27:00 INFO - Successfully installed blobuploader requests docopt 08:27:00 INFO - Cleaning up... 08:27:00 INFO - Return code: 0 08:27:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:00 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 0x105b7d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8d57730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105991d30>, '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 0x1057f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105989b20>, '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.NScDpMLbik/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.qyG6EosauB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:27:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:27:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:27:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NScDpMLbik/Render', 08:27:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:00 INFO - 'HOME': '/Users/cltbld', 08:27:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:00 INFO - 'LOGNAME': 'cltbld', 08:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:00 INFO - 'MOZ_NO_REMOTE': '1', 08:27:00 INFO - 'NO_EM_RESTART': '1', 08:27:00 INFO - 'PAGER': '/bin/cat', 08:27:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:00 INFO - 'PWD': '/builds/slave/test', 08:27:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:00 INFO - 'SHELL': '/bin/bash', 08:27:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qyG6EosauB/Listeners', 08:27:00 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 08:27:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:00 INFO - 'USER': 'cltbld', 08:27:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:00 INFO - 'XPC_FLAGS': '0x0', 08:27:00 INFO - 'XPC_SERVICE_NAME': '0', 08:27:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:27:00 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Jov331-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:27:00 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-qyNXjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:27:00 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-SXuqK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:27:01 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-b6BL31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:27:01 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-2LnluM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:27:01 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-N9uBCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:27:01 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-v7ebic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:27:01 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-BaTUrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:27:01 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-0jJFOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:27:01 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-VqeD0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:27:01 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-HUykJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:27:01 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-8BxrYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:27:01 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-LB34W0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:27:02 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-depova-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:02 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-cJ8z7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:27:02 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-mRAILw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:27:02 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-VZqMUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:27:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:27:02 INFO - Running setup.py install for manifestparser 08:27:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:27:02 INFO - Running setup.py install for mozcrash 08:27:02 INFO - Running setup.py install for mozdebug 08:27:02 INFO - Running setup.py install for mozdevice 08:27:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:27:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:27:03 INFO - Running setup.py install for mozfile 08:27:03 INFO - Running setup.py install for mozhttpd 08:27:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:27:03 INFO - Running setup.py install for mozinfo 08:27:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:27:03 INFO - Running setup.py install for mozInstall 08:27:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:27:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:27:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:27:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:27:03 INFO - Running setup.py install for mozleak 08:27:03 INFO - Running setup.py install for mozlog 08:27:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:27:04 INFO - Running setup.py install for moznetwork 08:27:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:27:04 INFO - Running setup.py install for mozprocess 08:27:04 INFO - Running setup.py install for mozprofile 08:27:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:27:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:27:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:27:04 INFO - Running setup.py install for mozrunner 08:27:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:27:04 INFO - Running setup.py install for mozscreenshot 08:27:04 INFO - Running setup.py install for moztest 08:27:04 INFO - Running setup.py install for mozversion 08:27:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:27:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:27:05 INFO - Cleaning up... 08:27:05 INFO - Return code: 0 08:27:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:05 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 0x105b7d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8d57730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105991d30>, '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 0x1057f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105989b20>, '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.NScDpMLbik/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.qyG6EosauB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NScDpMLbik/Render', 08:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:05 INFO - 'HOME': '/Users/cltbld', 08:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:05 INFO - 'LOGNAME': 'cltbld', 08:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:05 INFO - 'MOZ_NO_REMOTE': '1', 08:27:05 INFO - 'NO_EM_RESTART': '1', 08:27:05 INFO - 'PAGER': '/bin/cat', 08:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:05 INFO - 'PWD': '/builds/slave/test', 08:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:05 INFO - 'SHELL': '/bin/bash', 08:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qyG6EosauB/Listeners', 08:27:05 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 08:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:05 INFO - 'USER': 'cltbld', 08:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:05 INFO - 'XPC_FLAGS': '0x0', 08:27:05 INFO - 'XPC_SERVICE_NAME': '0', 08:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:27:05 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-z109CT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:27:05 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-MwiSTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:27:05 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)) 08:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:27:05 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-H9aS_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:27:05 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-bk1bQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:27:05 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-KfEK5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:27:05 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-yOOta0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:27:06 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-zv6kwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:27:06 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-zDpOiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:27:06 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ebzar8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:27:06 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-kmkpwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:27:06 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ASNJy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:27:06 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-hdVtKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:27:06 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-RoB2cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:27:06 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-uTKJ9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:06 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-CsKJTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:27:06 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-nMgaE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:27:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:27:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:27:07 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-6P7OGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:27:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:27:07 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)) 08:27:07 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)) 08:27:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:27:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:27:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:27:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:27:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:27:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:27:09 INFO - Downloading blessings-1.6.tar.gz 08:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:27:09 INFO - Installing collected packages: blessings 08:27:09 INFO - Running setup.py install for blessings 08:27:09 INFO - Successfully installed blessings 08:27:09 INFO - Cleaning up... 08:27:10 INFO - Return code: 0 08:27:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:27:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:27:10 INFO - Reading from file tmpfile_stdout 08:27:10 INFO - Current package versions: 08:27:10 INFO - blessings == 1.6 08:27:10 INFO - blobuploader == 1.2.4 08:27:10 INFO - docopt == 0.6.1 08:27:10 INFO - functools32 == 3.2.3-2 08:27:10 INFO - jsonschema == 2.5.1 08:27:10 INFO - manifestparser == 1.1 08:27:10 INFO - mozInstall == 1.12 08:27:10 INFO - mozcrash == 1.0 08:27:10 INFO - mozdebug == 0.1 08:27:10 INFO - mozdevice == 0.48 08:27:10 INFO - mozfile == 1.2 08:27:10 INFO - mozhttpd == 0.7 08:27:10 INFO - mozinfo == 0.9 08:27:10 INFO - mozleak == 0.1 08:27:10 INFO - mozlog == 3.4 08:27:10 INFO - moznetwork == 0.27 08:27:10 INFO - mozprocess == 0.23 08:27:10 INFO - mozprofile == 0.28 08:27:10 INFO - mozrunner == 6.13 08:27:10 INFO - mozscreenshot == 0.1 08:27:10 INFO - mozsystemmonitor == 0.3 08:27:10 INFO - moztest == 0.7 08:27:10 INFO - mozversion == 1.4 08:27:10 INFO - psutil == 3.1.1 08:27:10 INFO - requests == 1.2.3 08:27:10 INFO - wsgiref == 0.1.2 08:27:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:11 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 0x105b7d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8d57730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105991d30>, '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 0x1057f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105989b20>, '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.NScDpMLbik/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.qyG6EosauB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:27:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:27:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NScDpMLbik/Render', 08:27:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:11 INFO - 'HOME': '/Users/cltbld', 08:27:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:11 INFO - 'LOGNAME': 'cltbld', 08:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:11 INFO - 'MOZ_NO_REMOTE': '1', 08:27:11 INFO - 'NO_EM_RESTART': '1', 08:27:11 INFO - 'PAGER': '/bin/cat', 08:27:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:11 INFO - 'PWD': '/builds/slave/test', 08:27:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:11 INFO - 'SHELL': '/bin/bash', 08:27:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qyG6EosauB/Listeners', 08:27:11 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 08:27:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:11 INFO - 'USER': 'cltbld', 08:27:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:11 INFO - 'XPC_FLAGS': '0x0', 08:27:11 INFO - 'XPC_SERVICE_NAME': '0', 08:27:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:27:11 INFO - Cleaning up... 08:27:11 INFO - Return code: 0 08:27:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:11 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 0x105b7d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8d57730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105991d30>, '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 0x1057f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105989b20>, '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.NScDpMLbik/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.qyG6EosauB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:27:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:27:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NScDpMLbik/Render', 08:27:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:11 INFO - 'HOME': '/Users/cltbld', 08:27:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:11 INFO - 'LOGNAME': 'cltbld', 08:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:11 INFO - 'MOZ_NO_REMOTE': '1', 08:27:11 INFO - 'NO_EM_RESTART': '1', 08:27:11 INFO - 'PAGER': '/bin/cat', 08:27:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:11 INFO - 'PWD': '/builds/slave/test', 08:27:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:11 INFO - 'SHELL': '/bin/bash', 08:27:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qyG6EosauB/Listeners', 08:27:11 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 08:27:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:11 INFO - 'USER': 'cltbld', 08:27:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:11 INFO - 'XPC_FLAGS': '0x0', 08:27:11 INFO - 'XPC_SERVICE_NAME': '0', 08:27:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:27:11 INFO - Cleaning up... 08:27:12 INFO - Return code: 0 08:27:12 INFO - Running post-action listener: _resource_record_post_action 08:27:12 INFO - Running post-action listener: _start_resource_monitoring 08:27:12 INFO - Starting resource monitoring. 08:27:12 INFO - [mozharness: 2016-11-18 16:27:12.037297Z] Finished create-virtualenv step (success) 08:27:12 INFO - [mozharness: 2016-11-18 16:27:12.038312Z] Running install step. 08:27:12 INFO - Running pre-action listener: _resource_record_pre_action 08:27:12 INFO - Running main action method: install 08:27:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:27:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:27:12 INFO - Reading from file tmpfile_stdout 08:27:12 INFO - Detecting whether we're running mozinstall >=1.0... 08:27:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:27:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:27:12 INFO - Reading from file tmpfile_stdout 08:27:12 INFO - Output received: 08:27:12 INFO - Usage: mozinstall [options] installer 08:27:12 INFO - Options: 08:27:12 INFO - -h, --help show this help message and exit 08:27:12 INFO - -d DEST, --destination=DEST 08:27:12 INFO - Directory to install application into. [default: 08:27:12 INFO - "/builds/slave/test"] 08:27:12 INFO - --app=APP Application being installed. [default: firefox] 08:27:12 INFO - mkdir: /builds/slave/test/build/application 08:27:12 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'] 08:27:12 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 08:27:45 INFO - Reading from file tmpfile_stdout 08:27:45 INFO - Output received: 08:27:45 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:27:45 INFO - Running post-action listener: _resource_record_post_action 08:27:45 INFO - [mozharness: 2016-11-18 16:27:45.511875Z] Finished install step (success) 08:27:45 INFO - [mozharness: 2016-11-18 16:27:45.512021Z] Running run-tests step. 08:27:45 INFO - Running pre-action listener: _resource_record_pre_action 08:27:45 INFO - Running main action method: run_tests 08:27:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:27:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:27:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:27:45 INFO - 2016-11-18 08:27:45.524 screenresolution[1621:7406] starting screenresolution argv=screenresolution get 08:27:45 INFO - 2016-11-18 08:27:45.527 screenresolution[1621:7406] Display 0: 1600x1200x32@60 08:27:45 INFO - 2016-11-18 08:27:45.532 screenresolution[1622:7408] starting screenresolution argv=screenresolution list 08:27:45 INFO - Available Modes on Display 0 08:27:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:27:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:27:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:27:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:27:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:27:45 INFO - Intel Iris: 08:27:45 INFO - Chipset Model: Intel Iris 08:27:45 INFO - Type: GPU 08:27:45 INFO - Bus: Built-In 08:27:45 INFO - VRAM (Dynamic, Max): 1536 MB 08:27:45 INFO - Vendor: Intel (0x8086) 08:27:45 INFO - Device ID: 0x0a2e 08:27:45 INFO - Revision ID: 0x0009 08:27:45 INFO - Displays: 08:27:45 INFO - Display: 08:27:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:27:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:27:45 INFO - Display Serial Number: 1600x1200 60 08:27:45 INFO - Main Display: Yes 08:27:45 INFO - Mirror: Off 08:27:45 INFO - Online: Yes 08:27:45 INFO - Rotation: Supported 08:27:45 INFO - Return code: 0 08:27:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:27:45 INFO - Python 2.7.3 08:27:45 INFO - Return code: 0 08:27:45 INFO - grabbing minidump binary from tooltool 08:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:45 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 0x105991d30>, '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 0x1057f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105989b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:27:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:27:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:27:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:27:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:27:45 INFO - Return code: 0 08:27:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:27:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:27:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:27:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:27:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:27:45 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/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:27:45 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/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0312 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NScDpMLbik/Render', 08:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:45 INFO - 'HOME': '/Users/cltbld', 08:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:45 INFO - 'LOGNAME': 'cltbld', 08:27:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:27:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:45 INFO - 'MOZ_NO_REMOTE': '1', 08:27:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:27:45 INFO - 'NO_EM_RESTART': '1', 08:27:45 INFO - 'PAGER': '/bin/cat', 08:27:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:45 INFO - 'PWD': '/builds/slave/test', 08:27:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:45 INFO - 'SHELL': '/bin/bash', 08:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qyG6EosauB/Listeners', 08:27:45 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 08:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:45 INFO - 'USER': 'cltbld', 08:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:45 INFO - 'XPC_FLAGS': '0x0', 08:27:45 INFO - 'XPC_SERVICE_NAME': '0', 08:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:45 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/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:27:46 INFO - mozversion application_buildid: 20161118060928 08:27:46 INFO - mozversion application_changeset: 48f5a1870ec3974ddf717576e6e54d345e476677 08:27:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:27:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:27:46 INFO - mozversion application_name: Firefox 08:27:46 INFO - mozversion application_remotingname: firefox-dev 08:27:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:27:46 INFO - mozversion application_vendor: Mozilla 08:27:46 INFO - mozversion application_version: 52.0a2 08:27:46 INFO - mozversion platform_buildid: 20161118060928 08:27:46 INFO - mozversion platform_changeset: 48f5a1870ec3974ddf717576e6e54d345e476677 08:27:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:27:46 INFO - mozversion platform_version: 52.0a2 08:27:46 INFO - using testdate: 1479486466 08:27:46 INFO - actual date: 1479486466 08:27:46 INFO - starting webserver on 'localhost:49240' 08:27:46 INFO - SUITE-START | Running 2 tests 08:27:46 INFO - TEST-START | dromaeo_css 08:27:46 INFO - operating with platform_type : mac_ 08:27:46 INFO - Initialising browser for dromaeo_css test... 08:27:46 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmphYzbSe/profile http://localhost:49240/getInfo.html) 08:27:47 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 08:27:48 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 08:27:48 INFO - PROCESS | 1632 | colorDepth:24 08:27:48 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 08:27:48 INFO - PROCESS | 1632 | __metrics 08:27:48 INFO - PROCESS | 1632 | 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" 08:27:48 INFO - PROCESS | 1632 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:27:49 INFO - TEST-INFO | 1632: exit 0 08:27:49 INFO - Browser initialized. 08:27:49 INFO - Running cycle 1/1 for dromaeo_css test... 08:27:49 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmphYzbSe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:28:01 INFO - PROCESS | 1634 | RSS: Main: 217735168 08:28:01 INFO - PROCESS | 1634 | 08:28:53 INFO - PROCESS | 1634 | 1479486533119 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:31:06 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 08:31:08 INFO - PROCESS | 1634 | RSS: Main: 349609984 08:31:08 INFO - PROCESS | 1634 | 08:34:10 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 08:34:11 INFO - PROCESS | 1634 | RSS: Main: 362201088 08:34:11 INFO - PROCESS | 1634 | 08:37:14 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 08:37:14 INFO - PROCESS | 1634 | RSS: Main: 315375616 08:37:14 INFO - PROCESS | 1634 | 08:40:16 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 08:40:17 INFO - PROCESS | 1634 | RSS: Main: 327704576 08:40:17 INFO - PROCESS | 1634 | 08:43:21 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 08:43:21 INFO - PROCESS | 1634 | RSS: Main: 307359744 08:43:21 INFO - PROCESS | 1634 | 08:46:24 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 08:46:24 INFO - PROCESS | 1634 | RSS: Main: 360337408 08:46:24 INFO - PROCESS | 1634 | 08:46:24 INFO - PROCESS | 1634 | __start_tp_report 08:46:24 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 08:46:24 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 08:46:24 INFO - PROCESS | 1634 | |i|pagename|runs| 08:46:24 INFO - PROCESS | 1634 | |0;dojo.html;3308.9007329780106;3310.2882880180773;3325.517799919012;3351.7151042161413;3385.6106547747017;5599.804006859761;5623.381428042912;5705.6006079574345;5706.229658996034;5726.255586773719;5633.80281690141;5688.829335119953;5711.171880077386;5717.056685646874;5725.942740572593;5193.9740301298625;5208.947910520903;5209.244659524366;5236.162214045753;5253.6322457428;5163.896722065557;5200.583953283747;5309.787608495655;5310.20346947957;5338.572914166877;5181.248718935752;5191.0656081905245;5210.791568337262;5213.426523082458;5254.737263136847;5196.064708352514;5218.843434696965;5261.053010458116;5276.683398996034;5298.284731561228;5160.741962901878;5217.034848553029;5218.947810521884;5230.320058392384;5230.633855630107;5553.722313884289;5591;5612.298462692163;5641.492265696107;5647.29408823897;6112.132124055526;6125.322810980791;6161.537884658624;6166.7533298667995;6176.660283684396;6246.781362652331;6305.936940630627;6306.369363063703;6310.905336419957;6321.620702757849;5631.493165615114;5678.545716342765;5725.599208055396;5765.394633563457;5773.653580785166;6371.362863713592;6397.168368112116;6412.35876412355;6418.614883107029;6425.8714825704155;6099.573029887954;6109.480694141048;6115.388461153938;6125.754969801247;6128.295246046672;6097;6115.0215965446205;6139.982816839433;6224.221972253468;6262.5929314594405;6070.878582428415;6141.481992383102;6142.232220972379;6237.095621135;6240.656763878031;5978.372270911578;6202.689865506707;6243.094751261042;6270;6275.341089185661;4860.878478038077;4904.6321525885705;4912.926306105412;4918.040982008545;4929.186684197139;4319.589638984291;4358.215521206213;4380.277254253013;4441.444819397575;4455.485135054098;5682.573806964494;5703.201551782754;5703.515201207943;5709;5711.771529138871;6191.690415479208;6204.906926396107;6210.844728881723;6218.129461875341;6229.127922090911;5647.971760141173;5716.228309178291;5720.742566584514;5722.284714410699;5723.942760572424;5338.199270109515;5356.84827762033;5368.302120724281;5368.946310536845;5372.62391632582;6315.90526142099;6338.683065846597;6351.460125889351;6367.4906007519285;6371.362863713684;6254.124422580933;6330.968345158245;6341.682915854281;6348.460380867678;6350.904736428864;6397.104405383157;6404.071409645669;6411.230652321751;6413.038044293393;6415.807525774234;5902.822915312548;5965.642061476326;6037;6207.565470417027;6213.627182369072;6151.015837465984;6179.320274769681;6201.503879689704;6213.720382582885;6296.779612713533;4893.09477746618;5856.707164641836;6027.547933905063;6030.306514750684;6037.245344331958;5970.671613061323;5999.760009599567;6003.129546215687;6013.5189184865985;6019.097135429721;5749.741261643152;5811.883762324645;5815.302163740378;5820.417958204146;5828.912566311424;5939.643621382731;6094.146819445193;6095.695215239131;6101.542384321105;6114.266288045463;5853.2976042875125;5898.174255604133;5898.8230353089475;5905.409459054232;5939.821805345847 08:46:24 INFO - PROCESS | 1634 | |1;ext.html;473.99526004739954;516.8371962831709;529.8304542546387;553.9196816461613;566.3147591414388;30515.694843051562;38785.83642490726;39915.60084399159;40025.79987100061;41802.37296440556;41036.794816025955;41358.58641413585;41414.171716565725;41472.79263603678;41581.58418415822;9448.952755236214;9625;9634.98832622575;9637.18083962864;9639.084286992724;8161.673533058671;9386.249100072013;9503;9519.143277105059;9530.618775248982;9503.334766566342;9552.90447095527;9555.426674399523;9560.521973901312;9569.04309569041;9483.05169483053;9533;9548.80902381952;9550.140487356102;9552.283578731553;9430.95284523573;9523.428594284365;9526.90473095274;9527.666531671428;9530.809383812284;39347.01632459183;39677.40483892744;39720.60279397198;39769.60230397688;39831.00422489432;3152.574402455662;3285.9342813143844;3429.725621950238;3660.9084772880624;3856.267309211241;29507.40985180306;29998.100056998108;30128;30530.084097477113;30691.925782597504;3115.937681246385;3193.5050067239613;3198.280386912926;3215.1640573450836;3217.9034628961167;514559;515791;516108;602452;634623;63473.04790428147;63861.680691596244;63931.36068639254;64049.35950640434;64084.35915640877;34704.826475867965;34943.82528087343;65645.67177164083;65940.34059659342;66036.66981665061;15583.84416155824;15645.921770391074;15646.843531564766;15674;15706.607334816721;10029.79940401188;11841.467133978991;11953.820692689616;11955;12240.326782026896;19076;19107.80892191088;19112;19134.61730765377;19168.13743381551;7305.45209109319;7417.406607471389;7455.664495097733;7472.476926615085;7540.17058123606;854.2695994924375;933.4166146158651;980.9215262778961;1049.5696764326742;1051.2940148709938;30004.04973676704;30089.097327080224;30149.547756783664;30540.54189187164;30624.08127756171;869.9173578510031;935.335911502827;975.6389836178633;1027.5941003303653;1051.1958351860833;15700.450484232997;15704.057756534416;15720.606984825243;15773;16018.439354622766;375021;378274;379180.10409947776;379206;673091;58150;58643;58674;58687.70656146777;58777;1148.6439203846835;1220.4324988880214;1258.4588626890525;1358.8844948179515;1377.6555861034742;29049.85475072611;30892;31377.8431107843;32296.192595185308;32714.34571308608;55822;56767.71616141893;56998;59965;59976;615202;619370;622426;622525;623045;26432.735672643026;26917.327066823487;41034.38448423216;41309;41456.79271603622;45347.31979020383;46001.769991151166;46212.53787462082;46426.07147856957;46846.53153468422;37773.81113094417;38359.42460863033;38952;39018.414723778595;39484.60515394809;38628;38725.419118713806;38747.6125238744;39521.2095758089;39564.60435395609;31452.842735786173;34442.65557344495;38679.6132038676;38819.6118038816;38982;27873.721262787112;28966.42067158687;33960.83019584886;34499.65500345065;34614.096224706875;25970.610440843026;26008.34979125537;26096.8695156523;26340.868295658398;26477.33806654849 08:46:24 INFO - PROCESS | 1634 | |2;jquery.html;348.3172980957323;366.17427700535296;370.6238168259219;379.73798079325275;398.780670631153;15032.699346013072;15033.548993530198;16040.679186416264;19921.302754403598;20845.68731469029;18617;18744.6790502696;18791.812081879176;18960.90519547404;19137.52156196096;5596.552275817935;5955.570663040882;5958.642481451105;5959.553033522481;5977.282726072855;5065.366829146356;5873.207117039209;6492.480601551888;6568.047633093199;6623.768168114117;6292.937070629304;6576.473882089422;6586.37429444202;6587.868242635144;6599.274079851212;5998.8500287492725;6126.17296664949;6487.643179625119;6521.250056243545;6573.079768832391;5118.181091025418;5389.703566303851;5463.672179669233;5681.0910254359105;5725.656460612377;12150.33173175475;12309.322987235699;12349.75300493985;12379.00967922573;23038.539229215323;2164.0910817456706;2171.0230396321563;2236.7651396603287;2434.0385547708734;2551.6810398700163;10308.020738029874;13705.383257753425;14003.159810411407;14719.484818031424;15406.691866162613;1683.4781217822515;1834.0863403857243;1840.5122642499748;1883.8226108682074;1900.3158862809378;105429;119588.40205798915;119690.4015479917;120225.39887300595;120524.39737801255;11511.597094101666;11598.94200528992;11793.646190614296;11892.524299027942;11921.344326061977;14755.483558075415;14756.262186890612;14781.556553303419;14789.556313310617;14799.556013319618;8079.878801817978;8131.349492040741;8353.498790072616;8497.70258040978;8512.74461766148;8120.066192387951;8133.785319629386;8160;8264.545450000187;8325.625346859406;4511.684182107221;4515.187266292033;4522.140793249272;4523.932141017887;4598.126355992417;1622.8052633683797;1851.1947302923272;1912.4836294200488;1926.9036548172817;1939.5829896572304;821.9630116644765;892.3625147853631;901.8016036471965;961.5288508630721;964.5948701545368;16574.337026518806;20820.583588328453;20953.47616309574;20953.580928381347;21084.894575527025;904.4482865452069;941.1670671455674;946.9526523673788;950.6292545907102;987.1609132237684;10591.735206619896;10922.836157457645;11034.951679590426;11096.445177741081;11145.554177832959;594657;598460;603469;615095;622325;92544.53727731318;128440;130003;131064;131641;918.5682729117304;973.4889183178747;995.1292618958412;998.55065220651;1003.518311210623;18949.621007580048;19488.512787180432;19870.50323741918;19937.900310498353;19967.500812479804;54158;55205.44794552003;56091.71954140203;56752;57626.55933601694;78645;81775;82355;83020.75468868093;83646.16353836383;6957.304269573205;7896;7898.328642065489;8051.838963220585;8218.054923683852;10448.843267350843;10964.232503724663;11164.218504704917;12460.937695311466;12849.935750321189;10816.296940698829;10947.014768670859;11044.889551104707;11068.395082713123;11196.600424946882;10623.734406639896;10772.515236814206;10821.67534973952;10875.401852898167;11001.724956875822;11058.723531911766;11421.200515963803;11427.714307142389;11554.653360399201;11576.421178941187;6928.099347084847;7280.781576552712;7924.1283458820635;7958.204179581995;8288.336933045463;9896.901030989598;10980.890191097988;11044;11158.888411115784;11349.4892729829 08:46:24 INFO - PROCESS | 1634 | |3;mootools.html;1329.5014369611395;1465.421158642336;1571.55210764932;1758.3757765993064;1770.4423106721374;7538.434617403689;7815.179406162342;8123.268905798477;8553.101924297946;8560.571971401436;7676.117246516652;8065.475744076631;8294.83410331793;8305.003399592042;8400.41197116202;2323.6514522821544;2352.9411764705847;2354.7174339079334;2360.6223004319313;2361.8464799788044;2069.544700165961;2095.6227878981776;2265.7764807004282;2309.4457330240707;2325.8604483730946;2110.2930518276394;2133.925312614059;2136.423165745248;2138.9465263368384;2139.5613899150635;2258.1306197114063;2334.533093381322;2334.8949297281492;2335.3694502484236;2337.1118974789642;2316.5714342846627;2339.7894189523026;2353.882305884716;2356.2577787996725;2362.2913126062112;8296.460730052528;8356;8378;8384.03583587889;8392.370572207108;5017.37282839645;5167.870803229949;5184;5188.040212560651;5199.220116982445;3092.659807421182;3320.8837690680953;3484.4250698634696;3614.078410005458;3617.3488772021024;1032.4785983078498;1050.9106725928305;1106.9446527673583;1109.5450865145333;1110.3781882145943;10622.840657390145;10636.521356538975;10643.414612196326;10646;10667.253292269545;4511.616512596466;4569.954300456953;4652.116097941448;4723.149833030019;4787.569118779326;9565.426074435418;9570.090841370058;9570.090841370058;9585.90414095864;9589.664361747306;5166.250893620404;5262.105442074857;5263.157894736823;5271.894562108813;5292.232626269247;4536.5236650151255;4599;4675.345451636774;4787.066522028241;4803.543663351976;3756.229972855558;3766.981165094157;3806.562245341766;3842.558105817811;3861.34357159278;1255.3974092435683;1264.4815625593646;1266.2019931697012;1272.5355245335386;1279.635303938373;616.5591602004588;875.159846547309;893.4326702543931;903.5617725403174;911.9680811171577;5578.776848926079;5583.3858275589655;5648.661080335193;5675.858103547444;5781.508571771363;432.38169417732945;441.0825482995362;441.2652932866805;449.285635839011;455.0830077394054;3344.0970937847133;3784.413415920537;3787.962120378761;3788.6211378861894;3791.2227993261317;9631.759206019906;9977.501124943868;10237.385756854612;10255.846162307571;10259.17926565873;10178.491075446198;10200.948995255125;10206.285560010729;10222.693319200436;10237.795244095076;7361.521501102411;7744.922550774533;7888.447808653454;7896.486728362638;7902.407319451064;5272.367315922113;5398.64908780928;5563.276774019513;5591.27313449249;5659.207710920557;9215.44707317563;9306.022867598804;9352.67265645699;9414.811703766023;9448.527573621428;9368.765780855532;9369.484678342757;9475.147236748726;9478.620855165991;9533.47565883883;7008.649567521706;7132.322429369202;7139.2503787101605;7139.607321597362;7145.4998150128995;7604.961975190088;7674.846503069795;7765.805854853448;7785.532868027936;7787.338076263581;7120;7298.2701729826595;7394.704211831467;7418.517796343221;7436.442266830118;7190.676419561237;7195;7297.817554561178;7309.634518274172;7336.963315183603;6583.242927063449;6678.966605166943;6693.230278518033;6763.086983257197;6772.187337519529;4890.951090489049;4899.534544218293;4903.6077113831525;4969.279454479152;4992.126377883928;4182.9581704182565;4876.975615121902;4915.582175514978;5092.9745351273;5144.202648589475 08:46:24 INFO - PROCESS | 1634 | |4;prototype.html;269.71883553122666;278.6753432251427;284.4538486106675;301.654620393047;312.2334668389105;4159.7312819590015;4330.956690433095;4348.608625223729;4727.55088266615;4902.534259245375;4047.51429828421;4067.8779636610875;5054.974725126378;5079.466656001122;5083.669561478501;2941.808782429135;3671.76133551319;3706.277275931181;3827.5598306194725;3915.6867450604027;3316.535685004101;3484.094135524769;3875.418687196915;3883;3962.9009274768073;3595.6404359563967;3921.313770090223;3944.2505923874373;3950.2889479893606;3980.263651224518;3917.5298964124345;3929.646331830149;3957.920841583152;3969.3450580654157;3984.2230765000836;3479.599846017715;3488.3023395320783;3495.7727747696317;3496.230829217568;3499.4400895856543;4009.458723072377;4739.336492890998;4947.851564453073;4962.255661650745;4999.9500004999845;3807.9337785420125;4464.754438505881;4477.619402350771;4498.077894031716;4503.279475283972;4971.328870602459;5057.179415410449;5320.52115309624;5342.626016178869;5353.625246232766;3677.921974084634;3790.8960526907554;3810.2950954073585;3835.520559930009;3849.5188101487315;5499.7250137492965;5519.751611177467;5575.498205161555;5594.496495315441;5620.887582248373;3551.6093229744706;3690.9815450922574;3756.887293381203;3835.328817456934;3927.6857851371824;3419.8714424239943;3714.832832522543;3914.412838074312;3968.269826363209;3978.3236849735613;3805.657490825839;4203.285441474956;4348.608625223745;4425.344793124164;4520.412346394947;4687.632745463519;5160.7419629018395;5340.225667278223;5357.19642053695;5383.335249051666;1101.6419663609358;1256.8680288569751;1259.7228609706033;1267.2649863079391;1268.6574624851423;682.8975653651893;825.8396952282053;881.0660699658255;885.1458342699326;894.579547612621;4195.811188496525;4369.453818272716;4542.432195975503;4625.912910466038;5019.29729837823;4771.260454629538;5465.890682186334;5465.945340546623;5466.234727138204;5498.890022199534;4183.4979802423295;4248.383984322256;4580.450345958439;4671.8364857229835;5019.092744757042;2091.7385326834146;2677.437738075026;2691.1253842501264;2727.2500062482977;2744.9588256176175;4322.956770432318;5300.826661069021;5331.546818520392;5336.519713225828;5376.38171610262;4071.6742660587083;4995.025969935828;5095.949040509621;5120.974395128001;5297.8344764151825;4938.629602779806;5058.898822023539;5695.658260504383;5703.351768308815;5711.228984087095;4796.808127674994;5038.168702164248;5346.545543628679;5461.399246083007;5531.03206938776;4580.083983203306;5346.812861549827;5394.973025134849;5438.755256013568;5448.536874365724;5353.785848566014;5636.701500589433;5671.347795003462;5679.734152862038;5681.77272909079;3721.776693398405;3726.1988672434472;3743.1765011697385;3746.9508537609513;3758.5489741231227;4246.95753042478;4978.8257410990445;5062.063518249136;5157.922631160461;5387.245785589942;4966.826161084345;5322.494363035506;5336.67979921206;5349.652272602269;5371.248025276386;4670.182718024264;4817.518248175154;5372.274742909657;5388.488093631099;5408.269883565668;3727.4595183698752;4331.956680433155;5079.796808127633;5173.396247163391;5339.012282727708;5083.87290317745;5275.657082289639;5291.920621190762;5311.814086506954;5422.403535611158;4719.4100737407825;4968.503149685147;5171.637985341141;5282.777505721584;5287.788488460573 08:46:24 INFO - PROCESS | 1634 | |5;yui.html;587.8952854750341;664.9767258145965;676.8578598494316;761.7562380038387;794.7059587952458;13655.590332290034;13825.032247742662;14150.575482735509;14275.357608907598;14370.20963846988;12647.051471139655;12884.742305153915;12956.287404192766;13007;13036.217826930368;786.6381464526324;811.6104269950428;812.163471624228;813.5769399912042;814.1207495904409;654.460070441885;675.368530424055;678.6742363665444;788.846174995876;821.9794505137389;861.0356400831078;869.6173683579236;873.4802792338548;873.8252349530088;875.1511034296753;869.2307308032374;869.2784988459626;869.8477766390888;869.9652013919435;869.9826003479926;498.55707035525853;511.6597462687308;614.2168734863085;640.2381166411955;640.6348381422591;13867.861321386757;14259.215743134124;14285.928570357186;14296.356663950148;14313.71372572553;13219.545849956497;13453.66365840852;13535.864641353559;13581.320933953262;13604.31978401076;12058.69853253667;12108.334041627708;12225.144239903122;12496.25022498653;12877.806832897513;4437.2900335946415;4474.8881277967985;4496.797644106023;4566.06395688883;4632.2356811126465;70476;91971.25867195243;94618.52690736503;97172.51413742887;97347.51326243323;10583.94708026455;10681.412522311191;10782.997762863535;10953.507092180871;10966.506507207036;20969.370918872457;21083.367498975054;21116.78883211179;21123.471913202295;21413.036413361435;7577.734779282699;7686.423518236154;7703.1526532081425;7703.807404814813;7901.723439679584;3688.9262214755554;4117.279476091672;4233.936490952638;4377.474703035592;4423.557644235615;3324.7506437016837;3423.383790917609;3425.3149370125575;3432.0390290718638;3436.1409647588102;636.710296814948;638.6391688695873;643.6813777180325;660.4122331125302;662.5858838226109;591.5179129009844;601.4046094366546;607.5413063137391;610.3164455809522;621.9875602487925;8601.827963440695;8683.56582170889;8693.608787604573;8741.30069594431;8808.955955220183;548.8600406896215;581.892349915267;586.642148289543;603.2480300336273;626.4330780643525;3151.8108913465267;3152.952705709416;3234.2884565395575;3264.4613638749342;3280.7375409967144;96956;98170.5091474538;100483;105358.473207635;111046.44476777564;90990;91013.63479547812;92685.5365723167;94818;95158.52420737852;81107.18892810996;81227;81361.6104812892;84434.57782711046;85373;5465.688234823534;6265.342139075331;6268.1851359323;6435.646039467687;6501.349865013461;52667.473325266255;53234.46765532295;53386.73306633442;55375.44624553864;56068.5982850432;51672.48327516676;58368.708156458946;67850;75282;78532.60733696294;9034.277257819227;10626.8405973912;10798.784024319626;10864.130869530261;10954.945225273823;11352;11570.94214528922;11601.419929003347;11731.941340293586;12117.333546654667;11118.277311974696;11148.108151347711;11241.213115081866;11408.82886756716;11584.189106762446;10876.673699789018;11135.220534562503;11176.776464470502;11250.268732532359;11299.872834207625;10595;10713.464326783786;10773.676789696321;10859.131269498579;10862.619808306672;9151.039140890376;9307.441553506811;9375.671851485165;9423.57593908262;9600.471974041215;9243.491607961358;9322.766930826783;9555.761105972406;9608;9608.893200423752 08:46:24 INFO - PROCESS | 1634 | __end_tp_report 08:46:24 INFO - PROCESS | 1634 | __start_cc_report 08:46:24 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,1902 08:46:24 INFO - PROCESS | 1634 | __end_cc_report 08:46:24 INFO - PROCESS | 1634 | __startTimestamp1479487584858__endTimestamp 08:46:24 INFO - PROCESS | 1634 | 08:46:24 INFO - PROCESS | 1634 | ------- Summary: start ------- 08:46:24 INFO - PROCESS | 1634 | Number of tests: 6 08:46:24 INFO - PROCESS | 1634 | 08:46:24 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:5722.94 Median:5856.71 stddev:643.06 (11.0%) stddev-sans-first:616.51 08:46:24 INFO - PROCESS | 1634 | Values: 3308.9 3310.3 3325.5 3351.7 3385.6 5599.8 5623.4 5705.6 5706.2 5726.3 5633.8 5688.8 5711.2 5717.1 5725.9 5194.0 5208.9 5209.2 5236.2 5253.6 5163.9 5200.6 5309.8 5310.2 5338.6 5181.2 5191.1 5210.8 5213.4 5254.7 5196.1 5218.8 5261.1 5276.7 5298.3 5160.7 5217.0 5218.9 5230.3 5230.6 5553.7 5591.0 5612.3 5641.5 5647.3 6112.1 6125.3 6161.5 6166.8 6176.7 6246.8 6305.9 6306.4 6310.9 6321.6 5631.5 5678.5 5725.6 5765.4 5773.7 6371.4 6397.2 6412.4 6418.6 6425.9 6099.6 6109.5 6115.4 6125.8 6128.3 6097.0 6115.0 6140.0 6224.2 6262.6 6070.9 6141.5 6142.2 6237.1 6240.7 5978.4 6202.7 6243.1 6270.0 6275.3 4860.9 4904.6 4912.9 4918.0 4929.2 4319.6 4358.2 4380.3 4441.4 4455.5 5682.6 5703.2 5703.5 5709.0 5711.8 6191.7 6204.9 6210.8 6218.1 6229.1 5648.0 5716.2 5720.7 5722.3 5723.9 5338.2 5356.8 5368.3 5368.9 5372.6 6315.9 6338.7 6351.5 6367.5 6371.4 6254.1 6331.0 6341.7 6348.5 6350.9 6397.1 6404.1 6411.2 6413.0 6415.8 5902.8 5965.6 6037.0 6207.6 6213.6 6151.0 6179.3 6201.5 6213.7 6296.8 4893.1 5856.7 6027.5 6030.3 6037.2 5970.7 5999.8 6003.1 6013.5 6019.1 5749.7 5811.9 5815.3 5820.4 5828.9 5939.6 6094.1 6095.7 6101.5 6114.3 5853.3 5898.2 5898.8 5905.4 5939.8 08:46:24 INFO - PROCESS | 1634 | 08:46:24 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:67788.20 Median:39699.00 stddev:146622.47 (369.3%) stddev-sans-first:146946.67 08:46:24 INFO - PROCESS | 1634 | Values: 474.0 516.8 529.8 553.9 566.3 30515.7 38785.8 39915.6 40025.8 41802.4 41036.8 41358.6 41414.2 41472.8 41581.6 9449.0 9625.0 9635.0 9637.2 9639.1 8161.7 9386.2 9503.0 9519.1 9530.6 9503.3 9552.9 9555.4 9560.5 9569.0 9483.1 9533.0 9548.8 9550.1 9552.3 9431.0 9523.4 9526.9 9527.7 9530.8 39347.0 39677.4 39720.6 39769.6 39831.0 3152.6 3285.9 3429.7 3660.9 3856.3 29507.4 29998.1 30128.0 30530.1 30691.9 3115.9 3193.5 3198.3 3215.2 3217.9 514559.0 515791.0 516108.0 602452.0 634623.0 63473.0 63861.7 63931.4 64049.4 64084.4 34704.8 34943.8 65645.7 65940.3 66036.7 15583.8 15645.9 15646.8 15674.0 15706.6 10029.8 11841.5 11953.8 11955.0 12240.3 19076.0 19107.8 19112.0 19134.6 19168.1 7305.5 7417.4 7455.7 7472.5 7540.2 854.3 933.4 980.9 1049.6 1051.3 30004.0 30089.1 30149.5 30540.5 30624.1 869.9 935.3 975.6 1027.6 1051.2 15700.5 15704.1 15720.6 15773.0 16018.4 375021.0 378274.0 379180.1 379206.0 673091.0 58150.0 58643.0 58674.0 58687.7 58777.0 1148.6 1220.4 1258.5 1358.9 1377.7 29049.9 30892.0 31377.8 32296.2 32714.3 55822.0 56767.7 56998.0 59965.0 59976.0 615202.0 619370.0 622426.0 622525.0 623045.0 26432.7 26917.3 41034.4 41309.0 41456.8 45347.3 46001.8 46212.5 46426.1 46846.5 37773.8 38359.4 38952.0 39018.4 39484.6 38628.0 38725.4 38747.6 39521.2 39564.6 31452.8 34442.7 38679.6 38819.6 38982.0 27873.7 28966.4 33960.8 34499.7 34614.1 25970.6 26008.3 26096.9 26340.9 26477.3 08:46:24 INFO - PROCESS | 1634 | 08:46:24 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:34938.19 Median:12637.65 stddev:101207.76 (800.8%) stddev-sans-first:101458.35 08:46:24 INFO - PROCESS | 1634 | Values: 348.3 366.2 370.6 379.7 398.8 15032.7 15033.5 16040.7 19921.3 20845.7 18617.0 18744.7 18791.8 18960.9 19137.5 5596.6 5955.6 5958.6 5959.6 5977.3 5065.4 5873.2 6492.5 6568.0 6623.8 6292.9 6576.5 6586.4 6587.9 6599.3 5998.9 6126.2 6487.6 6521.3 6573.1 5118.2 5389.7 5463.7 5681.1 5725.7 12150.3 12309.3 12349.8 12379.0 23038.5 2164.1 2171.0 2236.8 2434.0 2551.7 10308.0 13705.4 14003.2 14719.5 15406.7 1683.5 1834.1 1840.5 1883.8 1900.3 105429.0 119588.4 119690.4 120225.4 120524.4 11511.6 11598.9 11793.6 11892.5 11921.3 14755.5 14756.3 14781.6 14789.6 14799.6 8079.9 8131.3 8353.5 8497.7 8512.7 8120.1 8133.8 8160.0 8264.5 8325.6 4511.7 4515.2 4522.1 4523.9 4598.1 1622.8 1851.2 1912.5 1926.9 1939.6 822.0 892.4 901.8 961.5 964.6 16574.3 20820.6 20953.5 20953.6 21084.9 904.4 941.2 947.0 950.6 987.2 10591.7 10922.8 11035.0 11096.4 11145.6 594657.0 598460.0 603469.0 615095.0 622325.0 92544.5 128440.0 130003.0 131064.0 131641.0 918.6 973.5 995.1 998.6 1003.5 18949.6 19488.5 19870.5 19937.9 19967.5 54158.0 55205.4 56091.7 56752.0 57626.6 78645.0 81775.0 82355.0 83020.8 83646.2 6957.3 7896.0 7898.3 8051.8 8218.1 10448.8 10964.2 11164.2 12460.9 12849.9 10816.3 10947.0 11044.9 11068.4 11196.6 10623.7 10772.5 10821.7 10875.4 11001.7 11058.7 11421.2 11427.7 11554.7 11576.4 6928.1 7280.8 7924.1 7958.2 8288.3 9896.9 10980.9 11044.0 11158.9 11349.5 08:46:24 INFO - PROCESS | 1634 | 08:46:24 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:5443.80 Median:5004.75 stddev:3011.55 (60.2%) stddev-sans-first:3004.12 08:46:24 INFO - PROCESS | 1634 | Values: 1329.5 1465.4 1571.6 1758.4 1770.4 7538.4 7815.2 8123.3 8553.1 8560.6 7676.1 8065.5 8294.8 8305.0 8400.4 2323.7 2352.9 2354.7 2360.6 2361.8 2069.5 2095.6 2265.8 2309.4 2325.9 2110.3 2133.9 2136.4 2138.9 2139.6 2258.1 2334.5 2334.9 2335.4 2337.1 2316.6 2339.8 2353.9 2356.3 2362.3 8296.5 8356.0 8378.0 8384.0 8392.4 5017.4 5167.9 5184.0 5188.0 5199.2 3092.7 3320.9 3484.4 3614.1 3617.3 1032.5 1050.9 1106.9 1109.5 1110.4 10622.8 10636.5 10643.4 10646.0 10667.3 4511.6 4570.0 4652.1 4723.1 4787.6 9565.4 9570.1 9570.1 9585.9 9589.7 5166.3 5262.1 5263.2 5271.9 5292.2 4536.5 4599.0 4675.3 4787.1 4803.5 3756.2 3767.0 3806.6 3842.6 3861.3 1255.4 1264.5 1266.2 1272.5 1279.6 616.6 875.2 893.4 903.6 912.0 5578.8 5583.4 5648.7 5675.9 5781.5 432.4 441.1 441.3 449.3 455.1 3344.1 3784.4 3788.0 3788.6 3791.2 9631.8 9977.5 10237.4 10255.8 10259.2 10178.5 10200.9 10206.3 10222.7 10237.8 7361.5 7744.9 7888.4 7896.5 7902.4 5272.4 5398.6 5563.3 5591.3 5659.2 9215.4 9306.0 9352.7 9414.8 9448.5 9368.8 9369.5 9475.1 9478.6 9533.5 7008.6 7132.3 7139.3 7139.6 7145.5 7605.0 7674.8 7765.8 7785.5 7787.3 7120.0 7298.3 7394.7 7418.5 7436.4 7190.7 7195.0 7297.8 7309.6 7337.0 6583.2 6679.0 6693.2 6763.1 6772.2 4891.0 4899.5 4903.6 4969.3 4992.1 4183.0 4877.0 4915.6 5093.0 5144.2 08:46:24 INFO - PROCESS | 1634 | 08:46:24 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4265.61 Median:4679.73 stddev:1298.53 (27.7%) stddev-sans-first:1267.07 08:46:24 INFO - PROCESS | 1634 | Values: 269.7 278.7 284.5 301.7 312.2 4159.7 4331.0 4348.6 4727.6 4902.5 4047.5 4067.9 5055.0 5079.5 5083.7 2941.8 3671.8 3706.3 3827.6 3915.7 3316.5 3484.1 3875.4 3883.0 3962.9 3595.6 3921.3 3944.3 3950.3 3980.3 3917.5 3929.6 3957.9 3969.3 3984.2 3479.6 3488.3 3495.8 3496.2 3499.4 4009.5 4739.3 4947.9 4962.3 5000.0 3807.9 4464.8 4477.6 4498.1 4503.3 4971.3 5057.2 5320.5 5342.6 5353.6 3677.9 3790.9 3810.3 3835.5 3849.5 5499.7 5519.8 5575.5 5594.5 5620.9 3551.6 3691.0 3756.9 3835.3 3927.7 3419.9 3714.8 3914.4 3968.3 3978.3 3805.7 4203.3 4348.6 4425.3 4520.4 4687.6 5160.7 5340.2 5357.2 5383.3 1101.6 1256.9 1259.7 1267.3 1268.7 682.9 825.8 881.1 885.1 894.6 4195.8 4369.5 4542.4 4625.9 5019.3 4771.3 5465.9 5465.9 5466.2 5498.9 4183.5 4248.4 4580.5 4671.8 5019.1 2091.7 2677.4 2691.1 2727.3 2745.0 4323.0 5300.8 5331.5 5336.5 5376.4 4071.7 4995.0 5095.9 5121.0 5297.8 4938.6 5058.9 5695.7 5703.4 5711.2 4796.8 5038.2 5346.5 5461.4 5531.0 4580.1 5346.8 5395.0 5438.8 5448.5 5353.8 5636.7 5671.3 5679.7 5681.8 3721.8 3726.2 3743.2 3747.0 3758.5 4247.0 4978.8 5062.1 5157.9 5387.2 4966.8 5322.5 5336.7 5349.7 5371.2 4670.2 4817.5 5372.3 5388.5 5408.3 3727.5 4332.0 5079.8 5173.4 5339.0 5083.9 5275.7 5291.9 5311.8 5422.4 4719.4 4968.5 5171.6 5282.8 5287.8 08:46:24 INFO - PROCESS | 1634 | 08:46:24 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:19618.62 Median:55722.02 stddev:29106.62 (52.2%) stddev-sans-first:29153.20 08:46:24 INFO - PROCESS | 1634 | Values: 587.9 665.0 676.9 761.8 794.7 13655.6 13825.0 14150.6 14275.4 14370.2 12647.1 12884.7 12956.3 13007.0 13036.2 786.6 811.6 812.2 813.6 814.1 654.5 675.4 678.7 788.8 822.0 861.0 869.6 873.5 873.8 875.2 869.2 869.3 869.8 870.0 870.0 498.6 511.7 614.2 640.2 640.6 13867.9 14259.2 14285.9 14296.4 14313.7 13219.5 13453.7 13535.9 13581.3 13604.3 12058.7 12108.3 12225.1 12496.3 12877.8 4437.3 4474.9 4496.8 4566.1 4632.2 70476.0 91971.3 94618.5 97172.5 97347.5 10583.9 10681.4 10783.0 10953.5 10966.5 20969.4 21083.4 21116.8 21123.5 21413.0 7577.7 7686.4 7703.2 7703.8 7901.7 3688.9 4117.3 4233.9 4377.5 4423.6 3324.8 3423.4 3425.3 3432.0 3436.1 636.7 638.6 643.7 660.4 662.6 591.5 601.4 607.5 610.3 622.0 8601.8 8683.6 8693.6 8741.3 8809.0 548.9 581.9 586.6 603.2 626.4 3151.8 3153.0 3234.3 3264.5 3280.7 96956.0 98170.5 100483.0 105358.5 111046.4 90990.0 91013.6 92685.5 94818.0 95158.5 81107.2 81227.0 81361.6 84434.6 85373.0 5465.7 6265.3 6268.2 6435.6 6501.3 52667.5 53234.5 53386.7 55375.4 56068.6 51672.5 58368.7 67850.0 75282.0 78532.6 9034.3 10626.8 10798.8 10864.1 10954.9 11352.0 11570.9 11601.4 11731.9 12117.3 11118.3 11148.1 11241.2 11408.8 11584.2 10876.7 11135.2 11176.8 11250.3 11299.9 10595.0 10713.5 10773.7 10859.1 10862.6 9151.0 9307.4 9375.7 9423.6 9600.5 9243.5 9322.8 9555.8 9608.0 9608.9 08:46:24 INFO - PROCESS | 1634 | -------- Summary: end -------- 08:46:24 INFO - PROCESS | 1634 | 08:46:25 INFO - TEST-INFO | 1634: exit 0 08:46:25 INFO - TEST-OK | dromaeo_css | took 1119454ms 08:46:25 INFO - TEST-START | kraken 08:46:25 INFO - operating with platform_type : mac_ 08:46:25 INFO - Initialising browser for kraken test... 08:46:25 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpiJjVit/profile http://localhost:49240/getInfo.html) 08:46:27 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 08:46:27 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 08:46:27 INFO - PROCESS | 1644 | colorDepth:24 08:46:27 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 08:46:27 INFO - PROCESS | 1644 | __metrics 08:46:28 INFO - PROCESS | 1644 | 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" 08:46:28 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:46:28 INFO - TEST-INFO | 1644: exit 0 08:46:28 INFO - Browser initialized. 08:46:28 INFO - Running cycle 1/1 for kraken test... 08:46:28 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpiJjVit/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:46:40 INFO - PROCESS | 1645 | RSS: Main: 217411584 08:46:40 INFO - PROCESS | 1645 | 08:46:43 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 08:46:44 INFO - PROCESS | 1645 | RSS: Main: 266813440 08:46:44 INFO - PROCESS | 1645 | 08:46:47 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 08:46:47 INFO - PROCESS | 1645 | RSS: Main: 277241856 08:46:47 INFO - PROCESS | 1645 | 08:46:50 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 08:46:51 INFO - PROCESS | 1645 | RSS: Main: 376537088 08:46:51 INFO - PROCESS | 1645 | 08:46:53 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 08:46:53 INFO - PROCESS | 1645 | RSS: Main: 278286336 08:46:53 INFO - PROCESS | 1645 | 08:46:56 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:46:56 INFO - PROCESS | 1645 | RSS: Main: 279625728 08:46:56 INFO - PROCESS | 1645 | 08:46:59 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 08:47:00 INFO - PROCESS | 1645 | RSS: Main: 320196608 08:47:00 INFO - PROCESS | 1645 | 08:47:03 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 08:47:03 INFO - PROCESS | 1645 | RSS: Main: 320339968 08:47:03 INFO - PROCESS | 1645 | 08:47:06 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 08:47:07 INFO - PROCESS | 1645 | RSS: Main: 318607360 08:47:07 INFO - PROCESS | 1645 | 08:47:08 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:47:09 INFO - PROCESS | 1645 | RSS: Main: 295923712 08:47:09 INFO - PROCESS | 1645 | 08:47:11 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:47:11 INFO - PROCESS | 1645 | RSS: Main: 298295296 08:47:11 INFO - PROCESS | 1645 | 08:47:14 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:47:14 INFO - PROCESS | 1645 | RSS: Main: 300457984 08:47:14 INFO - PROCESS | 1645 | 08:47:17 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:47:18 INFO - PROCESS | 1645 | RSS: Main: 306077696 08:47:18 INFO - PROCESS | 1645 | 08:47:21 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:47:21 INFO - PROCESS | 1645 | RSS: Main: 305733632 08:47:21 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 08:47:23 INFO - PROCESS | 1645 | RSS: Main: 349487104 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | __start_tp_report 08:47:23 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 08:47:23 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 08:47:23 INFO - PROCESS | 1645 | |i|pagename|runs| 08:47:23 INFO - PROCESS | 1645 | |0;ai-astar;115;115;114;115;115;122;165;159;113;114 08:47:23 INFO - PROCESS | 1645 | |1;audio-beat-detection;143;115;114;148;113;112;111;113;141;115 08:47:23 INFO - PROCESS | 1645 | |2;audio-dft;185;182;184;184;183;182;182;182;183;184 08:47:23 INFO - PROCESS | 1645 | |3;audio-fft;82;59;59;90;63;59;60;59;83;59 08:47:23 INFO - PROCESS | 1645 | |4;audio-oscillator;91;70;95;69;68;69;91;89;100;125 08:47:23 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;90;87;90;86;89;86;91;87;88;86 08:47:23 INFO - PROCESS | 1645 | |6;imaging-darkroom;105;151;94;96;96;95;91;150;127;95 08:47:23 INFO - PROCESS | 1645 | |7;imaging-desaturate;98;124;123;80;85;83;85;84;84;83 08:47:23 INFO - PROCESS | 1645 | |8;json-parse-financial;56;54;53;55;57;56;57;58;57;57 08:47:23 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;64;75;76;78;72;70;70;70;69;75 08:47:23 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;82;84;83;82;84;84;87;86;84;83 08:47:23 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;146;167;154;149;144;149;151;149;195;162 08:47:23 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;167;169;168;165;165;168;168;165;168;166 08:47:23 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;68;68;67;66;66;66;66;65;67;70 08:47:23 INFO - PROCESS | 1645 | __end_tp_report 08:47:23 INFO - PROCESS | 1645 | __start_cc_report 08:47:23 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2018 08:47:23 INFO - PROCESS | 1645 | __end_cc_report 08:47:23 INFO - PROCESS | 1645 | __startTimestamp1479487643945__endTimestamp 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | ------- Summary: start ------- 08:47:23 INFO - PROCESS | 1645 | Number of tests: 14 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:124.70 Median:115.00 stddev:19.86 (17.3%) stddev-sans-first:20.75 08:47:23 INFO - PROCESS | 1645 | Values: 115.0 115.0 114.0 115.0 115.0 122.0 165.0 159.0 113.0 114.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:122.50 Median:115.00 stddev:14.98 (13.0%) stddev-sans-first:13.94 08:47:23 INFO - PROCESS | 1645 | Values: 143.0 115.0 114.0 148.0 113.0 112.0 111.0 113.0 141.0 115.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.50 stddev:1.10 (0.6%) stddev-sans-first:0.93 08:47:23 INFO - PROCESS | 1645 | Values: 185.0 182.0 184.0 184.0 183.0 182.0 182.0 182.0 183.0 184.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:67.30 Median:61.50 stddev:12.45 (20.2%) stddev-sans-first:12.01 08:47:23 INFO - PROCESS | 1645 | Values: 82.0 59.0 59.0 90.0 63.0 59.0 60.0 59.0 83.0 59.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:79.50 stddev:18.29 (23.0%) stddev-sans-first:19.33 08:47:23 INFO - PROCESS | 1645 | Values: 91.0 70.0 95.0 69.0 68.0 69.0 91.0 89.0 100.0 125.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:88.50 stddev:1.89 (2.1%) stddev-sans-first:1.86 08:47:23 INFO - PROCESS | 1645 | Values: 90.0 87.0 90.0 86.0 89.0 86.0 91.0 87.0 88.0 86.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:110.00 Median:94.50 stddev:23.70 (25.1%) stddev-sans-first:25.07 08:47:23 INFO - PROCESS | 1645 | Values: 105.0 151.0 94.0 96.0 96.0 95.0 91.0 150.0 127.0 95.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:92.90 Median:84.00 stddev:16.82 (20.0%) stddev-sans-first:17.73 08:47:23 INFO - PROCESS | 1645 | Values: 98.0 124.0 123.0 80.0 85.0 83.0 85.0 84.0 84.0 83.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:57.00 stddev:1.56 (2.7%) stddev-sans-first:1.66 08:47:23 INFO - PROCESS | 1645 | Values: 56.0 54.0 53.0 55.0 57.0 56.0 57.0 58.0 57.0 57.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:73.50 stddev:4.15 (5.6%) stddev-sans-first:3.27 08:47:23 INFO - PROCESS | 1645 | Values: 64.0 75.0 76.0 78.0 72.0 70.0 70.0 70.0 69.0 75.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:1.60 (1.9%) stddev-sans-first:1.54 08:47:23 INFO - PROCESS | 1645 | Values: 82.0 84.0 83.0 82.0 84.0 84.0 87.0 86.0 84.0 83.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:156.60 Median:152.50 stddev:15.25 (10.0%) stddev-sans-first:15.69 08:47:23 INFO - PROCESS | 1645 | Values: 146.0 167.0 154.0 149.0 144.0 149.0 151.0 149.0 195.0 162.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:168.00 stddev:1.52 (0.9%) stddev-sans-first:1.62 08:47:23 INFO - PROCESS | 1645 | Values: 167.0 169.0 168.0 165.0 165.0 168.0 168.0 165.0 168.0 166.0 08:47:23 INFO - PROCESS | 1645 | 08:47:23 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.45 (2.2%) stddev-sans-first:1.48 08:47:23 INFO - PROCESS | 1645 | Values: 68.0 68.0 67.0 66.0 66.0 66.0 66.0 65.0 67.0 70.0 08:47:23 INFO - PROCESS | 1645 | -------- Summary: end -------- 08:47:23 INFO - PROCESS | 1645 | 08:47:24 INFO - TEST-INFO | 1645: exit 0 08:47:24 INFO - TEST-OK | kraken | took 59348ms 08:47:24 INFO - SUITE-END | took 1178s 08:47:25 INFO - Completed test suite (00:19:39) 08:47:25 INFO - javascript benchmark 08:47:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.9007329780106, 3310.2882880180773, 3325.517799919012, 3351.7151042161413, 3385.6106547747017, 5599.804006859761, 5623.381428042912, 5705.6006079574345, 5706.229658996034, 5726.255586773719, 5633.80281690141, 5688.829335119953, 5711.171880077386, 5717.056685646874, 5725.942740572593, 5193.9740301298625, 5208.947910520903, 5209.244659524366, 5236.162214045753, 5253.6322457428, 5163.896722065557, 5200.583953283747, 5309.787608495655, 5310.20346947957, 5338.572914166877, 5181.248718935752, 5191.0656081905245, 5210.791568337262, 5213.426523082458, 5254.737263136847, 5196.064708352514, 5218.843434696965, 5261.053010458116, 5276.683398996034, 5298.284731561228, 5160.741962901878, 5217.034848553029, 5218.947810521884, 5230.320058392384, 5230.633855630107, 5553.722313884289, 5591.0, 5612.298462692163, 5641.492265696107, 5647.29408823897, 6112.132124055526, 6125.322810980791, 6161.537884658624, 6166.7533298667995, 6176.660283684396, 6246.781362652331, 6305.936940630627, 6306.369363063703, 6310.905336419957, 6321.620702757849, 5631.493165615114, 5678.545716342765, 5725.599208055396, 5765.394633563457, 5773.653580785166, 6371.362863713592, 6397.168368112116, 6412.35876412355, 6418.614883107029, 6425.8714825704155, 6099.573029887954, 6109.480694141048, 6115.388461153938, 6125.754969801247, 6128.295246046672, 6097.0, 6115.0215965446205, 6139.982816839433, 6224.221972253468, 6262.5929314594405, 6070.878582428415, 6141.481992383102, 6142.232220972379, 6237.095621135, 6240.656763878031, 5978.372270911578, 6202.689865506707, 6243.094751261042, 6270.0, 6275.341089185661, 4860.878478038077, 4904.6321525885705, 4912.926306105412, 4918.040982008545, 4929.186684197139, 4319.589638984291, 4358.215521206213, 4380.277254253013, 4441.444819397575, 4455.485135054098, 5682.573806964494, 5703.201551782754, 5703.515201207943, 5709.0, 5711.771529138871, 6191.690415479208, 6204.906926396107, 6210.844728881723, 6218.129461875341, 6229.127922090911, 5647.971760141173, 5716.228309178291, 5720.742566584514, 5722.284714410699, 5723.942760572424, 5338.199270109515, 5356.84827762033, 5368.302120724281, 5368.946310536845, 5372.62391632582, 6315.90526142099, 6338.683065846597, 6351.460125889351, 6367.4906007519285, 6371.362863713684, 6254.124422580933, 6330.968345158245, 6341.682915854281, 6348.460380867678, 6350.904736428864, 6397.104405383157, 6404.071409645669, 6411.230652321751, 6413.038044293393, 6415.807525774234, 5902.822915312548, 5965.642061476326, 6037.0, 6207.565470417027, 6213.627182369072, 6151.015837465984, 6179.320274769681, 6201.503879689704, 6213.720382582885, 6296.779612713533, 4893.09477746618, 5856.707164641836, 6027.547933905063, 6030.306514750684, 6037.245344331958, 5970.671613061323, 5999.760009599567, 6003.129546215687, 6013.5189184865985, 6019.097135429721, 5749.741261643152, 5811.883762324645, 5815.302163740378, 5820.417958204146, 5828.912566311424, 5939.643621382731, 6094.146819445193, 6095.695215239131, 6101.542384321105, 6114.266288045463, 5853.2976042875125, 5898.174255604133, 5898.8230353089475, 5905.409459054232, 5939.821805345847], "value": 5681.020233336409, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.99526004739954, 516.8371962831709, 529.8304542546387, 553.9196816461613, 566.3147591414388, 30515.694843051562, 38785.83642490726, 39915.60084399159, 40025.79987100061, 41802.37296440556, 41036.794816025955, 41358.58641413585, 41414.171716565725, 41472.79263603678, 41581.58418415822, 9448.952755236214, 9625.0, 9634.98832622575, 9637.18083962864, 9639.084286992724, 8161.673533058671, 9386.249100072013, 9503.0, 9519.143277105059, 9530.618775248982, 9503.334766566342, 9552.90447095527, 9555.426674399523, 9560.521973901312, 9569.04309569041, 9483.05169483053, 9533.0, 9548.80902381952, 9550.140487356102, 9552.283578731553, 9430.95284523573, 9523.428594284365, 9526.90473095274, 9527.666531671428, 9530.809383812284, 39347.01632459183, 39677.40483892744, 39720.60279397198, 39769.60230397688, 39831.00422489432, 3152.574402455662, 3285.9342813143844, 3429.725621950238, 3660.9084772880624, 3856.267309211241, 29507.40985180306, 29998.100056998108, 30128.0, 30530.084097477113, 30691.925782597504, 3115.937681246385, 3193.5050067239613, 3198.280386912926, 3215.1640573450836, 3217.9034628961167, 514559.0, 515791.0, 516108.0, 602452.0, 634623.0, 63473.04790428147, 63861.680691596244, 63931.36068639254, 64049.35950640434, 64084.35915640877, 34704.826475867965, 34943.82528087343, 65645.67177164083, 65940.34059659342, 66036.66981665061, 15583.84416155824, 15645.921770391074, 15646.843531564766, 15674.0, 15706.607334816721, 10029.79940401188, 11841.467133978991, 11953.820692689616, 11955.0, 12240.326782026896, 19076.0, 19107.80892191088, 19112.0, 19134.61730765377, 19168.13743381551, 7305.45209109319, 7417.406607471389, 7455.664495097733, 7472.476926615085, 7540.17058123606, 854.2695994924375, 933.4166146158651, 980.9215262778961, 1049.5696764326742, 1051.2940148709938, 30004.04973676704, 30089.097327080224, 30149.547756783664, 30540.54189187164, 30624.08127756171, 869.9173578510031, 935.335911502827, 975.6389836178633, 1027.5941003303653, 1051.1958351860833, 15700.450484232997, 15704.057756534416, 15720.606984825243, 15773.0, 16018.439354622766, 375021.0, 378274.0, 379180.10409947776, 379206.0, 673091.0, 58150.0, 58643.0, 58674.0, 58687.70656146777, 58777.0, 1148.6439203846835, 1220.4324988880214, 1258.4588626890525, 1358.8844948179515, 1377.6555861034742, 29049.85475072611, 30892.0, 31377.8431107843, 32296.192595185308, 32714.34571308608, 55822.0, 56767.71616141893, 56998.0, 59965.0, 59976.0, 615202.0, 619370.0, 622426.0, 622525.0, 623045.0, 26432.735672643026, 26917.327066823487, 41034.38448423216, 41309.0, 41456.79271603622, 45347.31979020383, 46001.769991151166, 46212.53787462082, 46426.07147856957, 46846.53153468422, 37773.81113094417, 38359.42460863033, 38952.0, 39018.414723778595, 39484.60515394809, 38628.0, 38725.419118713806, 38747.6125238744, 39521.2095758089, 39564.60435395609, 31452.842735786173, 34442.65557344495, 38679.6132038676, 38819.6118038816, 38982.0, 27873.721262787112, 28966.42067158687, 33960.83019584886, 34499.65500345065, 34614.096224706875, 25970.610440843026, 26008.34979125537, 26096.8695156523, 26340.868295658398, 26477.33806654849], "value": 19849.31568927855, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.3172980957323, 366.17427700535296, 370.6238168259219, 379.73798079325275, 398.780670631153, 15032.699346013072, 15033.548993530198, 16040.679186416264, 19921.302754403598, 20845.68731469029, 18617.0, 18744.6790502696, 18791.812081879176, 18960.90519547404, 19137.52156196096, 5596.552275817935, 5955.570663040882, 5958.642481451105, 5959.553033522481, 5977.282726072855, 5065.366829146356, 5873.207117039209, 6492.480601551888, 6568.047633093199, 6623.768168114117, 6292.937070629304, 6576.473882089422, 6586.37429444202, 6587.868242635144, 6599.274079851212, 5998.8500287492725, 6126.17296664949, 6487.643179625119, 6521.250056243545, 6573.079768832391, 5118.181091025418, 5389.703566303851, 5463.672179669233, 5681.0910254359105, 5725.656460612377, 12150.33173175475, 12309.322987235699, 12349.75300493985, 12379.00967922573, 23038.539229215323, 2164.0910817456706, 2171.0230396321563, 2236.7651396603287, 2434.0385547708734, 2551.6810398700163, 10308.020738029874, 13705.383257753425, 14003.159810411407, 14719.484818031424, 15406.691866162613, 1683.4781217822515, 1834.0863403857243, 1840.5122642499748, 1883.8226108682074, 1900.3158862809378, 105429.0, 119588.40205798915, 119690.4015479917, 120225.39887300595, 120524.39737801255, 11511.597094101666, 11598.94200528992, 11793.646190614296, 11892.524299027942, 11921.344326061977, 14755.483558075415, 14756.262186890612, 14781.556553303419, 14789.556313310617, 14799.556013319618, 8079.878801817978, 8131.349492040741, 8353.498790072616, 8497.70258040978, 8512.74461766148, 8120.066192387951, 8133.785319629386, 8160.0, 8264.545450000187, 8325.625346859406, 4511.684182107221, 4515.187266292033, 4522.140793249272, 4523.932141017887, 4598.126355992417, 1622.8052633683797, 1851.1947302923272, 1912.4836294200488, 1926.9036548172817, 1939.5829896572304, 821.9630116644765, 892.3625147853631, 901.8016036471965, 961.5288508630721, 964.5948701545368, 16574.337026518806, 20820.583588328453, 20953.47616309574, 20953.580928381347, 21084.894575527025, 904.4482865452069, 941.1670671455674, 946.9526523673788, 950.6292545907102, 987.1609132237684, 10591.735206619896, 10922.836157457645, 11034.951679590426, 11096.445177741081, 11145.554177832959, 594657.0, 598460.0, 603469.0, 615095.0, 622325.0, 92544.53727731318, 128440.0, 130003.0, 131064.0, 131641.0, 918.5682729117304, 973.4889183178747, 995.1292618958412, 998.55065220651, 1003.518311210623, 18949.621007580048, 19488.512787180432, 19870.50323741918, 19937.900310498353, 19967.500812479804, 54158.0, 55205.44794552003, 56091.71954140203, 56752.0, 57626.55933601694, 78645.0, 81775.0, 82355.0, 83020.75468868093, 83646.16353836383, 6957.304269573205, 7896.0, 7898.328642065489, 8051.838963220585, 8218.054923683852, 10448.843267350843, 10964.232503724663, 11164.218504704917, 12460.937695311466, 12849.935750321189, 10816.296940698829, 10947.014768670859, 11044.889551104707, 11068.395082713123, 11196.600424946882, 10623.734406639896, 10772.515236814206, 10821.67534973952, 10875.401852898167, 11001.724956875822, 11058.723531911766, 11421.200515963803, 11427.714307142389, 11554.653360399201, 11576.421178941187, 6928.099347084847, 7280.781576552712, 7924.1283458820635, 7958.204179581995, 8288.336933045463, 9896.901030989598, 10980.890191097988, 11044.0, 11158.888411115784, 11349.4892729829], "value": 9435.872407213796, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.5014369611395, 1465.421158642336, 1571.55210764932, 1758.3757765993064, 1770.4423106721374, 7538.434617403689, 7815.179406162342, 8123.268905798477, 8553.101924297946, 8560.571971401436, 7676.117246516652, 8065.475744076631, 8294.83410331793, 8305.003399592042, 8400.41197116202, 2323.6514522821544, 2352.9411764705847, 2354.7174339079334, 2360.6223004319313, 2361.8464799788044, 2069.544700165961, 2095.6227878981776, 2265.7764807004282, 2309.4457330240707, 2325.8604483730946, 2110.2930518276394, 2133.925312614059, 2136.423165745248, 2138.9465263368384, 2139.5613899150635, 2258.1306197114063, 2334.533093381322, 2334.8949297281492, 2335.3694502484236, 2337.1118974789642, 2316.5714342846627, 2339.7894189523026, 2353.882305884716, 2356.2577787996725, 2362.2913126062112, 8296.460730052528, 8356.0, 8378.0, 8384.03583587889, 8392.370572207108, 5017.37282839645, 5167.870803229949, 5184.0, 5188.040212560651, 5199.220116982445, 3092.659807421182, 3320.8837690680953, 3484.4250698634696, 3614.078410005458, 3617.3488772021024, 1032.4785983078498, 1050.9106725928305, 1106.9446527673583, 1109.5450865145333, 1110.3781882145943, 10622.840657390145, 10636.521356538975, 10643.414612196326, 10646.0, 10667.253292269545, 4511.616512596466, 4569.954300456953, 4652.116097941448, 4723.149833030019, 4787.569118779326, 9565.426074435418, 9570.090841370058, 9570.090841370058, 9585.90414095864, 9589.664361747306, 5166.250893620404, 5262.105442074857, 5263.157894736823, 5271.894562108813, 5292.232626269247, 4536.5236650151255, 4599.0, 4675.345451636774, 4787.066522028241, 4803.543663351976, 3756.229972855558, 3766.981165094157, 3806.562245341766, 3842.558105817811, 3861.34357159278, 1255.3974092435683, 1264.4815625593646, 1266.2019931697012, 1272.5355245335386, 1279.635303938373, 616.5591602004588, 875.159846547309, 893.4326702543931, 903.5617725403174, 911.9680811171577, 5578.776848926079, 5583.3858275589655, 5648.661080335193, 5675.858103547444, 5781.508571771363, 432.38169417732945, 441.0825482995362, 441.2652932866805, 449.285635839011, 455.0830077394054, 3344.0970937847133, 3784.413415920537, 3787.962120378761, 3788.6211378861894, 3791.2227993261317, 9631.759206019906, 9977.501124943868, 10237.385756854612, 10255.846162307571, 10259.17926565873, 10178.491075446198, 10200.948995255125, 10206.285560010729, 10222.693319200436, 10237.795244095076, 7361.521501102411, 7744.922550774533, 7888.447808653454, 7896.486728362638, 7902.407319451064, 5272.367315922113, 5398.64908780928, 5563.276774019513, 5591.27313449249, 5659.207710920557, 9215.44707317563, 9306.022867598804, 9352.67265645699, 9414.811703766023, 9448.527573621428, 9368.765780855532, 9369.484678342757, 9475.147236748726, 9478.620855165991, 9533.47565883883, 7008.649567521706, 7132.322429369202, 7139.2503787101605, 7139.607321597362, 7145.4998150128995, 7604.961975190088, 7674.846503069795, 7765.805854853448, 7785.532868027936, 7787.338076263581, 7120.0, 7298.2701729826595, 7394.704211831467, 7418.517796343221, 7436.442266830118, 7190.676419561237, 7195.0, 7297.817554561178, 7309.634518274172, 7336.963315183603, 6583.242927063449, 6678.966605166943, 6693.230278518033, 6763.086983257197, 6772.187337519529, 4890.951090489049, 4899.534544218293, 4903.6077113831525, 4969.279454479152, 4992.126377883928, 4182.9581704182565, 4876.975615121902, 4915.582175514978, 5092.9745351273, 5144.202648589475], "value": 4327.54106714486, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.71883553122666, 278.6753432251427, 284.4538486106675, 301.654620393047, 312.2334668389105, 4159.7312819590015, 4330.956690433095, 4348.608625223729, 4727.55088266615, 4902.534259245375, 4047.51429828421, 4067.8779636610875, 5054.974725126378, 5079.466656001122, 5083.669561478501, 2941.808782429135, 3671.76133551319, 3706.277275931181, 3827.5598306194725, 3915.6867450604027, 3316.535685004101, 3484.094135524769, 3875.418687196915, 3883.0, 3962.9009274768073, 3595.6404359563967, 3921.313770090223, 3944.2505923874373, 3950.2889479893606, 3980.263651224518, 3917.5298964124345, 3929.646331830149, 3957.920841583152, 3969.3450580654157, 3984.2230765000836, 3479.599846017715, 3488.3023395320783, 3495.7727747696317, 3496.230829217568, 3499.4400895856543, 4009.458723072377, 4739.336492890998, 4947.851564453073, 4962.255661650745, 4999.9500004999845, 3807.9337785420125, 4464.754438505881, 4477.619402350771, 4498.077894031716, 4503.279475283972, 4971.328870602459, 5057.179415410449, 5320.52115309624, 5342.626016178869, 5353.625246232766, 3677.921974084634, 3790.8960526907554, 3810.2950954073585, 3835.520559930009, 3849.5188101487315, 5499.7250137492965, 5519.751611177467, 5575.498205161555, 5594.496495315441, 5620.887582248373, 3551.6093229744706, 3690.9815450922574, 3756.887293381203, 3835.328817456934, 3927.6857851371824, 3419.8714424239943, 3714.832832522543, 3914.412838074312, 3968.269826363209, 3978.3236849735613, 3805.657490825839, 4203.285441474956, 4348.608625223745, 4425.344793124164, 4520.412346394947, 4687.632745463519, 5160.7419629018395, 5340.225667278223, 5357.19642053695, 5383.335249051666, 1101.6419663609358, 1256.8680288569751, 1259.7228609706033, 1267.2649863079391, 1268.6574624851423, 682.8975653651893, 825.8396952282053, 881.0660699658255, 885.1458342699326, 894.579547612621, 4195.811188496525, 4369.453818272716, 4542.432195975503, 4625.912910466038, 5019.29729837823, 4771.260454629538, 5465.890682186334, 5465.945340546623, 5466.234727138204, 5498.890022199534, 4183.4979802423295, 4248.383984322256, 4580.450345958439, 4671.8364857229835, 5019.092744757042, 2091.7385326834146, 2677.437738075026, 2691.1253842501264, 2727.2500062482977, 2744.9588256176175, 4322.956770432318, 5300.826661069021, 5331.546818520392, 5336.519713225828, 5376.38171610262, 4071.6742660587083, 4995.025969935828, 5095.949040509621, 5120.974395128001, 5297.8344764151825, 4938.629602779806, 5058.898822023539, 5695.658260504383, 5703.351768308815, 5711.228984087095, 4796.808127674994, 5038.168702164248, 5346.545543628679, 5461.399246083007, 5531.03206938776, 4580.083983203306, 5346.812861549827, 5394.973025134849, 5438.755256013568, 5448.536874365724, 5353.785848566014, 5636.701500589433, 5671.347795003462, 5679.734152862038, 5681.77272909079, 3721.776693398405, 3726.1988672434472, 3743.1765011697385, 3746.9508537609513, 3758.5489741231227, 4246.95753042478, 4978.8257410990445, 5062.063518249136, 5157.922631160461, 5387.245785589942, 4966.826161084345, 5322.494363035506, 5336.67979921206, 5349.652272602269, 5371.248025276386, 4670.182718024264, 4817.518248175154, 5372.274742909657, 5388.488093631099, 5408.269883565668, 3727.4595183698752, 4331.956680433155, 5079.796808127633, 5173.396247163391, 5339.012282727708, 5083.87290317745, 5275.657082289639, 5291.920621190762, 5311.814086506954, 5422.403535611158, 4719.4100737407825, 4968.503149685147, 5171.637985341141, 5282.777505721584, 5287.788488460573], "value": 3852.3912227260817, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.8952854750341, 664.9767258145965, 676.8578598494316, 761.7562380038387, 794.7059587952458, 13655.590332290034, 13825.032247742662, 14150.575482735509, 14275.357608907598, 14370.20963846988, 12647.051471139655, 12884.742305153915, 12956.287404192766, 13007.0, 13036.217826930368, 786.6381464526324, 811.6104269950428, 812.163471624228, 813.5769399912042, 814.1207495904409, 654.460070441885, 675.368530424055, 678.6742363665444, 788.846174995876, 821.9794505137389, 861.0356400831078, 869.6173683579236, 873.4802792338548, 873.8252349530088, 875.1511034296753, 869.2307308032374, 869.2784988459626, 869.8477766390888, 869.9652013919435, 869.9826003479926, 498.55707035525853, 511.6597462687308, 614.2168734863085, 640.2381166411955, 640.6348381422591, 13867.861321386757, 14259.215743134124, 14285.928570357186, 14296.356663950148, 14313.71372572553, 13219.545849956497, 13453.66365840852, 13535.864641353559, 13581.320933953262, 13604.31978401076, 12058.69853253667, 12108.334041627708, 12225.144239903122, 12496.25022498653, 12877.806832897513, 4437.2900335946415, 4474.8881277967985, 4496.797644106023, 4566.06395688883, 4632.2356811126465, 70476.0, 91971.25867195243, 94618.52690736503, 97172.51413742887, 97347.51326243323, 10583.94708026455, 10681.412522311191, 10782.997762863535, 10953.507092180871, 10966.506507207036, 20969.370918872457, 21083.367498975054, 21116.78883211179, 21123.471913202295, 21413.036413361435, 7577.734779282699, 7686.423518236154, 7703.1526532081425, 7703.807404814813, 7901.723439679584, 3688.9262214755554, 4117.279476091672, 4233.936490952638, 4377.474703035592, 4423.557644235615, 3324.7506437016837, 3423.383790917609, 3425.3149370125575, 3432.0390290718638, 3436.1409647588102, 636.710296814948, 638.6391688695873, 643.6813777180325, 660.4122331125302, 662.5858838226109, 591.5179129009844, 601.4046094366546, 607.5413063137391, 610.3164455809522, 621.9875602487925, 8601.827963440695, 8683.56582170889, 8693.608787604573, 8741.30069594431, 8808.955955220183, 548.8600406896215, 581.892349915267, 586.642148289543, 603.2480300336273, 626.4330780643525, 3151.8108913465267, 3152.952705709416, 3234.2884565395575, 3264.4613638749342, 3280.7375409967144, 96956.0, 98170.5091474538, 100483.0, 105358.473207635, 111046.44476777564, 90990.0, 91013.63479547812, 92685.5365723167, 94818.0, 95158.52420737852, 81107.18892810996, 81227.0, 81361.6104812892, 84434.57782711046, 85373.0, 5465.688234823534, 6265.342139075331, 6268.1851359323, 6435.646039467687, 6501.349865013461, 52667.473325266255, 53234.46765532295, 53386.73306633442, 55375.44624553864, 56068.5982850432, 51672.48327516676, 58368.708156458946, 67850.0, 75282.0, 78532.60733696294, 9034.277257819227, 10626.8405973912, 10798.784024319626, 10864.130869530261, 10954.945225273823, 11352.0, 11570.94214528922, 11601.419929003347, 11731.941340293586, 12117.333546654667, 11118.277311974696, 11148.108151347711, 11241.213115081866, 11408.82886756716, 11584.189106762446, 10876.673699789018, 11135.220534562503, 11176.776464470502, 11250.268732532359, 11299.872834207625, 10595.0, 10713.464326783786, 10773.676789696321, 10859.131269498579, 10862.619808306672, 9151.039140890376, 9307.441553506811, 9375.671851485165, 9423.57593908262, 9600.471974041215, 9243.491607961358, 9322.766930826783, 9555.761105972406, 9608.0, 9608.893200423752], "value": 6866.224016806351, "unit": "score"}], "name": "dromaeo_css", "value": 7040.697366497893, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 115.0, 115.0, 122.0, 165.0, 159.0, 113.0, 114.0], "value": 124.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 114.0, 148.0, 113.0, 112.0, 111.0, 113.0, 141.0, 115.0], "value": 122.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 184.0, 184.0, 183.0, 182.0, 182.0, 182.0, 183.0, 184.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 90.0, 63.0, 59.0, 60.0, 59.0, 83.0, 59.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 95.0, 69.0, 68.0, 69.0, 91.0, 89.0, 100.0, 125.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 86.0, 89.0, 86.0, 91.0, 87.0, 88.0, 86.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 151.0, 94.0, 96.0, 96.0, 95.0, 91.0, 150.0, 127.0, 95.0], "value": 110.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 124.0, 123.0, 80.0, 85.0, 83.0, 85.0, 84.0, 84.0, 83.0], "value": 92.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 53.0, 55.0, 57.0, 56.0, 57.0, 58.0, 57.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 76.0, 78.0, 72.0, 70.0, 70.0, 70.0, 69.0, 75.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 83.0, 82.0, 84.0, 84.0, 87.0, 86.0, 84.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 167.0, 154.0, 149.0, 144.0, 149.0, 151.0, 149.0, 195.0, 162.0], "value": 156.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 168.0, 165.0, 165.0, 168.0, 168.0, 165.0, 168.0, 166.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 66.0, 66.0, 66.0, 66.0, 65.0, 67.0, 70.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1477.4, "alertThreshold": 2.0}]} 08:47:25 INFO - Return code: 0 08:47:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:47:25 ERROR - # TBPL SUCCESS # 08:47:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:47:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:47:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:47:25 INFO - 2016-11-18 08:47:25.279 screenresolution[1649:9803] starting screenresolution argv=screenresolution get 08:47:25 INFO - 2016-11-18 08:47:25.284 screenresolution[1649:9803] Display 0: 1600x1200x32@60 08:47:25 INFO - 2016-11-18 08:47:25.290 screenresolution[1650:9806] starting screenresolution argv=screenresolution list 08:47:25 INFO - Available Modes on Display 0 08:47:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:47:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:47:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:47:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:47:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:47:25 INFO - Intel Iris: 08:47:25 INFO - Chipset Model: Intel Iris 08:47:25 INFO - Type: GPU 08:47:25 INFO - Bus: Built-In 08:47:25 INFO - VRAM (Dynamic, Max): 1536 MB 08:47:25 INFO - Vendor: Intel (0x8086) 08:47:25 INFO - Device ID: 0x0a2e 08:47:25 INFO - Revision ID: 0x0009 08:47:25 INFO - Displays: 08:47:25 INFO - Display: 08:47:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:47:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:47:25 INFO - Display Serial Number: 1600x1200 60 08:47:25 INFO - Main Display: Yes 08:47:25 INFO - Mirror: Off 08:47:25 INFO - Online: Yes 08:47:25 INFO - Rotation: Supported 08:47:25 INFO - Return code: 0 08:47:25 INFO - Running post-action listener: _resource_record_post_action 08:47:25 INFO - [mozharness: 2016-11-18 16:47:25.528368Z] Finished run-tests step (success) 08:47:25 INFO - Running post-run listener: _resource_record_post_run 08:47:25 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37203968; Write bytes: 653066240; Read time: 240; Write time: 1857 08:47:25 INFO - TinderboxPrint: CPU usage
53.3% 08:47:25 INFO - TinderboxPrint: I/O read bytes / time
37,203,968 / 240 08:47:25 INFO - TinderboxPrint: I/O write bytes / time
653,066,240 / 1,857 08:47:25 INFO - TinderboxPrint: CPU idle
2,268.2 (46.7%) 08:47:25 INFO - TinderboxPrint: CPU system
968.1 (19.9%) 08:47:25 INFO - TinderboxPrint: CPU user
1,617.3 (33.3%) 08:47:25 INFO - TinderboxPrint: Swap in / out
390,443,008 / 0 08:47:25 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 348531200; Write bytes: 345974784; Read time: 27690; Write time: 719 08:47:25 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 35102720; Write bytes: 295946240; Read time: 218; Write time: 1107 08:47:26 INFO - Running post-run listener: _upload_blobber_files 08:47:26 INFO - Blob upload gear active. 08:47:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:47:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:47:26 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'] 08:47:26 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 08:47:26 INFO - (blobuploader) - INFO - Open directory for files ... 08:47:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:47:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:47:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:47:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:47:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:47:27 INFO - (blobuploader) - INFO - Done attempting. 08:47:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:47:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:47:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:47:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:47:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:47:28 INFO - (blobuploader) - INFO - Done attempting. 08:47:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:47:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:47:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:47:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:47:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:47:31 INFO - (blobuploader) - INFO - Done attempting. 08:47:31 INFO - (blobuploader) - INFO - Iteration through files over. 08:47:31 INFO - Return code: 0 08:47:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:47:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:47:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03fa4858a3d686e2ec32c693f04211f25868fb49c45a372a780f954d9bd02f8bda26c1ade85e4bf67e86cbe4cd4a98f1342ed67024b4e322bd1f58da5b164918", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c996afb148d49e9083b87cc9bc6725ac549759717d14f28eae7367b0b4ee3d7391026c9164744df15917551aec50380efbd430c4d6bde3f739d5d244be741dbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f1638a6ad7bc0d3e5e7197e94d1f85d59d4568e0845db392bf4e6f4f83981fdbc04d29e977584ec28aae0740cd3f4b28a8ec06f5ff2b940234b78108a0a2a50"} 08:47:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:47:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:47:31 INFO - Contents: 08:47:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03fa4858a3d686e2ec32c693f04211f25868fb49c45a372a780f954d9bd02f8bda26c1ade85e4bf67e86cbe4cd4a98f1342ed67024b4e322bd1f58da5b164918", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c996afb148d49e9083b87cc9bc6725ac549759717d14f28eae7367b0b4ee3d7391026c9164744df15917551aec50380efbd430c4d6bde3f739d5d244be741dbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f1638a6ad7bc0d3e5e7197e94d1f85d59d4568e0845db392bf4e6f4f83981fdbc04d29e977584ec28aae0740cd3f4b28a8ec06f5ff2b940234b78108a0a2a50"} 08:47:31 INFO - Running post-run listener: copy_logs_to_upload_dir 08:47:31 INFO - Copying logs to upload dir... 08:47:31 INFO - mkdir: /builds/slave/test/build/upload/logs 08:47:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.192433 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-18 08:47:31.146038) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 08:47:31.149617) ========= 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.NScDpMLbik/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.qyG6EosauB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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/03fa4858a3d686e2ec32c693f04211f25868fb49c45a372a780f954d9bd02f8bda26c1ade85e4bf67e86cbe4cd4a98f1342ed67024b4e322bd1f58da5b164918", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c996afb148d49e9083b87cc9bc6725ac549759717d14f28eae7367b0b4ee3d7391026c9164744df15917551aec50380efbd430c4d6bde3f739d5d244be741dbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f1638a6ad7bc0d3e5e7197e94d1f85d59d4568e0845db392bf4e6f4f83981fdbc04d29e977584ec28aae0740cd3f4b28a8ec06f5ff2b940234b78108a0a2a50"} build_url:https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011097 build_url: 'https://queue.taskcluster.net/v1/task/GalWbEGDQ2qHUQOolDtSPw/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/03fa4858a3d686e2ec32c693f04211f25868fb49c45a372a780f954d9bd02f8bda26c1ade85e4bf67e86cbe4cd4a98f1342ed67024b4e322bd1f58da5b164918", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c996afb148d49e9083b87cc9bc6725ac549759717d14f28eae7367b0b4ee3d7391026c9164744df15917551aec50380efbd430c4d6bde3f739d5d244be741dbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f1638a6ad7bc0d3e5e7197e94d1f85d59d4568e0845db392bf4e6f4f83981fdbc04d29e977584ec28aae0740cd3f4b28a8ec06f5ff2b940234b78108a0a2a50"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 08:47:31.182296) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:47:31.182634) ========= 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.NScDpMLbik/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.qyG6EosauB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.005103 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:47:31.231108) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 08:47:31.231450) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 08:47:31.231796) ========= ========= Total master_lag: 0.17 =========