builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0253 starttime: 1488246148.7 results: success (0) buildid: 20170227151041 builduid: d2b79f1e60e04589bd5f3bc3cb7cb70a revision: f6462becdfe1e87617fc7570c06a72abb29751d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:28.697622) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:28.698340) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:28.698612) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:28.855536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:28.855806) ========= 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.IucdcXxdvn/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.NmLXVcgcNH/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-27 17:42:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 71.3M=0s 2017-02-27 17:42:29 (71.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.167684 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:29.049163) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:29.049494) ========= 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.IucdcXxdvn/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.NmLXVcgcNH/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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.024347 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:29.098682) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-27 17:42:29.099016) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f6462becdfe1e87617fc7570c06a72abb29751d0 --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-beta --rev f6462becdfe1e87617fc7570c06a72abb29751d0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IucdcXxdvn/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.NmLXVcgcNH/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-27 17:42:29,207 truncating revision to first 12 chars 2017-02-27 17:42:29,207 Setting DEBUG logging. 2017-02-27 17:42:29,207 attempt 1/10 2017-02-27 17:42:29,208 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f6462becdfe1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-27 17:42:29,977 unpacking tar archive at: mozilla-beta-f6462becdfe1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.049353 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-27 17:42:30.173930) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:30.174291) ========= script_repo_revision: f6462becdfe1e87617fc7570c06a72abb29751d0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:30.174723) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:30.175028) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-27 17:42:30.195004) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-02-27 17:42:30.195393) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IucdcXxdvn/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.NmLXVcgcNH/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 17:42:30 INFO - MultiFileLogger online at 20170227 17:42:30 in /builds/slave/test 17:42:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:42:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:42:30 INFO - {'append_to_log': False, 17:42:30 INFO - 'base_work_dir': '/builds/slave/test', 17:42:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:42:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:42:30 INFO - 'branch': 'Mozilla-Beta', 17:42:30 INFO - 'buildbot_json_path': 'buildprops.json', 17:42:30 INFO - 'config_files': ('talos/mac_config.py',), 17:42:30 INFO - 'default_actions': ('clobber', 17:42:30 INFO - 'read-buildbot-config', 17:42:30 INFO - 'download-and-extract', 17:42:30 INFO - 'populate-webroot', 17:42:30 INFO - 'create-virtualenv', 17:42:30 INFO - 'install', 17:42:30 INFO - 'run-tests'), 17:42:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:42:30 INFO - 'download_minidump_stackwalk': True, 17:42:30 INFO - 'download_symbols': 'ondemand', 17:42:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:42:30 INFO - 'tooltool.py': '/tools/tooltool.py', 17:42:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:42:30 INFO - '/tools/misc-python/virtualenv.py')}, 17:42:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:42:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:42:30 INFO - 'installer_path': 'installer.exe', 17:42:30 INFO - 'log_level': 'info', 17:42:30 INFO - 'log_name': 'talos', 17:42:30 INFO - 'log_to_console': True, 17:42:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:42:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:42:30 INFO - 'opt_config_files': (), 17:42:30 INFO - 'pip_index': False, 17:42:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:42:30 INFO - 'cmd': ('bash', 17:42:30 INFO - '-c', 17:42:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:42:30 INFO - 'enabled': True, 17:42:30 INFO - 'halt_on_failure': False, 17:42:30 INFO - 'name': 'check_screen_resolution'},), 17:42:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:42:30 INFO - 'cmd': ('bash', 17:42:30 INFO - '-c', 17:42:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:42:30 INFO - 'enabled': True, 17:42:30 INFO - 'halt_on_failure': False, 17:42:30 INFO - 'name': 'check_screen_resolution'},), 17:42:30 INFO - 'run_cmd_checks_enabled': True, 17:42:30 INFO - 'sps_profile': False, 17:42:30 INFO - 'sps_profile_interval': 0, 17:42:30 INFO - 'suite': 'dromaeojs', 17:42:30 INFO - 'system_bits': '32', 17:42:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:42:30 INFO - 'title': 't-yosemite-r7-0253', 17:42:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:42:30 INFO - 'use_talos_json': True, 17:42:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:42:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:42:30 INFO - 'work_dir': 'build'} 17:42:30 INFO - [mozharness: 2017-02-28 01:42:30.412248Z] Running clobber step. 17:42:30 INFO - Running pre-action listener: _resource_record_pre_action 17:42:30 INFO - Running main action method: clobber 17:42:30 INFO - rmtree: /builds/slave/test/build 17:42:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:42:32 INFO - Running post-action listener: _resource_record_post_action 17:42:32 INFO - [mozharness: 2017-02-28 01:42:32.790358Z] Finished clobber step (success) 17:42:32 INFO - [mozharness: 2017-02-28 01:42:32.790438Z] Running read-buildbot-config step. 17:42:32 INFO - Running pre-action listener: _resource_record_pre_action 17:42:32 INFO - Running main action method: read_buildbot_config 17:42:32 INFO - Using buildbot properties: 17:42:32 INFO - { 17:42:32 INFO - "project": "", 17:42:32 INFO - "product": "firefox", 17:42:32 INFO - "script_repo_revision": "production", 17:42:32 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 17:42:32 INFO - "repository": "", 17:42:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 17:42:32 INFO - "buildid": "20170227151041", 17:42:32 INFO - "pgo_build": "False", 17:42:32 INFO - "basedir": "/builds/slave/test", 17:42:32 INFO - "buildnumber": 1, 17:42:32 INFO - "slavename": "t-yosemite-r7-0253", 17:42:32 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 17:42:32 INFO - "platform": "yosemite_r7", 17:42:32 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 17:42:32 INFO - "branch": "mozilla-beta", 17:42:32 INFO - "repo_path": "releases/mozilla-beta", 17:42:32 INFO - "revision": "f6462becdfe1e87617fc7570c06a72abb29751d0", 17:42:32 INFO - "stage_platform": "macosx64", 17:42:32 INFO - "builduid": "d2b79f1e60e04589bd5f3bc3cb7cb70a", 17:42:32 INFO - "slavebuilddir": "test" 17:42:32 INFO - } 17:42:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 17:42:32 INFO - Running post-action listener: _resource_record_post_action 17:42:32 INFO - [mozharness: 2017-02-28 01:42:32.793655Z] Finished read-buildbot-config step (success) 17:42:32 INFO - [mozharness: 2017-02-28 01:42:32.793748Z] Running download-and-extract step. 17:42:32 INFO - Running pre-action listener: _resource_record_pre_action 17:42:32 INFO - Running main action method: download_and_extract 17:42:32 INFO - mkdir: /builds/slave/test/build/tests 17:42:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:42:32 INFO - trying https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 17:42:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 17:42:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 17:42:33 INFO - Downloaded 1165 bytes. 17:42:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 17:42:33 INFO - Using the following test package requirements: 17:42:33 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 17:42:33 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 17:42:33 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 17:42:33 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 17:42:33 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 17:42:33 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:42:33 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 17:42:33 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 17:42:33 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 17:42:33 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 17:42:33 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 17:42:33 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 17:42:33 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 17:42:33 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 17:42:33 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 17:42:33 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 17:42:33 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 17:42:33 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 17:42:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 17:42:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 17:42:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 17:42:36 INFO - Http code: 200 17:42:36 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 17:42:36 INFO - x-amz-cf-id: s7SldS999vV2Lr6a2Hm5UykAAnflGn80qnQddUWsC24uuXx8Mu0htg== 17:42:36 INFO - x-amz-version-id: LDSqtEAdQBMSZpFPUflVaKHeSdbZe_m1 17:42:36 INFO - Expected file size: 16093396 17:42:36 INFO - Obtained file size: 16093396 17:42:43 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 17:42:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 17:42:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 17:42:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 17:42:44 INFO - Http code: 200 17:42:44 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 17:42:44 INFO - x-amz-cf-id: F6cUYIyAebpYIRcMqM1p50gJdzQxP8n8XW2DPS56fgzWymVQK6qPIg== 17:42:44 INFO - x-amz-version-id: LDSqtEAdQBMSZpFPUflVaKHeSdbZe_m1 17:42:44 INFO - Expected file size: 16093396 17:42:44 INFO - Obtained file size: 16093396 17:42:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 17:42:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 17:42:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 17:42:51 INFO - Http code: 200 17:42:51 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 17:42:51 INFO - x-amz-cf-id: dhdyQ0PndMvIwc6fRgMCGD_ftm5RLzqR40f625xvnQkvOq2ApMIQAg== 17:42:51 INFO - x-amz-version-id: gpWElgFpaxAZK5wTkBTLMlwB8dZx18HI 17:42:51 INFO - Expected file size: 14402992 17:42:51 INFO - Obtained file size: 14402992 17:42:52 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')]} 17:42:52 INFO - trying https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.dmg 17:42:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 17:42:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 17:42:56 INFO - Downloaded 82116841 bytes. 17:42:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.dmg 17:42:56 INFO - mkdir: /builds/slave/test/properties 17:42:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:42:56 INFO - Writing to file /builds/slave/test/properties/build_url 17:42:56 INFO - Contents: 17:42:56 INFO - build_url:https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.dmg 17:42:57 INFO - Running post-action listener: _resource_record_post_action 17:42:57 INFO - Running post-action listener: set_extra_try_arguments 17:42:57 INFO - [mozharness: 2017-02-28 01:42:57.557912Z] Finished download-and-extract step (success) 17:42:57 INFO - [mozharness: 2017-02-28 01:42:57.558044Z] Running populate-webroot step. 17:42:57 INFO - Running pre-action listener: _resource_record_pre_action 17:42:57 INFO - Running main action method: populate_webroot 17:42:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:42:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:42:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:42:57 INFO - '--tppagecycles', 17:42:57 INFO - '7'], 17:42:57 INFO - 'tests': ['tsvgm']}}, 17:42:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:42:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:42:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:42:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:42:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:42:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:42:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:57 INFO - 'talos_options': ['--disable-e10s'], 17:42:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:42:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:42:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:57 INFO - 'talos_options': ['--disable-e10s'], 17:42:57 INFO - 'tests': ['damp', 'tps']}, 17:42:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:57 INFO - 'tests': ['damp', 'tps']}, 17:42:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:42:57 INFO - 'tests': ['dromaeo_dom']}, 17:42:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:42:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:42:57 INFO - 'tests': ['basic_compositor_video']}, 17:42:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:42:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:42:57 INFO - 'tests': ['a11yr', 17:42:57 INFO - 'ts_paint', 17:42:57 INFO - 'tpaint', 17:42:57 INFO - 'sessionrestore', 17:42:57 INFO - 'sessionrestore_no_auto_restore', 17:42:57 INFO - 'tabpaint']}, 17:42:57 INFO - 'other-e10s': {'tests': ['a11yr', 17:42:57 INFO - 'ts_paint', 17:42:57 INFO - 'tpaint', 17:42:57 INFO - 'sessionrestore', 17:42:57 INFO - 'sessionrestore_no_auto_restore', 17:42:57 INFO - 'tabpaint']}, 17:42:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:42:57 INFO - 'ts_paint', 17:42:57 INFO - 'tpaint', 17:42:57 INFO - 'sessionrestore', 17:42:57 INFO - 'sessionrestore_no_auto_restore', 17:42:57 INFO - 'tabpaint']}, 17:42:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:42:57 INFO - 'ts_paint', 17:42:57 INFO - 'tpaint', 17:42:57 INFO - 'sessionrestore', 17:42:57 INFO - 'sessionrestore_no_auto_restore', 17:42:57 INFO - 'tabpaint']}, 17:42:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:42:57 INFO - 'tests': ['a11yr', 17:42:57 INFO - 'ts_paint', 17:42:57 INFO - 'tpaint', 17:42:57 INFO - 'sessionrestore', 17:42:57 INFO - 'sessionrestore_no_auto_restore', 17:42:57 INFO - 'tabpaint']}, 17:42:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:42:57 INFO - 'tests': ['a11yr', 17:42:57 INFO - 'ts_paint', 17:42:57 INFO - 'tpaint', 17:42:57 INFO - 'sessionrestore', 17:42:57 INFO - 'sessionrestore_no_auto_restore', 17:42:57 INFO - 'tabpaint']}, 17:42:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:42:57 INFO - 'tests': ['tsvgx', 17:42:57 INFO - 'tsvgr_opacity', 17:42:57 INFO - 'tart', 17:42:57 INFO - 'tscrollx', 17:42:57 INFO - 'cart']}, 17:42:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:42:57 INFO - 'tsvgr_opacity', 17:42:57 INFO - 'tart', 17:42:57 INFO - 'tscrollx', 17:42:57 INFO - 'cart']}, 17:42:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:57 INFO - 'talos_options': ['--disable-e10s'], 17:42:57 INFO - 'tests': ['tp5o']}, 17:42:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:57 INFO - 'tests': ['tp5o']}, 17:42:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:57 INFO - 'talos_options': ['--disable-e10s', 17:42:57 INFO - '--xperf_path', 17:42:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:42:57 INFO - 'tests': ['tp5n']}, 17:42:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:57 INFO - 'talos_options': ['--xperf_path', 17:42:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:42:57 INFO - 'tests': ['tp5n']}}, 17:42:57 INFO - 'talos.zip': {'path': '', 17:42:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:42:57 INFO - Running post-action listener: _resource_record_post_action 17:42:57 INFO - [mozharness: 2017-02-28 01:42:57.576424Z] Finished populate-webroot step (success) 17:42:57 INFO - [mozharness: 2017-02-28 01:42:57.576560Z] Running create-virtualenv step. 17:42:57 INFO - Running pre-action listener: _resource_record_pre_action 17:42:57 INFO - Running main action method: create_virtualenv 17:42:57 INFO - Creating virtualenv /builds/slave/test/build/venv 17:42:57 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 17:42:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:42:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:42:57 INFO - Using real prefix '/tools/python27' 17:42:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:42:58 INFO - Installing distribute.............................................................................................................................................................................................done. 17:43:01 INFO - Installing pip.................done. 17:43:01 INFO - Return code: 0 17:43:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:43:01 INFO - Reading from file tmpfile_stdout 17:43:01 INFO - Output received: 17:43:01 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) 17:43:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:43:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:43:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:43:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e074490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd53cc1ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de88d30>, '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 0x10dceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de80b20>, '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.IucdcXxdvn/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.NmLXVcgcNH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 17:43:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:43:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IucdcXxdvn/Render', 17:43:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:43:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:43:01 INFO - 'HOME': '/Users/cltbld', 17:43:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:43:01 INFO - 'LOGNAME': 'cltbld', 17:43:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:43:01 INFO - 'MOZ_NO_REMOTE': '1', 17:43:01 INFO - 'NO_EM_RESTART': '1', 17:43:01 INFO - 'PAGER': '/bin/cat', 17:43:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:43:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:43:01 INFO - 'PWD': '/builds/slave/test', 17:43:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:43:01 INFO - 'SHELL': '/bin/bash', 17:43:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmLXVcgcNH/Listeners', 17:43:01 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 17:43:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:43:01 INFO - 'USER': 'cltbld', 17:43:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:43:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:43:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:43:01 INFO - 'XPC_FLAGS': '0x0', 17:43:01 INFO - 'XPC_SERVICE_NAME': '0', 17:43:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:43:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:43:01 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 17:43:01 INFO - Cleaning up... 17:43:01 INFO - Return code: 0 17:43:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:43:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:43:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:43:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e074490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd53cc1ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de88d30>, '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 0x10dceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de80b20>, '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.IucdcXxdvn/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.NmLXVcgcNH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 17:43:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:43:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IucdcXxdvn/Render', 17:43:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:43:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:43:01 INFO - 'HOME': '/Users/cltbld', 17:43:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:43:01 INFO - 'LOGNAME': 'cltbld', 17:43:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:43:01 INFO - 'MOZ_NO_REMOTE': '1', 17:43:01 INFO - 'NO_EM_RESTART': '1', 17:43:01 INFO - 'PAGER': '/bin/cat', 17:43:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:43:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:43:01 INFO - 'PWD': '/builds/slave/test', 17:43:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:43:01 INFO - 'SHELL': '/bin/bash', 17:43:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmLXVcgcNH/Listeners', 17:43:01 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 17:43:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:43:01 INFO - 'USER': 'cltbld', 17:43:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:43:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:43:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:43:01 INFO - 'XPC_FLAGS': '0x0', 17:43:01 INFO - 'XPC_SERVICE_NAME': '0', 17:43:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:43:02 INFO - Downloading/unpacking psutil>=3.1.1 17:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:43:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:43:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:43:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:43:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:43:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:43:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:43:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:43:06 INFO - Installing collected packages: psutil 17:43:06 INFO - Running setup.py install for psutil 17:43:06 INFO - building 'psutil._psutil_osx' extension 17:43:06 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 17:43:06 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 17:43:06 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 17:43:06 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 17:43:06 INFO - building 'psutil._psutil_posix' extension 17:43:06 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 17:43:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:43:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:43:07 INFO - ^ 17:43:07 INFO - 1 warning generated. 17:43:07 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 17:43:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:43:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:43:07 INFO - Successfully installed psutil 17:43:07 INFO - Cleaning up... 17:43:07 INFO - Return code: 0 17:43:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:43:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:43:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:43:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:43:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:07 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 0x10e074490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd53cc1ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de88d30>, '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 0x10dceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de80b20>, '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.IucdcXxdvn/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.NmLXVcgcNH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 17:43:07 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 17:43:07 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 17:43:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IucdcXxdvn/Render', 17:43:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:43:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:43:07 INFO - 'HOME': '/Users/cltbld', 17:43:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:43:07 INFO - 'LOGNAME': 'cltbld', 17:43:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:43:07 INFO - 'MOZ_NO_REMOTE': '1', 17:43:07 INFO - 'NO_EM_RESTART': '1', 17:43:07 INFO - 'PAGER': '/bin/cat', 17:43:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:43:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:43:07 INFO - 'PWD': '/builds/slave/test', 17:43:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:43:07 INFO - 'SHELL': '/bin/bash', 17:43:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmLXVcgcNH/Listeners', 17:43:07 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 17:43:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:43:07 INFO - 'USER': 'cltbld', 17:43:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:43:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:43:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:43:07 INFO - 'XPC_FLAGS': '0x0', 17:43:07 INFO - 'XPC_SERVICE_NAME': '0', 17:43:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:43:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:43:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:43:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:43:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:43:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:43:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:43:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:43:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:43:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:43:10 INFO - Installing collected packages: mozsystemmonitor 17:43:10 INFO - Running setup.py install for mozsystemmonitor 17:43:10 INFO - Successfully installed mozsystemmonitor 17:43:10 INFO - Cleaning up... 17:43:10 INFO - Return code: 0 17:43:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:43: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')]} 17:43:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:43:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43: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')]} 17:43:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:43:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e074490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd53cc1ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de88d30>, '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 0x10dceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de80b20>, '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.IucdcXxdvn/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.NmLXVcgcNH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 17:43: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==2.5.1'] in /builds/slave/test/build 17:43: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==2.5.1 17:43:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IucdcXxdvn/Render', 17:43:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:43:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:43:10 INFO - 'HOME': '/Users/cltbld', 17:43:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:43:10 INFO - 'LOGNAME': 'cltbld', 17:43:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:43:10 INFO - 'MOZ_NO_REMOTE': '1', 17:43:10 INFO - 'NO_EM_RESTART': '1', 17:43:10 INFO - 'PAGER': '/bin/cat', 17:43:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:43:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:43:10 INFO - 'PWD': '/builds/slave/test', 17:43:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:43:10 INFO - 'SHELL': '/bin/bash', 17:43:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmLXVcgcNH/Listeners', 17:43:10 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 17:43:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:43:10 INFO - 'USER': 'cltbld', 17:43:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:43:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:43:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:43:10 INFO - 'XPC_FLAGS': '0x0', 17:43:10 INFO - 'XPC_SERVICE_NAME': '0', 17:43:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:43:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:43:10 INFO - Downloading/unpacking jsonschema==2.5.1 17:43:10 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 17:43:10 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 17:43:10 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 17:43:10 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 17:43:10 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 17:43:10 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 17:43:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:43:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:43:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:43:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:43:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:43:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:43:13 INFO - Downloading functools32-3.2.3-2.tar.gz 17:43:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:43:13 INFO - warning: no files found matching '*.txt' 17:43:13 INFO - no previously-included directories found matching 'build' 17:43:13 INFO - no previously-included directories found matching 'dist' 17:43:13 INFO - no previously-included directories found matching '.git*' 17:43:13 INFO - Installing collected packages: jsonschema, functools32 17:43:13 INFO - Running setup.py install for functools32 17:43:14 INFO - warning: no files found matching '*.txt' 17:43:14 INFO - no previously-included directories found matching 'build' 17:43:14 INFO - no previously-included directories found matching 'dist' 17:43:14 INFO - no previously-included directories found matching '.git*' 17:43:14 INFO - Successfully installed jsonschema functools32 17:43:14 INFO - Cleaning up... 17:43:14 INFO - Return code: 0 17:43:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:43:14 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')]} 17:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:43:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:14 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')]} 17:43:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:43:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:14 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 0x10e074490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd53cc1ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de88d30>, '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 0x10dceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de80b20>, '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.IucdcXxdvn/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.NmLXVcgcNH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 17:43:14 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 17:43:14 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 17:43:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IucdcXxdvn/Render', 17:43:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:43:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:43:14 INFO - 'HOME': '/Users/cltbld', 17:43:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:43:14 INFO - 'LOGNAME': 'cltbld', 17:43:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:43:14 INFO - 'MOZ_NO_REMOTE': '1', 17:43:14 INFO - 'NO_EM_RESTART': '1', 17:43:14 INFO - 'PAGER': '/bin/cat', 17:43:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:43:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:43:14 INFO - 'PWD': '/builds/slave/test', 17:43:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:43:14 INFO - 'SHELL': '/bin/bash', 17:43:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmLXVcgcNH/Listeners', 17:43:14 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 17:43:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:43:14 INFO - 'USER': 'cltbld', 17:43:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:43:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:43:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:43:14 INFO - 'XPC_FLAGS': '0x0', 17:43:14 INFO - 'XPC_SERVICE_NAME': '0', 17:43:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:43:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:43:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:43:14 INFO - Cleaning up... 17:43:14 INFO - Return code: 0 17:43:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:43:14 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')]} 17:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:43:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:14 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')]} 17:43:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:43:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:14 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 0x10e074490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd53cc1ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de88d30>, '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 0x10dceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de80b20>, '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.IucdcXxdvn/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.NmLXVcgcNH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 17:43:14 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 17:43:14 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 17:43:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IucdcXxdvn/Render', 17:43:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:43:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:43:14 INFO - 'HOME': '/Users/cltbld', 17:43:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:43:14 INFO - 'LOGNAME': 'cltbld', 17:43:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:43:14 INFO - 'MOZ_NO_REMOTE': '1', 17:43:14 INFO - 'NO_EM_RESTART': '1', 17:43:14 INFO - 'PAGER': '/bin/cat', 17:43:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:43:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:43:14 INFO - 'PWD': '/builds/slave/test', 17:43:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:43:14 INFO - 'SHELL': '/bin/bash', 17:43:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmLXVcgcNH/Listeners', 17:43:14 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 17:43:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:43:14 INFO - 'USER': 'cltbld', 17:43:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:43:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:43:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:43:14 INFO - 'XPC_FLAGS': '0x0', 17:43:14 INFO - 'XPC_SERVICE_NAME': '0', 17:43:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:43:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:43:14 INFO - Downloading/unpacking blobuploader==1.2.4 17:43:14 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 17:43:14 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 17:43:14 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 17:43:14 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 17:43:14 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 17:43:14 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 17:43:17 INFO - Downloading blobuploader-1.2.4.tar.gz 17:43:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:43:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:43:17 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 17:43:17 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 17:43:17 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 17:43:17 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 17:43:17 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 17:43:17 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 17:43:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:43:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:43:18 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 17:43:18 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 17:43:18 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 17:43:18 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 17:43:18 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 17:43:18 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 17:43:18 INFO - Downloading docopt-0.6.1.tar.gz 17:43:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:43:18 INFO - Installing collected packages: blobuploader, requests, docopt 17:43:18 INFO - Running setup.py install for blobuploader 17:43:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:43:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:43:18 INFO - Running setup.py install for requests 17:43:19 INFO - Running setup.py install for docopt 17:43:19 INFO - Successfully installed blobuploader requests docopt 17:43:19 INFO - Cleaning up... 17:43:19 INFO - Return code: 0 17:43:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:43:19 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')]} 17:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:43:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:19 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')]} 17:43:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:43:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:19 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 0x10e074490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd53cc1ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de88d30>, '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 0x10dceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de80b20>, '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.IucdcXxdvn/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.NmLXVcgcNH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 17:43:19 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 17:43:19 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 17:43:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IucdcXxdvn/Render', 17:43:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:43:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:43:19 INFO - 'HOME': '/Users/cltbld', 17:43:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:43:19 INFO - 'LOGNAME': 'cltbld', 17:43:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:43:19 INFO - 'MOZ_NO_REMOTE': '1', 17:43:19 INFO - 'NO_EM_RESTART': '1', 17:43:19 INFO - 'PAGER': '/bin/cat', 17:43:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:43:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:43:19 INFO - 'PWD': '/builds/slave/test', 17:43:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:43:19 INFO - 'SHELL': '/bin/bash', 17:43:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmLXVcgcNH/Listeners', 17:43:19 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 17:43:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:43:19 INFO - 'USER': 'cltbld', 17:43:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:43:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:43:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:43:19 INFO - 'XPC_FLAGS': '0x0', 17:43:19 INFO - 'XPC_SERVICE_NAME': '0', 17:43:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:43:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:43:19 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-rFNsCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:43:19 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-7xA6TK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:43:19 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-OIHWDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:43:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-SVQmUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:43:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-0AuQ1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:43:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-chXoEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:43:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-cUajLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:43:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-OP_7hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:43:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-H3LzTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:43:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-8RMWt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:43:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Oo5NKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:43:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-b7pzY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:43:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Me34aC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:43:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Zf0F3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:43:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-KngYcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:43:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-lceQk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:43:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-EXYorT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:43:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:43:21 INFO - Running setup.py install for manifestparser 17:43:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:43:21 INFO - Running setup.py install for mozcrash 17:43:21 INFO - Running setup.py install for mozdebug 17:43:22 INFO - Running setup.py install for mozdevice 17:43:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:43:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:43:22 INFO - Running setup.py install for mozfile 17:43:22 INFO - Running setup.py install for mozhttpd 17:43:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:43:22 INFO - Running setup.py install for mozinfo 17:43:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:43:22 INFO - Running setup.py install for mozInstall 17:43:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:43:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:43:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:43:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:43:22 INFO - Running setup.py install for mozleak 17:43:22 INFO - Running setup.py install for mozlog 17:43:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:43:23 INFO - Running setup.py install for moznetwork 17:43:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:43:23 INFO - Running setup.py install for mozprocess 17:43:23 INFO - Running setup.py install for mozprofile 17:43:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:43:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:43:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:43:23 INFO - Running setup.py install for mozrunner 17:43:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:43:23 INFO - Running setup.py install for mozscreenshot 17:43:23 INFO - Running setup.py install for moztest 17:43:24 INFO - Running setup.py install for mozversion 17:43:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:43:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:43:24 INFO - Cleaning up... 17:43:24 INFO - Return code: 0 17:43:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:43:24 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')]} 17:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:43:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:24 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')]} 17:43:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:43:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:24 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 0x10e074490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd53cc1ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de88d30>, '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 0x10dceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de80b20>, '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.IucdcXxdvn/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.NmLXVcgcNH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 17:43:24 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 17:43:24 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 17:43:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IucdcXxdvn/Render', 17:43:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:43:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:43:24 INFO - 'HOME': '/Users/cltbld', 17:43:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:43:24 INFO - 'LOGNAME': 'cltbld', 17:43:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:43:24 INFO - 'MOZ_NO_REMOTE': '1', 17:43:24 INFO - 'NO_EM_RESTART': '1', 17:43:24 INFO - 'PAGER': '/bin/cat', 17:43:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:43:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:43:24 INFO - 'PWD': '/builds/slave/test', 17:43:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:43:24 INFO - 'SHELL': '/bin/bash', 17:43:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmLXVcgcNH/Listeners', 17:43:24 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 17:43:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:43:24 INFO - 'USER': 'cltbld', 17:43:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:43:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:43:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:43:24 INFO - 'XPC_FLAGS': '0x0', 17:43:24 INFO - 'XPC_SERVICE_NAME': '0', 17:43:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:43:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:43:24 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-5qT4D3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:43:24 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)) 17:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:43:24 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-XeUtSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:43:24 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)) 17:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:43:24 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-TB8WZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:43:24 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)) 17:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:43:24 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-ZvpF5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:43:24 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)) 17:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:43:24 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-FcIHe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:43:24 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)) 17:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:43:24 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-zWkKfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:43:25 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)) 17:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:43:25 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-1NQSMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:43:25 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)) 17:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:43:25 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-B0RyUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:43:25 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)) 17:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:43:25 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-TJgd14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:43:25 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)) 17:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:43:25 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-uLP1eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:43:25 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)) 17:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:43:25 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-NUrdVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:43:25 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)) 17:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:43:25 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-JGV5Cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:43:25 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)) 17:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:43:25 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-zHPVaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:43:25 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)) 17:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:43:25 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-_z5ODm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:43:25 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)) 17:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:43:25 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-h649Nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:43:26 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)) 17:43:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:43:26 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-EHUl_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:43:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:43:26 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-c1wUQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:43:26 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)) 17:43:26 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)) 17:43:26 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)) 17:43:26 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)) 17:43:26 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)) 17:43:26 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)) 17:43:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:43:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:43:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:43:28 INFO - Downloading blessings-1.6.tar.gz 17:43:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:43:29 INFO - Installing collected packages: blessings 17:43:29 INFO - Running setup.py install for blessings 17:43:29 INFO - Successfully installed blessings 17:43:29 INFO - Cleaning up... 17:43:29 INFO - Return code: 0 17:43:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:43:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:43:29 INFO - Reading from file tmpfile_stdout 17:43:29 INFO - Current package versions: 17:43:29 INFO - blessings == 1.6 17:43:29 INFO - blobuploader == 1.2.4 17:43:29 INFO - docopt == 0.6.1 17:43:29 INFO - functools32 == 3.2.3-2 17:43:29 INFO - jsonschema == 2.5.1 17:43:29 INFO - manifestparser == 1.1 17:43:29 INFO - mozInstall == 1.12 17:43:29 INFO - mozcrash == 1.0 17:43:29 INFO - mozdebug == 0.1 17:43:29 INFO - mozdevice == 0.48 17:43:29 INFO - mozfile == 1.2 17:43:29 INFO - mozhttpd == 0.7 17:43:29 INFO - mozinfo == 0.9 17:43:29 INFO - mozleak == 0.1 17:43:29 INFO - mozlog == 3.4 17:43:29 INFO - moznetwork == 0.27 17:43:29 INFO - mozprocess == 0.23 17:43:29 INFO - mozprofile == 0.28 17:43:29 INFO - mozrunner == 6.13 17:43:29 INFO - mozscreenshot == 0.1 17:43:29 INFO - mozsystemmonitor == 0.3 17:43:29 INFO - moztest == 0.8 17:43:29 INFO - mozversion == 1.4 17:43:29 INFO - psutil == 3.1.1 17:43:29 INFO - requests == 1.2.3 17:43:29 INFO - wsgiref == 0.1.2 17:43:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:43:29 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')]} 17:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:29 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')]} 17:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:29 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 0x10e074490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd53cc1ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de88d30>, '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 0x10dceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de80b20>, '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.IucdcXxdvn/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.NmLXVcgcNH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 17:43:29 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 17:43:29 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 17:43:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IucdcXxdvn/Render', 17:43:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:43:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:43:29 INFO - 'HOME': '/Users/cltbld', 17:43:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:43:29 INFO - 'LOGNAME': 'cltbld', 17:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:43:29 INFO - 'MOZ_NO_REMOTE': '1', 17:43:29 INFO - 'NO_EM_RESTART': '1', 17:43:29 INFO - 'PAGER': '/bin/cat', 17:43:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:43:29 INFO - 'PWD': '/builds/slave/test', 17:43:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:43:29 INFO - 'SHELL': '/bin/bash', 17:43:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmLXVcgcNH/Listeners', 17:43:29 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 17:43:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:43:29 INFO - 'USER': 'cltbld', 17:43:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:43:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:43:29 INFO - 'XPC_FLAGS': '0x0', 17:43:29 INFO - 'XPC_SERVICE_NAME': '0', 17:43:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:43:29 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)) 17:43:29 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)) 17:43:29 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)) 17:43:29 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)) 17:43:29 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)) 17:43:29 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)) 17:43:29 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)) 17:43:29 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)) 17:43:29 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)) 17:43:29 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)) 17:43:29 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)) 17:43:29 INFO - Cleaning up... 17:43:29 INFO - Return code: 0 17:43:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:43:29 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')]} 17:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:29 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')]} 17:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:43:29 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 0x10e074490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd53cc1ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de88d30>, '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 0x10dceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de80b20>, '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.IucdcXxdvn/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.NmLXVcgcNH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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 17:43:29 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 17:43:29 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 17:43:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IucdcXxdvn/Render', 17:43:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:43:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:43:29 INFO - 'HOME': '/Users/cltbld', 17:43:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:43:29 INFO - 'LOGNAME': 'cltbld', 17:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:43:29 INFO - 'MOZ_NO_REMOTE': '1', 17:43:29 INFO - 'NO_EM_RESTART': '1', 17:43:29 INFO - 'PAGER': '/bin/cat', 17:43:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:43:29 INFO - 'PWD': '/builds/slave/test', 17:43:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:43:29 INFO - 'SHELL': '/bin/bash', 17:43:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmLXVcgcNH/Listeners', 17:43:29 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 17:43:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:43:29 INFO - 'USER': 'cltbld', 17:43:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:43:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:43:29 INFO - 'XPC_FLAGS': '0x0', 17:43:29 INFO - 'XPC_SERVICE_NAME': '0', 17:43:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:43:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:43:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:43:30 INFO - Cleaning up... 17:43:30 INFO - Return code: 0 17:43:30 INFO - Running post-action listener: _resource_record_post_action 17:43:30 INFO - Running post-action listener: _start_resource_monitoring 17:43:30 INFO - Starting resource monitoring. 17:43:30 INFO - [mozharness: 2017-02-28 01:43:30.111512Z] Finished create-virtualenv step (success) 17:43:30 INFO - [mozharness: 2017-02-28 01:43:30.112524Z] Running install step. 17:43:30 INFO - Running pre-action listener: _resource_record_pre_action 17:43:30 INFO - Running main action method: install 17:43:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:43:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:43:30 INFO - Reading from file tmpfile_stdout 17:43:30 INFO - Detecting whether we're running mozinstall >=1.0... 17:43:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:43:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:43:30 INFO - Reading from file tmpfile_stdout 17:43:30 INFO - Output received: 17:43:30 INFO - Usage: mozinstall [options] installer 17:43:30 INFO - Options: 17:43:30 INFO - -h, --help show this help message and exit 17:43:30 INFO - -d DEST, --destination=DEST 17:43:30 INFO - Directory to install application into. [default: 17:43:30 INFO - "/builds/slave/test"] 17:43:30 INFO - --app=APP Application being installed. [default: firefox] 17:43:30 INFO - mkdir: /builds/slave/test/build/application 17:43:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:43:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 17:43:52 INFO - Reading from file tmpfile_stdout 17:43:52 INFO - Output received: 17:43:52 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 17:43:52 INFO - Running post-action listener: _resource_record_post_action 17:43:52 INFO - [mozharness: 2017-02-28 01:43:52.039068Z] Finished install step (success) 17:43:52 INFO - [mozharness: 2017-02-28 01:43:52.039204Z] Running run-tests step. 17:43:52 INFO - Running pre-action listener: _resource_record_pre_action 17:43:52 INFO - Running main action method: run_tests 17:43:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:43:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:43:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:43:52 INFO - 2017-02-27 17:43:52.053 screenresolution[1641:13002] starting screenresolution argv=screenresolution get 17:43:52 INFO - 2017-02-27 17:43:52.056 screenresolution[1641:13002] Display 0: 1600x1200x32@60 17:43:52 INFO - 2017-02-27 17:43:52.062 screenresolution[1642:13005] starting screenresolution argv=screenresolution list 17:43:52 INFO - Available Modes on Display 0 17:43:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:43:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:43:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:43:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:43:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:43:52 INFO - Intel Iris: 17:43:52 INFO - Chipset Model: Intel Iris 17:43:52 INFO - Type: GPU 17:43:52 INFO - Bus: Built-In 17:43:52 INFO - VRAM (Dynamic, Max): 1536 MB 17:43:52 INFO - Vendor: Intel (0x8086) 17:43:52 INFO - Device ID: 0x0a2e 17:43:52 INFO - Revision ID: 0x0009 17:43:52 INFO - Displays: 17:43:52 INFO - Display: 17:43:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:43:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:43:52 INFO - Display Serial Number: 1600x1200 60 17:43:52 INFO - Main Display: Yes 17:43:52 INFO - Mirror: Off 17:43:52 INFO - Online: Yes 17:43:52 INFO - Rotation: Supported 17:43:52 INFO - Return code: 0 17:43:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:43:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:43:52 INFO - Python 2.7.3 17:43:52 INFO - Return code: 0 17:43:52 INFO - grabbing minidump binary from tooltool 17:43:52 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')]} 17:43:52 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 0x10de88d30>, '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 0x10dceedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de80b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:43:52 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 17:43:52 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 17:43:52 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 17:43:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:43:52 INFO - Return code: 0 17:43:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:43:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:43:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:43:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:43:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:43:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--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 17:43:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0253 --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 17:43:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IucdcXxdvn/Render', 17:43:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:43:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:43:52 INFO - 'HOME': '/Users/cltbld', 17:43:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:43:52 INFO - 'LOGNAME': 'cltbld', 17:43:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:43:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:43:52 INFO - 'MOZ_NO_REMOTE': '1', 17:43:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:43:52 INFO - 'NO_EM_RESTART': '1', 17:43:52 INFO - 'PAGER': '/bin/cat', 17:43:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:43:52 INFO - 'PWD': '/builds/slave/test', 17:43:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:43:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:43:52 INFO - 'SHELL': '/bin/bash', 17:43:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmLXVcgcNH/Listeners', 17:43:52 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 17:43:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:43:52 INFO - 'USER': 'cltbld', 17:43:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:43:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:43:52 INFO - 'XPC_FLAGS': '0x0', 17:43:52 INFO - 'XPC_SERVICE_NAME': '0', 17:43:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:43:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--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 17:43:52 INFO - mozversion application_buildid: 20170227151041 17:43:52 INFO - mozversion application_changeset: f6462becdfe1e87617fc7570c06a72abb29751d0 17:43:52 INFO - mozversion application_display_name: Firefox 17:43:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:43:52 INFO - mozversion application_name: Firefox 17:43:52 INFO - mozversion application_remotingname: firefox 17:43:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:43:52 INFO - mozversion application_vendor: Mozilla 17:43:52 INFO - mozversion application_version: 52.0 17:43:52 INFO - mozversion platform_buildid: 20170227151041 17:43:52 INFO - mozversion platform_changeset: f6462becdfe1e87617fc7570c06a72abb29751d0 17:43:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:43:52 INFO - mozversion platform_version: 52.0 17:43:52 INFO - using testdate: 1488246232 17:43:52 INFO - actual date: 1488246232 17:43:52 INFO - starting webserver on 'localhost:49246' 17:43:52 INFO - SUITE-START | Running 2 tests 17:43:52 INFO - TEST-START | dromaeo_css 17:43:52 INFO - operating with platform_type : mac_ 17:43:52 INFO - Initialising browser for dromaeo_css test... 17:43:52 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpMTnc5q/profile http://localhost:49246/getInfo.html) 17:43:55 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 17:43:55 INFO - PROCESS | 1652 | colorDepth:24 17:43:55 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 17:43:55 INFO - PROCESS | 1652 | __metrics 17:43:55 INFO - PROCESS | 1652 | 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" 17:43:55 INFO - PROCESS | 1652 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:43:56 INFO - TEST-INFO | 1652: exit 0 17:43:56 INFO - Browser initialized. 17:43:56 INFO - Running cycle 1/1 for dromaeo_css test... 17:43:56 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpMTnc5q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:44:08 INFO - PROCESS | 1656 | RSS: Main: 167464960 17:44:08 INFO - PROCESS | 1656 | 17:47:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 17:47:13 INFO - PROCESS | 1656 | RSS: Main: 282189824 17:47:13 INFO - PROCESS | 1656 | 17:50:16 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 17:50:16 INFO - PROCESS | 1656 | RSS: Main: 302882816 17:50:16 INFO - PROCESS | 1656 | 17:53:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 17:53:20 INFO - PROCESS | 1656 | RSS: Main: 256942080 17:53:20 INFO - PROCESS | 1656 | 17:56:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 17:56:22 INFO - PROCESS | 1656 | RSS: Main: 278601728 17:56:22 INFO - PROCESS | 1656 | 17:59:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 17:59:26 INFO - PROCESS | 1656 | RSS: Main: 255512576 17:59:26 INFO - PROCESS | 1656 | 18:02:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 18:02:30 INFO - PROCESS | 1656 | RSS: Main: 330084352 18:02:30 INFO - PROCESS | 1656 | 18:02:30 INFO - PROCESS | 1656 | __start_tp_report 18:02:30 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 18:02:30 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 18:02:30 INFO - PROCESS | 1656 | |i|pagename|runs| 18:02:30 INFO - PROCESS | 1656 | |0;dojo.html;3517.1910460594063;3522.6653467920546;3544.7695899766513;3573.5705717712926;3620.275944811037;5816.941830581693;5861.941380586203;5864.76540938363;5871.941280587187;5897.4102589741005;5792.536597072235;5841.182234487175;5877.70611469427;5878.941210587893;5879.588428809985;5240.37115546134;5259.684418934857;5276.498732620395;5333.253344531769;5401.567874570044;5196.272521846944;5220.112580861243;5220.634555581111;5373.247745315659;5389.191621256804;5287.841364759063;5293.68237905725;5295.258663787073;5300.257963885059;5303.628745987782;4242.8120126364665;5066.873328166788;5262;5348.625596208267;5348.652337598063;5196.29849970253;5248.186531087687;5309.946900530984;5327.813526526553;5362.544183744387;5926.8814623707285;5945.702714864239;6043.365446628129;6053.788117415869;6056.424639659225;6170.84572885677;6213.782517611862;6241.313455519889;6250.1874756281395;6264.248290205159;6241.625502469867;6278.560500764948;6285.9057114143325;6286.654234017127;6296.275928268262;5931.525477961839;5934.792282270143;5938.970305148489;5958.344582095966;5976.7908123215475;6213.130161777355;6265.749370025239;6276.340984196685;6319.020551814476;6384.297727249998;6290.150829638032;6290.21372328459;6293.905591416133;6299.464545513591;6304.18045654062;6172.598781079216;6294.15028971083;6305.117283580303;6306.1171435998995;6323.367663233732;6127.017204504793;6205.162303089025;6223.595466294767;6291.056341548713;6303.684815759194;6245.687715614201;6279.529035322369;6286.057091436322;6288.717007734663;6306.8423289417215;5106.821261255913;5108.259302401131;5113.948860511347;5116.590672746172;5117.616178786531;4496.437945256843;4499.977500112544;4606.677532572755;4613.30800379939;4629.074185162981;5961;5992.820215393545;6041.335453100243;6045.395460454008;6141;6232.439080482778;6233.3143354231;6282.963311053716;6283.057541368831;6286.05709143623;6030.819075427744;6071.332153463116;6079.088136779518;6082.118092876508;6084.178635884187;5418.864528386822;5422.349318081855;5475.397706252309;5529.640573362719;5538.972305138529;6306.117143599808;6343.238811342669;6346.397092276226;6360.745570177141;6376.936230637727;6229.937700623117;6246.406591373812;6268.341824108586;6273.937260627518;6280.560360774702;5244.659097158673;6365.681715914278;6392.0411938209645;6428.8071357859335;6454.67726613658;6162.722677479614;6276.937230627818;6288.339724328879;6300.149479820166;6312.242530896322;6191.938080619136;6229.844253893508;6241.656708880873;6241.875162496815;6274.937250627435;6243.875122497616;6247.125402443752;6260.217472815898;6262.843428914313;6272.121902933502;6256.530760192913;6257.7184026719815;6262.780802671885;6276.717547710456;6277.18396608438;5851.590388672752;6037.215162028908;6041.546883983807;6095.329513753395;6151.907721384272;6196.907046394398;6269.247690277196;6289.528285378707;6292.307846137013;6306.337834527309;6108.969455152874;6125.632462052113;6154.630722156506;6155.353687862889;6161.983272759944 18:02:30 INFO - PROCESS | 1656 | |1;ext.html;465.61819308167304;517.0821791320406;534;537.9107307701903;575.6920047774441;37840;39945.8002709986;39980.40029399554;40000.799996000016;40024.39963400552;34678.65321346786;39098.609013909925;39613.40579891304;39998.80000599993;40143.79568612939;8673.306135509176;8842.5136617486;9410.247180225582;9607.759806004871;9608.471534065624;11246.325220486797;11509.3094414335;11525.827112593317;11745.647630571097;11794.056475481984;9891.406515609087;11534.653960381202;11549.422528873565;11550.191486595942;11555;10076.395416275047;11612.535498580046;11674.007709344716;11681.883181168207;11690.649280521598;11358.26171298863;11432.142589305753;11436.94281528587;11453.198276120675;11453.770924581546;39393.015174620574;39601;39662.801685991384;39695.40456893149;39730;3335.699787019156;3500.702440292578;3530.5586801649793;3579.1767893384667;3755.686286220521;29043.273918152005;29365.559516607267;30633.540496892343;30911.31844312489;31270.530942035886;3129.6244450666068;3217.539721637531;3252.414565378255;3253.8210398428077;3374.1058619465616;480958;484952;487943.5602821999;488028;488394;54813;57627.711861440424;60809;60868.39131608627;60891;35224.82387588046;35312.82343588266;35322;63902;64167.358326416135;16616.25226864794;17157.91421042887;17163;17175.227114779864;17223.052732099855;11903.880961190278;12150.270983741004;12701.237925724487;13102.541411050568;13238;18861.339853105077;19154.329598463988;19264.518387040436;19330.323438679578;19340.903295483713;7623.627747005481;7837.804054898559;7854.685812567547;7920.366370690331;7975.521468711896;895.4850960697624;1024.5082360467018;1073.0557109743377;1095.7863216672831;1106.972325691848;29909.700902991128;30375.544366834514;30634;31065.689343106733;31075.68924310728;1048.2138396202847;1070.989290107089;1088.455772113943;1091.4651820607844;1156.259993604094;15289.159096249814;15627.14050727221;15728.248008988076;15806.446774362843;15938.60153496172;668547;671496;674734;675219;675512;50115.997680046195;58232.708836455546;58685.70657146687;58720;58946;1268.5560053981033;1301.3818436242898;1365.8019587159608;1385.6328073060647;1430.141914851081;29426.705732942395;30458.695413045585;30462.23844403819;30821.691783081882;30935.381292373575;50969.23546146737;51517.71205719887;51530.74234628953;51579.22631160461;51706.48293517167;566680;573135;573538;573702;574123;23742.762572374057;24101;26665.7333426671;26984.055558055374;26994.460110798067;45243.547564523935;45323.32015019711;45475.54524454713;45729.54270457386;45745.31382029339;36705;37033.81483092567;37721.434178486794;38691.8392448227;39159.60840391673;37290.254194916495;37558.62441375551;37900.86297411082;38464.2307153861;38621.034474138374;37307.626923730415;37451;38324;38341;38744.61255387523;31438.52842207323;33376.83311583426;34058.8297058523;34088.48867266943;34233;21234.893825530773;25428.49143017092;25450.490990180464;25516.362090947874;25798.77515310586 18:02:30 INFO - PROCESS | 1656 | |2;jquery.html;373.1659740480027;380.09347705721865;388.64091118977973;392.33303384246767;414.5419311660615;13756.076181369033;18936.431907042777;19892.303769368074;20643.483912902186;20675.483112922164;19445.902770486162;19860.503487412785;19971.600567988633;20241.291554795585;20270.29053983111;5290.920636190451;5718.17086522454;5784.971075144618;5785.334686511042;5792.913106303409;5453.427390124039;5625;5717.228174196493;5722.113072473773;5725.7995970141055;5625.353084395286;5672.773089076432;5680.9715951420185;5683.573731970098;5694.060480020812;5376.327959005124;5599.160125981135;5655.4910058094765;5684.8294551163535;5689.4879460848515;4988.7006779592975;4990;4998.375203099613;5006.774695138727;5009.348784658007;23413.531729365488;23881;24553.754462455327;24957.376065598506;25187.118450854134;2311.6763653088583;2510.6610607567927;2650.5361561726622;2764.9308767280777;3023.576699262105;11847;14445.71108577833;14450.277486125757;15199.316030778531;15252.466163684328;1559.5399357189656;1753.5791410061493;1782.9926091758246;1903.4860587641397;1919.481739930211;92155.53922230413;109782.45108774485;114192;114215;116364.99999999916;10313.793724125475;10554.313969591953;10584.364938103738;10937.726556835984;11096.889031109748;8774.824503510023;12734.936325318315;13738.10702304347;13751.724965500634;13755.724885502234;7610.885836712454;7887.171846956102;8008.199180081945;8035.357171426272;8160.020797504338;6788.456923446112;7573.886391704129;7781.688732450639;7924.326432253323;7943.24539168778;5692.829215123553;6397.52018598607;6446.3553644636095;6455.096286519891;6470.579412338183;1490.5975386645762;1725.1632958015352;1725.7919456380332;1769.8318659727306;1802.8287312705534;870.9608067636972;915.6566287642135;918.4030380252756;982.2731178927685;1055.5935964653663;19835.10742016613;20242.69635955462;21103.261385851423;21125.260615878677;21142.47143821417;925.3938670171049;972.8929817720045;983.8573406855966;992.4739887859446;1042.8122937871256;10336.5348559315;11169.237514448589;11246.931541503543;11267.26762760418;11341.659750207507;498097.50951245736;610643;623152;624228;624264.9080791376;101274.4936275314;101755.66133561783;108993;109235;109814;945.9101385368241;998.2413365841867;1024.3495380433478;1025.9743506412399;1026.630413051301;17586.03276819787;18054.09729513493;18480;18630.720539192193;18672.533186669898;50989.235161471865;51947.22524753712;52522.474775253286;53262.73368633132;53573.73213133909;70508.29491705222;73593;73696.26303737109;75049.87425188736;75084.49831003459;7539.472236943361;7755.806104847424;7783.766487005399;7895.171007044178;8026.277635012643;10233.590656373959;10494.160467162761;11616.186866919235;11898;12031.4585843639;6943.218303503285;9658.613655453702;10360.481975901326;10707.357558546511;10815.297005694605;10333.638322658671;10498.632547860789;10535.15718742514;10701.89298107009;10782.784344312913;10586.04725574702;10700.250982431466;10900.236983411085;11085.057770089632;11177.72055698614;6887.758928437481;7802;8062.113167551682;8125.959370203112;8158.714444994397;9941.801163976825;10359.617943168125;10408;10574.735631608964;10773 18:02:30 INFO - PROCESS | 1656 | |3;mootools.html;1490.5975386645605;1515.7347464193767;1519.4302136698739;1867.9813201867978;1899.069455966576;8022.277994980451;8470.449420787652;8477.830443391129;8480.66077356905;8489.320854331654;7450;7667.156612772605;7978.800529986754;8207.097219305886;8241;1884.4629280655051;1908.9236430542762;1914.818092281231;1917.3768525229357;1919.1939385458072;2351.1065794997876;2378.3102900158933;2380.1907351500486;2406.2300063979533;2406.8194885383577;2338.672585837984;2346.6245400735884;2360.9409764755846;2364.562555927159;2366.0417530900013;2333.125078095714;2356.045801450415;2357.316378250305;2357.575636385441;2360.9173678921156;2293.6903518025188;2368;2370.8221883358815;2375.7149142102885;2376.7742064503846;8122.472039317485;8337.624806883645;8346.0402053764;8347;8351.24838764514;5075.822346217865;5238.738063096868;5245.055889939809;5246.632735708502;5248.763805628756;3140.671546833133;3351.5307856900054;3450.8274586270586;3534.68187863091;3545.8936231913085;1048.8584041154422;1060.543966094579;1085.0560012788844;1096.36958748719;1097.1990446973755;10471.528781204865;10736.677899663022;10746.570137194503;10747.78504429907;10912.181586380973;4300.462442194726;4380.9123817524105;4479.081788233404;4508.098380323949;4510.0979804039325;8619.1811777881;8624.655013799378;8633.95683021581;8637.309015278763;8639.308855291561;5099.158638824627;5100.260462232955;5107.157319042391;5108.66793658411;5111.080005599028;4626.918530120343;4724.574788269072;4752.572268495852;4794.400699912511;4798.088363210979;3488.110531814371;3488.89533314001;3537.25717599307;3572.696320812708;3587.4798154267487;1085.853409789684;1091.5088210305394;1092.836074588818;1102.6581759654532;1104.0615476844619;812.9390295727844;872.908344623818;886.8847049883474;932.8460803967405;945.3713280668416;4819.6744246469825;5335.733213339317;5644.435556444323;5667.688277144714;5807.041838096667;417.1469345189096;439.8240703718538;463.3073555035244;465.78806642977366;466.22141024489184;3697.3159965406485;3708.147126160998;3724.938392558162;3733.216024634857;3811.5045044144085;8454.788630284293;9715.07706767856;10069.345492542961;10072.647457338959;10095.24285678577;9654.469004204695;9947.701568952943;10011;10018.949905250427;10019.248556358303;7730.961345193238;7908.802279942933;7920.801979950547;7921.485103468256;7922.286994170552;4739.526047395233;5305.867353316198;5516.227728117987;5541.501264886179;5571.498565129158;9230.307726920373;9257.35198536096;9325.160735533835;9325.953370233105;9467.053294670477;9389.718308450758;9418.058194180527;9453.149216570542;9500.762480938032;9573.760655983657;6272.40412160844;6445.709943052668;6619.834504137435;6734.427573656294;6748.898766518408;7902.920970790218;7909.169537198742;7991.200879912195;7993.280604745601;8026.919730802617;7110.004599356198;7116.928830711627;7134.892976605459;7202.135743710788;7279.526830755984;6943.51395402317;7038.542494738031;7052.8589428210125;7061.6469176542;7082.256363082009;6617.43751781104;6774.187097548326;6779.423748981195;6785.457163426815;6796.796096117125;4661.743604101672;4772.5227477252;4786.305985632133;4816.34979277793;4858.441279252931;4734.21885388907;4736.668433209642;4806.182948898766;4847.806087756591;4924.753762311799 18:02:30 INFO - PROCESS | 1656 | |4;prototype.html;277.8999560158343;311.08695977306604;320.40244943180966;323.6035856076305;327.31101032326967;4363.432753742013;4457.796394973355;5248.47515248475;5316.93661267746;5341.009034940431;4917;4982.501749825015;5116.6185130014965;5166.844994650157;5385.246065550817;4036.273470775252;4058.701215611006;4094.344904815215;4095.4266402703647;4128.855490057848;4082.9591704082877;4086.3257562502154;4089.5297040840237;4092.4270602115726;4113.177364527091;3785.4321851722325;3979.4480152740452;4076.368162934735;4076.429299898017;4097.528784189812;4006.1587066716024;4029.395590661395;4034.818433170485;4058.8376464941366;4059.3099173140636;2954.4829654810324;3366.898993030213;3383.830808459592;3498.6851183393615;3502.2995400919663;4333.263345231318;4374.9781251093855;4433.889152771207;4748.311494833264;5066.392032956032;4266.530681625019;4370.759608221547;4625.537446255381;4634.9073018539775;4649.930251046237;4722.645801564897;5374.91937620936;5646.745896434671;5648.51987581052;5793.333766616851;3267.477203647405;3291.489819078047;3817.9809100954594;3858.3247931611854;3880.708946828971;5460.781568737246;5784.021229067034;5821;5830.3586605473365;5846.444587764156;3264.3144939562485;3820.8280627371837;3846.3845784674318;3852.210296889131;3867.961320386816;3470.410030294856;3756.69946404287;3907.4138879167785;3914.0410599402758;3916.5300163980505;3727.105494681311;4112.712110152321;4413.867583972486;4604.8388306409115;4623.537646235417;4909.950900491021;5614.382417934024;5624.156376543551;5625.662460252398;5653.9151912721345;1044.1490185498794;1112.2436743014828;1197.6167626359656;1252.555342853274;1264.2856785916113;821.2362502872386;840.3529282452599;875;881.6209030117111;887.5887339130684;4155.272827255279;4473.597376236155;4733.053389322149;5023.924641130386;5117.641765076409;5521.613487055948;5537.363203231599;5553.16702494629;5596.244506991586;5625.943740562624;4658.557437043475;4814.555633309993;5081.8311788288165;5144.897102057937;5464.9453505464435;2571.987140064288;2677.4243537639113;2754.374918797919;2869.6986816384397;2930.3113768264443;4982.77577509013;5727.914081288784;5774.566907481872;5778.335491418541;5790.913136302959;4719.882002949885;5159.974200128975;5243.056249875059;5425.267588875451;5659.207710920475;5550.278463799761;5659.208330418629;5727.398623144593;5789.189513468202;5794.710264486843;5270.868228294324;5428.824137879385;5531.419200983839;5543.445655434585;5573.968815769095;4665.86002419928;4875.902481950412;4881.658283920092;5327.813526526553;5450.411054748963;4757.857264282077;5364.034473794754;5621.831345059655;5752.884942301214;5840.408148988427;3620.5293311869286;3701.1857391373856;3716.368217402994;3950.5456872460036;3956.4658771065538;5075.187969924795;5765.299236725093;5765.4522820332;5770.567207459541;5789.94210057894;4328.264195086906;5204.1489297920025;5336.412994570672;5623.2408624835125;5726.284214473191;5410.648307859977;5707.714614269187;5708.143778433102;5727.6849773262875;5745.827625171251;5277.627816767591;5464.23500709893;5576.972115139561;5577.52591029767;5607.271054762855;4634.698744581591;5136.383633963948;5563.221149039056;5567.24842146305;5575.721213939206;4347.869563913087;5514.828973340265;5560.777568897361;5561.5272701820795;5563.193336966198 18:02:30 INFO - PROCESS | 1656 | |5;yui.html;630.5460068750499;703.2264509040057;705.7459314646728;725.5791640848308;770.537677393564;14047.719045619082;14487;15145.545633630982;15381.69236615267;15415.614609634764;12922.612321630342;13015.154014989019;13109.344532773372;13118.934405327984;13346.332683365818;836.7991681996323;853.7481442974306;854.3336197765752;854.799122206281;857.6783706110209;693.7641201991322;713.2190251674408;836.3601844588894;852.8848605438249;853.1127627267634;881.973540793774;900.4912224593083;900.8018235988072;904.3353135445442;904.5658084119626;894.3381897395946;894.4990805149138;895.9731208063704;896.1352295035322;897.0491279244022;667.5660820466687;667.7829705345733;668.4618881800149;670;670.8758879607288;13898.930505347409;14478;14615.707685846224;14631.853681463263;14648.70702585953;13243.86756132436;13313.86686133136;13489;13565.864341356559;13923.58229253124;10990.835137472945;11098.945600167972;11165.553377864875;11282.605108821233;11338.773224535546;3593.1915319053264;3622.5471816022996;3638.9272214555826;3744.906377340561;3872.5159355080614;76314.8552771709;86205.99999999937;91280.54359728226;92370;92440;10041.849372259421;10176.898231017743;10366.429846358522;10527.894721052844;10881.178207910825;20249.898750506447;20372.388828335173;20677;20776.896115519325;20809.37571872846;7465.104187497426;7566.092068951651;7632.045994250719;7673.271039251262;7774.805629859299;4239.40648309237;4254.532001479834;4270.935935960963;4290.099079193342;4485.865424037284;3401.8129002904584;3506.772059816155;3507.193345530491;3507.3862074136923;3507.877224297137;619.1826788638954;621.0590954703628;628.2806186915956;628.7610707930957;629.9937000629935;556.3574071946908;573.3744833397282;573.9598228123991;647.5240698086902;675.1998881325645;8417.410781245377;9145.862812057825;9293.767655808659;9387.5306234688;9608.711738647851;564.7910273198943;622.7695752571577;624.7938180400457;635.7615894039735;684.3354430379767;3926.293267211929;4346.760928148982;4398.64810815134;4528.50186479487;4674.252119660838;53838;79946.60026699946;105544.47227763812;108677;113001.86998129914;97839.53240701395;97882;99346;100063;103358;56665.716671416376;56811.7159414192;66278.33721662722;67988.6600566994;86128;9401.10689484498;9675.516224188903;9780.85328720084;9794;9827.705168844946;39708;50271.49728502668;50920.74539627278;51490.97018059693;52776.73611632071;48730;55123.72438137783;57783.39974701065;66959.33040669531;71447.64276178586;11950.760984780429;12200.14598978063;12251.264924104582;12641.051921105769;12983.545575904798;12138.466965780837;13900.985787244343;14085.718285634435;14144.9866123169;14308.928455358073;11926.880731192578;12036.699082523008;12188.390580471118;12341.876581234073;12557.434915428645;12207.143675898022;12835.871641283467;12870.742585148431;12930.482780688668;13588.660283492993;11208.607698730506;12274.570390036306;12831.230126192459;12969.286689231805;13733.038687291793;10880.932423262733;11024.669259922215;11289.491972861406;11301.321920684784;11672.47473863695;11009.9251001741;11264.155188360743;11331.716707082389;11531.88468115308;11610.419479026184 18:02:30 INFO - PROCESS | 1656 | __end_tp_report 18:02:30 INFO - PROCESS | 1656 | __start_cc_report 18:02:30 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1733 18:02:30 INFO - PROCESS | 1656 | __end_cc_report 18:02:30 INFO - PROCESS | 1656 | __startTimestamp1488247350267__endTimestamp 18:02:30 INFO - PROCESS | 1656 | 18:02:30 INFO - PROCESS | 1656 | ------- Summary: start ------- 18:02:30 INFO - PROCESS | 1656 | Number of tests: 6 18:02:30 INFO - PROCESS | 1656 | 18:02:30 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5852.08 Median:6141.00 stddev:627.76 (10.2%) stddev-sans-first:602.37 18:02:30 INFO - PROCESS | 1656 | Values: 3517.2 3522.7 3544.8 3573.6 3620.3 5816.9 5861.9 5864.8 5871.9 5897.4 5792.5 5841.2 5877.7 5878.9 5879.6 5240.4 5259.7 5276.5 5333.3 5401.6 5196.3 5220.1 5220.6 5373.2 5389.2 5287.8 5293.7 5295.3 5300.3 5303.6 4242.8 5066.9 5262.0 5348.6 5348.7 5196.3 5248.2 5309.9 5327.8 5362.5 5926.9 5945.7 6043.4 6053.8 6056.4 6170.8 6213.8 6241.3 6250.2 6264.2 6241.6 6278.6 6285.9 6286.7 6296.3 5931.5 5934.8 5939.0 5958.3 5976.8 6213.1 6265.7 6276.3 6319.0 6384.3 6290.2 6290.2 6293.9 6299.5 6304.2 6172.6 6294.2 6305.1 6306.1 6323.4 6127.0 6205.2 6223.6 6291.1 6303.7 6245.7 6279.5 6286.1 6288.7 6306.8 5106.8 5108.3 5113.9 5116.6 5117.6 4496.4 4500.0 4606.7 4613.3 4629.1 5961.0 5992.8 6041.3 6045.4 6141.0 6232.4 6233.3 6283.0 6283.1 6286.1 6030.8 6071.3 6079.1 6082.1 6084.2 5418.9 5422.3 5475.4 5529.6 5539.0 6306.1 6343.2 6346.4 6360.7 6376.9 6229.9 6246.4 6268.3 6273.9 6280.6 5244.7 6365.7 6392.0 6428.8 6454.7 6162.7 6276.9 6288.3 6300.1 6312.2 6191.9 6229.8 6241.7 6241.9 6274.9 6243.9 6247.1 6260.2 6262.8 6272.1 6256.5 6257.7 6262.8 6276.7 6277.2 5851.6 6037.2 6041.5 6095.3 6151.9 6196.9 6269.2 6289.5 6292.3 6306.3 6109.0 6125.6 6154.6 6155.4 6162.0 18:02:30 INFO - PROCESS | 1656 | 18:02:30 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:70441.70 Median:19362.76 stddev:155701.54 (804.1%) stddev-sans-first:156049.68 18:02:30 INFO - PROCESS | 1656 | Values: 465.6 517.1 534.0 537.9 575.7 37840.0 39945.8 39980.4 40000.8 40024.4 34678.7 39098.6 39613.4 39998.8 40143.8 8673.3 8842.5 9410.2 9607.8 9608.5 11246.3 11509.3 11525.8 11745.6 11794.1 9891.4 11534.7 11549.4 11550.2 11555.0 10076.4 11612.5 11674.0 11681.9 11690.6 11358.3 11432.1 11436.9 11453.2 11453.8 39393.0 39601.0 39662.8 39695.4 39730.0 3335.7 3500.7 3530.6 3579.2 3755.7 29043.3 29365.6 30633.5 30911.3 31270.5 3129.6 3217.5 3252.4 3253.8 3374.1 480958.0 484952.0 487943.6 488028.0 488394.0 54813.0 57627.7 60809.0 60868.4 60891.0 35224.8 35312.8 35322.0 63902.0 64167.4 16616.3 17157.9 17163.0 17175.2 17223.1 11903.9 12150.3 12701.2 13102.5 13238.0 18861.3 19154.3 19264.5 19330.3 19340.9 7623.6 7837.8 7854.7 7920.4 7975.5 895.5 1024.5 1073.1 1095.8 1107.0 29909.7 30375.5 30634.0 31065.7 31075.7 1048.2 1071.0 1088.5 1091.5 1156.3 15289.2 15627.1 15728.2 15806.4 15938.6 668547.0 671496.0 674734.0 675219.0 675512.0 50116.0 58232.7 58685.7 58720.0 58946.0 1268.6 1301.4 1365.8 1385.6 1430.1 29426.7 30458.7 30462.2 30821.7 30935.4 50969.2 51517.7 51530.7 51579.2 51706.5 566680.0 573135.0 573538.0 573702.0 574123.0 23742.8 24101.0 26665.7 26984.1 26994.5 45243.5 45323.3 45475.5 45729.5 45745.3 36705.0 37033.8 37721.4 38691.8 39159.6 37290.3 37558.6 37900.9 38464.2 38621.0 37307.6 37451.0 38324.0 38341.0 38744.6 31438.5 33376.8 34058.8 34088.5 34233.0 21234.9 25428.5 25450.5 25516.4 25798.8 18:02:30 INFO - PROCESS | 1656 | 18:02:30 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:33768.76 Median:13848.89 stddev:99146.72 (715.9%) stddev-sans-first:99393.14 18:02:30 INFO - PROCESS | 1656 | Values: 373.2 380.1 388.6 392.3 414.5 13756.1 18936.4 19892.3 20643.5 20675.5 19445.9 19860.5 19971.6 20241.3 20270.3 5290.9 5718.2 5785.0 5785.3 5792.9 5453.4 5625.0 5717.2 5722.1 5725.8 5625.4 5672.8 5681.0 5683.6 5694.1 5376.3 5599.2 5655.5 5684.8 5689.5 4988.7 4990.0 4998.4 5006.8 5009.3 23413.5 23881.0 24553.8 24957.4 25187.1 2311.7 2510.7 2650.5 2764.9 3023.6 11847.0 14445.7 14450.3 15199.3 15252.5 1559.5 1753.6 1783.0 1903.5 1919.5 92155.5 109782.5 114192.0 114215.0 116365.0 10313.8 10554.3 10584.4 10937.7 11096.9 8774.8 12734.9 13738.1 13751.7 13755.7 7610.9 7887.2 8008.2 8035.4 8160.0 6788.5 7573.9 7781.7 7924.3 7943.2 5692.8 6397.5 6446.4 6455.1 6470.6 1490.6 1725.2 1725.8 1769.8 1802.8 871.0 915.7 918.4 982.3 1055.6 19835.1 20242.7 21103.3 21125.3 21142.5 925.4 972.9 983.9 992.5 1042.8 10336.5 11169.2 11246.9 11267.3 11341.7 498097.5 610643.0 623152.0 624228.0 624264.9 101274.5 101755.7 108993.0 109235.0 109814.0 945.9 998.2 1024.3 1026.0 1026.6 17586.0 18054.1 18480.0 18630.7 18672.5 50989.2 51947.2 52522.5 53262.7 53573.7 70508.3 73593.0 73696.3 75049.9 75084.5 7539.5 7755.8 7783.8 7895.2 8026.3 10233.6 10494.2 11616.2 11898.0 12031.5 6943.2 9658.6 10360.5 10707.4 10815.3 10333.6 10498.6 10535.2 10701.9 10782.8 10586.0 10700.3 10900.2 11085.1 11177.7 6887.8 7802.0 8062.1 8126.0 8158.7 9941.8 10359.6 10408.0 10574.7 10773.0 18:02:30 INFO - PROCESS | 1656 | 18:02:30 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5355.34 Median:4853.12 stddev:2958.63 (61.0%) stddev-sans-first:2952.68 18:02:30 INFO - PROCESS | 1656 | Values: 1490.6 1515.7 1519.4 1868.0 1899.1 8022.3 8470.4 8477.8 8480.7 8489.3 7450.0 7667.2 7978.8 8207.1 8241.0 1884.5 1908.9 1914.8 1917.4 1919.2 2351.1 2378.3 2380.2 2406.2 2406.8 2338.7 2346.6 2360.9 2364.6 2366.0 2333.1 2356.0 2357.3 2357.6 2360.9 2293.7 2368.0 2370.8 2375.7 2376.8 8122.5 8337.6 8346.0 8347.0 8351.2 5075.8 5238.7 5245.1 5246.6 5248.8 3140.7 3351.5 3450.8 3534.7 3545.9 1048.9 1060.5 1085.1 1096.4 1097.2 10471.5 10736.7 10746.6 10747.8 10912.2 4300.5 4380.9 4479.1 4508.1 4510.1 8619.2 8624.7 8634.0 8637.3 8639.3 5099.2 5100.3 5107.2 5108.7 5111.1 4626.9 4724.6 4752.6 4794.4 4798.1 3488.1 3488.9 3537.3 3572.7 3587.5 1085.9 1091.5 1092.8 1102.7 1104.1 812.9 872.9 886.9 932.8 945.4 4819.7 5335.7 5644.4 5667.7 5807.0 417.1 439.8 463.3 465.8 466.2 3697.3 3708.1 3724.9 3733.2 3811.5 8454.8 9715.1 10069.3 10072.6 10095.2 9654.5 9947.7 10011.0 10018.9 10019.2 7731.0 7908.8 7920.8 7921.5 7922.3 4739.5 5305.9 5516.2 5541.5 5571.5 9230.3 9257.4 9325.2 9326.0 9467.1 9389.7 9418.1 9453.1 9500.8 9573.8 6272.4 6445.7 6619.8 6734.4 6748.9 7902.9 7909.2 7991.2 7993.3 8026.9 7110.0 7116.9 7134.9 7202.1 7279.5 6943.5 7038.5 7052.9 7061.6 7082.3 6617.4 6774.2 6779.4 6785.5 6796.8 4661.7 4772.5 4786.3 4816.3 4858.4 4734.2 4736.7 4806.2 4847.8 4924.8 18:02:30 INFO - PROCESS | 1656 | 18:02:30 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4450.18 Median:4913.48 stddev:1378.12 (28.0%) stddev-sans-first:1345.94 18:02:30 INFO - PROCESS | 1656 | Values: 277.9 311.1 320.4 323.6 327.3 4363.4 4457.8 5248.5 5316.9 5341.0 4917.0 4982.5 5116.6 5166.8 5385.2 4036.3 4058.7 4094.3 4095.4 4128.9 4083.0 4086.3 4089.5 4092.4 4113.2 3785.4 3979.4 4076.4 4076.4 4097.5 4006.2 4029.4 4034.8 4058.8 4059.3 2954.5 3366.9 3383.8 3498.7 3502.3 4333.3 4375.0 4433.9 4748.3 5066.4 4266.5 4370.8 4625.5 4634.9 4649.9 4722.6 5374.9 5646.7 5648.5 5793.3 3267.5 3291.5 3818.0 3858.3 3880.7 5460.8 5784.0 5821.0 5830.4 5846.4 3264.3 3820.8 3846.4 3852.2 3868.0 3470.4 3756.7 3907.4 3914.0 3916.5 3727.1 4112.7 4413.9 4604.8 4623.5 4910.0 5614.4 5624.2 5625.7 5653.9 1044.1 1112.2 1197.6 1252.6 1264.3 821.2 840.4 875.0 881.6 887.6 4155.3 4473.6 4733.1 5023.9 5117.6 5521.6 5537.4 5553.2 5596.2 5625.9 4658.6 4814.6 5081.8 5144.9 5464.9 2572.0 2677.4 2754.4 2869.7 2930.3 4982.8 5727.9 5774.6 5778.3 5790.9 4719.9 5160.0 5243.1 5425.3 5659.2 5550.3 5659.2 5727.4 5789.2 5794.7 5270.9 5428.8 5531.4 5543.4 5574.0 4665.9 4875.9 4881.7 5327.8 5450.4 4757.9 5364.0 5621.8 5752.9 5840.4 3620.5 3701.2 3716.4 3950.5 3956.5 5075.2 5765.3 5765.5 5770.6 5789.9 4328.3 5204.1 5336.4 5623.2 5726.3 5410.6 5707.7 5708.1 5727.7 5745.8 5277.6 5464.2 5577.0 5577.5 5607.3 4634.7 5136.4 5563.2 5567.2 5575.7 4347.9 5514.8 5560.8 5561.5 5563.2 18:02:30 INFO - PROCESS | 1656 | 18:02:30 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:19138.21 Median:4318.43 stddev:27453.35 (635.7%) stddev-sans-first:27495.19 18:02:30 INFO - PROCESS | 1656 | Values: 630.5 703.2 705.7 725.6 770.5 14047.7 14487.0 15145.5 15381.7 15415.6 12922.6 13015.2 13109.3 13118.9 13346.3 836.8 853.7 854.3 854.8 857.7 693.8 713.2 836.4 852.9 853.1 882.0 900.5 900.8 904.3 904.6 894.3 894.5 896.0 896.1 897.0 667.6 667.8 668.5 670.0 670.9 13898.9 14478.0 14615.7 14631.9 14648.7 13243.9 13313.9 13489.0 13565.9 13923.6 10990.8 11098.9 11165.6 11282.6 11338.8 3593.2 3622.5 3638.9 3744.9 3872.5 76314.9 86206.0 91280.5 92370.0 92440.0 10041.8 10176.9 10366.4 10527.9 10881.2 20249.9 20372.4 20677.0 20776.9 20809.4 7465.1 7566.1 7632.0 7673.3 7774.8 4239.4 4254.5 4270.9 4290.1 4485.9 3401.8 3506.8 3507.2 3507.4 3507.9 619.2 621.1 628.3 628.8 630.0 556.4 573.4 574.0 647.5 675.2 8417.4 9145.9 9293.8 9387.5 9608.7 564.8 622.8 624.8 635.8 684.3 3926.3 4346.8 4398.6 4528.5 4674.3 53838.0 79946.6 105544.5 108677.0 113001.9 97839.5 97882.0 99346.0 100063.0 103358.0 56665.7 56811.7 66278.3 67988.7 86128.0 9401.1 9675.5 9780.9 9794.0 9827.7 39708.0 50271.5 50920.7 51491.0 52776.7 48730.0 55123.7 57783.4 66959.3 71447.6 11950.8 12200.1 12251.3 12641.1 12983.5 12138.5 13901.0 14085.7 14145.0 14308.9 11926.9 12036.7 12188.4 12341.9 12557.4 12207.1 12835.9 12870.7 12930.5 13588.7 11208.6 12274.6 12831.2 12969.3 13733.0 10880.9 11024.7 11289.5 11301.3 11672.5 11009.9 11264.2 11331.7 11531.9 11610.4 18:02:30 INFO - PROCESS | 1656 | -------- Summary: end -------- 18:02:30 INFO - PROCESS | 1656 | 18:02:30 INFO - TEST-INFO | 1656: exit 0 18:02:30 INFO - TEST-OK | dromaeo_css | took 1118233ms 18:02:30 INFO - TEST-START | kraken 18:02:30 INFO - operating with platform_type : mac_ 18:02:30 INFO - Initialising browser for kraken test... 18:02:30 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpDdGvcF/profile http://localhost:49246/getInfo.html) 18:02:33 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 18:02:33 INFO - PROCESS | 1662 | colorDepth:24 18:02:33 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 18:02:33 INFO - PROCESS | 1662 | __metrics 18:02:33 INFO - PROCESS | 1662 | 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" 18:02:33 INFO - PROCESS | 1662 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:02:34 INFO - TEST-INFO | 1662: exit 0 18:02:34 INFO - Browser initialized. 18:02:34 INFO - Running cycle 1/1 for kraken test... 18:02:34 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpDdGvcF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:02:46 INFO - PROCESS | 1664 | RSS: Main: 167718912 18:02:46 INFO - PROCESS | 1664 | 18:02:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 18:02:49 INFO - PROCESS | 1664 | RSS: Main: 217608192 18:02:49 INFO - PROCESS | 1664 | 18:02:52 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 18:02:53 INFO - PROCESS | 1664 | RSS: Main: 231157760 18:02:53 INFO - PROCESS | 1664 | 18:02:56 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 18:02:56 INFO - PROCESS | 1664 | RSS: Main: 329019392 18:02:56 INFO - PROCESS | 1664 | 18:02:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 18:02:59 INFO - PROCESS | 1664 | RSS: Main: 230592512 18:02:59 INFO - PROCESS | 1664 | 18:03:01 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:03:02 INFO - PROCESS | 1664 | RSS: Main: 231927808 18:03:02 INFO - PROCESS | 1664 | 18:03:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 18:03:05 INFO - PROCESS | 1664 | RSS: Main: 272224256 18:03:05 INFO - PROCESS | 1664 | 18:03:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 18:03:08 INFO - PROCESS | 1664 | RSS: Main: 271978496 18:03:08 INFO - PROCESS | 1664 | 18:03:11 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 18:03:12 INFO - PROCESS | 1664 | RSS: Main: 269750272 18:03:12 INFO - PROCESS | 1664 | 18:03:13 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:03:14 INFO - PROCESS | 1664 | RSS: Main: 249692160 18:03:14 INFO - PROCESS | 1664 | 18:03:16 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:03:16 INFO - PROCESS | 1664 | RSS: Main: 248025088 18:03:16 INFO - PROCESS | 1664 | 18:03:18 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:03:19 INFO - PROCESS | 1664 | RSS: Main: 251658240 18:03:19 INFO - PROCESS | 1664 | 18:03:22 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:03:23 INFO - PROCESS | 1664 | RSS: Main: 263692288 18:03:23 INFO - PROCESS | 1664 | 18:03:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:03:26 INFO - PROCESS | 1664 | RSS: Main: 263925760 18:03:26 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 18:03:28 INFO - PROCESS | 1664 | RSS: Main: 307429376 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | __start_tp_report 18:03:28 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 18:03:28 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 18:03:28 INFO - PROCESS | 1664 | |i|pagename|runs| 18:03:28 INFO - PROCESS | 1664 | |0;ai-astar;101;115;115;114;115;115;114;115;114;115 18:03:28 INFO - PROCESS | 1664 | |1;audio-beat-detection;142;107;109;144;131;132;133;104;176;137 18:03:28 INFO - PROCESS | 1664 | |2;audio-dft;181;184;185;183;182;182;182;182;184;184 18:03:28 INFO - PROCESS | 1664 | |3;audio-fft;81;59;58;88;59;58;59;58;84;71 18:03:28 INFO - PROCESS | 1664 | |4;audio-oscillator;89;69;93;69;69;68;89;68;69;91 18:03:28 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;88;85;89;86;88;84;88;86;89;129 18:03:28 INFO - PROCESS | 1664 | |6;imaging-darkroom;94;93;94;94;94;94;94;94;94;94 18:03:28 INFO - PROCESS | 1664 | |7;imaging-desaturate;82;85;82;80;83;83;86;83;84;84 18:03:28 INFO - PROCESS | 1664 | |8;json-parse-financial;55;54;53;55;58;56;58;55;56;56 18:03:28 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;68;72;63;73;71;67;67;67;67;69 18:03:28 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;80;87;95;82;83;81;81;84;81;92 18:03:28 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;137;134;138;136;137;141;140;139;172;146 18:03:28 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;169;160;162;161;163;159;159;168;161;161 18:03:28 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;65;65;66;66;66;67;78;56;70;81 18:03:28 INFO - PROCESS | 1664 | __end_tp_report 18:03:28 INFO - PROCESS | 1664 | __start_cc_report 18:03:28 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1942 18:03:28 INFO - PROCESS | 1664 | __end_cc_report 18:03:28 INFO - PROCESS | 1664 | __startTimestamp1488247408443__endTimestamp 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | ------- Summary: start ------- 18:03:28 INFO - PROCESS | 1664 | Number of tests: 14 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:113.30 Median:115.00 stddev:4.35 (3.8%) stddev-sans-first:0.50 18:03:28 INFO - PROCESS | 1664 | Values: 101.0 115.0 115.0 114.0 115.0 115.0 114.0 115.0 114.0 115.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:135.00 stddev:21.45 (15.9%) stddev-sans-first:22.42 18:03:28 INFO - PROCESS | 1664 | Values: 142.0 107.0 109.0 144.0 131.0 132.0 133.0 104.0 176.0 137.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.50 stddev:1.29 (0.7%) stddev-sans-first:1.17 18:03:28 INFO - PROCESS | 1664 | Values: 181.0 184.0 185.0 183.0 182.0 182.0 182.0 182.0 184.0 184.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:67.50 Median:65.00 stddev:12.36 (19.0%) stddev-sans-first:12.10 18:03:28 INFO - PROCESS | 1664 | Values: 81.0 59.0 58.0 88.0 59.0 58.0 59.0 58.0 84.0 71.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:79.00 stddev:11.34 (14.3%) stddev-sans-first:11.22 18:03:28 INFO - PROCESS | 1664 | Values: 89.0 69.0 93.0 69.0 69.0 68.0 89.0 68.0 69.0 91.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:88.00 stddev:13.39 (15.2%) stddev-sans-first:14.15 18:03:28 INFO - PROCESS | 1664 | Values: 88.0 85.0 89.0 86.0 88.0 84.0 88.0 86.0 89.0 129.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:03:28 INFO - PROCESS | 1664 | Values: 94.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:83.20 Median:83.50 stddev:1.69 (2.0%) stddev-sans-first:1.73 18:03:28 INFO - PROCESS | 1664 | Values: 82.0 85.0 82.0 80.0 83.0 83.0 86.0 83.0 84.0 84.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.58 (2.8%) stddev-sans-first:1.66 18:03:28 INFO - PROCESS | 1664 | Values: 55.0 54.0 53.0 55.0 58.0 56.0 58.0 55.0 56.0 56.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.50 stddev:2.95 (4.3%) stddev-sans-first:3.13 18:03:28 INFO - PROCESS | 1664 | Values: 68.0 72.0 63.0 73.0 71.0 67.0 67.0 67.0 67.0 69.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:83.50 stddev:5.15 (6.2%) stddev-sans-first:5.18 18:03:28 INFO - PROCESS | 1664 | Values: 80.0 87.0 95.0 82.0 83.0 81.0 81.0 84.0 81.0 92.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:142.00 Median:139.50 stddev:11.04 (7.9%) stddev-sans-first:11.56 18:03:28 INFO - PROCESS | 1664 | Values: 137.0 134.0 138.0 136.0 137.0 141.0 140.0 139.0 172.0 146.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.30 Median:161.50 stddev:3.50 (2.2%) stddev-sans-first:2.74 18:03:28 INFO - PROCESS | 1664 | Values: 169.0 160.0 162.0 161.0 163.0 159.0 159.0 168.0 161.0 161.0 18:03:28 INFO - PROCESS | 1664 | 18:03:28 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:66.50 stddev:7.06 (10.6%) stddev-sans-first:7.40 18:03:28 INFO - PROCESS | 1664 | Values: 65.0 65.0 66.0 66.0 66.0 67.0 78.0 56.0 70.0 81.0 18:03:28 INFO - PROCESS | 1664 | -------- Summary: end -------- 18:03:28 INFO - PROCESS | 1664 | 18:03:29 INFO - TEST-INFO | 1664: exit 0 18:03:29 INFO - TEST-OK | kraken | took 58691ms 18:03:29 INFO - SUITE-END | took 1176s 18:03:29 INFO - Completed test suite (00:19:36) 18:03:29 INFO - javascript benchmark 18:03:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3517.1910460594063, 3522.6653467920546, 3544.7695899766513, 3573.5705717712926, 3620.275944811037, 5816.941830581693, 5861.941380586203, 5864.76540938363, 5871.941280587187, 5897.4102589741005, 5792.536597072235, 5841.182234487175, 5877.70611469427, 5878.941210587893, 5879.588428809985, 5240.37115546134, 5259.684418934857, 5276.498732620395, 5333.253344531769, 5401.567874570044, 5196.272521846944, 5220.112580861243, 5220.634555581111, 5373.247745315659, 5389.191621256804, 5287.841364759063, 5293.68237905725, 5295.258663787073, 5300.257963885059, 5303.628745987782, 4242.8120126364665, 5066.873328166788, 5262.0, 5348.625596208267, 5348.652337598063, 5196.29849970253, 5248.186531087687, 5309.946900530984, 5327.813526526553, 5362.544183744387, 5926.8814623707285, 5945.702714864239, 6043.365446628129, 6053.788117415869, 6056.424639659225, 6170.84572885677, 6213.782517611862, 6241.313455519889, 6250.1874756281395, 6264.248290205159, 6241.625502469867, 6278.560500764948, 6285.9057114143325, 6286.654234017127, 6296.275928268262, 5931.525477961839, 5934.792282270143, 5938.970305148489, 5958.344582095966, 5976.7908123215475, 6213.130161777355, 6265.749370025239, 6276.340984196685, 6319.020551814476, 6384.297727249998, 6290.150829638032, 6290.21372328459, 6293.905591416133, 6299.464545513591, 6304.18045654062, 6172.598781079216, 6294.15028971083, 6305.117283580303, 6306.1171435998995, 6323.367663233732, 6127.017204504793, 6205.162303089025, 6223.595466294767, 6291.056341548713, 6303.684815759194, 6245.687715614201, 6279.529035322369, 6286.057091436322, 6288.717007734663, 6306.8423289417215, 5106.821261255913, 5108.259302401131, 5113.948860511347, 5116.590672746172, 5117.616178786531, 4496.437945256843, 4499.977500112544, 4606.677532572755, 4613.30800379939, 4629.074185162981, 5961.0, 5992.820215393545, 6041.335453100243, 6045.395460454008, 6141.0, 6232.439080482778, 6233.3143354231, 6282.963311053716, 6283.057541368831, 6286.05709143623, 6030.819075427744, 6071.332153463116, 6079.088136779518, 6082.118092876508, 6084.178635884187, 5418.864528386822, 5422.349318081855, 5475.397706252309, 5529.640573362719, 5538.972305138529, 6306.117143599808, 6343.238811342669, 6346.397092276226, 6360.745570177141, 6376.936230637727, 6229.937700623117, 6246.406591373812, 6268.341824108586, 6273.937260627518, 6280.560360774702, 5244.659097158673, 6365.681715914278, 6392.0411938209645, 6428.8071357859335, 6454.67726613658, 6162.722677479614, 6276.937230627818, 6288.339724328879, 6300.149479820166, 6312.242530896322, 6191.938080619136, 6229.844253893508, 6241.656708880873, 6241.875162496815, 6274.937250627435, 6243.875122497616, 6247.125402443752, 6260.217472815898, 6262.843428914313, 6272.121902933502, 6256.530760192913, 6257.7184026719815, 6262.780802671885, 6276.717547710456, 6277.18396608438, 5851.590388672752, 6037.215162028908, 6041.546883983807, 6095.329513753395, 6151.907721384272, 6196.907046394398, 6269.247690277196, 6289.528285378707, 6292.307846137013, 6306.337834527309, 6108.969455152874, 6125.632462052113, 6154.630722156506, 6155.353687862889, 6161.983272759944], "value": 5813.856849837197, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.61819308167304, 517.0821791320406, 534.0, 537.9107307701903, 575.6920047774441, 37840.0, 39945.8002709986, 39980.40029399554, 40000.799996000016, 40024.39963400552, 34678.65321346786, 39098.609013909925, 39613.40579891304, 39998.80000599993, 40143.79568612939, 8673.306135509176, 8842.5136617486, 9410.247180225582, 9607.759806004871, 9608.471534065624, 11246.325220486797, 11509.3094414335, 11525.827112593317, 11745.647630571097, 11794.056475481984, 9891.406515609087, 11534.653960381202, 11549.422528873565, 11550.191486595942, 11555.0, 10076.395416275047, 11612.535498580046, 11674.007709344716, 11681.883181168207, 11690.649280521598, 11358.26171298863, 11432.142589305753, 11436.94281528587, 11453.198276120675, 11453.770924581546, 39393.015174620574, 39601.0, 39662.801685991384, 39695.40456893149, 39730.0, 3335.699787019156, 3500.702440292578, 3530.5586801649793, 3579.1767893384667, 3755.686286220521, 29043.273918152005, 29365.559516607267, 30633.540496892343, 30911.31844312489, 31270.530942035886, 3129.6244450666068, 3217.539721637531, 3252.414565378255, 3253.8210398428077, 3374.1058619465616, 480958.0, 484952.0, 487943.5602821999, 488028.0, 488394.0, 54813.0, 57627.711861440424, 60809.0, 60868.39131608627, 60891.0, 35224.82387588046, 35312.82343588266, 35322.0, 63902.0, 64167.358326416135, 16616.25226864794, 17157.91421042887, 17163.0, 17175.227114779864, 17223.052732099855, 11903.880961190278, 12150.270983741004, 12701.237925724487, 13102.541411050568, 13238.0, 18861.339853105077, 19154.329598463988, 19264.518387040436, 19330.323438679578, 19340.903295483713, 7623.627747005481, 7837.804054898559, 7854.685812567547, 7920.366370690331, 7975.521468711896, 895.4850960697624, 1024.5082360467018, 1073.0557109743377, 1095.7863216672831, 1106.972325691848, 29909.700902991128, 30375.544366834514, 30634.0, 31065.689343106733, 31075.68924310728, 1048.2138396202847, 1070.989290107089, 1088.455772113943, 1091.4651820607844, 1156.259993604094, 15289.159096249814, 15627.14050727221, 15728.248008988076, 15806.446774362843, 15938.60153496172, 668547.0, 671496.0, 674734.0, 675219.0, 675512.0, 50115.997680046195, 58232.708836455546, 58685.70657146687, 58720.0, 58946.0, 1268.5560053981033, 1301.3818436242898, 1365.8019587159608, 1385.6328073060647, 1430.141914851081, 29426.705732942395, 30458.695413045585, 30462.23844403819, 30821.691783081882, 30935.381292373575, 50969.23546146737, 51517.71205719887, 51530.74234628953, 51579.22631160461, 51706.48293517167, 566680.0, 573135.0, 573538.0, 573702.0, 574123.0, 23742.762572374057, 24101.0, 26665.7333426671, 26984.055558055374, 26994.460110798067, 45243.547564523935, 45323.32015019711, 45475.54524454713, 45729.54270457386, 45745.31382029339, 36705.0, 37033.81483092567, 37721.434178486794, 38691.8392448227, 39159.60840391673, 37290.254194916495, 37558.62441375551, 37900.86297411082, 38464.2307153861, 38621.034474138374, 37307.626923730415, 37451.0, 38324.0, 38341.0, 38744.61255387523, 31438.52842207323, 33376.83311583426, 34058.8297058523, 34088.48867266943, 34233.0, 21234.893825530773, 25428.49143017092, 25450.490990180464, 25516.362090947874, 25798.77515310586], "value": 20210.157528525775, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.1659740480027, 380.09347705721865, 388.64091118977973, 392.33303384246767, 414.5419311660615, 13756.076181369033, 18936.431907042777, 19892.303769368074, 20643.483912902186, 20675.483112922164, 19445.902770486162, 19860.503487412785, 19971.600567988633, 20241.291554795585, 20270.29053983111, 5290.920636190451, 5718.17086522454, 5784.971075144618, 5785.334686511042, 5792.913106303409, 5453.427390124039, 5625.0, 5717.228174196493, 5722.113072473773, 5725.7995970141055, 5625.353084395286, 5672.773089076432, 5680.9715951420185, 5683.573731970098, 5694.060480020812, 5376.327959005124, 5599.160125981135, 5655.4910058094765, 5684.8294551163535, 5689.4879460848515, 4988.7006779592975, 4990.0, 4998.375203099613, 5006.774695138727, 5009.348784658007, 23413.531729365488, 23881.0, 24553.754462455327, 24957.376065598506, 25187.118450854134, 2311.6763653088583, 2510.6610607567927, 2650.5361561726622, 2764.9308767280777, 3023.576699262105, 11847.0, 14445.71108577833, 14450.277486125757, 15199.316030778531, 15252.466163684328, 1559.5399357189656, 1753.5791410061493, 1782.9926091758246, 1903.4860587641397, 1919.481739930211, 92155.53922230413, 109782.45108774485, 114192.0, 114215.0, 116364.99999999916, 10313.793724125475, 10554.313969591953, 10584.364938103738, 10937.726556835984, 11096.889031109748, 8774.824503510023, 12734.936325318315, 13738.10702304347, 13751.724965500634, 13755.724885502234, 7610.885836712454, 7887.171846956102, 8008.199180081945, 8035.357171426272, 8160.020797504338, 6788.456923446112, 7573.886391704129, 7781.688732450639, 7924.326432253323, 7943.24539168778, 5692.829215123553, 6397.52018598607, 6446.3553644636095, 6455.096286519891, 6470.579412338183, 1490.5975386645762, 1725.1632958015352, 1725.7919456380332, 1769.8318659727306, 1802.8287312705534, 870.9608067636972, 915.6566287642135, 918.4030380252756, 982.2731178927685, 1055.5935964653663, 19835.10742016613, 20242.69635955462, 21103.261385851423, 21125.260615878677, 21142.47143821417, 925.3938670171049, 972.8929817720045, 983.8573406855966, 992.4739887859446, 1042.8122937871256, 10336.5348559315, 11169.237514448589, 11246.931541503543, 11267.26762760418, 11341.659750207507, 498097.50951245736, 610643.0, 623152.0, 624228.0, 624264.9080791376, 101274.4936275314, 101755.66133561783, 108993.0, 109235.0, 109814.0, 945.9101385368241, 998.2413365841867, 1024.3495380433478, 1025.9743506412399, 1026.630413051301, 17586.03276819787, 18054.09729513493, 18480.0, 18630.720539192193, 18672.533186669898, 50989.235161471865, 51947.22524753712, 52522.474775253286, 53262.73368633132, 53573.73213133909, 70508.29491705222, 73593.0, 73696.26303737109, 75049.87425188736, 75084.49831003459, 7539.472236943361, 7755.806104847424, 7783.766487005399, 7895.171007044178, 8026.277635012643, 10233.590656373959, 10494.160467162761, 11616.186866919235, 11898.0, 12031.4585843639, 6943.218303503285, 9658.613655453702, 10360.481975901326, 10707.357558546511, 10815.297005694605, 10333.638322658671, 10498.632547860789, 10535.15718742514, 10701.89298107009, 10782.784344312913, 10586.04725574702, 10700.250982431466, 10900.236983411085, 11085.057770089632, 11177.72055698614, 6887.758928437481, 7802.0, 8062.113167551682, 8125.959370203112, 8158.714444994397, 9941.801163976825, 10359.617943168125, 10408.0, 10574.735631608964, 10773.0], "value": 9350.64449171285, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1490.5975386645605, 1515.7347464193767, 1519.4302136698739, 1867.9813201867978, 1899.069455966576, 8022.277994980451, 8470.449420787652, 8477.830443391129, 8480.66077356905, 8489.320854331654, 7450.0, 7667.156612772605, 7978.800529986754, 8207.097219305886, 8241.0, 1884.4629280655051, 1908.9236430542762, 1914.818092281231, 1917.3768525229357, 1919.1939385458072, 2351.1065794997876, 2378.3102900158933, 2380.1907351500486, 2406.2300063979533, 2406.8194885383577, 2338.672585837984, 2346.6245400735884, 2360.9409764755846, 2364.562555927159, 2366.0417530900013, 2333.125078095714, 2356.045801450415, 2357.316378250305, 2357.575636385441, 2360.9173678921156, 2293.6903518025188, 2368.0, 2370.8221883358815, 2375.7149142102885, 2376.7742064503846, 8122.472039317485, 8337.624806883645, 8346.0402053764, 8347.0, 8351.24838764514, 5075.822346217865, 5238.738063096868, 5245.055889939809, 5246.632735708502, 5248.763805628756, 3140.671546833133, 3351.5307856900054, 3450.8274586270586, 3534.68187863091, 3545.8936231913085, 1048.8584041154422, 1060.543966094579, 1085.0560012788844, 1096.36958748719, 1097.1990446973755, 10471.528781204865, 10736.677899663022, 10746.570137194503, 10747.78504429907, 10912.181586380973, 4300.462442194726, 4380.9123817524105, 4479.081788233404, 4508.098380323949, 4510.0979804039325, 8619.1811777881, 8624.655013799378, 8633.95683021581, 8637.309015278763, 8639.308855291561, 5099.158638824627, 5100.260462232955, 5107.157319042391, 5108.66793658411, 5111.080005599028, 4626.918530120343, 4724.574788269072, 4752.572268495852, 4794.400699912511, 4798.088363210979, 3488.110531814371, 3488.89533314001, 3537.25717599307, 3572.696320812708, 3587.4798154267487, 1085.853409789684, 1091.5088210305394, 1092.836074588818, 1102.6581759654532, 1104.0615476844619, 812.9390295727844, 872.908344623818, 886.8847049883474, 932.8460803967405, 945.3713280668416, 4819.6744246469825, 5335.733213339317, 5644.435556444323, 5667.688277144714, 5807.041838096667, 417.1469345189096, 439.8240703718538, 463.3073555035244, 465.78806642977366, 466.22141024489184, 3697.3159965406485, 3708.147126160998, 3724.938392558162, 3733.216024634857, 3811.5045044144085, 8454.788630284293, 9715.07706767856, 10069.345492542961, 10072.647457338959, 10095.24285678577, 9654.469004204695, 9947.701568952943, 10011.0, 10018.949905250427, 10019.248556358303, 7730.961345193238, 7908.802279942933, 7920.801979950547, 7921.485103468256, 7922.286994170552, 4739.526047395233, 5305.867353316198, 5516.227728117987, 5541.501264886179, 5571.498565129158, 9230.307726920373, 9257.35198536096, 9325.160735533835, 9325.953370233105, 9467.053294670477, 9389.718308450758, 9418.058194180527, 9453.149216570542, 9500.762480938032, 9573.760655983657, 6272.40412160844, 6445.709943052668, 6619.834504137435, 6734.427573656294, 6748.898766518408, 7902.920970790218, 7909.169537198742, 7991.200879912195, 7993.280604745601, 8026.919730802617, 7110.004599356198, 7116.928830711627, 7134.892976605459, 7202.135743710788, 7279.526830755984, 6943.51395402317, 7038.542494738031, 7052.8589428210125, 7061.6469176542, 7082.256363082009, 6617.43751781104, 6774.187097548326, 6779.423748981195, 6785.457163426815, 6796.796096117125, 4661.743604101672, 4772.5227477252, 4786.305985632133, 4816.34979277793, 4858.441279252931, 4734.21885388907, 4736.668433209642, 4806.182948898766, 4847.806087756591, 4924.753762311799], "value": 4265.646005544016, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.8999560158343, 311.08695977306604, 320.40244943180966, 323.6035856076305, 327.31101032326967, 4363.432753742013, 4457.796394973355, 5248.47515248475, 5316.93661267746, 5341.009034940431, 4917.0, 4982.501749825015, 5116.6185130014965, 5166.844994650157, 5385.246065550817, 4036.273470775252, 4058.701215611006, 4094.344904815215, 4095.4266402703647, 4128.855490057848, 4082.9591704082877, 4086.3257562502154, 4089.5297040840237, 4092.4270602115726, 4113.177364527091, 3785.4321851722325, 3979.4480152740452, 4076.368162934735, 4076.429299898017, 4097.528784189812, 4006.1587066716024, 4029.395590661395, 4034.818433170485, 4058.8376464941366, 4059.3099173140636, 2954.4829654810324, 3366.898993030213, 3383.830808459592, 3498.6851183393615, 3502.2995400919663, 4333.263345231318, 4374.9781251093855, 4433.889152771207, 4748.311494833264, 5066.392032956032, 4266.530681625019, 4370.759608221547, 4625.537446255381, 4634.9073018539775, 4649.930251046237, 4722.645801564897, 5374.91937620936, 5646.745896434671, 5648.51987581052, 5793.333766616851, 3267.477203647405, 3291.489819078047, 3817.9809100954594, 3858.3247931611854, 3880.708946828971, 5460.781568737246, 5784.021229067034, 5821.0, 5830.3586605473365, 5846.444587764156, 3264.3144939562485, 3820.8280627371837, 3846.3845784674318, 3852.210296889131, 3867.961320386816, 3470.410030294856, 3756.69946404287, 3907.4138879167785, 3914.0410599402758, 3916.5300163980505, 3727.105494681311, 4112.712110152321, 4413.867583972486, 4604.8388306409115, 4623.537646235417, 4909.950900491021, 5614.382417934024, 5624.156376543551, 5625.662460252398, 5653.9151912721345, 1044.1490185498794, 1112.2436743014828, 1197.6167626359656, 1252.555342853274, 1264.2856785916113, 821.2362502872386, 840.3529282452599, 875.0, 881.6209030117111, 887.5887339130684, 4155.272827255279, 4473.597376236155, 4733.053389322149, 5023.924641130386, 5117.641765076409, 5521.613487055948, 5537.363203231599, 5553.16702494629, 5596.244506991586, 5625.943740562624, 4658.557437043475, 4814.555633309993, 5081.8311788288165, 5144.897102057937, 5464.9453505464435, 2571.987140064288, 2677.4243537639113, 2754.374918797919, 2869.6986816384397, 2930.3113768264443, 4982.77577509013, 5727.914081288784, 5774.566907481872, 5778.335491418541, 5790.913136302959, 4719.882002949885, 5159.974200128975, 5243.056249875059, 5425.267588875451, 5659.207710920475, 5550.278463799761, 5659.208330418629, 5727.398623144593, 5789.189513468202, 5794.710264486843, 5270.868228294324, 5428.824137879385, 5531.419200983839, 5543.445655434585, 5573.968815769095, 4665.86002419928, 4875.902481950412, 4881.658283920092, 5327.813526526553, 5450.411054748963, 4757.857264282077, 5364.034473794754, 5621.831345059655, 5752.884942301214, 5840.408148988427, 3620.5293311869286, 3701.1857391373856, 3716.368217402994, 3950.5456872460036, 3956.4658771065538, 5075.187969924795, 5765.299236725093, 5765.4522820332, 5770.567207459541, 5789.94210057894, 4328.264195086906, 5204.1489297920025, 5336.412994570672, 5623.2408624835125, 5726.284214473191, 5410.648307859977, 5707.714614269187, 5708.143778433102, 5727.6849773262875, 5745.827625171251, 5277.627816767591, 5464.23500709893, 5576.972115139561, 5577.52591029767, 5607.271054762855, 4634.698744581591, 5136.383633963948, 5563.221149039056, 5567.24842146305, 5575.721213939206, 4347.869563913087, 5514.828973340265, 5560.777568897361, 5561.5272701820795, 5563.193336966198], "value": 4008.3259829421872, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.5460068750499, 703.2264509040057, 705.7459314646728, 725.5791640848308, 770.537677393564, 14047.719045619082, 14487.0, 15145.545633630982, 15381.69236615267, 15415.614609634764, 12922.612321630342, 13015.154014989019, 13109.344532773372, 13118.934405327984, 13346.332683365818, 836.7991681996323, 853.7481442974306, 854.3336197765752, 854.799122206281, 857.6783706110209, 693.7641201991322, 713.2190251674408, 836.3601844588894, 852.8848605438249, 853.1127627267634, 881.973540793774, 900.4912224593083, 900.8018235988072, 904.3353135445442, 904.5658084119626, 894.3381897395946, 894.4990805149138, 895.9731208063704, 896.1352295035322, 897.0491279244022, 667.5660820466687, 667.7829705345733, 668.4618881800149, 670.0, 670.8758879607288, 13898.930505347409, 14478.0, 14615.707685846224, 14631.853681463263, 14648.70702585953, 13243.86756132436, 13313.86686133136, 13489.0, 13565.864341356559, 13923.58229253124, 10990.835137472945, 11098.945600167972, 11165.553377864875, 11282.605108821233, 11338.773224535546, 3593.1915319053264, 3622.5471816022996, 3638.9272214555826, 3744.906377340561, 3872.5159355080614, 76314.8552771709, 86205.99999999937, 91280.54359728226, 92370.0, 92440.0, 10041.849372259421, 10176.898231017743, 10366.429846358522, 10527.894721052844, 10881.178207910825, 20249.898750506447, 20372.388828335173, 20677.0, 20776.896115519325, 20809.37571872846, 7465.104187497426, 7566.092068951651, 7632.045994250719, 7673.271039251262, 7774.805629859299, 4239.40648309237, 4254.532001479834, 4270.935935960963, 4290.099079193342, 4485.865424037284, 3401.8129002904584, 3506.772059816155, 3507.193345530491, 3507.3862074136923, 3507.877224297137, 619.1826788638954, 621.0590954703628, 628.2806186915956, 628.7610707930957, 629.9937000629935, 556.3574071946908, 573.3744833397282, 573.9598228123991, 647.5240698086902, 675.1998881325645, 8417.410781245377, 9145.862812057825, 9293.767655808659, 9387.5306234688, 9608.711738647851, 564.7910273198943, 622.7695752571577, 624.7938180400457, 635.7615894039735, 684.3354430379767, 3926.293267211929, 4346.760928148982, 4398.64810815134, 4528.50186479487, 4674.252119660838, 53838.0, 79946.60026699946, 105544.47227763812, 108677.0, 113001.86998129914, 97839.53240701395, 97882.0, 99346.0, 100063.0, 103358.0, 56665.716671416376, 56811.7159414192, 66278.33721662722, 67988.6600566994, 86128.0, 9401.10689484498, 9675.516224188903, 9780.85328720084, 9794.0, 9827.705168844946, 39708.0, 50271.49728502668, 50920.74539627278, 51490.97018059693, 52776.73611632071, 48730.0, 55123.72438137783, 57783.39974701065, 66959.33040669531, 71447.64276178586, 11950.760984780429, 12200.14598978063, 12251.264924104582, 12641.051921105769, 12983.545575904798, 12138.466965780837, 13900.985787244343, 14085.718285634435, 14144.9866123169, 14308.928455358073, 11926.880731192578, 12036.699082523008, 12188.390580471118, 12341.876581234073, 12557.434915428645, 12207.143675898022, 12835.871641283467, 12870.742585148431, 12930.482780688668, 13588.660283492993, 11208.607698730506, 12274.570390036306, 12831.230126192459, 12969.286689231805, 13733.038687291793, 10880.932423262733, 11024.669259922215, 11289.491972861406, 11301.321920684784, 11672.47473863695, 11009.9251001741, 11264.155188360743, 11331.716707082389, 11531.88468115308, 11610.419479026184], "value": 7169.21211313641, "unit": "score"}], "name": "dromaeo_css", "value": 7159.632156952172, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 115.0, 115.0, 114.0, 115.0, 115.0, 114.0, 115.0, 114.0, 115.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 107.0, 109.0, 144.0, 131.0, 132.0, 133.0, 104.0, 176.0, 137.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 185.0, 183.0, 182.0, 182.0, 182.0, 182.0, 184.0, 184.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 58.0, 88.0, 59.0, 58.0, 59.0, 58.0, 84.0, 71.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 93.0, 69.0, 69.0, 68.0, 89.0, 68.0, 69.0, 91.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 86.0, 88.0, 84.0, 88.0, 86.0, 89.0, 129.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 82.0, 80.0, 83.0, 83.0, 86.0, 83.0, 84.0, 84.0], "value": 83.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 55.0, 58.0, 56.0, 58.0, 55.0, 56.0, 56.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 63.0, 73.0, 71.0, 67.0, 67.0, 67.0, 67.0, 69.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 87.0, 95.0, 82.0, 83.0, 81.0, 81.0, 84.0, 81.0, 92.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 138.0, 136.0, 137.0, 141.0, 140.0, 139.0, 172.0, 146.0], "value": 142.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 160.0, 162.0, 161.0, 163.0, 159.0, 159.0, 168.0, 161.0, 161.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 66.0, 66.0, 67.0, 78.0, 56.0, 70.0, 81.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1421.8, "alertThreshold": 2.0}]} 18:03:29 INFO - Return code: 0 18:03:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:03:29 INFO - # TBPL SUCCESS # 18:03:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:03:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:03:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:03:29 INFO - 2017-02-27 18:03:29.760 screenresolution[1668:15355] starting screenresolution argv=screenresolution get 18:03:29 INFO - 2017-02-27 18:03:29.763 screenresolution[1668:15355] Display 0: 1600x1200x32@60 18:03:29 INFO - 2017-02-27 18:03:29.769 screenresolution[1669:15358] starting screenresolution argv=screenresolution list 18:03:29 INFO - Available Modes on Display 0 18:03:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:03:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:03:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:03:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:03:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:03:29 INFO - Intel Iris: 18:03:29 INFO - Chipset Model: Intel Iris 18:03:29 INFO - Type: GPU 18:03:29 INFO - Bus: Built-In 18:03:29 INFO - VRAM (Dynamic, Max): 1536 MB 18:03:29 INFO - Vendor: Intel (0x8086) 18:03:29 INFO - Device ID: 0x0a2e 18:03:29 INFO - Revision ID: 0x0009 18:03:29 INFO - Displays: 18:03:29 INFO - Display: 18:03:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:03:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:03:29 INFO - Display Serial Number: 1600x1200 60 18:03:29 INFO - Main Display: Yes 18:03:29 INFO - Mirror: Off 18:03:29 INFO - Online: Yes 18:03:29 INFO - Rotation: Supported 18:03:30 INFO - Return code: 0 18:03:30 INFO - Running post-action listener: _resource_record_post_action 18:03:30 INFO - [mozharness: 2017-02-28 02:03:30.000915Z] Finished run-tests step (success) 18:03:30 INFO - Running post-run listener: _resource_record_post_run 18:03:30 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 32635904; Write bytes: 504145408; Read time: 193; Write time: 1601 18:03:30 INFO - TinderboxPrint: CPU usage
53.6% 18:03:30 INFO - TinderboxPrint: I/O read bytes / time
32,635,904 / 193 18:03:30 INFO - TinderboxPrint: I/O write bytes / time
504,145,408 / 1,601 18:03:30 INFO - TinderboxPrint: CPU idle
2,227.8 (46.4%) 18:03:30 INFO - TinderboxPrint: CPU system
961.3 (20.0%) 18:03:30 INFO - TinderboxPrint: CPU user
1,608.6 (33.5%) 18:03:30 INFO - TinderboxPrint: Swap in / out
220,237,824 / 0 18:03:30 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 179337728; Write bytes: 177113088; Read time: 16790; Write time: 381 18:03:30 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31697920; Write bytes: 309296640; Read time: 179; Write time: 1169 18:03:30 INFO - Running post-run listener: _upload_blobber_files 18:03:30 INFO - Blob upload gear active. 18:03:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:03:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:03:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:03:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:03:30 INFO - (blobuploader) - INFO - Open directory for files ... 18:03:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:03:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:03:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:03:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:03:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:03:32 INFO - (blobuploader) - INFO - Done attempting. 18:03:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:03:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:03:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:03:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:03:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:03:33 INFO - (blobuploader) - INFO - Done attempting. 18:03:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:03:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:03:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:03:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:03:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:03:38 INFO - (blobuploader) - INFO - Done attempting. 18:03:38 INFO - (blobuploader) - INFO - Iteration through files over. 18:03:38 INFO - Return code: 0 18:03:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:03:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:03:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/34f327b2f1bbf83b753a4f6d0326354897c8088ea68013217962591138f39fae3df35312c0d14c3c35974153957d59b7e860f99b8bd10623cb0684f414722ddf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9dfcb925011dfaadcea5a6d01a118db0a94b660373f09146239b802311b4506c162a881820c158dded0601751dce7808fb5db3e247ce933a4ee3111c522515c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/898afdda3ad738cbe277cb74c709b700af123dc13899d35a6c58621743942fb6059c5ded5c9a437a1dd30ac08470e13f6c9ad2553ec3ed80c699eb7931246e5b"} 18:03:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:03:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:03:38 INFO - Contents: 18:03:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/34f327b2f1bbf83b753a4f6d0326354897c8088ea68013217962591138f39fae3df35312c0d14c3c35974153957d59b7e860f99b8bd10623cb0684f414722ddf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9dfcb925011dfaadcea5a6d01a118db0a94b660373f09146239b802311b4506c162a881820c158dded0601751dce7808fb5db3e247ce933a4ee3111c522515c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/898afdda3ad738cbe277cb74c709b700af123dc13899d35a6c58621743942fb6059c5ded5c9a437a1dd30ac08470e13f6c9ad2553ec3ed80c699eb7931246e5b"} 18:03:38 INFO - Running post-run listener: copy_logs_to_upload_dir 18:03:38 INFO - Copying logs to upload dir... 18:03:38 INFO - mkdir: /builds/slave/test/build/upload/logs 18:03:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.008774 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-02-27 18:03:38.248959) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-27 18:03:38.252877) ========= 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.IucdcXxdvn/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.NmLXVcgcNH/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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-Beta/sha512/34f327b2f1bbf83b753a4f6d0326354897c8088ea68013217962591138f39fae3df35312c0d14c3c35974153957d59b7e860f99b8bd10623cb0684f414722ddf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9dfcb925011dfaadcea5a6d01a118db0a94b660373f09146239b802311b4506c162a881820c158dded0601751dce7808fb5db3e247ce933a4ee3111c522515c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/898afdda3ad738cbe277cb74c709b700af123dc13899d35a6c58621743942fb6059c5ded5c9a437a1dd30ac08470e13f6c9ad2553ec3ed80c699eb7931246e5b"} build_url:https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011500 build_url: 'https://queue.taskcluster.net/v1/task/Zd9MwTtJS0Cz2l8UkdGDZA/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/34f327b2f1bbf83b753a4f6d0326354897c8088ea68013217962591138f39fae3df35312c0d14c3c35974153957d59b7e860f99b8bd10623cb0684f414722ddf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9dfcb925011dfaadcea5a6d01a118db0a94b660373f09146239b802311b4506c162a881820c158dded0601751dce7808fb5db3e247ce933a4ee3111c522515c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/898afdda3ad738cbe277cb74c709b700af123dc13899d35a6c58621743942fb6059c5ded5c9a437a1dd30ac08470e13f6c9ad2553ec3ed80c699eb7931246e5b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-27 18:03:38.291442) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 18:03:38.291960) ========= 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.IucdcXxdvn/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.NmLXVcgcNH/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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.005076 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 18:03:38.341050) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-27 18:03:38.341369) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-27 18:03:38.341691) ========= ========= Total master_lag: 0.19 =========