builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0105 starttime: 1481833033.67 results: success (0) buildid: 20161215065301 builduid: d36040f6112a477592d6caa23b050253 revision: e92c50c1c5cdd95b59f0a72f15e3aa2841908beb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:13.670191) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:13.671012) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:13.671560) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:13.701731) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:13.702372) ========= 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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-15 12:17: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% 9.56M=0.001s 2016-12-15 12:17:13 (9.56 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113730 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:13.842916) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:13.843251) ========= 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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.026252 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:13.892795) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-15 12:17:13.893376) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e92c50c1c5cdd95b59f0a72f15e3aa2841908beb --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 e92c50c1c5cdd95b59f0a72f15e3aa2841908beb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9QBf4sYoNR/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.ZndAM8bt4d/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-15 12:17:13,988 truncating revision to first 12 chars 2016-12-15 12:17:13,988 Setting DEBUG logging. 2016-12-15 12:17:13,988 attempt 1/10 2016-12-15 12:17:13,988 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e92c50c1c5cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-15 12:17:14,843 unpacking tar archive at: mozilla-aurora-e92c50c1c5cd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.108566 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-15 12:17:15.027280) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:15.027589) ========= script_repo_revision: e92c50c1c5cdd95b59f0a72f15e3aa2841908beb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:15.027991) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:15.028268) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 12:17:15.049095) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-15 12:17:15.049406) ========= /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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 12:17:15 INFO - MultiFileLogger online at 20161215 12:17:15 in /builds/slave/test 12:17: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 12:17:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:17:15 INFO - {'append_to_log': False, 12:17:15 INFO - 'base_work_dir': '/builds/slave/test', 12:17:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:17:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:17:15 INFO - 'branch': 'Mozilla-Aurora', 12:17:15 INFO - 'buildbot_json_path': 'buildprops.json', 12:17:15 INFO - 'config_files': ('talos/mac_config.py',), 12:17:15 INFO - 'default_actions': ('clobber', 12:17:15 INFO - 'read-buildbot-config', 12:17:15 INFO - 'download-and-extract', 12:17:15 INFO - 'populate-webroot', 12:17:15 INFO - 'create-virtualenv', 12:17:15 INFO - 'install', 12:17:15 INFO - 'run-tests'), 12:17:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:17:15 INFO - 'download_minidump_stackwalk': True, 12:17:15 INFO - 'download_symbols': 'ondemand', 12:17:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:17:15 INFO - 'tooltool.py': '/tools/tooltool.py', 12:17:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:17:15 INFO - '/tools/misc-python/virtualenv.py')}, 12:17:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:17:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:17:15 INFO - 'installer_path': 'installer.exe', 12:17:15 INFO - 'log_level': 'info', 12:17:15 INFO - 'log_name': 'talos', 12:17:15 INFO - 'log_to_console': True, 12:17:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:17:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:17:15 INFO - 'opt_config_files': (), 12:17:15 INFO - 'pip_index': False, 12:17:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:17:15 INFO - 'cmd': ('bash', 12:17:15 INFO - '-c', 12:17:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:17:15 INFO - 'enabled': True, 12:17:15 INFO - 'halt_on_failure': False, 12:17:15 INFO - 'name': 'check_screen_resolution'},), 12:17:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:17:15 INFO - 'cmd': ('bash', 12:17:15 INFO - '-c', 12:17:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:17:15 INFO - 'enabled': True, 12:17:15 INFO - 'halt_on_failure': False, 12:17:15 INFO - 'name': 'check_screen_resolution'},), 12:17:15 INFO - 'run_cmd_checks_enabled': True, 12:17:15 INFO - 'sps_profile': False, 12:17:15 INFO - 'sps_profile_interval': 0, 12:17:15 INFO - 'suite': 'dromaeojs', 12:17:15 INFO - 'system_bits': '32', 12:17:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:17:15 INFO - 'title': 't-yosemite-r7-0105', 12:17:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:17:15 INFO - 'use_talos_json': True, 12:17:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:17:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:17:15 INFO - 'work_dir': 'build'} 12:17:15 INFO - [mozharness: 2016-12-15 20:17:15.257722Z] Running clobber step. 12:17:15 INFO - Running pre-action listener: _resource_record_pre_action 12:17:15 INFO - Running main action method: clobber 12:17:15 INFO - rmtree: /builds/slave/test/build 12:17:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:17:17 INFO - Running post-action listener: _resource_record_post_action 12:17:17 INFO - [mozharness: 2016-12-15 20:17:17.554902Z] Finished clobber step (success) 12:17:17 INFO - [mozharness: 2016-12-15 20:17:17.555027Z] Running read-buildbot-config step. 12:17:17 INFO - Running pre-action listener: _resource_record_pre_action 12:17:17 INFO - Running main action method: read_buildbot_config 12:17:17 INFO - Using buildbot properties: 12:17:17 INFO - { 12:17:17 INFO - "project": "", 12:17:17 INFO - "product": "firefox", 12:17:17 INFO - "script_repo_revision": "production", 12:17:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:17:17 INFO - "repository": "", 12:17:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:17:17 INFO - "buildid": "20161215065301", 12:17:17 INFO - "pgo_build": "False", 12:17:17 INFO - "basedir": "/builds/slave/test", 12:17:17 INFO - "buildnumber": 44, 12:17:17 INFO - "slavename": "t-yosemite-r7-0105", 12:17:17 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:17:17 INFO - "platform": "yosemite_r7", 12:17:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:17:17 INFO - "branch": "mozilla-aurora", 12:17:17 INFO - "repo_path": "releases/mozilla-aurora", 12:17:17 INFO - "revision": "e92c50c1c5cdd95b59f0a72f15e3aa2841908beb", 12:17:17 INFO - "stage_platform": "macosx64", 12:17:17 INFO - "builduid": "d36040f6112a477592d6caa23b050253", 12:17:17 INFO - "slavebuilddir": "test" 12:17:17 INFO - } 12:17:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 12:17:17 INFO - Running post-action listener: _resource_record_post_action 12:17:17 INFO - [mozharness: 2016-12-15 20:17:17.558345Z] Finished read-buildbot-config step (success) 12:17:17 INFO - [mozharness: 2016-12-15 20:17:17.558428Z] Running download-and-extract step. 12:17:17 INFO - Running pre-action listener: _resource_record_pre_action 12:17:17 INFO - Running main action method: download_and_extract 12:17:17 INFO - mkdir: /builds/slave/test/build/tests 12:17: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')]} 12:17:17 INFO - trying https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 12:17:17 INFO - Downloading https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/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 12:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/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 12:17:18 INFO - Downloaded 1199 bytes. 12:17:18 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:17:18 INFO - Using the following test package requirements: 12:17:18 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:17:18 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:18 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 12:17:18 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:18 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 12:17:18 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:18 INFO - u'jsshell-mac.zip'], 12:17:18 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:18 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 12:17:18 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:17:18 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:18 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 12:17:18 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:18 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 12:17:18 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:18 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 12:17:18 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:18 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 12:17:18 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:17:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:17:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:17:18 INFO - Fetch https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:17:19 INFO - Http code: 200 12:17:19 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 12:17:19 INFO - x-amz-cf-id: t3Wp8U2kc_CxcH3szj354M2HT9eRy_rzOVitBQfBM3kLCOBJq7gOwg== 12:17:19 INFO - x-amz-version-id: KXaBrwy_E6ov3lcyA6M3nJr7o6v.5SV5 12:17:19 INFO - Expected file size: 16281118 12:17:19 INFO - Obtained file size: 16281118 12:17: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 12:17:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:17:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:17:26 INFO - Fetch https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:17:27 INFO - Http code: 200 12:17:27 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 12:17:27 INFO - x-amz-cf-id: TM3hP8tTwlaqW8RV3Dg4o-M2pG9cXyV88JdO31WDSayVleDUwsYunA== 12:17:27 INFO - x-amz-version-id: KXaBrwy_E6ov3lcyA6M3nJr7o6v.5SV5 12:17:27 INFO - Expected file size: 16281118 12:17:27 INFO - Obtained file size: 16281118 12:17:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 12:17:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:17:33 INFO - Fetch https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 12:17:34 INFO - Http code: 200 12:17:34 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 12:17:34 INFO - x-amz-cf-id: sVX6YaZFjGKlEE9LN9z-zckBWKBqrFiyeLKno2qXZgjJTqdBCdNK0g== 12:17:34 INFO - x-amz-version-id: BR8Bah14GIGyva.7ew9gLwA9wnY5eI3I 12:17:34 INFO - Expected file size: 14409038 12:17:34 INFO - Obtained file size: 14409038 12:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:34 INFO - trying https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:17:34 INFO - Downloading https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 12:17:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/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 12:17:37 INFO - Downloaded 109752178 bytes. 12:17:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:17:37 INFO - mkdir: /builds/slave/test/properties 12:17:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:17:37 INFO - Writing to file /builds/slave/test/properties/build_url 12:17:37 INFO - Contents: 12:17:37 INFO - build_url:https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:17:38 INFO - Running post-action listener: _resource_record_post_action 12:17:38 INFO - Running post-action listener: set_extra_try_arguments 12:17:38 INFO - [mozharness: 2016-12-15 20:17:38.368756Z] Finished download-and-extract step (success) 12:17:38 INFO - [mozharness: 2016-12-15 20:17:38.368863Z] Running populate-webroot step. 12:17:38 INFO - Running pre-action listener: _resource_record_pre_action 12:17:38 INFO - Running main action method: populate_webroot 12:17:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:17:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:17:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:17:38 INFO - '--tppagecycles', 12:17:38 INFO - '7'], 12:17:38 INFO - 'tests': ['tsvgm']}}, 12:17:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:17:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:17:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:17:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:17:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:17:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:17:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:38 INFO - 'talos_options': ['--disable-e10s'], 12:17:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:17:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:17:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:38 INFO - 'talos_options': ['--disable-e10s'], 12:17:38 INFO - 'tests': ['damp', 'tps']}, 12:17:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:38 INFO - 'tests': ['damp', 'tps']}, 12:17:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:17:38 INFO - 'tests': ['dromaeo_dom']}, 12:17:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:17:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:17:38 INFO - 'tests': ['basic_compositor_video']}, 12:17:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:17:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:17:38 INFO - 'tests': ['a11yr', 12:17:38 INFO - 'ts_paint', 12:17:38 INFO - 'tpaint', 12:17:38 INFO - 'sessionrestore', 12:17:38 INFO - 'sessionrestore_no_auto_restore', 12:17:38 INFO - 'tabpaint']}, 12:17:38 INFO - 'other-e10s': {'tests': ['a11yr', 12:17:38 INFO - 'ts_paint', 12:17:38 INFO - 'tpaint', 12:17:38 INFO - 'sessionrestore', 12:17:38 INFO - 'sessionrestore_no_auto_restore', 12:17:38 INFO - 'tabpaint']}, 12:17:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:17:38 INFO - 'ts_paint', 12:17:38 INFO - 'tpaint', 12:17:38 INFO - 'sessionrestore', 12:17:38 INFO - 'sessionrestore_no_auto_restore', 12:17:38 INFO - 'tabpaint']}, 12:17:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:17:38 INFO - 'ts_paint', 12:17:38 INFO - 'tpaint', 12:17:38 INFO - 'sessionrestore', 12:17:38 INFO - 'sessionrestore_no_auto_restore', 12:17:38 INFO - 'tabpaint']}, 12:17:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:17:38 INFO - 'tests': ['a11yr', 12:17:38 INFO - 'ts_paint', 12:17:38 INFO - 'tpaint', 12:17:38 INFO - 'sessionrestore', 12:17:38 INFO - 'sessionrestore_no_auto_restore', 12:17:38 INFO - 'tabpaint']}, 12:17:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:17:38 INFO - 'tests': ['a11yr', 12:17:38 INFO - 'ts_paint', 12:17:38 INFO - 'tpaint', 12:17:38 INFO - 'sessionrestore', 12:17:38 INFO - 'sessionrestore_no_auto_restore', 12:17:38 INFO - 'tabpaint']}, 12:17:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:17:38 INFO - 'tests': ['tsvgx', 12:17:38 INFO - 'tsvgr_opacity', 12:17:38 INFO - 'tart', 12:17:38 INFO - 'tscrollx', 12:17:38 INFO - 'cart']}, 12:17:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:17:38 INFO - 'tsvgr_opacity', 12:17:38 INFO - 'tart', 12:17:38 INFO - 'tscrollx', 12:17:38 INFO - 'cart']}, 12:17:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:38 INFO - 'talos_options': ['--disable-e10s'], 12:17:38 INFO - 'tests': ['tp5o']}, 12:17:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:38 INFO - 'tests': ['tp5o']}, 12:17:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:38 INFO - 'talos_options': ['--disable-e10s', 12:17:38 INFO - '--xperf_path', 12:17:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:17:38 INFO - 'tests': ['tp5n']}, 12:17:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:38 INFO - 'talos_options': ['--xperf_path', 12:17:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:17:38 INFO - 'tests': ['tp5n']}}, 12:17:38 INFO - 'talos.zip': {'path': '', 12:17:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:17:38 INFO - Running post-action listener: _resource_record_post_action 12:17:38 INFO - [mozharness: 2016-12-15 20:17:38.388042Z] Finished populate-webroot step (success) 12:17:38 INFO - [mozharness: 2016-12-15 20:17:38.388145Z] Running create-virtualenv step. 12:17:38 INFO - Running pre-action listener: _resource_record_pre_action 12:17:38 INFO - Running main action method: create_virtualenv 12:17:38 INFO - Creating virtualenv /builds/slave/test/build/venv 12:17:38 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 12:17:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:17:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:17:38 INFO - Using real prefix '/tools/python27' 12:17:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:17:39 INFO - Installing distribute.............................................................................................................................................................................................done. 12:17:42 INFO - Installing pip.................done. 12:17:42 INFO - Return code: 0 12:17:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:17:42 INFO - Reading from file tmpfile_stdout 12:17:42 INFO - Output received: 12:17:42 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) 12:17:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101741570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f394b3fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015dfd30>, '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 0x101445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d7b20>, '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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 12:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9QBf4sYoNR/Render', 12:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:42 INFO - 'HOME': '/Users/cltbld', 12:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:42 INFO - 'LOGNAME': 'cltbld', 12:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:42 INFO - 'MOZ_NO_REMOTE': '1', 12:17:42 INFO - 'NO_EM_RESTART': '1', 12:17:42 INFO - 'PAGER': '/bin/cat', 12:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:42 INFO - 'PWD': '/builds/slave/test', 12:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:42 INFO - 'SHELL': '/bin/bash', 12:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZndAM8bt4d/Listeners', 12:17:42 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 12:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:42 INFO - 'USER': 'cltbld', 12:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:42 INFO - 'XPC_FLAGS': '0x0', 12:17:42 INFO - 'XPC_SERVICE_NAME': '0', 12:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:17:42 INFO - Cleaning up... 12:17:42 INFO - Return code: 0 12:17:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101741570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f394b3fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015dfd30>, '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 0x101445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d7b20>, '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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 12:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9QBf4sYoNR/Render', 12:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:42 INFO - 'HOME': '/Users/cltbld', 12:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:42 INFO - 'LOGNAME': 'cltbld', 12:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:42 INFO - 'MOZ_NO_REMOTE': '1', 12:17:42 INFO - 'NO_EM_RESTART': '1', 12:17:42 INFO - 'PAGER': '/bin/cat', 12:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:42 INFO - 'PWD': '/builds/slave/test', 12:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:42 INFO - 'SHELL': '/bin/bash', 12:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZndAM8bt4d/Listeners', 12:17:42 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 12:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:42 INFO - 'USER': 'cltbld', 12:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:42 INFO - 'XPC_FLAGS': '0x0', 12:17:42 INFO - 'XPC_SERVICE_NAME': '0', 12:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:42 INFO - Downloading/unpacking psutil>=3.1.1 12:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:17:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:17:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:17:46 INFO - Installing collected packages: psutil 12:17:46 INFO - Running setup.py install for psutil 12:17:46 INFO - building 'psutil._psutil_osx' extension 12:17:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:17: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 12:17:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:17:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:17:47 INFO - building 'psutil._psutil_posix' extension 12:17:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:17:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:17:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:17:47 INFO - ^ 12:17:47 INFO - 1 warning generated. 12:17:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:17:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:17:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:17:47 INFO - Successfully installed psutil 12:17:47 INFO - Cleaning up... 12:17:47 INFO - Return code: 0 12:17:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101741570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f394b3fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015dfd30>, '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 0x101445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d7b20>, '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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 12:17:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:17:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9QBf4sYoNR/Render', 12:17:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:47 INFO - 'HOME': '/Users/cltbld', 12:17:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:47 INFO - 'LOGNAME': 'cltbld', 12:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:47 INFO - 'MOZ_NO_REMOTE': '1', 12:17:47 INFO - 'NO_EM_RESTART': '1', 12:17:47 INFO - 'PAGER': '/bin/cat', 12:17:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:47 INFO - 'PWD': '/builds/slave/test', 12:17:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:47 INFO - 'SHELL': '/bin/bash', 12:17:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZndAM8bt4d/Listeners', 12:17:47 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 12:17:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:47 INFO - 'USER': 'cltbld', 12:17:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:47 INFO - 'XPC_FLAGS': '0x0', 12:17:47 INFO - 'XPC_SERVICE_NAME': '0', 12:17:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:17:50 INFO - Installing collected packages: mozsystemmonitor 12:17:50 INFO - Running setup.py install for mozsystemmonitor 12:17:50 INFO - Successfully installed mozsystemmonitor 12:17:50 INFO - Cleaning up... 12:17:51 INFO - Return code: 0 12:17:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:17: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')]} 12:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17: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')]} 12:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17: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 0x1017cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101741570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f394b3fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015dfd30>, '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 0x101445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d7b20>, '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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 12:17: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 12:17: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 12:17:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9QBf4sYoNR/Render', 12:17:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:51 INFO - 'HOME': '/Users/cltbld', 12:17:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:51 INFO - 'LOGNAME': 'cltbld', 12:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:51 INFO - 'MOZ_NO_REMOTE': '1', 12:17:51 INFO - 'NO_EM_RESTART': '1', 12:17:51 INFO - 'PAGER': '/bin/cat', 12:17:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:51 INFO - 'PWD': '/builds/slave/test', 12:17:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:51 INFO - 'SHELL': '/bin/bash', 12:17:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZndAM8bt4d/Listeners', 12:17:51 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 12:17:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:51 INFO - 'USER': 'cltbld', 12:17:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:51 INFO - 'XPC_FLAGS': '0x0', 12:17:51 INFO - 'XPC_SERVICE_NAME': '0', 12:17:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:51 INFO - Downloading/unpacking jsonschema==2.5.1 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:17:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:54 INFO - Downloading functools32-3.2.3-2.tar.gz 12:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:17:54 INFO - warning: no files found matching '*.txt' 12:17:54 INFO - no previously-included directories found matching 'build' 12:17:54 INFO - no previously-included directories found matching 'dist' 12:17:54 INFO - no previously-included directories found matching '.git*' 12:17:54 INFO - Installing collected packages: jsonschema, functools32 12:17:54 INFO - Running setup.py install for functools32 12:17:54 INFO - warning: no files found matching '*.txt' 12:17:54 INFO - no previously-included directories found matching 'build' 12:17:54 INFO - no previously-included directories found matching 'dist' 12:17:54 INFO - no previously-included directories found matching '.git*' 12:17:54 INFO - Successfully installed jsonschema functools32 12:17:54 INFO - Cleaning up... 12:17:54 INFO - Return code: 0 12:17:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101741570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f394b3fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015dfd30>, '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 0x101445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d7b20>, '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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 12:17:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:17:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9QBf4sYoNR/Render', 12:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:54 INFO - 'HOME': '/Users/cltbld', 12:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:54 INFO - 'LOGNAME': 'cltbld', 12:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:54 INFO - 'MOZ_NO_REMOTE': '1', 12:17:54 INFO - 'NO_EM_RESTART': '1', 12:17:54 INFO - 'PAGER': '/bin/cat', 12:17:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:54 INFO - 'PWD': '/builds/slave/test', 12:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:54 INFO - 'SHELL': '/bin/bash', 12:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZndAM8bt4d/Listeners', 12:17:54 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 12:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:54 INFO - 'USER': 'cltbld', 12:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:54 INFO - 'XPC_FLAGS': '0x0', 12:17:54 INFO - 'XPC_SERVICE_NAME': '0', 12:17:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:17:54 INFO - Cleaning up... 12:17:54 INFO - Return code: 0 12:17:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101741570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f394b3fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015dfd30>, '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 0x101445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d7b20>, '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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 12:17:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:17:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9QBf4sYoNR/Render', 12:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:54 INFO - 'HOME': '/Users/cltbld', 12:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:54 INFO - 'LOGNAME': 'cltbld', 12:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:54 INFO - 'MOZ_NO_REMOTE': '1', 12:17:54 INFO - 'NO_EM_RESTART': '1', 12:17:54 INFO - 'PAGER': '/bin/cat', 12:17:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:54 INFO - 'PWD': '/builds/slave/test', 12:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:54 INFO - 'SHELL': '/bin/bash', 12:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZndAM8bt4d/Listeners', 12:17:54 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 12:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:54 INFO - 'USER': 'cltbld', 12:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:54 INFO - 'XPC_FLAGS': '0x0', 12:17:54 INFO - 'XPC_SERVICE_NAME': '0', 12:17:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:55 INFO - Downloading/unpacking blobuploader==1.2.4 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17:57 INFO - Downloading blobuploader-1.2.4.tar.gz 12:17:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:17:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:17:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17:58 INFO - Downloading docopt-0.6.1.tar.gz 12:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:17:59 INFO - Installing collected packages: blobuploader, requests, docopt 12:17:59 INFO - Running setup.py install for blobuploader 12:17:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:17:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:17:59 INFO - Running setup.py install for requests 12:17:59 INFO - Running setup.py install for docopt 12:17:59 INFO - Successfully installed blobuploader requests docopt 12:17:59 INFO - Cleaning up... 12:17:59 INFO - Return code: 0 12:17:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101741570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f394b3fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015dfd30>, '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 0x101445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d7b20>, '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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 12:17:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:17:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9QBf4sYoNR/Render', 12:17:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:59 INFO - 'HOME': '/Users/cltbld', 12:17:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:59 INFO - 'LOGNAME': 'cltbld', 12:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:59 INFO - 'MOZ_NO_REMOTE': '1', 12:17:59 INFO - 'NO_EM_RESTART': '1', 12:17:59 INFO - 'PAGER': '/bin/cat', 12:17:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:59 INFO - 'PWD': '/builds/slave/test', 12:17:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:59 INFO - 'SHELL': '/bin/bash', 12:17:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZndAM8bt4d/Listeners', 12:17:59 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 12:17:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:59 INFO - 'USER': 'cltbld', 12:17:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:59 INFO - 'XPC_FLAGS': '0x0', 12:17:59 INFO - 'XPC_SERVICE_NAME': '0', 12:17:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:18:00 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-d7qnRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:18:00 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-g8Xoqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:18:00 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-FTf7jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:18:00 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-k4J_Db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:18:00 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-4CWa7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:18:00 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-K1WHvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:18:00 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-lTDXrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:18:00 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-XMZDRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:18:01 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-RMgNJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:18:01 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Q_H3W7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:18:01 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-gsq6Gq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:18:01 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-YNWPbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:18:01 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-2xPn8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:18:01 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-slaeED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:01 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-5mCSlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:18:01 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-DRl2tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:18:01 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-3VZFLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:18:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:18:01 INFO - Running setup.py install for manifestparser 12:18:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:18:02 INFO - Running setup.py install for mozcrash 12:18:02 INFO - Running setup.py install for mozdebug 12:18:02 INFO - Running setup.py install for mozdevice 12:18:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:18:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:18:02 INFO - Running setup.py install for mozfile 12:18:02 INFO - Running setup.py install for mozhttpd 12:18:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:18:02 INFO - Running setup.py install for mozinfo 12:18:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:18:02 INFO - Running setup.py install for mozInstall 12:18:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:18:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:18:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:18:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:18:03 INFO - Running setup.py install for mozleak 12:18:03 INFO - Running setup.py install for mozlog 12:18:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:18:03 INFO - Running setup.py install for moznetwork 12:18:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:18:03 INFO - Running setup.py install for mozprocess 12:18:03 INFO - Running setup.py install for mozprofile 12:18:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:18:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:18:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:18:03 INFO - Running setup.py install for mozrunner 12:18:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:18:04 INFO - Running setup.py install for mozscreenshot 12:18:04 INFO - Running setup.py install for moztest 12:18:04 INFO - Running setup.py install for mozversion 12:18:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:18:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:18:04 INFO - Cleaning up... 12:18:04 INFO - Return code: 0 12:18:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101741570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f394b3fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015dfd30>, '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 0x101445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d7b20>, '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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 12:18:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:18:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9QBf4sYoNR/Render', 12:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:04 INFO - 'HOME': '/Users/cltbld', 12:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:04 INFO - 'LOGNAME': 'cltbld', 12:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:04 INFO - 'MOZ_NO_REMOTE': '1', 12:18:04 INFO - 'NO_EM_RESTART': '1', 12:18:04 INFO - 'PAGER': '/bin/cat', 12:18:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:04 INFO - 'PWD': '/builds/slave/test', 12:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:04 INFO - 'SHELL': '/bin/bash', 12:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZndAM8bt4d/Listeners', 12:18:04 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 12:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:04 INFO - 'USER': 'cltbld', 12:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:04 INFO - 'XPC_FLAGS': '0x0', 12:18:04 INFO - 'XPC_SERVICE_NAME': '0', 12:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:18:04 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-jplcF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:18:04 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-mMX2EP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:18: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)) 12:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:18:05 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-_knLPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:18: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)) 12:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:18:05 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-hb1r_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:18: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)) 12:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:18:05 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-IUrCQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:18: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)) 12:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:18:05 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-wdVCJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:18:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:18:05 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-UoKBsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:18:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:18:05 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ErZhwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:18:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:18:05 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-tZ5wEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:18:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:18:05 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-debo94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:18:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:18:05 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-7YAzI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:18:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:18:05 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-9ytP9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:18: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)) 12:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:18:06 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-WxJLAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:18: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)) 12:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:18:06 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-86Mb4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:18: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)) 12:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:06 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-EONK_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:18: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)) 12:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:18:06 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-pumnGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:18:06 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)) 12:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:18:06 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-HkaLAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:18:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:18:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:18:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:18:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:18:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:18:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:18:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:09 INFO - Downloading blessings-1.6.tar.gz 12:18:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:18:09 INFO - Installing collected packages: blessings 12:18:09 INFO - Running setup.py install for blessings 12:18:09 INFO - Successfully installed blessings 12:18:09 INFO - Cleaning up... 12:18:09 INFO - Return code: 0 12:18:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:18:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:18:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:18:09 INFO - Reading from file tmpfile_stdout 12:18:09 INFO - Current package versions: 12:18:09 INFO - blessings == 1.6 12:18:09 INFO - blobuploader == 1.2.4 12:18:09 INFO - docopt == 0.6.1 12:18:09 INFO - functools32 == 3.2.3-2 12:18:09 INFO - jsonschema == 2.5.1 12:18:09 INFO - manifestparser == 1.1 12:18:09 INFO - mozInstall == 1.12 12:18:09 INFO - mozcrash == 1.0 12:18:09 INFO - mozdebug == 0.1 12:18:09 INFO - mozdevice == 0.48 12:18:09 INFO - mozfile == 1.2 12:18:09 INFO - mozhttpd == 0.7 12:18:09 INFO - mozinfo == 0.9 12:18:09 INFO - mozleak == 0.1 12:18:09 INFO - mozlog == 3.4 12:18:09 INFO - moznetwork == 0.27 12:18:09 INFO - mozprocess == 0.23 12:18:09 INFO - mozprofile == 0.28 12:18:09 INFO - mozrunner == 6.13 12:18:09 INFO - mozscreenshot == 0.1 12:18:09 INFO - mozsystemmonitor == 0.3 12:18:09 INFO - moztest == 0.7 12:18:09 INFO - mozversion == 1.4 12:18:09 INFO - psutil == 3.1.1 12:18:09 INFO - requests == 1.2.3 12:18:09 INFO - wsgiref == 0.1.2 12:18:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:10 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 0x1017cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101741570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f394b3fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015dfd30>, '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 0x101445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d7b20>, '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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 12:18:10 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 12:18:10 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 12:18:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9QBf4sYoNR/Render', 12:18:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:10 INFO - 'HOME': '/Users/cltbld', 12:18:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:10 INFO - 'LOGNAME': 'cltbld', 12:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:10 INFO - 'MOZ_NO_REMOTE': '1', 12:18:10 INFO - 'NO_EM_RESTART': '1', 12:18:10 INFO - 'PAGER': '/bin/cat', 12:18:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:10 INFO - 'PWD': '/builds/slave/test', 12:18:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:10 INFO - 'SHELL': '/bin/bash', 12:18:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZndAM8bt4d/Listeners', 12:18:10 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 12:18:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:10 INFO - 'USER': 'cltbld', 12:18:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:10 INFO - 'XPC_FLAGS': '0x0', 12:18:10 INFO - 'XPC_SERVICE_NAME': '0', 12:18:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:10 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)) 12:18:10 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)) 12:18:10 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)) 12:18:10 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)) 12:18:10 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)) 12:18:10 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)) 12:18:10 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)) 12:18:10 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)) 12:18:10 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)) 12:18:10 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)) 12:18:10 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)) 12:18:10 INFO - Cleaning up... 12:18:10 INFO - Return code: 0 12:18:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:18: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')]} 12:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18: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')]} 12:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:10 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 0x1017cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101741570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f394b3fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015dfd30>, '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 0x101445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d7b20>, '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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 12:18:10 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 12:18:10 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 12:18:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9QBf4sYoNR/Render', 12:18:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:10 INFO - 'HOME': '/Users/cltbld', 12:18:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:10 INFO - 'LOGNAME': 'cltbld', 12:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:10 INFO - 'MOZ_NO_REMOTE': '1', 12:18:10 INFO - 'NO_EM_RESTART': '1', 12:18:10 INFO - 'PAGER': '/bin/cat', 12:18:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:10 INFO - 'PWD': '/builds/slave/test', 12:18:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:10 INFO - 'SHELL': '/bin/bash', 12:18:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZndAM8bt4d/Listeners', 12:18:10 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 12:18:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:10 INFO - 'USER': 'cltbld', 12:18:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:10 INFO - 'XPC_FLAGS': '0x0', 12:18:10 INFO - 'XPC_SERVICE_NAME': '0', 12:18:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:18:11 INFO - Cleaning up... 12:18:11 INFO - Return code: 0 12:18:11 INFO - Running post-action listener: _resource_record_post_action 12:18:11 INFO - Running post-action listener: _start_resource_monitoring 12:18:11 INFO - Starting resource monitoring. 12:18:11 INFO - [mozharness: 2016-12-15 20:18:11.276496Z] Finished create-virtualenv step (success) 12:18:11 INFO - [mozharness: 2016-12-15 20:18:11.277466Z] Running install step. 12:18:11 INFO - Running pre-action listener: _resource_record_pre_action 12:18:11 INFO - Running main action method: install 12:18:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:18:11 INFO - Reading from file tmpfile_stdout 12:18:11 INFO - Detecting whether we're running mozinstall >=1.0... 12:18:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:18:11 INFO - Reading from file tmpfile_stdout 12:18:11 INFO - Output received: 12:18:11 INFO - Usage: mozinstall [options] installer 12:18:11 INFO - Options: 12:18:11 INFO - -h, --help show this help message and exit 12:18:11 INFO - -d DEST, --destination=DEST 12:18:11 INFO - Directory to install application into. [default: 12:18:11 INFO - "/builds/slave/test"] 12:18:11 INFO - --app=APP Application being installed. [default: firefox] 12:18:11 INFO - mkdir: /builds/slave/test/build/application 12:18:11 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'] 12:18:11 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 12:18:42 INFO - Reading from file tmpfile_stdout 12:18:42 INFO - Output received: 12:18:42 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:18:42 INFO - Running post-action listener: _resource_record_post_action 12:18:42 INFO - [mozharness: 2016-12-15 20:18:42.732580Z] Finished install step (success) 12:18:42 INFO - [mozharness: 2016-12-15 20:18:42.732740Z] Running run-tests step. 12:18:42 INFO - Running pre-action listener: _resource_record_pre_action 12:18:42 INFO - Running main action method: run_tests 12:18:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:18:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:18:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:18:42 INFO - 2016-12-15 12:18:42.747 screenresolution[1627:8162] starting screenresolution argv=screenresolution get 12:18:42 INFO - 2016-12-15 12:18:42.749 screenresolution[1627:8162] Display 0: 1600x1200x32@60 12:18:42 INFO - 2016-12-15 12:18:42.755 screenresolution[1628:8164] starting screenresolution argv=screenresolution list 12:18:42 INFO - Available Modes on Display 0 12:18:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:18:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:18:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:18:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:18:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:18:42 INFO - Intel Iris: 12:18:42 INFO - Chipset Model: Intel Iris 12:18:42 INFO - Type: GPU 12:18:42 INFO - Bus: Built-In 12:18:42 INFO - VRAM (Dynamic, Max): 1536 MB 12:18:42 INFO - Vendor: Intel (0x8086) 12:18:42 INFO - Device ID: 0x0a2e 12:18:42 INFO - Revision ID: 0x0009 12:18:42 INFO - Displays: 12:18:42 INFO - Display: 12:18:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:18:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:18:42 INFO - Display Serial Number: 1600x1200 60 12:18:42 INFO - Main Display: Yes 12:18:42 INFO - Mirror: Off 12:18:42 INFO - Online: Yes 12:18:42 INFO - Rotation: Supported 12:18:42 INFO - Return code: 0 12:18:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:18:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:18:42 INFO - Python 2.7.3 12:18:42 INFO - Return code: 0 12:18:42 INFO - grabbing minidump binary from tooltool 12:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:42 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015dfd30>, '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 0x101445db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015d7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:18:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:18:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:18:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:18:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:18:43 INFO - Return code: 0 12:18:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:18:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:18:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:18:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:18:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:18:43 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/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 12:18:43 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/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0105 --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 12:18:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9QBf4sYoNR/Render', 12:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:43 INFO - 'HOME': '/Users/cltbld', 12:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:43 INFO - 'LOGNAME': 'cltbld', 12:18:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:18:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:43 INFO - 'MOZ_NO_REMOTE': '1', 12:18:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:18:43 INFO - 'NO_EM_RESTART': '1', 12:18:43 INFO - 'PAGER': '/bin/cat', 12:18:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:43 INFO - 'PWD': '/builds/slave/test', 12:18:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:43 INFO - 'SHELL': '/bin/bash', 12:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZndAM8bt4d/Listeners', 12:18:43 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 12:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:43 INFO - 'USER': 'cltbld', 12:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:43 INFO - 'XPC_FLAGS': '0x0', 12:18:43 INFO - 'XPC_SERVICE_NAME': '0', 12:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:43 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/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 12:18:43 INFO - mozversion application_buildid: 20161215065301 12:18:43 INFO - mozversion application_changeset: e92c50c1c5cdd95b59f0a72f15e3aa2841908beb 12:18:43 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:18:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:18:43 INFO - mozversion application_name: Firefox 12:18:43 INFO - mozversion application_remotingname: firefox-dev 12:18:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:18:43 INFO - mozversion application_vendor: Mozilla 12:18:43 INFO - mozversion application_version: 52.0a2 12:18:43 INFO - mozversion platform_buildid: 20161215065301 12:18:43 INFO - mozversion platform_changeset: e92c50c1c5cdd95b59f0a72f15e3aa2841908beb 12:18:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:18:43 INFO - mozversion platform_version: 52.0a2 12:18:43 INFO - using testdate: 1481833123 12:18:43 INFO - actual date: 1481833123 12:18:43 INFO - starting webserver on 'localhost:49244' 12:18:43 INFO - SUITE-START | Running 2 tests 12:18:43 INFO - TEST-START | dromaeo_css 12:18:43 INFO - operating with platform_type : mac_ 12:18:43 INFO - Initialising browser for dromaeo_css test... 12:18:43 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmprFw_Sd/profile http://localhost:49244/getInfo.html) 12:18:45 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 12:18:45 INFO - PROCESS | 1638 | colorDepth:24 12:18:45 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 12:18:45 INFO - PROCESS | 1638 | __metrics 12:18:46 INFO - PROCESS | 1638 | 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" 12:18:46 INFO - PROCESS | 1638 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:18:46 INFO - TEST-INFO | 1638: exit 0 12:18:46 INFO - Browser initialized. 12:18:46 INFO - Running cycle 1/1 for dromaeo_css test... 12:18:46 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmprFw_Sd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:18:59 INFO - PROCESS | 1641 | RSS: Main: 238174208 12:18:59 INFO - PROCESS | 1641 | 12:19:50 INFO - PROCESS | 1641 | 1481833190019 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] 12:22:03 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 12:22:06 INFO - PROCESS | 1641 | RSS: Main: 437116928 12:22:06 INFO - PROCESS | 1641 | 12:25:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 12:25:10 INFO - PROCESS | 1641 | RSS: Main: 362594304 12:25:10 INFO - PROCESS | 1641 | 12:28:12 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 12:28:13 INFO - PROCESS | 1641 | RSS: Main: 365195264 12:28:13 INFO - PROCESS | 1641 | 12:31:15 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 12:31:16 INFO - PROCESS | 1641 | RSS: Main: 340148224 12:31:16 INFO - PROCESS | 1641 | 12:34:19 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 12:34:20 INFO - PROCESS | 1641 | RSS: Main: 325771264 12:34:20 INFO - PROCESS | 1641 | 12:37:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 12:37:23 INFO - PROCESS | 1641 | RSS: Main: 370270208 12:37:23 INFO - PROCESS | 1641 | 12:37:23 INFO - PROCESS | 1641 | __start_tp_report 12:37:23 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 12:37:23 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 12:37:23 INFO - PROCESS | 1641 | |i|pagename|runs| 12:37:23 INFO - PROCESS | 1641 | |0;dojo.html;3230.5315729219265;3249.4800831866905;3253.6095668519765;3263.8368081595913;3270.525773762803;5360.919586206205;5413.891722165555;5415.323084614423;5419.403865574794;5424.674519528826;5482.80810171644;5501.229827824108;5516.420775818541;5586.692731899744;5588.6646801191955;5090.109230884599;5107.489251074881;5125.666831655949;5201.271821944931;5203.531682148606;5099.311592934943;5122.769475373599;5131.204663277198;5132.101882170624;5223.608229382792;5166.457521960197;5181.766820493087;5183.974080129594;5191.402988656315;5193.974030129844;5179.611529135311;5182.429932707418;5189.766460509286;5202.245674377232;5209;5049.64652474327;5093.668911520739;5138.229265610151;5145.305383773218;5153.587712982952;4865.2945322928335;5556.805511807067;5657.688827114507;5662.6036177467595;5670.518005969498;5977.1333156692035;6011.248593925759;6019.307779605358;6021.909671354933;6038.426349496791;6100.036227029357;6117.188044311255;6131.866048757612;6139.570230083896;6200.937990620081;4880.633952453544;5624.128260119688;5639.041362968264;5683.857903552403;5689.317281926195;6129.08063790435;6273.87452250957;6283.654399008053;6288.402601752826;6294.0558916162945;5832.066869300977;5906.995810712189;5910.970445147746;5926.762929482858;5963.671998040063;6126.23422072241;6139.723712432951;6152.323244443108;6171.352008039181;6180.443760061616;6091.390860913873;6103.8474038148515;6111.449969502766;6126.050462178369;6126.090206827809;5932.436418540232;5971.790987315423;6043.909341359883;6059.394060593905;6095.1771510845465;4790.8323208688225;4824.011077729057;4826.879328016757;4833.95166048335;4850.151223535867;4332.740035597874;4365.257906155961;4367.847125350598;4375;4412.558744125562;5544.722763861791;5604.215409842625;5613.550915926716;5626.465485778845;5656.943430565641;6129.756146212483;6177.62934223948;6193.504519638418;6197.287311959182;6200.534959877937;5650.830475085754;5677.403872593401;5691.0609749391715;5704.629199102045;5706.828795136153;5213.087709650796;5258.39528454225;5279.076161671692;5287.471252874681;5304.549113325411;6199.56603037783;6226.782062627786;6238.157848690369;6283.685815709287;6320.178376811169;6067.453929146398;6164.599301045597;6170.568060235741;6173.84565385851;6181.196444462191;6244.125822384961;6262.843428914313;6306.400891915261;6306.432421082125;6313;5222.791088356423;5718.1136923776885;5756.079027355603;5956.017257152521;5983.042713165873;5082.212257100007;6109.829807539466;6114.908276375769;6123.050927106306;6197.287311959182;5954.315253745702;5987.850303742442;5991.460768530853;5999.3100793407575;6006.459418652343;5801.071828507418;5985.461308482258;5986.341502434641;5987.700614969147;5995.130706047686;5707.885842283048;5764.481196692318;5769;5769.884602308014;5776.393478684678;4991.9500804991485;5880.117982302518;5893.882122357614;5898.056310990221;5921.526277897844;5810.796622118206;5818.970905145447;5823;5835.095560188178;5842.38654941225 12:37:23 INFO - PROCESS | 1641 | |1;ext.html;477.96654234203606;478.964077694173;485.59452856864516;506.228002296498;517.549731733392;38850.83447496576;39479;39491.605083949114;39564.615238466584;39685.206295874064;41419.58580414195;41917.58082419175;41942;41947.161056778845;41966.16067678644;9299.442033477995;9428.10433008863;9450.102240287162;9458.19605333544;9480.43117412954;9232.769180770501;9321.114494123047;9326.53367331634;9345.485998270093;9355.953220233923;9200.953995230047;9308.62765489383;9359.906400935972;9370.203532699728;9372.578233979488;9249.352545321797;9322.906770932272;9337.766555836091;9341.532923353805;9357.298202634762;9352.579133938989;9356.204722598588;9427.811443771156;9446.291528135418;9488.810223795554;39289.41065884014;39701;39781.40327895084;39788.60211397878;39868;3202.151429871086;3305.603327600679;3464.22055037617;3657.3416784978695;3757.0982964088694;28693.56959645607;29181.27046823825;29299.707002930125;29420;30630.54054189189;3128.765342599291;3131.076332481923;3166.509191075387;3230.0794273632137;3246.675332466758;433672.831635843;435315;438030;442035;444397.77801111114;30780.84609576938;30881.84559077235;59362;59606.70196648989;59765.70117149474;34422.48366274508;34838;34857;34884.30231395409;34922.47616285758;13324;13424.395902184426;13431.3955871986;13444.126131801402;13458.192508449525;10919.672409827717;11255.324680519196;11316.830247546293;11320.433978301053;11351.14866385008;7666.310032097138;7703.845923081507;7755.689772409153;7761.650725717356;7772.455928085093;3050.816950982948;3095.8235870369567;3209.9679003209667;3254.072589312035;3289.0954987378664;869.8956125264882;871.638270117894;892.4868200784575;999.5002498750624;1054.335768465877;28778.856105719336;29259.7074029257;29447.85276073606;29993.950211742485;30121.69878301233;1016.6594190946116;1031.7626945802508;1099.6426161497386;1115.642994241835;1119.5521791283552;12962.740745185232;15334.23328833554;15337.499937551083;15476.613084672748;15628.296726647328;594402.0559794434;595208;596232;597751;599072;56929.14606280909;57258.713706431205;57290.71354643283;57406;57445.13832292519;1198.6224339333082;1323.1135139456587;1358.8437329707012;1365.0853927868352;1366.4124426496503;28507.42985140327;29087.85456072706;29101.85449072741;29196;29760.851195743882;49246;49914.750426247636;49930.50069499259;49949.50050499449;49986;529716;532546;533124;534058;535828;26517.33706657349;26850.865745671148;26900.05849795326;26904.865475672497;26917.865410673603;45709.31436028529;45830.770846145555;46044;46844.765776172266;47115;33388.16529586683;36930.44604330883;38216.80891595524;38776.41835372528;38926.41610375903;37598.8120059398;38403.615963840006;38434;39767;39789.80105099456;32034.839825800722;32674.50988235226;32693.346133076728;37263.6273637271;37757.81121094377;31836.5224521637;33733;34344.82827585846;34400;34485;25112.37219069538;25390.87304563539;25670.055567397216;25738.613920791577;25924 12:37:23 INFO - PROCESS | 1641 | |2;jquery.html;353.3039911374591;371.3909188930155;372.07353689313607;382.1840370808324;398.5595916512254;14589.92705036476;14770.852291477082;15495.61260968475;19585.118669659863;20218.58469907107;16622.33510659575;18143.00213488261;19461.902690486528;19531.02344882754;20311.898440507815;5529.778808847651;5846.678432686201;5853.239078919734;5856.73644685988;5857.970710146444;5344.812931547397;5367.275417818583;5723.656580605156;5771.826845194651;5781.277340332458;5713.143028545731;5718.771249150029;5732.455416735404;5736.139579063132;5739.196512488255;5515.4760297771845;5830.387809280007;5875.882482350372;5934.792282270143;5945.464908158287;5505.834824955257;5721.856953576153;5722.656640601577;5733.140028995684;5750.424957504258;13080.345982700923;13329.400176992058;13338.266395348252;13341.933290333583;18279.908600457045;2152.698622192894;2423.672804171432;2794.455081259155;2826.3216827961346;2930.223490774946;9620.567074481665;10340.121089707383;10347.586096556128;10363.740906477324;10475.214358923035;1786.7051936430473;1814.7368631548484;1824.7226941141255;1876.9436916892514;1893.2048539613259;107254.46372768164;114624.85375146309;118085;126883;129479;11233.775324493465;11545.942270288595;11564.826527602092;11663.708407289885;11702.88297117035;14538.636534086732;14542.854571454362;14546;14552.345144468525;14595.708085838223;7841.411894107965;8116.173860881255;8147.103818579952;8235.547044912588;8274.958625206835;7878;7878.409119316074;7886.211378862068;7989.600519973978;8020.5588692621295;4452.30989196675;4455.799489023002;4463.486699029653;4468.731876087445;4554.863354099382;1603.4227678035897;1878.4646375782836;1889.7448844406103;1903.790583035865;1925.6231794267167;754.7735679296189;846.8475674378548;869.573908784691;957.9137877590911;962.3840741925173;19177.904110479638;20379.388618341472;20635;20652.483687907923;20961.89519052395;795.6061749434067;900.9954950225207;910.0717268386342;921.5023887101024;941.204682043672;10188.796224075477;10746.677599672023;10764.838527422095;10775.353478791298;10793.460326983619;455945;504480;508071;608412;611993;126294.73705263014;138989;140918;150065;151268.48731512766;976.7899901521207;1002.819492491344;1017.8931212222758;1030.314840630987;1031.1647565471992;18011.639767204844;18852.717209242146;19219;19221.423357299303;19247.23011079541;54579.57809250367;54687.72656136694;54932.990307526816;55422;56686.433135669766;74630.62684686542;75474;76933.61533192298;77970.61014694891;78791.21208788067;7953.920460795318;7981.521108733495;8084.393670474856;8157.673693052211;8271.048829384697;10369.377837329785;12366.010719142627;12695.36523173856;12928.482860685843;13032.934835326141;10947.507362168562;11075.5569777208;11185.32888026721;11273.830892536782;11479.885201147881;9329.533523323942;10285.794284114425;11008.504617292081;11046.613368532062;11127.666170014912;11232.943835280772;11299.983001529901;11404.315741055563;11428.085753139896;11674;7841.725539606086;7878.094019187867;8002.9599852000365;8042.994625671791;8201.562297355305;8199.385046121446;10861.40262285582;11094.761331888276;11236.887631123911;11422.428878556206 12:37:23 INFO - PROCESS | 1641 | |3;mootools.html;1304.3869381390755;1441.2937660546331;1476.9808831905984;1612.9516114516562;1809.873308868379;7481.438892083095;7885.80285492863;8035.477693949896;8384.287335576484;8406.285465735404;8218.465799723028;8224.218699223564;8269.669213231478;8313.210245026712;8393.83212335753;2315.1896836107394;2316.0041182291607;2316.2587971849016;2325.6278995360744;2348.753380895007;2323.1636610820174;2356.646503024543;2365.775251351119;2374.4063984004;2383.261189031397;2294.1855641247366;2300.9539809203807;2325.6046472099783;2325.9069637214493;2325.9534809303805;2198.120751699317;2218.6561083032157;2257.0294773247497;2257.367936977657;2262.2195342606774;2321.721393432782;2347.096367898371;2347.4131467133216;2348;2350.635651474024;8124.5937703114605;8297.25324720778;8329.37529685276;8388.8741668875;8414.032386275596;4920.384951881015;5119.872003199913;5123.359580052494;5133.281340612317;5135.691858488465;3356.9496457553155;3515.859365625372;3550.715942724576;3581.247937933037;3592.497050412944;1043.660810236676;1057.5845843603004;1060.2684147937975;1065.3767545985609;1075.2795626929976;10336;10343.482825858751;10352.378857268588;10436.217283703752;10454.581816727321;4260.147970405931;4400.625946794558;4494.483134439582;4496.3705081288645;4503;8637.136286371438;8638.006629237592;8641.697540586048;8645.610947507377;8663.740087797376;4591.701539399928;5065.594752419797;5067.569256613156;5080.949190508048;5096.439391666913;3923.803809809498;4222.21888950539;4254.829806807755;4411.316245981878;4749.4775574686755;3186.1715953852167;3654.8172591370326;3664.871729489455;3740.3080430120513;3748.662620364126;1135.4890299365318;1136.1251836086167;1145.238416453066;1151.2344291046527;1153.682737247247;799.2726618776885;851.6295411496019;882.2633101360436;909.8453262945316;917.6742256498846;5480.7533660985355;5511.173324001352;5724.542036637059;5835.212246346774;5937.970310148422;862.8610234490412;897.7127319257775;912.9637861027649;914.1955079529971;926.6386109417333;3515.876944306937;3843.404272337736;3919.9020024499046;3938.9473158302544;3988.12261302513;9643.27675424332;10046.04562566555;10115.848262276071;10241.539130739135;10244.64143754965;8486.618102185417;10031.749206269757;10056.497175141214;10260.973902609829;10294.691159265085;7487.3261406473675;7718.575478348745;7718.691252349955;7726.652300646484;7727.343175830118;5198.610104242121;5437.700926449083;5504.587155963239;5574.9163762542785;5753.338366087954;9353.158215760614;9414.293927955294;9438.764030899283;9467.621295148117;9567.37812042215;9498.95250523743;9616.75958101053;9683.515824208902;9696.612135514782;9767.169790567881;6128.387161283836;7180;7236.674349654383;7344.559326440431;7384.187739348775;7932.801679957816;7937.603119844101;7940.6426710796995;8094.676212951416;8099.716509922125;7108.182559005781;7211.38703210212;7219.422446204396;7243.311885370881;7348.889766653398;6815.488838337244;7023.964880175567;7024.789256322319;7034.683439245348;7074.257202993612;5764.481196692318;6629;6669.233038200475;6750.1562304711915;6762.323767623395;4698.671093023456;4740.834070807643;4748.406449193851;4808.35087263215;4832.710037397767;4556.521565235603;4742.407199100113;4761.738104404152;4921.237208232729;4998.800047998039 12:37:23 INFO - PROCESS | 1641 | |4;prototype.html;295.9926001849954;297.1886749174754;305.0436880380008;309.47080492358066;312.4500878453923;4306.698531102824;4980.360408983235;5081.9745901270535;5094.388673359193;5174.637775355722;4208.379773787099;4222.606539841852;4867.610591152708;5050.191969284915;5093.770780314885;3618.4029635110305;3695.6858667013335;3967.583403742609;4015.377616469452;4016.1365306459106;3329.700326970572;3599.2981368632995;3837.827297771593;3997.9800100999455;4023.8330884043594;3918.3338832398417;3953.8336190823634;3984.1234928315725;3988.421678856564;3991.4012898065375;3302.752293577967;3960.9009774755573;3964.7621142731527;3981.705945567702;3983.4821473208444;2775.653043369579;3492.891326719222;3495.9650403495893;3496.3881320768764;3497.91255218619;4476.724133621902;4844.975775121137;4967.379077615298;5027.472115427864;5034.748262586856;4483.4843992940905;4504.324351347292;4504.729716217037;4520.2315606347;4522.3216517522305;4837.959838634675;4943.579795717368;5362.383325917531;5442.673439593597;5458.1266997280645;3698.6671199592165;3793.696504279651;3821.197548514815;3824.101336185994;3857.209272099241;4709.434867815849;5570.805021824257;5592.748326325325;5600.747966341524;5759.055047292618;3511.543499345069;3615.680276698986;3687.4100143976834;3782.8108594570162;3864.806759662006;3323.202431416443;3935.6064393560414;3942.329803933338;3943.645071943539;3960.920781584352;3638.6543278388513;4444.599986001275;4460;4483.677315192026;4497.775111244425;5065.645404821701;5087;5377.8117765878005;5389.19461980241;5402.91895621566;1190.5118901250446;1214.4110106598293;1220.4873952939786;1251.2680082152042;1272.2620879889641;775.7440044785208;784.7718320827886;868.9913100869037;879.9748293238306;893.5175005497089;4222.936655950163;4263.339182426729;4429.601335879786;4519.887002824956;4810.59109975656;4520.683552151384;4649.674522783373;5273.630845840754;5304.708241046779;5306.548943339782;3907.081835768592;4218.49378074633;4479.552044795494;4531.614812740888;4803.092196598134;2492.6385674077155;2516.5337968790323;2561.7447450749373;2728.6452761140927;2731.945361092767;4528.0793887942755;4607.6313894889;4695.718256904596;5117.385913690381;5302.549283310885;4718.414005355954;4834.613230941516;5153.294520376832;5257.78968841242;5306.965141797313;5176.482351764794;5344.545713614309;5349.384820745586;5369.40936496985;5369.973150134224;4918.4343800461975;5219.530242278213;5224.9738751306;5230.5815534757885;5277.472252774845;4596.977015114903;5010.248462730474;5023.949760502494;5024.291740808143;5147.356580427447;4975.6019518437715;5269.78920843162;5287.814926477555;5289.312389389354;5369.301990741179;3317.781751700846;3437.507806840121;3580.2302504960517;3601.6038235794567;3638.8180590970874;4690.326513887052;4868.610511159028;4913.385826771653;5588.916166257591;5592.881423715192;4887.0714564232685;5224.85053288276;5379.073276508249;5423.62034657586;5513.448655134454;5037.5642941761425;5354.920920470667;5458.181272809111;5485.61600687948;5524;4833.830815921426;5269.890764448994;5331.893362132658;5344;5375.623706340519;4513.885419060969;5364.222187782671;5373.220882972026;5532.3637781655625;5540.584456165723;5398.595105367034;5400.864978375572;5407.810726624549;5410.864728381822;5411.809401931569 12:37:23 INFO - PROCESS | 1641 | |5;yui.html;744.7356188553064;746.738641475484;754.7132089805873;777.6539439949221;787.6455594982259;13803.861961380384;15585.688286234268;16262.512124636265;16299.185040747945;16616.66766664666;14074.366653500592;14113.94145439091;14157.858421415784;14172.433102675881;14180.92909535451;649.3863299182251;663.2240278873705;802.5144787403624;824.5629816197425;825.3149885594971;657.9967100164492;787.6061969015492;812.9837403251931;821.3059964330131;825.7687847402736;861.3238607692962;861.9396642235015;863.1153068105211;863.2619110660357;864.5331520978664;858.7466697324242;860.5310105992249;860.5955201055494;863.119617989647;863.568215892054;499.383261671835;510.6119349294563;521.8512723873715;606.3269770554681;629.7166275176144;13866.791998120036;13898.23559704216;14101.2244326562;14196.787048194285;14308.57074287773;12415;12665.936670316682;12866.935665321707;12900.354982250943;12954;10944.616938407118;11544.884551154464;11581.536738530449;12038.157328986974;12412.62762117138;3343.6823501767535;3645.1798345372345;3660.213054193361;3685.0787303174206;3778.659920607131;77279;77496;78558.21441785623;84508.30983380298;85206.57396712976;10132.316484304183;10220.642277520252;10251.161954779884;10348.172146228435;10572.682819515427;18522.81477185238;19792.40622781319;19920.800791992184;19935.700964485546;19948.3018094366;7321.341079302888;7476.70093196277;7496.925030749731;7598.582077985764;7604.391648668094;3692.8522859085338;3725.6088110747983;3743.756655817363;3750.9437358439645;3760.7555508891833;3329.9667003329655;3489.0753950203216;3499.7725147865817;3504.8247587620517;3505.2288496530723;630.486153784664;638.7030031035591;638.9137466441971;644.8581312111328;658.1246941567704;580.3731969472959;615.6429271022796;642.7011439680477;646.1858058845888;682.9039391776128;8164.265216130577;8565.52889591066;8994.685186018458;9048.819023619491;9115.58708400199;581.2792137749161;639.9744010239631;655.4035827397046;672.8217022489044;687.182253118787;4212.915741685149;4450.154470650566;4487.941766219062;4496.4154659894;4517.6160026397465;85751.57124214339;86346.56826715951;89434.55282723675;93521.06478935124;113122.73754525027;76523.61738191273;80685.59657201676;80916;81281.18718812735;82542.17457825465;66535;71906.28093718996;75794.21028948641;75961.9999999989;76554;9205.723828285161;9589.712308630753;9688.031196880256;9697.369670971364;9713.417194968324;39295.624653137005;43110.56889431066;46323;46800.531994680976;48165.27752083792;65705;65792.67103664642;69111.65444172747;70278;72270.27729722635;12196.939015304866;12218.51125954952;12239.75520489603;12286.078544109048;12647.810282845949;13160.14459060158;13397.464101436224;13753.656158596115;13859.30703464843;14254.643633909236;12932.676683082622;13137.408816603085;13304.800427993394;14011.229382384066;14016.579502614937;12626.873731262569;13128.34358282063;13348.666283342605;13624.318784060955;13731.176129432266;11992.40037998114;12338.321392323149;13088.018398619952;13344.315487333084;13626.795598066235;10963.122950163808;11161.330320180816;11222.775544489228;11375.544978201111;11413.543458261576;10643.308184967953;10730.463476825971;11256.268342557707;11361.772764544829;11378.260413073123 12:37:23 INFO - PROCESS | 1641 | __end_tp_report 12:37:23 INFO - PROCESS | 1641 | __start_cc_report 12:37:23 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,2561 12:37:23 INFO - PROCESS | 1641 | __end_cc_report 12:37:23 INFO - PROCESS | 1641 | __startTimestamp1481834243498__endTimestamp 12:37:23 INFO - PROCESS | 1641 | 12:37:23 INFO - PROCESS | 1641 | ------- Summary: start ------- 12:37:23 INFO - PROCESS | 1641 | Number of tests: 6 12:37:23 INFO - PROCESS | 1641 | 12:37:23 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5608.62 Median:5769.00 stddev:633.30 (11.0%) stddev-sans-first:607.14 12:37:23 INFO - PROCESS | 1641 | Values: 3230.5 3249.5 3253.6 3263.8 3270.5 5360.9 5413.9 5415.3 5419.4 5424.7 5482.8 5501.2 5516.4 5586.7 5588.7 5090.1 5107.5 5125.7 5201.3 5203.5 5099.3 5122.8 5131.2 5132.1 5223.6 5166.5 5181.8 5184.0 5191.4 5194.0 5179.6 5182.4 5189.8 5202.2 5209.0 5049.6 5093.7 5138.2 5145.3 5153.6 4865.3 5556.8 5657.7 5662.6 5670.5 5977.1 6011.2 6019.3 6021.9 6038.4 6100.0 6117.2 6131.9 6139.6 6200.9 4880.6 5624.1 5639.0 5683.9 5689.3 6129.1 6273.9 6283.7 6288.4 6294.1 5832.1 5907.0 5911.0 5926.8 5963.7 6126.2 6139.7 6152.3 6171.4 6180.4 6091.4 6103.8 6111.4 6126.1 6126.1 5932.4 5971.8 6043.9 6059.4 6095.2 4790.8 4824.0 4826.9 4834.0 4850.2 4332.7 4365.3 4367.8 4375.0 4412.6 5544.7 5604.2 5613.6 5626.5 5656.9 6129.8 6177.6 6193.5 6197.3 6200.5 5650.8 5677.4 5691.1 5704.6 5706.8 5213.1 5258.4 5279.1 5287.5 5304.5 6199.6 6226.8 6238.2 6283.7 6320.2 6067.5 6164.6 6170.6 6173.8 6181.2 6244.1 6262.8 6306.4 6306.4 6313.0 5222.8 5718.1 5756.1 5956.0 5983.0 5082.2 6109.8 6114.9 6123.1 6197.3 5954.3 5987.9 5991.5 5999.3 6006.5 5801.1 5985.5 5986.3 5987.7 5995.1 5707.9 5764.5 5769.0 5769.9 5776.4 4992.0 5880.1 5893.9 5898.1 5921.5 5810.8 5819.0 5823.0 5835.1 5842.4 12:37:23 INFO - PROCESS | 1641 | 12:37:23 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:64389.66 Median:38888.63 stddev:140825.30 (362.1%) stddev-sans-first:141138.59 12:37:23 INFO - PROCESS | 1641 | Values: 478.0 479.0 485.6 506.2 517.5 38850.8 39479.0 39491.6 39564.6 39685.2 41419.6 41917.6 41942.0 41947.2 41966.2 9299.4 9428.1 9450.1 9458.2 9480.4 9232.8 9321.1 9326.5 9345.5 9356.0 9201.0 9308.6 9359.9 9370.2 9372.6 9249.4 9322.9 9337.8 9341.5 9357.3 9352.6 9356.2 9427.8 9446.3 9488.8 39289.4 39701.0 39781.4 39788.6 39868.0 3202.2 3305.6 3464.2 3657.3 3757.1 28693.6 29181.3 29299.7 29420.0 30630.5 3128.8 3131.1 3166.5 3230.1 3246.7 433672.8 435315.0 438030.0 442035.0 444397.8 30780.8 30881.8 59362.0 59606.7 59765.7 34422.5 34838.0 34857.0 34884.3 34922.5 13324.0 13424.4 13431.4 13444.1 13458.2 10919.7 11255.3 11316.8 11320.4 11351.1 7666.3 7703.8 7755.7 7761.7 7772.5 3050.8 3095.8 3210.0 3254.1 3289.1 869.9 871.6 892.5 999.5 1054.3 28778.9 29259.7 29447.9 29994.0 30121.7 1016.7 1031.8 1099.6 1115.6 1119.6 12962.7 15334.2 15337.5 15476.6 15628.3 594402.1 595208.0 596232.0 597751.0 599072.0 56929.1 57258.7 57290.7 57406.0 57445.1 1198.6 1323.1 1358.8 1365.1 1366.4 28507.4 29087.9 29101.9 29196.0 29760.9 49246.0 49914.8 49930.5 49949.5 49986.0 529716.0 532546.0 533124.0 534058.0 535828.0 26517.3 26850.9 26900.1 26904.9 26917.9 45709.3 45830.8 46044.0 46844.8 47115.0 33388.2 36930.4 38216.8 38776.4 38926.4 37598.8 38403.6 38434.0 39767.0 39789.8 32034.8 32674.5 32693.3 37263.6 37757.8 31836.5 33733.0 34344.8 34400.0 34485.0 25112.4 25390.9 25670.1 25738.6 25924.0 12:37:23 INFO - PROCESS | 1641 | 12:37:23 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:33265.28 Median:11557.30 stddev:91516.64 (791.9%) stddev-sans-first:91740.01 12:37:23 INFO - PROCESS | 1641 | Values: 353.3 371.4 372.1 382.2 398.6 14589.9 14770.9 15495.6 19585.1 20218.6 16622.3 18143.0 19461.9 19531.0 20311.9 5529.8 5846.7 5853.2 5856.7 5858.0 5344.8 5367.3 5723.7 5771.8 5781.3 5713.1 5718.8 5732.5 5736.1 5739.2 5515.5 5830.4 5875.9 5934.8 5945.5 5505.8 5721.9 5722.7 5733.1 5750.4 13080.3 13329.4 13338.3 13341.9 18279.9 2152.7 2423.7 2794.5 2826.3 2930.2 9620.6 10340.1 10347.6 10363.7 10475.2 1786.7 1814.7 1824.7 1876.9 1893.2 107254.5 114624.9 118085.0 126883.0 129479.0 11233.8 11545.9 11564.8 11663.7 11702.9 14538.6 14542.9 14546.0 14552.3 14595.7 7841.4 8116.2 8147.1 8235.5 8275.0 7878.0 7878.4 7886.2 7989.6 8020.6 4452.3 4455.8 4463.5 4468.7 4554.9 1603.4 1878.5 1889.7 1903.8 1925.6 754.8 846.8 869.6 957.9 962.4 19177.9 20379.4 20635.0 20652.5 20961.9 795.6 901.0 910.1 921.5 941.2 10188.8 10746.7 10764.8 10775.4 10793.5 455945.0 504480.0 508071.0 608412.0 611993.0 126294.7 138989.0 140918.0 150065.0 151268.5 976.8 1002.8 1017.9 1030.3 1031.2 18011.6 18852.7 19219.0 19221.4 19247.2 54579.6 54687.7 54933.0 55422.0 56686.4 74630.6 75474.0 76933.6 77970.6 78791.2 7953.9 7981.5 8084.4 8157.7 8271.0 10369.4 12366.0 12695.4 12928.5 13032.9 10947.5 11075.6 11185.3 11273.8 11479.9 9329.5 10285.8 11008.5 11046.6 11127.7 11232.9 11300.0 11404.3 11428.1 11674.0 7841.7 7878.1 8003.0 8043.0 8201.6 8199.4 10861.4 11094.8 11236.9 11422.4 12:37:23 INFO - PROCESS | 1641 | 12:37:23 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5381.38 Median:5066.58 stddev:2946.18 (58.1%) stddev-sans-first:2938.51 12:37:23 INFO - PROCESS | 1641 | Values: 1304.4 1441.3 1477.0 1613.0 1809.9 7481.4 7885.8 8035.5 8384.3 8406.3 8218.5 8224.2 8269.7 8313.2 8393.8 2315.2 2316.0 2316.3 2325.6 2348.8 2323.2 2356.6 2365.8 2374.4 2383.3 2294.2 2301.0 2325.6 2325.9 2326.0 2198.1 2218.7 2257.0 2257.4 2262.2 2321.7 2347.1 2347.4 2348.0 2350.6 8124.6 8297.3 8329.4 8388.9 8414.0 4920.4 5119.9 5123.4 5133.3 5135.7 3356.9 3515.9 3550.7 3581.2 3592.5 1043.7 1057.6 1060.3 1065.4 1075.3 10336.0 10343.5 10352.4 10436.2 10454.6 4260.1 4400.6 4494.5 4496.4 4503.0 8637.1 8638.0 8641.7 8645.6 8663.7 4591.7 5065.6 5067.6 5080.9 5096.4 3923.8 4222.2 4254.8 4411.3 4749.5 3186.2 3654.8 3664.9 3740.3 3748.7 1135.5 1136.1 1145.2 1151.2 1153.7 799.3 851.6 882.3 909.8 917.7 5480.8 5511.2 5724.5 5835.2 5938.0 862.9 897.7 913.0 914.2 926.6 3515.9 3843.4 3919.9 3938.9 3988.1 9643.3 10046.0 10115.8 10241.5 10244.6 8486.6 10031.7 10056.5 10261.0 10294.7 7487.3 7718.6 7718.7 7726.7 7727.3 5198.6 5437.7 5504.6 5574.9 5753.3 9353.2 9414.3 9438.8 9467.6 9567.4 9499.0 9616.8 9683.5 9696.6 9767.2 6128.4 7180.0 7236.7 7344.6 7384.2 7932.8 7937.6 7940.6 8094.7 8099.7 7108.2 7211.4 7219.4 7243.3 7348.9 6815.5 7024.0 7024.8 7034.7 7074.3 5764.5 6629.0 6669.2 6750.2 6762.3 4698.7 4740.8 4748.4 4808.4 4832.7 4556.5 4742.4 4761.7 4921.2 4998.8 12:37:23 INFO - PROCESS | 1641 | 12:37:23 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4276.20 Median:4713.92 stddev:1290.65 (27.4%) stddev-sans-first:1259.22 12:37:23 INFO - PROCESS | 1641 | Values: 296.0 297.2 305.0 309.5 312.5 4306.7 4980.4 5082.0 5094.4 5174.6 4208.4 4222.6 4867.6 5050.2 5093.8 3618.4 3695.7 3967.6 4015.4 4016.1 3329.7 3599.3 3837.8 3998.0 4023.8 3918.3 3953.8 3984.1 3988.4 3991.4 3302.8 3960.9 3964.8 3981.7 3983.5 2775.7 3492.9 3496.0 3496.4 3497.9 4476.7 4845.0 4967.4 5027.5 5034.7 4483.5 4504.3 4504.7 4520.2 4522.3 4838.0 4943.6 5362.4 5442.7 5458.1 3698.7 3793.7 3821.2 3824.1 3857.2 4709.4 5570.8 5592.7 5600.7 5759.1 3511.5 3615.7 3687.4 3782.8 3864.8 3323.2 3935.6 3942.3 3943.6 3960.9 3638.7 4444.6 4460.0 4483.7 4497.8 5065.6 5087.0 5377.8 5389.2 5402.9 1190.5 1214.4 1220.5 1251.3 1272.3 775.7 784.8 869.0 880.0 893.5 4222.9 4263.3 4429.6 4519.9 4810.6 4520.7 4649.7 5273.6 5304.7 5306.5 3907.1 4218.5 4479.6 4531.6 4803.1 2492.6 2516.5 2561.7 2728.6 2731.9 4528.1 4607.6 4695.7 5117.4 5302.5 4718.4 4834.6 5153.3 5257.8 5307.0 5176.5 5344.5 5349.4 5369.4 5370.0 4918.4 5219.5 5225.0 5230.6 5277.5 4597.0 5010.2 5023.9 5024.3 5147.4 4975.6 5269.8 5287.8 5289.3 5369.3 3317.8 3437.5 3580.2 3601.6 3638.8 4690.3 4868.6 4913.4 5588.9 5592.9 4887.1 5224.9 5379.1 5423.6 5513.4 5037.6 5354.9 5458.2 5485.6 5524.0 4833.8 5269.9 5331.9 5344.0 5375.6 4513.9 5364.2 5373.2 5532.4 5540.6 5398.6 5400.9 5407.8 5410.9 5411.8 12:37:23 INFO - PROCESS | 1641 | 12:37:23 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:18756.70 Median:4492.18 stddev:25969.64 (578.1%) stddev-sans-first:26007.27 12:37:23 INFO - PROCESS | 1641 | Values: 744.7 746.7 754.7 777.7 787.6 13803.9 15585.7 16262.5 16299.2 16616.7 14074.4 14113.9 14157.9 14172.4 14180.9 649.4 663.2 802.5 824.6 825.3 658.0 787.6 813.0 821.3 825.8 861.3 861.9 863.1 863.3 864.5 858.7 860.5 860.6 863.1 863.6 499.4 510.6 521.9 606.3 629.7 13866.8 13898.2 14101.2 14196.8 14308.6 12415.0 12665.9 12866.9 12900.4 12954.0 10944.6 11544.9 11581.5 12038.2 12412.6 3343.7 3645.2 3660.2 3685.1 3778.7 77279.0 77496.0 78558.2 84508.3 85206.6 10132.3 10220.6 10251.2 10348.2 10572.7 18522.8 19792.4 19920.8 19935.7 19948.3 7321.3 7476.7 7496.9 7598.6 7604.4 3692.9 3725.6 3743.8 3750.9 3760.8 3330.0 3489.1 3499.8 3504.8 3505.2 630.5 638.7 638.9 644.9 658.1 580.4 615.6 642.7 646.2 682.9 8164.3 8565.5 8994.7 9048.8 9115.6 581.3 640.0 655.4 672.8 687.2 4212.9 4450.2 4487.9 4496.4 4517.6 85751.6 86346.6 89434.6 93521.1 113122.7 76523.6 80685.6 80916.0 81281.2 82542.2 66535.0 71906.3 75794.2 75962.0 76554.0 9205.7 9589.7 9688.0 9697.4 9713.4 39295.6 43110.6 46323.0 46800.5 48165.3 65705.0 65792.7 69111.7 70278.0 72270.3 12196.9 12218.5 12239.8 12286.1 12647.8 13160.1 13397.5 13753.7 13859.3 14254.6 12932.7 13137.4 13304.8 14011.2 14016.6 12626.9 13128.3 13348.7 13624.3 13731.2 11992.4 12338.3 13088.0 13344.3 13626.8 10963.1 11161.3 11222.8 11375.5 11413.5 10643.3 10730.5 11256.3 11361.8 11378.3 12:37:23 INFO - PROCESS | 1641 | -------- Summary: end -------- 12:37:23 INFO - PROCESS | 1641 | 12:37:24 INFO - TEST-INFO | 1641: exit 0 12:37:24 INFO - TEST-OK | dromaeo_css | took 1120796ms 12:37:24 INFO - TEST-START | kraken 12:37:24 INFO - operating with platform_type : mac_ 12:37:24 INFO - Initialising browser for kraken test... 12:37:24 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpOeZdaQ/profile http://localhost:49244/getInfo.html) 12:37:26 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 12:37:26 INFO - PROCESS | 1647 | colorDepth:24 12:37:26 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 12:37:26 INFO - PROCESS | 1647 | __metrics 12:37:26 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:37:26 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:37:27 INFO - TEST-INFO | 1647: exit 0 12:37:27 INFO - Browser initialized. 12:37:27 INFO - Running cycle 1/1 for kraken test... 12:37:27 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpOeZdaQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:37:39 INFO - PROCESS | 1648 | RSS: Main: 242618368 12:37:39 INFO - PROCESS | 1648 | 12:37:42 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 12:37:43 INFO - PROCESS | 1648 | RSS: Main: 289640448 12:37:43 INFO - PROCESS | 1648 | 12:37:46 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 12:37:46 INFO - PROCESS | 1648 | RSS: Main: 301301760 12:37:46 INFO - PROCESS | 1648 | 12:37:50 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 12:37:50 INFO - PROCESS | 1648 | RSS: Main: 395567104 12:37:50 INFO - PROCESS | 1648 | 12:37:52 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 12:37:53 INFO - PROCESS | 1648 | RSS: Main: 297148416 12:37:53 INFO - PROCESS | 1648 | 12:37:55 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:37:55 INFO - PROCESS | 1648 | RSS: Main: 298909696 12:37:55 INFO - PROCESS | 1648 | 12:37:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 12:37:59 INFO - PROCESS | 1648 | RSS: Main: 339005440 12:37:59 INFO - PROCESS | 1648 | 12:38:01 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 12:38:02 INFO - PROCESS | 1648 | RSS: Main: 339386368 12:38:02 INFO - PROCESS | 1648 | 12:38:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 12:38:05 INFO - PROCESS | 1648 | RSS: Main: 337022976 12:38:05 INFO - PROCESS | 1648 | 12:38:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:38:08 INFO - PROCESS | 1648 | RSS: Main: 311840768 12:38:08 INFO - PROCESS | 1648 | 12:38:10 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:38:10 INFO - PROCESS | 1648 | RSS: Main: 314200064 12:38:10 INFO - PROCESS | 1648 | 12:38:12 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:38:13 INFO - PROCESS | 1648 | RSS: Main: 317964288 12:38:13 INFO - PROCESS | 1648 | 12:38:16 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:38:17 INFO - PROCESS | 1648 | RSS: Main: 325943296 12:38:17 INFO - PROCESS | 1648 | 12:38:20 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:38:20 INFO - PROCESS | 1648 | RSS: Main: 325922816 12:38:20 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 12:38:22 INFO - PROCESS | 1648 | RSS: Main: 369672192 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | __start_tp_report 12:38:22 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 12:38:22 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 12:38:22 INFO - PROCESS | 1648 | |i|pagename|runs| 12:38:22 INFO - PROCESS | 1648 | |0;ai-astar;116;114;116;114;114;116;113;112;114;114 12:38:22 INFO - PROCESS | 1648 | |1;audio-beat-detection;147;132;133;143;113;115;102;136;167;133 12:38:22 INFO - PROCESS | 1648 | |2;audio-dft;185;186;183;182;185;183;181;183;184;185 12:38:22 INFO - PROCESS | 1648 | |3;audio-fft;80;59;58;88;63;59;59;60;82;59 12:38:22 INFO - PROCESS | 1648 | |4;audio-oscillator;92;72;93;69;67;69;89;67;70;92 12:38:22 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;86;84;86;84;86;84;87;83;87;83 12:38:22 INFO - PROCESS | 1648 | |6;imaging-darkroom;94;94;94;94;93;94;95;93;93;94 12:38:22 INFO - PROCESS | 1648 | |7;imaging-desaturate;83;83;108;84;83;79;84;83;81;84 12:38:22 INFO - PROCESS | 1648 | |8;json-parse-financial;55;54;54;53;57;58;59;58;57;57 12:38:22 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;66;76;65;77;71;70;69;70;71;73 12:38:22 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;82;86;82;80;77;99;97;96;95;81 12:38:22 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;142;128;167;128;149;154;155;151;216;172 12:38:22 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;220;179;165;163;164;168;162;166;164;166 12:38:22 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;68;70;69;64;66;66;68;67;66;66 12:38:22 INFO - PROCESS | 1648 | __end_tp_report 12:38:22 INFO - PROCESS | 1648 | __start_cc_report 12:38:22 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1996 12:38:22 INFO - PROCESS | 1648 | __end_cc_report 12:38:22 INFO - PROCESS | 1648 | __startTimestamp1481834302816__endTimestamp 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | ------- Summary: start ------- 12:38:22 INFO - PROCESS | 1648 | Number of tests: 14 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:114.30 Median:114.00 stddev:1.34 (1.2%) stddev-sans-first:1.27 12:38:22 INFO - PROCESS | 1648 | Values: 116.0 114.0 116.0 114.0 114.0 116.0 113.0 112.0 114.0 114.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:134.50 stddev:18.68 (13.9%) stddev-sans-first:19.01 12:38:22 INFO - PROCESS | 1648 | Values: 147.0 132.0 133.0 143.0 113.0 115.0 102.0 136.0 167.0 133.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:1.57 (0.8%) stddev-sans-first:1.59 12:38:22 INFO - PROCESS | 1648 | Values: 185.0 186.0 183.0 182.0 185.0 183.0 181.0 183.0 184.0 185.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:66.70 Median:61.50 stddev:11.72 (19.1%) stddev-sans-first:11.40 12:38:22 INFO - PROCESS | 1648 | Values: 80.0 59.0 58.0 88.0 63.0 59.0 59.0 60.0 82.0 59.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:80.50 stddev:11.75 (14.6%) stddev-sans-first:11.31 12:38:22 INFO - PROCESS | 1648 | Values: 92.0 72.0 93.0 69.0 67.0 69.0 89.0 67.0 70.0 92.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:86.00 stddev:1.56 (1.8%) stddev-sans-first:1.62 12:38:22 INFO - PROCESS | 1648 | Values: 86.0 84.0 86.0 84.0 86.0 84.0 87.0 83.0 87.0 83.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 12:38:22 INFO - PROCESS | 1648 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 95.0 93.0 93.0 94.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:83.00 stddev:8.16 (9.8%) stddev-sans-first:8.62 12:38:22 INFO - PROCESS | 1648 | Values: 83.0 83.0 108.0 84.0 83.0 79.0 84.0 83.0 81.0 84.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:57.00 stddev:2.04 (3.6%) stddev-sans-first:2.12 12:38:22 INFO - PROCESS | 1648 | Values: 55.0 54.0 54.0 53.0 57.0 58.0 59.0 58.0 57.0 57.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:3.82 (5.4%) stddev-sans-first:3.64 12:38:22 INFO - PROCESS | 1648 | Values: 66.0 76.0 65.0 77.0 71.0 70.0 69.0 70.0 71.0 73.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:90.50 stddev:8.32 (9.2%) stddev-sans-first:8.58 12:38:22 INFO - PROCESS | 1648 | Values: 82.0 86.0 82.0 80.0 77.0 99.0 97.0 96.0 95.0 81.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:156.20 Median:154.50 stddev:25.43 (16.5%) stddev-sans-first:26.45 12:38:22 INFO - PROCESS | 1648 | Values: 142.0 128.0 167.0 128.0 149.0 154.0 155.0 151.0 216.0 172.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:166.00 stddev:17.63 (10.6%) stddev-sans-first:5.07 12:38:22 INFO - PROCESS | 1648 | Values: 220.0 179.0 165.0 163.0 164.0 168.0 162.0 166.0 164.0 166.0 12:38:22 INFO - PROCESS | 1648 | 12:38:22 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.50 stddev:1.76 (2.6%) stddev-sans-first:1.83 12:38:22 INFO - PROCESS | 1648 | Values: 68.0 70.0 69.0 64.0 66.0 66.0 68.0 67.0 66.0 66.0 12:38:22 INFO - PROCESS | 1648 | -------- Summary: end -------- 12:38:22 INFO - PROCESS | 1648 | 12:38:23 INFO - TEST-INFO | 1648: exit 0 12:38:23 INFO - TEST-OK | kraken | took 59775ms 12:38:23 INFO - SUITE-END | took 1180s 12:38:23 INFO - Completed test suite (00:19:40) 12:38:23 INFO - javascript benchmark 12:38:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3230.5315729219265, 3249.4800831866905, 3253.6095668519765, 3263.8368081595913, 3270.525773762803, 5360.919586206205, 5413.891722165555, 5415.323084614423, 5419.403865574794, 5424.674519528826, 5482.80810171644, 5501.229827824108, 5516.420775818541, 5586.692731899744, 5588.6646801191955, 5090.109230884599, 5107.489251074881, 5125.666831655949, 5201.271821944931, 5203.531682148606, 5099.311592934943, 5122.769475373599, 5131.204663277198, 5132.101882170624, 5223.608229382792, 5166.457521960197, 5181.766820493087, 5183.974080129594, 5191.402988656315, 5193.974030129844, 5179.611529135311, 5182.429932707418, 5189.766460509286, 5202.245674377232, 5209.0, 5049.64652474327, 5093.668911520739, 5138.229265610151, 5145.305383773218, 5153.587712982952, 4865.2945322928335, 5556.805511807067, 5657.688827114507, 5662.6036177467595, 5670.518005969498, 5977.1333156692035, 6011.248593925759, 6019.307779605358, 6021.909671354933, 6038.426349496791, 6100.036227029357, 6117.188044311255, 6131.866048757612, 6139.570230083896, 6200.937990620081, 4880.633952453544, 5624.128260119688, 5639.041362968264, 5683.857903552403, 5689.317281926195, 6129.08063790435, 6273.87452250957, 6283.654399008053, 6288.402601752826, 6294.0558916162945, 5832.066869300977, 5906.995810712189, 5910.970445147746, 5926.762929482858, 5963.671998040063, 6126.23422072241, 6139.723712432951, 6152.323244443108, 6171.352008039181, 6180.443760061616, 6091.390860913873, 6103.8474038148515, 6111.449969502766, 6126.050462178369, 6126.090206827809, 5932.436418540232, 5971.790987315423, 6043.909341359883, 6059.394060593905, 6095.1771510845465, 4790.8323208688225, 4824.011077729057, 4826.879328016757, 4833.95166048335, 4850.151223535867, 4332.740035597874, 4365.257906155961, 4367.847125350598, 4375.0, 4412.558744125562, 5544.722763861791, 5604.215409842625, 5613.550915926716, 5626.465485778845, 5656.943430565641, 6129.756146212483, 6177.62934223948, 6193.504519638418, 6197.287311959182, 6200.534959877937, 5650.830475085754, 5677.403872593401, 5691.0609749391715, 5704.629199102045, 5706.828795136153, 5213.087709650796, 5258.39528454225, 5279.076161671692, 5287.471252874681, 5304.549113325411, 6199.56603037783, 6226.782062627786, 6238.157848690369, 6283.685815709287, 6320.178376811169, 6067.453929146398, 6164.599301045597, 6170.568060235741, 6173.84565385851, 6181.196444462191, 6244.125822384961, 6262.843428914313, 6306.400891915261, 6306.432421082125, 6313.0, 5222.791088356423, 5718.1136923776885, 5756.079027355603, 5956.017257152521, 5983.042713165873, 5082.212257100007, 6109.829807539466, 6114.908276375769, 6123.050927106306, 6197.287311959182, 5954.315253745702, 5987.850303742442, 5991.460768530853, 5999.3100793407575, 6006.459418652343, 5801.071828507418, 5985.461308482258, 5986.341502434641, 5987.700614969147, 5995.130706047686, 5707.885842283048, 5764.481196692318, 5769.0, 5769.884602308014, 5776.393478684678, 4991.9500804991485, 5880.117982302518, 5893.882122357614, 5898.056310990221, 5921.526277897844, 5810.796622118206, 5818.970905145447, 5823.0, 5835.095560188178, 5842.38654941225], "value": 5568.196613844872, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.96654234203606, 478.964077694173, 485.59452856864516, 506.228002296498, 517.549731733392, 38850.83447496576, 39479.0, 39491.605083949114, 39564.615238466584, 39685.206295874064, 41419.58580414195, 41917.58082419175, 41942.0, 41947.161056778845, 41966.16067678644, 9299.442033477995, 9428.10433008863, 9450.102240287162, 9458.19605333544, 9480.43117412954, 9232.769180770501, 9321.114494123047, 9326.53367331634, 9345.485998270093, 9355.953220233923, 9200.953995230047, 9308.62765489383, 9359.906400935972, 9370.203532699728, 9372.578233979488, 9249.352545321797, 9322.906770932272, 9337.766555836091, 9341.532923353805, 9357.298202634762, 9352.579133938989, 9356.204722598588, 9427.811443771156, 9446.291528135418, 9488.810223795554, 39289.41065884014, 39701.0, 39781.40327895084, 39788.60211397878, 39868.0, 3202.151429871086, 3305.603327600679, 3464.22055037617, 3657.3416784978695, 3757.0982964088694, 28693.56959645607, 29181.27046823825, 29299.707002930125, 29420.0, 30630.54054189189, 3128.765342599291, 3131.076332481923, 3166.509191075387, 3230.0794273632137, 3246.675332466758, 433672.831635843, 435315.0, 438030.0, 442035.0, 444397.77801111114, 30780.84609576938, 30881.84559077235, 59362.0, 59606.70196648989, 59765.70117149474, 34422.48366274508, 34838.0, 34857.0, 34884.30231395409, 34922.47616285758, 13324.0, 13424.395902184426, 13431.3955871986, 13444.126131801402, 13458.192508449525, 10919.672409827717, 11255.324680519196, 11316.830247546293, 11320.433978301053, 11351.14866385008, 7666.310032097138, 7703.845923081507, 7755.689772409153, 7761.650725717356, 7772.455928085093, 3050.816950982948, 3095.8235870369567, 3209.9679003209667, 3254.072589312035, 3289.0954987378664, 869.8956125264882, 871.638270117894, 892.4868200784575, 999.5002498750624, 1054.335768465877, 28778.856105719336, 29259.7074029257, 29447.85276073606, 29993.950211742485, 30121.69878301233, 1016.6594190946116, 1031.7626945802508, 1099.6426161497386, 1115.642994241835, 1119.5521791283552, 12962.740745185232, 15334.23328833554, 15337.499937551083, 15476.613084672748, 15628.296726647328, 594402.0559794434, 595208.0, 596232.0, 597751.0, 599072.0, 56929.14606280909, 57258.713706431205, 57290.71354643283, 57406.0, 57445.13832292519, 1198.6224339333082, 1323.1135139456587, 1358.8437329707012, 1365.0853927868352, 1366.4124426496503, 28507.42985140327, 29087.85456072706, 29101.85449072741, 29196.0, 29760.851195743882, 49246.0, 49914.750426247636, 49930.50069499259, 49949.50050499449, 49986.0, 529716.0, 532546.0, 533124.0, 534058.0, 535828.0, 26517.33706657349, 26850.865745671148, 26900.05849795326, 26904.865475672497, 26917.865410673603, 45709.31436028529, 45830.770846145555, 46044.0, 46844.765776172266, 47115.0, 33388.16529586683, 36930.44604330883, 38216.80891595524, 38776.41835372528, 38926.41610375903, 37598.8120059398, 38403.615963840006, 38434.0, 39767.0, 39789.80105099456, 32034.839825800722, 32674.50988235226, 32693.346133076728, 37263.6273637271, 37757.81121094377, 31836.5224521637, 33733.0, 34344.82827585846, 34400.0, 34485.0, 25112.37219069538, 25390.87304563539, 25670.055567397216, 25738.613920791577, 25924.0], "value": 18051.307418001154, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.3039911374591, 371.3909188930155, 372.07353689313607, 382.1840370808324, 398.5595916512254, 14589.92705036476, 14770.852291477082, 15495.61260968475, 19585.118669659863, 20218.58469907107, 16622.33510659575, 18143.00213488261, 19461.902690486528, 19531.02344882754, 20311.898440507815, 5529.778808847651, 5846.678432686201, 5853.239078919734, 5856.73644685988, 5857.970710146444, 5344.812931547397, 5367.275417818583, 5723.656580605156, 5771.826845194651, 5781.277340332458, 5713.143028545731, 5718.771249150029, 5732.455416735404, 5736.139579063132, 5739.196512488255, 5515.4760297771845, 5830.387809280007, 5875.882482350372, 5934.792282270143, 5945.464908158287, 5505.834824955257, 5721.856953576153, 5722.656640601577, 5733.140028995684, 5750.424957504258, 13080.345982700923, 13329.400176992058, 13338.266395348252, 13341.933290333583, 18279.908600457045, 2152.698622192894, 2423.672804171432, 2794.455081259155, 2826.3216827961346, 2930.223490774946, 9620.567074481665, 10340.121089707383, 10347.586096556128, 10363.740906477324, 10475.214358923035, 1786.7051936430473, 1814.7368631548484, 1824.7226941141255, 1876.9436916892514, 1893.2048539613259, 107254.46372768164, 114624.85375146309, 118085.0, 126883.0, 129479.0, 11233.775324493465, 11545.942270288595, 11564.826527602092, 11663.708407289885, 11702.88297117035, 14538.636534086732, 14542.854571454362, 14546.0, 14552.345144468525, 14595.708085838223, 7841.411894107965, 8116.173860881255, 8147.103818579952, 8235.547044912588, 8274.958625206835, 7878.0, 7878.409119316074, 7886.211378862068, 7989.600519973978, 8020.5588692621295, 4452.30989196675, 4455.799489023002, 4463.486699029653, 4468.731876087445, 4554.863354099382, 1603.4227678035897, 1878.4646375782836, 1889.7448844406103, 1903.790583035865, 1925.6231794267167, 754.7735679296189, 846.8475674378548, 869.573908784691, 957.9137877590911, 962.3840741925173, 19177.904110479638, 20379.388618341472, 20635.0, 20652.483687907923, 20961.89519052395, 795.6061749434067, 900.9954950225207, 910.0717268386342, 921.5023887101024, 941.204682043672, 10188.796224075477, 10746.677599672023, 10764.838527422095, 10775.353478791298, 10793.460326983619, 455945.0, 504480.0, 508071.0, 608412.0, 611993.0, 126294.73705263014, 138989.0, 140918.0, 150065.0, 151268.48731512766, 976.7899901521207, 1002.819492491344, 1017.8931212222758, 1030.314840630987, 1031.1647565471992, 18011.639767204844, 18852.717209242146, 19219.0, 19221.423357299303, 19247.23011079541, 54579.57809250367, 54687.72656136694, 54932.990307526816, 55422.0, 56686.433135669766, 74630.62684686542, 75474.0, 76933.61533192298, 77970.61014694891, 78791.21208788067, 7953.920460795318, 7981.521108733495, 8084.393670474856, 8157.673693052211, 8271.048829384697, 10369.377837329785, 12366.010719142627, 12695.36523173856, 12928.482860685843, 13032.934835326141, 10947.507362168562, 11075.5569777208, 11185.32888026721, 11273.830892536782, 11479.885201147881, 9329.533523323942, 10285.794284114425, 11008.504617292081, 11046.613368532062, 11127.666170014912, 11232.943835280772, 11299.983001529901, 11404.315741055563, 11428.085753139896, 11674.0, 7841.725539606086, 7878.094019187867, 8002.9599852000365, 8042.994625671791, 8201.562297355305, 8199.385046121446, 10861.40262285582, 11094.761331888276, 11236.887631123911, 11422.428878556206], "value": 9272.693221464238, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1304.3869381390755, 1441.2937660546331, 1476.9808831905984, 1612.9516114516562, 1809.873308868379, 7481.438892083095, 7885.80285492863, 8035.477693949896, 8384.287335576484, 8406.285465735404, 8218.465799723028, 8224.218699223564, 8269.669213231478, 8313.210245026712, 8393.83212335753, 2315.1896836107394, 2316.0041182291607, 2316.2587971849016, 2325.6278995360744, 2348.753380895007, 2323.1636610820174, 2356.646503024543, 2365.775251351119, 2374.4063984004, 2383.261189031397, 2294.1855641247366, 2300.9539809203807, 2325.6046472099783, 2325.9069637214493, 2325.9534809303805, 2198.120751699317, 2218.6561083032157, 2257.0294773247497, 2257.367936977657, 2262.2195342606774, 2321.721393432782, 2347.096367898371, 2347.4131467133216, 2348.0, 2350.635651474024, 8124.5937703114605, 8297.25324720778, 8329.37529685276, 8388.8741668875, 8414.032386275596, 4920.384951881015, 5119.872003199913, 5123.359580052494, 5133.281340612317, 5135.691858488465, 3356.9496457553155, 3515.859365625372, 3550.715942724576, 3581.247937933037, 3592.497050412944, 1043.660810236676, 1057.5845843603004, 1060.2684147937975, 1065.3767545985609, 1075.2795626929976, 10336.0, 10343.482825858751, 10352.378857268588, 10436.217283703752, 10454.581816727321, 4260.147970405931, 4400.625946794558, 4494.483134439582, 4496.3705081288645, 4503.0, 8637.136286371438, 8638.006629237592, 8641.697540586048, 8645.610947507377, 8663.740087797376, 4591.701539399928, 5065.594752419797, 5067.569256613156, 5080.949190508048, 5096.439391666913, 3923.803809809498, 4222.21888950539, 4254.829806807755, 4411.316245981878, 4749.4775574686755, 3186.1715953852167, 3654.8172591370326, 3664.871729489455, 3740.3080430120513, 3748.662620364126, 1135.4890299365318, 1136.1251836086167, 1145.238416453066, 1151.2344291046527, 1153.682737247247, 799.2726618776885, 851.6295411496019, 882.2633101360436, 909.8453262945316, 917.6742256498846, 5480.7533660985355, 5511.173324001352, 5724.542036637059, 5835.212246346774, 5937.970310148422, 862.8610234490412, 897.7127319257775, 912.9637861027649, 914.1955079529971, 926.6386109417333, 3515.876944306937, 3843.404272337736, 3919.9020024499046, 3938.9473158302544, 3988.12261302513, 9643.27675424332, 10046.04562566555, 10115.848262276071, 10241.539130739135, 10244.64143754965, 8486.618102185417, 10031.749206269757, 10056.497175141214, 10260.973902609829, 10294.691159265085, 7487.3261406473675, 7718.575478348745, 7718.691252349955, 7726.652300646484, 7727.343175830118, 5198.610104242121, 5437.700926449083, 5504.587155963239, 5574.9163762542785, 5753.338366087954, 9353.158215760614, 9414.293927955294, 9438.764030899283, 9467.621295148117, 9567.37812042215, 9498.95250523743, 9616.75958101053, 9683.515824208902, 9696.612135514782, 9767.169790567881, 6128.387161283836, 7180.0, 7236.674349654383, 7344.559326440431, 7384.187739348775, 7932.801679957816, 7937.603119844101, 7940.6426710796995, 8094.676212951416, 8099.716509922125, 7108.182559005781, 7211.38703210212, 7219.422446204396, 7243.311885370881, 7348.889766653398, 6815.488838337244, 7023.964880175567, 7024.789256322319, 7034.683439245348, 7074.257202993612, 5764.481196692318, 6629.0, 6669.233038200475, 6750.1562304711915, 6762.323767623395, 4698.671093023456, 4740.834070807643, 4748.406449193851, 4808.35087263215, 4832.710037397767, 4556.521565235603, 4742.407199100113, 4761.738104404152, 4921.237208232729, 4998.800047998039], "value": 4360.029101427222, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.9926001849954, 297.1886749174754, 305.0436880380008, 309.47080492358066, 312.4500878453923, 4306.698531102824, 4980.360408983235, 5081.9745901270535, 5094.388673359193, 5174.637775355722, 4208.379773787099, 4222.606539841852, 4867.610591152708, 5050.191969284915, 5093.770780314885, 3618.4029635110305, 3695.6858667013335, 3967.583403742609, 4015.377616469452, 4016.1365306459106, 3329.700326970572, 3599.2981368632995, 3837.827297771593, 3997.9800100999455, 4023.8330884043594, 3918.3338832398417, 3953.8336190823634, 3984.1234928315725, 3988.421678856564, 3991.4012898065375, 3302.752293577967, 3960.9009774755573, 3964.7621142731527, 3981.705945567702, 3983.4821473208444, 2775.653043369579, 3492.891326719222, 3495.9650403495893, 3496.3881320768764, 3497.91255218619, 4476.724133621902, 4844.975775121137, 4967.379077615298, 5027.472115427864, 5034.748262586856, 4483.4843992940905, 4504.324351347292, 4504.729716217037, 4520.2315606347, 4522.3216517522305, 4837.959838634675, 4943.579795717368, 5362.383325917531, 5442.673439593597, 5458.1266997280645, 3698.6671199592165, 3793.696504279651, 3821.197548514815, 3824.101336185994, 3857.209272099241, 4709.434867815849, 5570.805021824257, 5592.748326325325, 5600.747966341524, 5759.055047292618, 3511.543499345069, 3615.680276698986, 3687.4100143976834, 3782.8108594570162, 3864.806759662006, 3323.202431416443, 3935.6064393560414, 3942.329803933338, 3943.645071943539, 3960.920781584352, 3638.6543278388513, 4444.599986001275, 4460.0, 4483.677315192026, 4497.775111244425, 5065.645404821701, 5087.0, 5377.8117765878005, 5389.19461980241, 5402.91895621566, 1190.5118901250446, 1214.4110106598293, 1220.4873952939786, 1251.2680082152042, 1272.2620879889641, 775.7440044785208, 784.7718320827886, 868.9913100869037, 879.9748293238306, 893.5175005497089, 4222.936655950163, 4263.339182426729, 4429.601335879786, 4519.887002824956, 4810.59109975656, 4520.683552151384, 4649.674522783373, 5273.630845840754, 5304.708241046779, 5306.548943339782, 3907.081835768592, 4218.49378074633, 4479.552044795494, 4531.614812740888, 4803.092196598134, 2492.6385674077155, 2516.5337968790323, 2561.7447450749373, 2728.6452761140927, 2731.945361092767, 4528.0793887942755, 4607.6313894889, 4695.718256904596, 5117.385913690381, 5302.549283310885, 4718.414005355954, 4834.613230941516, 5153.294520376832, 5257.78968841242, 5306.965141797313, 5176.482351764794, 5344.545713614309, 5349.384820745586, 5369.40936496985, 5369.973150134224, 4918.4343800461975, 5219.530242278213, 5224.9738751306, 5230.5815534757885, 5277.472252774845, 4596.977015114903, 5010.248462730474, 5023.949760502494, 5024.291740808143, 5147.356580427447, 4975.6019518437715, 5269.78920843162, 5287.814926477555, 5289.312389389354, 5369.301990741179, 3317.781751700846, 3437.507806840121, 3580.2302504960517, 3601.6038235794567, 3638.8180590970874, 4690.326513887052, 4868.610511159028, 4913.385826771653, 5588.916166257591, 5592.881423715192, 4887.0714564232685, 5224.85053288276, 5379.073276508249, 5423.62034657586, 5513.448655134454, 5037.5642941761425, 5354.920920470667, 5458.181272809111, 5485.61600687948, 5524.0, 4833.830815921426, 5269.890764448994, 5331.893362132658, 5344.0, 5375.623706340519, 4513.885419060969, 5364.222187782671, 5373.220882972026, 5532.3637781655625, 5540.584456165723, 5398.595105367034, 5400.864978375572, 5407.810726624549, 5410.864728381822, 5411.809401931569], "value": 3866.595018002631, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.7356188553064, 746.738641475484, 754.7132089805873, 777.6539439949221, 787.6455594982259, 13803.861961380384, 15585.688286234268, 16262.512124636265, 16299.185040747945, 16616.66766664666, 14074.366653500592, 14113.94145439091, 14157.858421415784, 14172.433102675881, 14180.92909535451, 649.3863299182251, 663.2240278873705, 802.5144787403624, 824.5629816197425, 825.3149885594971, 657.9967100164492, 787.6061969015492, 812.9837403251931, 821.3059964330131, 825.7687847402736, 861.3238607692962, 861.9396642235015, 863.1153068105211, 863.2619110660357, 864.5331520978664, 858.7466697324242, 860.5310105992249, 860.5955201055494, 863.119617989647, 863.568215892054, 499.383261671835, 510.6119349294563, 521.8512723873715, 606.3269770554681, 629.7166275176144, 13866.791998120036, 13898.23559704216, 14101.2244326562, 14196.787048194285, 14308.57074287773, 12415.0, 12665.936670316682, 12866.935665321707, 12900.354982250943, 12954.0, 10944.616938407118, 11544.884551154464, 11581.536738530449, 12038.157328986974, 12412.62762117138, 3343.6823501767535, 3645.1798345372345, 3660.213054193361, 3685.0787303174206, 3778.659920607131, 77279.0, 77496.0, 78558.21441785623, 84508.30983380298, 85206.57396712976, 10132.316484304183, 10220.642277520252, 10251.161954779884, 10348.172146228435, 10572.682819515427, 18522.81477185238, 19792.40622781319, 19920.800791992184, 19935.700964485546, 19948.3018094366, 7321.341079302888, 7476.70093196277, 7496.925030749731, 7598.582077985764, 7604.391648668094, 3692.8522859085338, 3725.6088110747983, 3743.756655817363, 3750.9437358439645, 3760.7555508891833, 3329.9667003329655, 3489.0753950203216, 3499.7725147865817, 3504.8247587620517, 3505.2288496530723, 630.486153784664, 638.7030031035591, 638.9137466441971, 644.8581312111328, 658.1246941567704, 580.3731969472959, 615.6429271022796, 642.7011439680477, 646.1858058845888, 682.9039391776128, 8164.265216130577, 8565.52889591066, 8994.685186018458, 9048.819023619491, 9115.58708400199, 581.2792137749161, 639.9744010239631, 655.4035827397046, 672.8217022489044, 687.182253118787, 4212.915741685149, 4450.154470650566, 4487.941766219062, 4496.4154659894, 4517.6160026397465, 85751.57124214339, 86346.56826715951, 89434.55282723675, 93521.06478935124, 113122.73754525027, 76523.61738191273, 80685.59657201676, 80916.0, 81281.18718812735, 82542.17457825465, 66535.0, 71906.28093718996, 75794.21028948641, 75961.9999999989, 76554.0, 9205.723828285161, 9589.712308630753, 9688.031196880256, 9697.369670971364, 9713.417194968324, 39295.624653137005, 43110.56889431066, 46323.0, 46800.531994680976, 48165.27752083792, 65705.0, 65792.67103664642, 69111.65444172747, 70278.0, 72270.27729722635, 12196.939015304866, 12218.51125954952, 12239.75520489603, 12286.078544109048, 12647.810282845949, 13160.14459060158, 13397.464101436224, 13753.656158596115, 13859.30703464843, 14254.643633909236, 12932.676683082622, 13137.408816603085, 13304.800427993394, 14011.229382384066, 14016.579502614937, 12626.873731262569, 13128.34358282063, 13348.666283342605, 13624.318784060955, 13731.176129432266, 11992.40037998114, 12338.321392323149, 13088.018398619952, 13344.315487333084, 13626.795598066235, 10963.122950163808, 11161.330320180816, 11222.775544489228, 11375.544978201111, 11413.543458261576, 10643.308184967953, 10730.463476825971, 11256.268342557707, 11361.772764544829, 11378.260413073123], "value": 7081.1763453913645, "unit": "score"}], "name": "dromaeo_css", "value": 6935.319961471712, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 116.0, 114.0, 114.0, 116.0, 113.0, 112.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 132.0, 133.0, 143.0, 113.0, 115.0, 102.0, 136.0, 167.0, 133.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 183.0, 182.0, 185.0, 183.0, 181.0, 183.0, 184.0, 185.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 88.0, 63.0, 59.0, 59.0, 60.0, 82.0, 59.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 72.0, 93.0, 69.0, 67.0, 69.0, 89.0, 67.0, 70.0, 92.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 86.0, 84.0, 87.0, 83.0, 87.0, 83.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 93.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 108.0, 84.0, 83.0, 79.0, 84.0, 83.0, 81.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 53.0, 57.0, 58.0, 59.0, 58.0, 57.0, 57.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 65.0, 77.0, 71.0, 70.0, 69.0, 70.0, 71.0, 73.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 86.0, 82.0, 80.0, 77.0, 99.0, 97.0, 96.0, 95.0, 81.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 128.0, 167.0, 128.0, 149.0, 154.0, 155.0, 151.0, 216.0, 172.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 179.0, 165.0, 163.0, 164.0, 168.0, 162.0, 166.0, 164.0, 166.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 69.0, 64.0, 66.0, 66.0, 68.0, 67.0, 66.0, 66.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1448.2, "alertThreshold": 2.0}]} 12:38:24 INFO - Return code: 0 12:38:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:38:24 INFO - # TBPL SUCCESS # 12:38:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:38:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:38:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:38:24 INFO - 2016-12-15 12:38:24.163 screenresolution[1652:10544] starting screenresolution argv=screenresolution get 12:38:24 INFO - 2016-12-15 12:38:24.166 screenresolution[1652:10544] Display 0: 1600x1200x32@60 12:38:24 INFO - 2016-12-15 12:38:24.171 screenresolution[1653:10547] starting screenresolution argv=screenresolution list 12:38:24 INFO - Available Modes on Display 0 12:38:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:38:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:38:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:38:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:38:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:38:24 INFO - Intel Iris: 12:38:24 INFO - Chipset Model: Intel Iris 12:38:24 INFO - Type: GPU 12:38:24 INFO - Bus: Built-In 12:38:24 INFO - VRAM (Dynamic, Max): 1536 MB 12:38:24 INFO - Vendor: Intel (0x8086) 12:38:24 INFO - Device ID: 0x0a2e 12:38:24 INFO - Revision ID: 0x0009 12:38:24 INFO - Displays: 12:38:24 INFO - Display: 12:38:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:38:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:38:24 INFO - Display Serial Number: 1600x1200 60 12:38:24 INFO - Main Display: Yes 12:38:24 INFO - Mirror: Off 12:38:24 INFO - Online: Yes 12:38:24 INFO - Rotation: Supported 12:38:24 INFO - Return code: 0 12:38:24 INFO - Running post-action listener: _resource_record_post_action 12:38:24 INFO - [mozharness: 2016-12-15 20:38:24.416687Z] Finished run-tests step (success) 12:38:24 INFO - Running post-run listener: _resource_record_post_run 12:38:24 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 33328128; Write bytes: 651812864; Read time: 311; Write time: 1822 12:38:24 INFO - TinderboxPrint: CPU usage
53.5% 12:38:24 INFO - TinderboxPrint: I/O read bytes / time
33,328,128 / 311 12:38:24 INFO - TinderboxPrint: I/O write bytes / time
651,812,864 / 1,822 12:38:24 INFO - TinderboxPrint: CPU idle
2,255.4 (46.5%) 12:38:24 INFO - TinderboxPrint: CPU system
966.8 (19.9%) 12:38:24 INFO - TinderboxPrint: CPU user
1,627.5 (33.6%) 12:38:24 INFO - TinderboxPrint: Swap in / out
377,630,720 / 0 12:38:24 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345876992; Write bytes: 345208832; Read time: 27040; Write time: 667 12:38:24 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 31341568; Write bytes: 299665408; Read time: 288; Write time: 1127 12:38:24 INFO - Running post-run listener: _upload_blobber_files 12:38:24 INFO - Blob upload gear active. 12:38:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:38:24 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 12:38:24 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'] 12:38:24 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 12:38:25 INFO - (blobuploader) - INFO - Open directory for files ... 12:38:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:38:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:38:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:25 INFO - (blobuploader) - INFO - Done attempting. 12:38:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:38:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:38:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:26 INFO - (blobuploader) - INFO - Done attempting. 12:38:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:38:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:38:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:29 INFO - (blobuploader) - INFO - Done attempting. 12:38:29 INFO - (blobuploader) - INFO - Iteration through files over. 12:38:29 INFO - Return code: 0 12:38:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:38:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:38:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/335200cc4842f7fd3eb5ac2edc7630f3decec4d021a5c9ce72597bd6ae825979cb39f5ba5cbb11ec23d9cdbbcd1db17e43ecffe9172b8478b65dfdd59cdb15a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42c7ab0ca1d86844e28af2b85ee7dad280580b23d2599a94d699bc072a90f7c6e96d35686b5fa347d3f922734c44efada3df588cd85c97ebe74ad811e1a6a307", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a4a489396c2de009166cbb5b6f83b04b87b7f0a1a6fc8356fec933f414b6528746e36967a7b9655edafb17109fff09c12c09bc756c7fafb526602fbb1c9c245"} 12:38:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:38:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:38:29 INFO - Contents: 12:38:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/335200cc4842f7fd3eb5ac2edc7630f3decec4d021a5c9ce72597bd6ae825979cb39f5ba5cbb11ec23d9cdbbcd1db17e43ecffe9172b8478b65dfdd59cdb15a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42c7ab0ca1d86844e28af2b85ee7dad280580b23d2599a94d699bc072a90f7c6e96d35686b5fa347d3f922734c44efada3df588cd85c97ebe74ad811e1a6a307", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a4a489396c2de009166cbb5b6f83b04b87b7f0a1a6fc8356fec933f414b6528746e36967a7b9655edafb17109fff09c12c09bc756c7fafb526602fbb1c9c245"} 12:38:29 INFO - Running post-run listener: copy_logs_to_upload_dir 12:38:29 INFO - Copying logs to upload dir... 12:38:29 INFO - mkdir: /builds/slave/test/build/upload/logs 12:38:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.207861 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-15 12:38:29.303254) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 12:38:29.307253) ========= 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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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/335200cc4842f7fd3eb5ac2edc7630f3decec4d021a5c9ce72597bd6ae825979cb39f5ba5cbb11ec23d9cdbbcd1db17e43ecffe9172b8478b65dfdd59cdb15a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42c7ab0ca1d86844e28af2b85ee7dad280580b23d2599a94d699bc072a90f7c6e96d35686b5fa347d3f922734c44efada3df588cd85c97ebe74ad811e1a6a307", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a4a489396c2de009166cbb5b6f83b04b87b7f0a1a6fc8356fec933f414b6528746e36967a7b9655edafb17109fff09c12c09bc756c7fafb526602fbb1c9c245"} build_url:https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010970 build_url: 'https://queue.taskcluster.net/v1/task/RjbsaTLNRUKj0DJDGEj8ZQ/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/335200cc4842f7fd3eb5ac2edc7630f3decec4d021a5c9ce72597bd6ae825979cb39f5ba5cbb11ec23d9cdbbcd1db17e43ecffe9172b8478b65dfdd59cdb15a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42c7ab0ca1d86844e28af2b85ee7dad280580b23d2599a94d699bc072a90f7c6e96d35686b5fa347d3f922734c44efada3df588cd85c97ebe74ad811e1a6a307", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a4a489396c2de009166cbb5b6f83b04b87b7f0a1a6fc8356fec933f414b6528746e36967a7b9655edafb17109fff09c12c09bc756c7fafb526602fbb1c9c245"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 12:38:29.342673) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 12:38:29.343009) ========= 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.9QBf4sYoNR/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.ZndAM8bt4d/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.005175 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 12:38:29.392026) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 12:38:29.392352) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 12:38:29.392757) ========= ========= Total master_lag: 0.19 =========