builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0279 starttime: 1479137213.82 results: success (0) buildid: 20161114043455 builduid: d00525730d904e358afe39be231f63da revision: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:53.822545) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:53.822994) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:53.823263) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:53.846327) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:53.846606) ========= 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.Jr2fA19arr/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.QlMR1JiYYx/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-14 07:26:53-- 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% 8.05M=0.001s 2016-11-14 07:26:53 (8.05 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120233 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:53.991817) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:53.992138) ========= 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.Jr2fA19arr/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.QlMR1JiYYx/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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.020375 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:54.040171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 07:26:54.040515) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Jr2fA19arr/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.QlMR1JiYYx/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-14 07:26:54,130 truncating revision to first 12 chars 2016-11-14 07:26:54,130 Setting DEBUG logging. 2016-11-14 07:26:54,130 attempt 1/10 2016-11-14 07:26:54,130 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7e29173b1641?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 07:26:54,906 unpacking tar archive at: mozilla-aurora-7e29173b1641/testing/mozharness/ program finished with exit code 0 elapsedTime=1.062434 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 07:26:55.128075) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:55.128395) ========= script_repo_revision: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:55.128788) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:55.129053) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 07:26:55.147426) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 07:26:55.147836) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Jr2fA19arr/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.QlMR1JiYYx/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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 07:26:55 INFO - MultiFileLogger online at 20161114 07:26:55 in /builds/slave/test 07:26:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:26:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:26:55 INFO - {'append_to_log': False, 07:26:55 INFO - 'base_work_dir': '/builds/slave/test', 07:26:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:26:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:26:55 INFO - 'branch': 'Mozilla-Aurora', 07:26:55 INFO - 'buildbot_json_path': 'buildprops.json', 07:26:55 INFO - 'config_files': ('talos/mac_config.py',), 07:26:55 INFO - 'default_actions': ('clobber', 07:26:55 INFO - 'read-buildbot-config', 07:26:55 INFO - 'download-and-extract', 07:26:55 INFO - 'populate-webroot', 07:26:55 INFO - 'create-virtualenv', 07:26:55 INFO - 'install', 07:26:55 INFO - 'run-tests'), 07:26:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:26:55 INFO - 'download_minidump_stackwalk': True, 07:26:55 INFO - 'download_symbols': 'ondemand', 07:26:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:26:55 INFO - 'tooltool.py': '/tools/tooltool.py', 07:26:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:26:55 INFO - '/tools/misc-python/virtualenv.py')}, 07:26:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:26:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:26:55 INFO - 'installer_path': 'installer.exe', 07:26:55 INFO - 'log_level': 'info', 07:26:55 INFO - 'log_name': 'talos', 07:26:55 INFO - 'log_to_console': True, 07:26:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:26:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:26:55 INFO - 'opt_config_files': (), 07:26:55 INFO - 'pip_index': False, 07:26:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:26:55 INFO - 'cmd': ('bash', 07:26:55 INFO - '-c', 07:26:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:26:55 INFO - 'enabled': True, 07:26:55 INFO - 'halt_on_failure': False, 07:26:55 INFO - 'name': 'check_screen_resolution'},), 07:26:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:26:55 INFO - 'cmd': ('bash', 07:26:55 INFO - '-c', 07:26:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:26:55 INFO - 'enabled': True, 07:26:55 INFO - 'halt_on_failure': False, 07:26:55 INFO - 'name': 'check_screen_resolution'},), 07:26:55 INFO - 'run_cmd_checks_enabled': True, 07:26:55 INFO - 'sps_profile': False, 07:26:55 INFO - 'sps_profile_interval': 0, 07:26:55 INFO - 'suite': 'dromaeojs-e10s', 07:26:55 INFO - 'system_bits': '32', 07:26:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:26:55 INFO - 'title': 't-yosemite-r7-0279', 07:26:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:26:55 INFO - 'use_talos_json': True, 07:26:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:26:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:26:55 INFO - 'work_dir': 'build'} 07:26:55 INFO - [mozharness: 2016-11-14 15:26:55.353934Z] Running clobber step. 07:26:55 INFO - Running pre-action listener: _resource_record_pre_action 07:26:55 INFO - Running main action method: clobber 07:26:55 INFO - rmtree: /builds/slave/test/build 07:26:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:26:56 INFO - Running post-action listener: _resource_record_post_action 07:26:56 INFO - [mozharness: 2016-11-14 15:26:56.916871Z] Finished clobber step (success) 07:26:56 INFO - [mozharness: 2016-11-14 15:26:56.917004Z] Running read-buildbot-config step. 07:26:56 INFO - Running pre-action listener: _resource_record_pre_action 07:26:56 INFO - Running main action method: read_buildbot_config 07:26:56 INFO - Using buildbot properties: 07:26:56 INFO - { 07:26:56 INFO - "project": "", 07:26:56 INFO - "product": "firefox", 07:26:56 INFO - "script_repo_revision": "production", 07:26:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 07:26:56 INFO - "repository": "", 07:26:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 07:26:56 INFO - "buildid": "20161114043455", 07:26:56 INFO - "stage_platform": "macosx64", 07:26:56 INFO - "basedir": "/builds/slave/test", 07:26:56 INFO - "nightly_build": "True", 07:26:56 INFO - "buildnumber": 14, 07:26:56 INFO - "slavename": "t-yosemite-r7-0279", 07:26:56 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:26:56 INFO - "platform": "yosemite_r7", 07:26:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:26:56 INFO - "branch": "mozilla-aurora", 07:26:56 INFO - "repo_path": "releases/mozilla-aurora", 07:26:56 INFO - "revision": "7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f", 07:26:56 INFO - "pgo_build": "False", 07:26:56 INFO - "builduid": "d00525730d904e358afe39be231f63da", 07:26:56 INFO - "slavebuilddir": "test" 07:26:56 INFO - } 07:26:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:26:56 INFO - Running post-action listener: _resource_record_post_action 07:26:56 INFO - [mozharness: 2016-11-14 15:26:56.920466Z] Finished read-buildbot-config step (success) 07:26:56 INFO - [mozharness: 2016-11-14 15:26:56.920597Z] Running download-and-extract step. 07:26:56 INFO - Running pre-action listener: _resource_record_pre_action 07:26:56 INFO - Running main action method: download_and_extract 07:26:56 INFO - mkdir: /builds/slave/test/build/tests 07:26:56 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')]} 07:26:56 INFO - trying https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:26:56 INFO - Downloading https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:26:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 07:26:58 INFO - Downloaded 1199 bytes. 07:26:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:26:58 INFO - Using the following test package requirements: 07:26:58 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:26:58 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:26:58 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:26:58 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:26:58 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:26:58 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:26:58 INFO - u'jsshell-mac.zip'], 07:26:58 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:26:58 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:26:58 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:26:58 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:26:58 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:26:58 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:26:58 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:26:58 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:26:58 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:26:58 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:26:58 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:26:58 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:26:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:26:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:26:58 INFO - Fetch https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:26:59 INFO - Http code: 200 07:26:59 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 07:26:59 INFO - x-amz-cf-id: wZJ-T7wrSgDlnLALvDEO7yc4AtuZqWm1MRVJGPl1u96krIj7LyZ-Rg== 07:26:59 INFO - x-amz-version-id: PRl2kHif0GPZQracWVEwIm6B5F.eViyj 07:26:59 INFO - Expected file size: 16327587 07:26:59 INFO - Obtained file size: 16327587 07:27:06 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:27:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:27:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:27:06 INFO - Fetch https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:27:06 INFO - Http code: 200 07:27:06 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 07:27:06 INFO - x-amz-cf-id: KfAMAT0v_eCfRGZeTH_TOc-yecXULjwHr7fgSpXxot8mqoi5nbSkbg== 07:27:06 INFO - x-amz-version-id: PRl2kHif0GPZQracWVEwIm6B5F.eViyj 07:27:07 INFO - Expected file size: 16327587 07:27:07 INFO - Obtained file size: 16327587 07:27:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:27:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:27:13 INFO - Fetch https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:27:14 INFO - Http code: 200 07:27:14 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 07:27:14 INFO - x-amz-cf-id: JHEC9-6FvA-nh0gUvXfRGhKmvkcAyzbhF4uftxZ2DVVRa9xRbWL5Ew== 07:27:14 INFO - x-amz-version-id: J9ZJihbHmZTC5VZRjbGrijxDoBM6fTjT 07:27:14 INFO - Expected file size: 14409038 07:27:14 INFO - Obtained file size: 14409038 07:27: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')]} 07:27:14 INFO - trying https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:27:14 INFO - Downloading https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:27:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 07:27:17 INFO - Downloaded 114592160 bytes. 07:27:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:27:17 INFO - mkdir: /builds/slave/test/properties 07:27:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:27:17 INFO - Writing to file /builds/slave/test/properties/build_url 07:27:17 INFO - Contents: 07:27:17 INFO - build_url:https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:27:18 INFO - Running post-action listener: _resource_record_post_action 07:27:18 INFO - Running post-action listener: set_extra_try_arguments 07:27:18 INFO - [mozharness: 2016-11-14 15:27:18.611155Z] Finished download-and-extract step (success) 07:27:18 INFO - [mozharness: 2016-11-14 15:27:18.611294Z] Running populate-webroot step. 07:27:18 INFO - Running pre-action listener: _resource_record_pre_action 07:27:18 INFO - Running main action method: populate_webroot 07:27:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:27:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:27:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:27:18 INFO - '--tppagecycles', 07:27:18 INFO - '7'], 07:27:18 INFO - 'tests': ['tsvgm']}}, 07:27:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:27:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:27:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:27:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:27:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:27:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:27:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:18 INFO - 'talos_options': ['--disable-e10s'], 07:27:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:27:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:27:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:18 INFO - 'talos_options': ['--disable-e10s'], 07:27:18 INFO - 'tests': ['damp', 'tps']}, 07:27:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:18 INFO - 'tests': ['damp', 'tps']}, 07:27:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:27:18 INFO - 'tests': ['dromaeo_dom']}, 07:27:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:27:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:27:18 INFO - 'tests': ['basic_compositor_video']}, 07:27:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:27:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:27:18 INFO - 'tests': ['a11yr', 07:27:18 INFO - 'ts_paint', 07:27:18 INFO - 'tpaint', 07:27:18 INFO - 'sessionrestore', 07:27:18 INFO - 'sessionrestore_no_auto_restore', 07:27:18 INFO - 'tabpaint']}, 07:27:18 INFO - 'other-e10s': {'tests': ['a11yr', 07:27:18 INFO - 'ts_paint', 07:27:18 INFO - 'tpaint', 07:27:18 INFO - 'sessionrestore', 07:27:18 INFO - 'sessionrestore_no_auto_restore', 07:27:18 INFO - 'tabpaint']}, 07:27:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:27:18 INFO - 'ts_paint', 07:27:18 INFO - 'tpaint', 07:27:18 INFO - 'sessionrestore', 07:27:18 INFO - 'sessionrestore_no_auto_restore', 07:27:18 INFO - 'tabpaint']}, 07:27:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:27:18 INFO - 'ts_paint', 07:27:18 INFO - 'tpaint', 07:27:18 INFO - 'sessionrestore', 07:27:18 INFO - 'sessionrestore_no_auto_restore', 07:27:18 INFO - 'tabpaint']}, 07:27:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:27:18 INFO - 'tests': ['a11yr', 07:27:18 INFO - 'ts_paint', 07:27:18 INFO - 'tpaint', 07:27:18 INFO - 'sessionrestore', 07:27:18 INFO - 'sessionrestore_no_auto_restore', 07:27:18 INFO - 'tabpaint']}, 07:27:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:27:18 INFO - 'tests': ['a11yr', 07:27:18 INFO - 'ts_paint', 07:27:18 INFO - 'tpaint', 07:27:18 INFO - 'sessionrestore', 07:27:18 INFO - 'sessionrestore_no_auto_restore', 07:27:18 INFO - 'tabpaint']}, 07:27:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:27:18 INFO - 'tests': ['tsvgx', 07:27:18 INFO - 'tsvgr_opacity', 07:27:18 INFO - 'tart', 07:27:18 INFO - 'tscrollx', 07:27:18 INFO - 'cart']}, 07:27:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:27:18 INFO - 'tsvgr_opacity', 07:27:18 INFO - 'tart', 07:27:18 INFO - 'tscrollx', 07:27:18 INFO - 'cart']}, 07:27:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:18 INFO - 'talos_options': ['--disable-e10s'], 07:27:18 INFO - 'tests': ['tp5o']}, 07:27:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:18 INFO - 'tests': ['tp5o']}, 07:27:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:18 INFO - 'talos_options': ['--disable-e10s', 07:27:18 INFO - '--xperf_path', 07:27:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:27:18 INFO - 'tests': ['tp5n']}, 07:27:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:27:18 INFO - 'talos_options': ['--xperf_path', 07:27:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:27:18 INFO - 'tests': ['tp5n']}}, 07:27:18 INFO - 'talos.zip': {'path': '', 07:27:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:27:18 INFO - Running post-action listener: _resource_record_post_action 07:27:18 INFO - [mozharness: 2016-11-14 15:27:18.630251Z] Finished populate-webroot step (success) 07:27:18 INFO - [mozharness: 2016-11-14 15:27:18.630385Z] Running create-virtualenv step. 07:27:18 INFO - Running pre-action listener: _resource_record_pre_action 07:27:18 INFO - Running main action method: create_virtualenv 07:27:18 INFO - Creating virtualenv /builds/slave/test/build/venv 07:27:18 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 07:27:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:27:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:27:18 INFO - Using real prefix '/tools/python27' 07:27:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:27:19 INFO - Installing distribute.............................................................................................................................................................................................done. 07:27:22 INFO - Installing pip.................done. 07:27:22 INFO - Return code: 0 07:27:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:27:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:27:22 INFO - Reading from file tmpfile_stdout 07:27:22 INFO - Output received: 07:27:22 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) 07:27:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:27:22 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')]} 07:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:22 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')]} 07:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:22 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 0x10c655490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb68bc870a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c2cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c461b20>, '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.Jr2fA19arr/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.QlMR1JiYYx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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 07:27:22 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 07:27:22 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 07:27:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jr2fA19arr/Render', 07:27:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:22 INFO - 'HOME': '/Users/cltbld', 07:27:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:22 INFO - 'LOGNAME': 'cltbld', 07:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:22 INFO - 'MOZ_NO_REMOTE': '1', 07:27:22 INFO - 'NO_EM_RESTART': '1', 07:27:22 INFO - 'PAGER': '/bin/cat', 07:27:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:22 INFO - 'PWD': '/builds/slave/test', 07:27:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:22 INFO - 'SHELL': '/bin/bash', 07:27:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlMR1JiYYx/Listeners', 07:27:22 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 07:27:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:22 INFO - 'USER': 'cltbld', 07:27:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:22 INFO - 'XPC_FLAGS': '0x0', 07:27:22 INFO - 'XPC_SERVICE_NAME': '0', 07:27:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:22 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 07:27:22 INFO - Cleaning up... 07:27:22 INFO - Return code: 0 07:27:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:27:22 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')]} 07:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:22 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')]} 07:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:22 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 0x10c655490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb68bc870a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c2cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c461b20>, '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.Jr2fA19arr/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.QlMR1JiYYx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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 07:27:22 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 07:27:22 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 07:27:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jr2fA19arr/Render', 07:27:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:22 INFO - 'HOME': '/Users/cltbld', 07:27:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:22 INFO - 'LOGNAME': 'cltbld', 07:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:22 INFO - 'MOZ_NO_REMOTE': '1', 07:27:22 INFO - 'NO_EM_RESTART': '1', 07:27:22 INFO - 'PAGER': '/bin/cat', 07:27:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:22 INFO - 'PWD': '/builds/slave/test', 07:27:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:22 INFO - 'SHELL': '/bin/bash', 07:27:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlMR1JiYYx/Listeners', 07:27:22 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 07:27:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:22 INFO - 'USER': 'cltbld', 07:27:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:22 INFO - 'XPC_FLAGS': '0x0', 07:27:22 INFO - 'XPC_SERVICE_NAME': '0', 07:27:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:23 INFO - Downloading/unpacking psutil>=3.1.1 07:27:23 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 07:27:23 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 07:27:23 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 07:27:23 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 07:27:23 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 07:27:23 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 07:27:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:27:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:27:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:27:26 INFO - Installing collected packages: psutil 07:27:26 INFO - Running setup.py install for psutil 07:27:26 INFO - building 'psutil._psutil_osx' extension 07:27:26 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 07:27:27 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 07:27:27 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 07:27:27 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 07:27:27 INFO - building 'psutil._psutil_posix' extension 07:27:27 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 07:27:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:27:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:27:27 INFO - ^ 07:27:27 INFO - 1 warning generated. 07:27:27 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 07:27:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:27:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:27:27 INFO - Successfully installed psutil 07:27:27 INFO - Cleaning up... 07:27:27 INFO - Return code: 0 07:27:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c655490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb68bc870a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c2cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c461b20>, '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.Jr2fA19arr/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.QlMR1JiYYx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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 07:27:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:27:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jr2fA19arr/Render', 07:27:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:27 INFO - 'HOME': '/Users/cltbld', 07:27:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:27 INFO - 'LOGNAME': 'cltbld', 07:27:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:27 INFO - 'MOZ_NO_REMOTE': '1', 07:27:27 INFO - 'NO_EM_RESTART': '1', 07:27:27 INFO - 'PAGER': '/bin/cat', 07:27:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:27 INFO - 'PWD': '/builds/slave/test', 07:27:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:27 INFO - 'SHELL': '/bin/bash', 07:27:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlMR1JiYYx/Listeners', 07:27:27 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 07:27:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:27 INFO - 'USER': 'cltbld', 07:27:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:27 INFO - 'XPC_FLAGS': '0x0', 07:27:27 INFO - 'XPC_SERVICE_NAME': '0', 07:27:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:27:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:27:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:27:30 INFO - Installing collected packages: mozsystemmonitor 07:27:30 INFO - Running setup.py install for mozsystemmonitor 07:27:30 INFO - Successfully installed mozsystemmonitor 07:27:30 INFO - Cleaning up... 07:27:30 INFO - Return code: 0 07:27:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:27:30 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')]} 07:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:30 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')]} 07:27:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:30 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 0x10c655490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb68bc870a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c2cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c461b20>, '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.Jr2fA19arr/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.QlMR1JiYYx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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 07:27:30 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 07:27:30 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 07:27:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jr2fA19arr/Render', 07:27:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:30 INFO - 'HOME': '/Users/cltbld', 07:27:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:30 INFO - 'LOGNAME': 'cltbld', 07:27:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:30 INFO - 'MOZ_NO_REMOTE': '1', 07:27:30 INFO - 'NO_EM_RESTART': '1', 07:27:30 INFO - 'PAGER': '/bin/cat', 07:27:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:30 INFO - 'PWD': '/builds/slave/test', 07:27:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:30 INFO - 'SHELL': '/bin/bash', 07:27:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlMR1JiYYx/Listeners', 07:27:30 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 07:27:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:30 INFO - 'USER': 'cltbld', 07:27:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:30 INFO - 'XPC_FLAGS': '0x0', 07:27:30 INFO - 'XPC_SERVICE_NAME': '0', 07:27:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:31 INFO - Downloading/unpacking jsonschema==2.5.1 07:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:27:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:27:33 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 07:27:33 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 07:27:33 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 07:27:33 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 07:27:33 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 07:27:33 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 07:27:33 INFO - Downloading functools32-3.2.3-2.tar.gz 07:27:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:27:34 INFO - warning: no files found matching '*.txt' 07:27:34 INFO - no previously-included directories found matching 'build' 07:27:34 INFO - no previously-included directories found matching 'dist' 07:27:34 INFO - no previously-included directories found matching '.git*' 07:27:34 INFO - Installing collected packages: jsonschema, functools32 07:27:34 INFO - Running setup.py install for functools32 07:27:34 INFO - warning: no files found matching '*.txt' 07:27:34 INFO - no previously-included directories found matching 'build' 07:27:34 INFO - no previously-included directories found matching 'dist' 07:27:34 INFO - no previously-included directories found matching '.git*' 07:27:34 INFO - Successfully installed jsonschema functools32 07:27:34 INFO - Cleaning up... 07:27:34 INFO - Return code: 0 07:27:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:27:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:34 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 0x10c655490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb68bc870a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c2cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c461b20>, '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.Jr2fA19arr/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.QlMR1JiYYx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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 07:27:34 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 07:27:34 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 07:27:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jr2fA19arr/Render', 07:27:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:34 INFO - 'HOME': '/Users/cltbld', 07:27:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:34 INFO - 'LOGNAME': 'cltbld', 07:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:34 INFO - 'MOZ_NO_REMOTE': '1', 07:27:34 INFO - 'NO_EM_RESTART': '1', 07:27:34 INFO - 'PAGER': '/bin/cat', 07:27:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:34 INFO - 'PWD': '/builds/slave/test', 07:27:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:34 INFO - 'SHELL': '/bin/bash', 07:27:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlMR1JiYYx/Listeners', 07:27:34 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 07:27:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:34 INFO - 'USER': 'cltbld', 07:27:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:34 INFO - 'XPC_FLAGS': '0x0', 07:27:34 INFO - 'XPC_SERVICE_NAME': '0', 07:27:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:27:34 INFO - Cleaning up... 07:27:34 INFO - Return code: 0 07:27:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:27:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:34 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 0x10c655490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb68bc870a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c2cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c461b20>, '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.Jr2fA19arr/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.QlMR1JiYYx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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 07:27:34 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 07:27:34 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 07:27:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jr2fA19arr/Render', 07:27:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:34 INFO - 'HOME': '/Users/cltbld', 07:27:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:34 INFO - 'LOGNAME': 'cltbld', 07:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:34 INFO - 'MOZ_NO_REMOTE': '1', 07:27:34 INFO - 'NO_EM_RESTART': '1', 07:27:34 INFO - 'PAGER': '/bin/cat', 07:27:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:34 INFO - 'PWD': '/builds/slave/test', 07:27:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:34 INFO - 'SHELL': '/bin/bash', 07:27:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlMR1JiYYx/Listeners', 07:27:34 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 07:27:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:34 INFO - 'USER': 'cltbld', 07:27:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:34 INFO - 'XPC_FLAGS': '0x0', 07:27:34 INFO - 'XPC_SERVICE_NAME': '0', 07:27:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:34 INFO - Downloading/unpacking blobuploader==1.2.4 07:27:34 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 07:27:34 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 07:27:34 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 07:27:34 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 07:27:34 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 07:27:34 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 07:27:37 INFO - Downloading blobuploader-1.2.4.tar.gz 07:27:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:27:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:27:37 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 07:27:37 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 07:27:37 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 07:27:37 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 07:27:37 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 07:27:37 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 07:27:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:27:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:27:38 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 07:27:38 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 07:27:38 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 07:27:38 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 07:27:38 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 07:27:38 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 07:27:38 INFO - Downloading docopt-0.6.1.tar.gz 07:27:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:27:38 INFO - Installing collected packages: blobuploader, requests, docopt 07:27:38 INFO - Running setup.py install for blobuploader 07:27:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:27:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:27:38 INFO - Running setup.py install for requests 07:27:39 INFO - Running setup.py install for docopt 07:27:39 INFO - Successfully installed blobuploader requests docopt 07:27:39 INFO - Cleaning up... 07:27:39 INFO - Return code: 0 07:27:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:39 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')]} 07:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:39 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')]} 07:27:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:39 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 0x10c655490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb68bc870a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c2cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c461b20>, '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.Jr2fA19arr/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.QlMR1JiYYx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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 07:27:39 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 07:27:39 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 07:27:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jr2fA19arr/Render', 07:27:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:39 INFO - 'HOME': '/Users/cltbld', 07:27:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:39 INFO - 'LOGNAME': 'cltbld', 07:27:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:39 INFO - 'MOZ_NO_REMOTE': '1', 07:27:39 INFO - 'NO_EM_RESTART': '1', 07:27:39 INFO - 'PAGER': '/bin/cat', 07:27:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:39 INFO - 'PWD': '/builds/slave/test', 07:27:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:39 INFO - 'SHELL': '/bin/bash', 07:27:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlMR1JiYYx/Listeners', 07:27:39 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 07:27:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:39 INFO - 'USER': 'cltbld', 07:27:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:39 INFO - 'XPC_FLAGS': '0x0', 07:27:39 INFO - 'XPC_SERVICE_NAME': '0', 07:27:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:39 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-7G44aX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:39 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-0k82mw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-3vQhRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-xSkHmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-GIstRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-0De7_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-HInamx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-V4v78I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-OdcnWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-4cOw5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-k8HaV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-wAThkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:41 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-1yZ3dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:41 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-dzneLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:41 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-NCSRbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:41 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-8DZyvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:41 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-kzaRtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:27:41 INFO - Running setup.py install for manifestparser 07:27:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:27:41 INFO - Running setup.py install for mozcrash 07:27:41 INFO - Running setup.py install for mozdebug 07:27:42 INFO - Running setup.py install for mozdevice 07:27:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:27:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:27:42 INFO - Running setup.py install for mozfile 07:27:42 INFO - Running setup.py install for mozhttpd 07:27:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:27:42 INFO - Running setup.py install for mozinfo 07:27:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:27:42 INFO - Running setup.py install for mozInstall 07:27:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:27:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:27:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:27:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:27:42 INFO - Running setup.py install for mozleak 07:27:42 INFO - Running setup.py install for mozlog 07:27:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:27:43 INFO - Running setup.py install for moznetwork 07:27:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:27:43 INFO - Running setup.py install for mozprocess 07:27:43 INFO - Running setup.py install for mozprofile 07:27:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:27:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:27:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:27:43 INFO - Running setup.py install for mozrunner 07:27:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:27:43 INFO - Running setup.py install for mozscreenshot 07:27:43 INFO - Running setup.py install for moztest 07:27:44 INFO - Running setup.py install for mozversion 07:27:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:27:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:27:44 INFO - Cleaning up... 07:27:44 INFO - Return code: 0 07:27:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:44 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 0x10c655490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb68bc870a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c2cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c461b20>, '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.Jr2fA19arr/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.QlMR1JiYYx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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 07:27:44 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 07:27:44 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 07:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jr2fA19arr/Render', 07:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:44 INFO - 'HOME': '/Users/cltbld', 07:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:44 INFO - 'LOGNAME': 'cltbld', 07:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:44 INFO - 'MOZ_NO_REMOTE': '1', 07:27:44 INFO - 'NO_EM_RESTART': '1', 07:27:44 INFO - 'PAGER': '/bin/cat', 07:27:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:44 INFO - 'PWD': '/builds/slave/test', 07:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:44 INFO - 'SHELL': '/bin/bash', 07:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlMR1JiYYx/Listeners', 07:27:44 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 07:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:44 INFO - 'USER': 'cltbld', 07:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:44 INFO - 'XPC_FLAGS': '0x0', 07:27:44 INFO - 'XPC_SERVICE_NAME': '0', 07:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:44 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-3Gyyh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:44 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)) 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:44 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-a3F5qK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:44 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)) 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:44 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-p47Gqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:44 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)) 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:44 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-udwi80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:44 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)) 07:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:44 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-OM9hhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:45 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)) 07:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:45 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-dQkhXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:45 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)) 07:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:45 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-uAW2OP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:45 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)) 07:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:45 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-iAZm9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:45 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)) 07:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:45 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-gifleV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:45 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)) 07:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:45 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-E16iaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:45 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)) 07:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:45 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-NJhzD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:45 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)) 07:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:45 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-cGKxYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:45 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)) 07:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:45 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-7s4TG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:45 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)) 07:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:45 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-HxKX33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:46 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)) 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:46 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-PCwUh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:46 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)) 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:46 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-6nqT6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:46 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-JBZ5SO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:46 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)) 07:27:46 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)) 07:27:46 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)) 07:27:46 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)) 07:27:46 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)) 07:27:46 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)) 07:27:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:27:46 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 07:27:46 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 07:27:46 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 07:27:46 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 07:27:46 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 07:27:46 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 07:27:48 INFO - Downloading blessings-1.6.tar.gz 07:27:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:27:48 INFO - Installing collected packages: blessings 07:27:48 INFO - Running setup.py install for blessings 07:27:49 INFO - Successfully installed blessings 07:27:49 INFO - Cleaning up... 07:27:49 INFO - Return code: 0 07:27:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:27:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:49 INFO - Reading from file tmpfile_stdout 07:27:49 INFO - Current package versions: 07:27:49 INFO - blessings == 1.6 07:27:49 INFO - blobuploader == 1.2.4 07:27:49 INFO - docopt == 0.6.1 07:27:49 INFO - functools32 == 3.2.3-2 07:27:49 INFO - jsonschema == 2.5.1 07:27:49 INFO - manifestparser == 1.1 07:27:49 INFO - mozInstall == 1.12 07:27:49 INFO - mozcrash == 1.0 07:27:49 INFO - mozdebug == 0.1 07:27:49 INFO - mozdevice == 0.48 07:27:49 INFO - mozfile == 1.2 07:27:49 INFO - mozhttpd == 0.7 07:27:49 INFO - mozinfo == 0.9 07:27:49 INFO - mozleak == 0.1 07:27:49 INFO - mozlog == 3.4 07:27:49 INFO - moznetwork == 0.27 07:27:49 INFO - mozprocess == 0.23 07:27:49 INFO - mozprofile == 0.28 07:27:49 INFO - mozrunner == 6.13 07:27:49 INFO - mozscreenshot == 0.1 07:27:49 INFO - mozsystemmonitor == 0.3 07:27:49 INFO - moztest == 0.7 07:27:49 INFO - mozversion == 1.4 07:27:49 INFO - psutil == 3.1.1 07:27:49 INFO - requests == 1.2.3 07:27:49 INFO - wsgiref == 0.1.2 07:27:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27:49 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')]} 07:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:49 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')]} 07:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:50 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 0x10c655490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb68bc870a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c2cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c461b20>, '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.Jr2fA19arr/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.QlMR1JiYYx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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 07:27:50 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 07:27:50 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 07:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jr2fA19arr/Render', 07:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:50 INFO - 'HOME': '/Users/cltbld', 07:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:50 INFO - 'LOGNAME': 'cltbld', 07:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:50 INFO - 'MOZ_NO_REMOTE': '1', 07:27:50 INFO - 'NO_EM_RESTART': '1', 07:27:50 INFO - 'PAGER': '/bin/cat', 07:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:50 INFO - 'PWD': '/builds/slave/test', 07:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:50 INFO - 'SHELL': '/bin/bash', 07:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlMR1JiYYx/Listeners', 07:27:50 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 07:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:50 INFO - 'USER': 'cltbld', 07:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:50 INFO - 'XPC_FLAGS': '0x0', 07:27:50 INFO - 'XPC_SERVICE_NAME': '0', 07:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:50 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)) 07:27:50 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)) 07:27:50 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)) 07:27:50 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)) 07:27:50 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)) 07:27:50 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)) 07:27:50 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)) 07:27:50 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)) 07:27:50 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)) 07:27:50 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)) 07:27:50 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)) 07:27:50 INFO - Cleaning up... 07:27:50 INFO - Return code: 0 07:27:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c655490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb68bc870a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c2cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c461b20>, '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.Jr2fA19arr/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.QlMR1JiYYx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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 07:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jr2fA19arr/Render', 07:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:27:51 INFO - 'HOME': '/Users/cltbld', 07:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:27:51 INFO - 'LOGNAME': 'cltbld', 07:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:51 INFO - 'MOZ_NO_REMOTE': '1', 07:27:51 INFO - 'NO_EM_RESTART': '1', 07:27:51 INFO - 'PAGER': '/bin/cat', 07:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:51 INFO - 'PWD': '/builds/slave/test', 07:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:27:51 INFO - 'SHELL': '/bin/bash', 07:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlMR1JiYYx/Listeners', 07:27:51 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 07:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:27:51 INFO - 'USER': 'cltbld', 07:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:51 INFO - 'XPC_FLAGS': '0x0', 07:27:51 INFO - 'XPC_SERVICE_NAME': '0', 07:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:27:51 INFO - Cleaning up... 07:27:51 INFO - Return code: 0 07:27:51 INFO - Running post-action listener: _resource_record_post_action 07:27:51 INFO - Running post-action listener: _start_resource_monitoring 07:27:51 INFO - Starting resource monitoring. 07:27:51 INFO - [mozharness: 2016-11-14 15:27:51.363200Z] Finished create-virtualenv step (success) 07:27:51 INFO - [mozharness: 2016-11-14 15:27:51.364269Z] Running install step. 07:27:51 INFO - Running pre-action listener: _resource_record_pre_action 07:27:51 INFO - Running main action method: install 07:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:51 INFO - Reading from file tmpfile_stdout 07:27:51 INFO - Detecting whether we're running mozinstall >=1.0... 07:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:27:51 INFO - Reading from file tmpfile_stdout 07:27:51 INFO - Output received: 07:27:51 INFO - Usage: mozinstall [options] installer 07:27:51 INFO - Options: 07:27:51 INFO - -h, --help show this help message and exit 07:27:51 INFO - -d DEST, --destination=DEST 07:27:51 INFO - Directory to install application into. [default: 07:27:51 INFO - "/builds/slave/test"] 07:27:51 INFO - --app=APP Application being installed. [default: firefox] 07:27:51 INFO - mkdir: /builds/slave/test/build/application 07:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:28:23 INFO - Reading from file tmpfile_stdout 07:28:23 INFO - Output received: 07:28:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:28:23 INFO - Running post-action listener: _resource_record_post_action 07:28:23 INFO - [mozharness: 2016-11-14 15:28:23.469367Z] Finished install step (success) 07:28:23 INFO - [mozharness: 2016-11-14 15:28:23.469591Z] Running run-tests step. 07:28:23 INFO - Running pre-action listener: _resource_record_pre_action 07:28:23 INFO - Running main action method: run_tests 07:28:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:28:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:28:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:28:23 INFO - 2016-11-14 07:28:23.484 screenresolution[6709:125203] starting screenresolution argv=screenresolution get 07:28:23 INFO - 2016-11-14 07:28:23.487 screenresolution[6709:125203] Display 0: 1600x1200x32@60 07:28:23 INFO - 2016-11-14 07:28:23.493 screenresolution[6710:125206] starting screenresolution argv=screenresolution list 07:28:23 INFO - Available Modes on Display 0 07:28:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:28:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:28:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:28:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:28:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:28:23 INFO - Intel Iris: 07:28:23 INFO - Chipset Model: Intel Iris 07:28:23 INFO - Type: GPU 07:28:23 INFO - Bus: Built-In 07:28:23 INFO - VRAM (Dynamic, Max): 1536 MB 07:28:23 INFO - Vendor: Intel (0x8086) 07:28:23 INFO - Device ID: 0x0a2e 07:28:23 INFO - Revision ID: 0x0009 07:28:23 INFO - Displays: 07:28:23 INFO - Display: 07:28:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:28:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:28:23 INFO - Display Serial Number: 1600x1200 60 07:28:23 INFO - Main Display: Yes 07:28:23 INFO - Mirror: Off 07:28:23 INFO - Online: Yes 07:28:23 INFO - Rotation: Supported 07:28:23 INFO - Return code: 0 07:28:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:28:23 INFO - Python 2.7.3 07:28:23 INFO - Return code: 0 07:28:23 INFO - grabbing minidump binary from tooltool 07:28:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:28:23 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 0x10c469d30>, '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 0x10c2cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c461b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:28:23 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 07:28:23 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 07:28:23 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 07:28:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:28:23 INFO - Return code: 0 07:28:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:28:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:28:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:28:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:28:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:28:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0279', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0279 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:28:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jr2fA19arr/Render', 07:28:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:28:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:28:23 INFO - 'HOME': '/Users/cltbld', 07:28:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:28:23 INFO - 'LOGNAME': 'cltbld', 07:28:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:28:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:28:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:28:23 INFO - 'MOZ_NO_REMOTE': '1', 07:28:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:28:23 INFO - 'NO_EM_RESTART': '1', 07:28:23 INFO - 'PAGER': '/bin/cat', 07:28:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:28:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:28:23 INFO - 'PWD': '/builds/slave/test', 07:28:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:28:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:28:23 INFO - 'SHELL': '/bin/bash', 07:28:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlMR1JiYYx/Listeners', 07:28:23 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 07:28:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:28:23 INFO - 'USER': 'cltbld', 07:28:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:28:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:28:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:28:23 INFO - 'XPC_FLAGS': '0x0', 07:28:23 INFO - 'XPC_SERVICE_NAME': '0', 07:28:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:28:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0279', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:28:24 INFO - mozversion application_buildid: 20161114043455 07:28:24 INFO - mozversion application_changeset: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f 07:28:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:28:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:28:24 INFO - mozversion application_name: Firefox 07:28:24 INFO - mozversion application_remotingname: firefox-dev 07:28:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:28:24 INFO - mozversion application_vendor: Mozilla 07:28:24 INFO - mozversion application_version: 52.0a2 07:28:24 INFO - mozversion platform_buildid: 20161114043455 07:28:24 INFO - mozversion platform_changeset: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f 07:28:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:28:24 INFO - mozversion platform_version: 52.0a2 07:28:24 INFO - using testdate: 1479137304 07:28:24 INFO - actual date: 1479137304 07:28:24 INFO - starting webserver on 'localhost:61829' 07:28:24 INFO - SUITE-START | Running 2 tests 07:28:24 INFO - TEST-START | dromaeo_css 07:28:24 INFO - operating with platform_type : mac_ 07:28:24 INFO - Initialising browser for dromaeo_css test... 07:28:24 INFO - TEST-INFO | started process 6720 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmp2T4HLU/profile http://localhost:61829/getInfo.html) 07:28:25 ERROR - PROCESS | 6720 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 07:28:27 INFO - PROCESS | 6720 | __metrics Screen width/height:1600/1200 07:28:27 INFO - PROCESS | 6720 | colorDepth:24 07:28:27 INFO - PROCESS | 6720 | Browser inner width/height: 1024/689 07:28:27 INFO - PROCESS | 6720 | __metrics 07:28:27 ERROR - PROCESS | 6720 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 07:28:27 INFO - TEST-INFO | 6720: exit 0 07:28:27 INFO - Browser initialized. 07:28:27 INFO - Running cycle 1/1 for dromaeo_css test... 07:28:27 INFO - TEST-INFO | started process 6724 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmp2T4HLU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:28:39 INFO - PROCESS | 6724 | RSS: Main: 224407552 07:28:39 INFO - PROCESS | 6724 | 07:29:29 INFO - PROCESS | 6724 | 1479137369579 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:31:44 INFO - PROCESS | 6724 | Cycle 1(1): loaded http://localhost:61829/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61829/tests/dromaeo/cssquery-ext.html) 07:31:45 INFO - PROCESS | 6724 | RSS: Main: 235270144 07:31:45 INFO - PROCESS | 6724 | 07:34:48 INFO - PROCESS | 6724 | Cycle 1(1): loaded http://localhost:61829/tests/dromaeo/cssquery-ext.html (next: http://localhost:61829/tests/dromaeo/cssquery-jquery.html) 07:34:48 INFO - PROCESS | 6724 | RSS: Main: 247955456 07:34:48 INFO - PROCESS | 6724 | 07:37:50 INFO - PROCESS | 6724 | Cycle 1(1): loaded http://localhost:61829/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61829/tests/dromaeo/cssquery-mootools.html) 07:37:51 INFO - PROCESS | 6724 | RSS: Main: 252633088 07:37:51 INFO - PROCESS | 6724 | 07:40:53 INFO - PROCESS | 6724 | Cycle 1(1): loaded http://localhost:61829/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61829/tests/dromaeo/cssquery-prototype.html) 07:40:54 INFO - PROCESS | 6724 | RSS: Main: 251867136 07:40:54 INFO - PROCESS | 6724 | 07:43:57 INFO - PROCESS | 6724 | Cycle 1(1): loaded http://localhost:61829/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61829/tests/dromaeo/cssquery-yui.html) 07:43:57 INFO - PROCESS | 6724 | RSS: Main: 255303680 07:43:57 INFO - PROCESS | 6724 | 07:47:00 INFO - PROCESS | 6724 | Cycle 1(1): loaded http://localhost:61829/tests/dromaeo/cssquery-yui.html (next: http://localhost:61829/tests/dromaeo/cssquery-dojo.html) 07:47:00 INFO - PROCESS | 6724 | RSS: Main: 256577536 07:47:00 INFO - PROCESS | 6724 | 07:47:00 INFO - PROCESS | 6724 | __start_tp_report 07:47:00 INFO - PROCESS | 6724 | _x_x_mozilla_page_load 07:47:00 INFO - PROCESS | 6724 | _x_x_mozilla_page_load_details 07:47:00 INFO - PROCESS | 6724 | |i|pagename|runs| 07:47:00 INFO - PROCESS | 6724 | |0;dojo.html;3346.665333466653;3359.5800524934384;3359.8824041158564;3371.629120796712;3385.9153521161975;4782;5720.742566584503;5790.478856902878;5827.096799996008;5828.213191219179;5046.243063540471;5117.797317630363;5647.463490968362;5828.271466066742;5896.852578685535;5298.973505132479;5325.360956685203;5341.4658534146665;5341.65279256847;5385.623006389555;5405.108157154084;5407.107827208506;5416.864578385533;5441.891162176754;5451.236826844245;5219.71291578963;5239;5382.327209098863;5382.91925621116;5386.084365657828;4593.6095431888;5092.930484598221;5312.73436328184;5317.308749862493;5343.7328133593555;5142.562882155022;5260.763265653017;5279.260903473517;5279.60402969775;5310.309659744208;5752.568557358215;5772.191893134965;5890;5942;5959.910601340984;6097.664628445478;6223.705643106242;6229.4704950079295;6265.185525881606;6297.2128483939505;6168.013117901114;6277.811665650038;6300.873982520324;6312.337204593543;6316.494680425601;5788.247527821399;5882.264716910387;5885.823425297248;5897.8230653080045;5903.852403689899;6244.875102497925;6253.874922501524;6269.811905642838;6273.498120150377;6288.842778930563;6154.415330543591;6204.968975155095;6207.565470417027;6223.502119830403;6235.781747638811;6249.25008998923;6264.5301602379095;6280.7487700491465;6281.434670879551;6309.526785491014;6125.937554509019;6134.436587033474;6258.468030217391;6258.561900666909;6281.026440901667;6268.404501572343;6283.7800676977;6305.621662700252;6323.652199129092;6334.113224148623;4398.73607583546;4861.975690121527;4882.584980276716;4955.058538877602;4978.376547023497;4405.405270288534;4425.513193548707;4427.645788336926;4457.24226881431;4507.4365704286965;5995.250593675791;6026.9095963560585;6030.427109424598;6051.606645568024;6078.513718902477;6166.136740856284;6306.306306306303;6314.810555683245;6322.652254125976;6428.5178611604315;5872.17789509469;5887.823365298962;5922.318933322636;5943.64338139713;5953.017752070947;5420.430854760272;5430.945690543044;5466.863328416822;5486.890262194814;5533.22534845122;6222.346653601307;6417.614943103429;6436.60256026207;6457.838554036092;6459.257185423548;6179.536534759821;6213.1922850029205;6258.030005349178;6264.68676566179;6306.621602703853;6220.564560480723;6287.559870808999;6472.644004579794;6504.056911747864;6508.381703738138;5069.670471419346;6121.696863452088;6154.569180157346;6223.3465486123305;6223.564350495421;6271.717772700331;6282.120503129474;6291.937080629135;6297.370262973666;6308;6171.197744293213;6179.876402472015;6194.163787888758;6248.687565621792;6257.468115210076;6010.398960104129;6084.726187321669;6112.266528016667;6146.9550176471;6179.783707570214;5006.7246301452315;5037.546620804145;5523.1991361253195;5766.077427611562;6104.17593624855;6124;6145.354737752472;6168.475679567287;6192.133101365903;6209.96895015522;5000;5703.657780533182;6062.454379105901;6074.544409169418;6109.541784366279 07:47:00 INFO - PROCESS | 6724 | |1;ext.html;449.4157595126336;512.0731476028388;516.5428595692813;552.1303946284603;581.6016029020121;37996.24007519848;39133.60866391335;40289.798551007276;40337.59662403379;40393;40927.59072409275;41866;41893;41897.58102418982;41988.79005604968;8499.490030598154;8701.47791132531;9210.723678289645;11173.329600224013;11497.770044599103;11392;11475.942620286887;11476.426178691074;11492.827607585892;11532.250403723772;11474.311541307548;11530.135239857002;11532.423378831025;11532.884671153264;11541.134414918874;11396.14528910335;11454.885451145548;11467.197296189228;11473.885261147449;11484.71288217793;11335.979761821392;11457;11458.885411145866;11462.770744585145;11481.483333250024;38954;39364.212715745525;39371.60628393737;39379.60620393788;39612;3299.967000330014;3345.1135449106005;3358.546596209505;3383.7631365804405;3397.677220664033;25089.372765681004;28611.713882861113;29234.853825730734;29467.557986630218;29603.851980739957;3179.77741558091;3243.8053716777067;3251.3822373748912;3254.4304746669236;3295.340931813647;507235;510230;514864.42567787296;514919;515092;62194.68902655548;62511.6874415625;62542.374576254566;62960;64388;50499.747501262995;50986.725331867;58858.70570647119;61489.69255153696;63720.68139659272;12969.54606588765;13460.865391346157;14528.418863245563;16139.789515786362;16181;11206.831897521544;12082.033437324993;12439.813402798965;12576.937115314366;12816.80774788379;15344.309506072253;19097.2361105557;19160;19175.904120479307;19248.23007079729;7777.455578109478;7881.960590197013;7888.368930485547;7943.180171039314;7943.404244681672;862.2584577263548;956.1967946924477;1005.8440941654055;1019.2814066083394;1037.7301901505512;29815.39568057822;29887.103386898427;30002;30118.630708292054;30412.391752165277;988.2094324540338;1025.5077562769911;1086.782643471293;1117.7708569743183;1163;14997.325120369609;15075.437175680161;15613;15632.2183890805;15645.765313520305;187237;188253;188460;188962.05518972318;189264;57132.42867571354;57178;57299.71350143223;57798;57801.132983005285;1171.244547267008;1305.112523484021;1347.4340776873737;1352.5671785028696;1352.7226918481688;24097;29174.68713907922;29832.403351932404;30016.549751753304;30526.389472209987;50380.24429633632;50905.490945090074;50948.490515094374;50968.74515627398;51008.234876477625;583560;592259;592481;596350;598147;26470.80881360382;26685.066022689112;27219.727802721718;27255.454890902467;28107.718922811328;46197.76901115473;46475.07049859052;46618.300725489826;46866.53133468622;47311.52688473071;37361.626383736904;38090.61909380871;39661.80169099251;41081.4188787051;41570;38267.6173238264;38634.806825965694;39371;39454.60545394624;39748.2050358997;37082.55649190777;37130.88607341784;37893;37940.62059379481;38302.42546361751;33340.499892501146;34617.82691086528;34697.121151539584;34940.47589286214;34989.95030149099;18994;24490.63264051005;25707.10025149111;25979.22062338133;26495.60256596114 07:47:00 INFO - PROCESS | 6724 | |2;jquery.html;333.6146750503169;377.9414190800425;380.51484357444264;391.8001819072272;401.78460158022;17277.827221727795;19066;20014.399568012985;20680;20842.89578552109;19661.21355145796;19878.906660133685;20306.086226119824;20433.693494597555;20728.48178795531;5769.826905192851;5854.88290234195;6031.065184896348;6049.637021778685;6105.145279660851;6062.96968515159;6111.816645500619;6126.908096378558;6134.693265336738;6150.569460137792;3843.2121415109973;3965.8017099145072;6007.519398448114;6111.938880611181;6160.753569857223;5126.333576635032;5936.168936348915;6043.002904520748;6123.724432400552;6141.17094192285;5824.514748739044;5844.824655260349;5894.8821023579285;5895.675737834417;5912.852178695524;13221.669458263525;13294.667633309246;13299.335033248395;13496.5276215332;13527.661808454768;2158.190678495564;2180.269609680759;2264.8562475949707;2429.465517586128;2766.7233276672173;9323.601459781019;10069.345492542961;10531.210159238088;10534.736631584195;11545;1680.269082948921;1872.4008317338457;1915.6934890417606;1920.8271255587065;1948.1428171604591;124051;124131;127982;128983.35508322399;131083.68916310713;9440.339176257596;10101.292909496411;10186.08325250731;10296.794064118676;10309.793804123876;12467.376631168405;12589.118761686777;12636.494540218288;12695.55565555201;12711.427985740664;7941.682332706742;8018.639161237758;8069.233422824822;8160.83678326442;8170.959145204355;8286.254237118688;8307.875381869277;8327.583620818934;8389.958050209832;8458.87311690325;3842.7126912484073;3967.861124860674;3969.7816620085596;4021.718479706451;4041.777702226347;1868.7852895617957;1921.7693876734882;1932.8260456558978;1947.7175809507542;1958.2460752610348;346.7832604622111;406.5507614086451;414.2646801926604;415.89394704350195;417.92477354076556;11508.539658413738;11814.704632384088;11878.346690931909;11945.522179112739;11955;425.5659227587843;433.9631131353807;439.75593545582507;443.09829496974146;447.0790172245185;5942.70286485674;6111.8472038198515;6130.988386916198;6276.497880169667;6291.905621415683;469658;523926;599082;629648;631649;99971.00028999762;122170.55658886772;133826;138863.6113638871;144257;431.29913889928866;449.0300949948162;449.8088312467254;450.7160488891977;450.8850243188032;11021.013252676545;11397.601083962021;11542;11646.010089142517;11646.825297620373;53051.73474132604;53124.46875531195;53393.932121358135;55496;56416;62005.06992395027;77944.22055779597;79209.60395197987;79702;80219;6891;7383.224761400191;7434.219406962407;7459.738909138154;7827.178146294557;10275.7431064224;12366.62900112998;12376.690582735144;12394.256344619353;12661.303628300531;9250;9258.35191536586;9586.233101352014;10003.699889003341;10568;8677.739667809976;9104.590293436944;9718.319717619699;9769.120779129627;9877.456739879375;8164.346852251924;9807.950960245153;10074.345167564084;10090;10322.535485903303;6964.408025317702;7359.080114985627;7424.552212318762;7720.8455830884195;7851.489653172525;9138.223251023737;9329.16037556623;9907.702768916944;9917.950410247902;10096.34373765703 07:47:00 INFO - PROCESS | 6724 | |3;mootools.html;1474.7419201639711;1474.7861560073788;1683.150009245331;1782.1089455272363;1807.240958797304;7623.58070306133;7893.407994400414;8231.58842057897;8638.697645582399;8658.480491170534;8091.231333023368;8377.120402357756;8418.873716894252;8520.275776558985;8542.829143417128;2344.566255242781;2350.800181984525;2361.551305252005;2366.25462979161;2372.6678265042906;2359.8820058997067;2386.4033991502124;2395.6167013277877;2397.2328854766483;2407.987960060206;2342.9414264643433;2347.424880904181;2348.2720356689397;2370.442945907719;2372.95254094918;2314.36355002374;2315.8378913476063;2337.742848286687;2341.789238968501;2348.342464110052;2000.829929455998;2310.145246258895;2378.690770199863;2382.737898831127;2386.618141097416;8405.831883362298;8515.403921725481;8528.658853645848;8595.95702021492;8596.441231319945;5101.8724531887;5113.335266415379;5252.65857719247;5262.236975638511;5266.947330526722;2807.663080430341;3300.7029367356813;3404.42124838778;3519.718422526217;3540.398132317512;1023.698009087321;1027.563285603623;1089.4226060188114;1100.3397961223202;1114.2979922648678;11038.227324087316;11075.16936229778;11107.389093599848;11157.163212758995;11160.832587511275;4526.660500462479;4627.676062675648;4694.178518759203;4696.553827386393;4791.47293797682;9571.33000689945;9654.75863103428;9656.22750179984;9665.275104367201;9666.613335466644;5275;5282.920756188661;5284.762185701576;5290.788368465295;5303.920441193385;4326.069894972596;4345.456817897762;4791.952080479151;4864.416270047617;4868.683535570177;3814.923701525954;3843.0968722350226;3868.6131386861653;3934.252492026506;3942.8225729842225;1265.7152140768437;1267.3219827392302;1270.6505710929384;1279.9232046077266;1282.967211394836;770.445279398832;774.5159275452842;880.4761167105562;910.6220918318955;934.0472717827778;5623.212750214973;5712.257406537206;5845.561582881301;5864.091065884794;5870.236869207061;912.1790388650268;939.8684184214216;940.4874343482818;965.3194497878978;983.5328219095873;3760.4547340636;3885.611438856148;3894.92889455402;3921.588233235469;3928.3714605663527;9937.055979681918;10386.42874641902;10455.738606534745;10458.895411045793;10472.842907356397;8067.515949043076;8100.8379832403025;9410.717678469657;10409.375437473624;10443.738906527398;7364.852702945911;7965.442419030613;8031.477953932859;8101.554414507141;8146;5680.715964201856;5729;5910.852228694317;5928.703564821828;5969.164316995711;9223.44659320443;9502.714918552178;9542.141207291377;9667.516624168904;9786.266030047633;9525.666601668909;9603.991580884185;9612.1829644481;9741.123298903132;9888.357256778287;6586.550958789058;6626.536142470173;7109.680064397011;7167.104111986002;7292.34368906801;8002.919605853368;8076.878846817185;8113.107558168715;8113.18868113184;8113.6754529818145;7059.541129826544;7091.539049961736;7271.41828653717;7276.854462910606;7340.596267205355;6927.757528486681;7093.893591596234;7104.751333703295;7128.85742285141;7238.6380680966795;6730.057791909038;6895.207051189177;6897.655117244218;6902.413294869993;6928.757493487704;4502.369668246513;4514.7291162530355;4592.862214133581;4644.58198762113;4894.14352488306;4878.365812444359;4915.21356582945;4951.282064100757;5089.3638295213095;5130.076586214517 07:47:00 INFO - PROCESS | 6724 | |4;prototype.html;261.9751123643256;287.054156554154;297.48683520926403;312.8513955870961;316.4809712072202;5202.271681964528;5254.947450525494;5419.8374048778505;5519.586031047667;5539.695316757577;4700.341952126696;5251.684898906068;5356.892862142755;5374.876923615169;5401.216823560591;3838.8848334550007;3854.3447613905564;3907.609239076098;4191.559886211942;4260;3838.6776000519703;4060.7563546187325;4236.385724070008;4238.025254191522;4273.786310684469;3755.906102347436;4066.369712694522;4076.8369265229503;4130.070734084837;4135.8759237222785;3446.1556918555116;4096.815643296059;4122.536499542664;4131.648809851151;4159.313713237312;3567.9821600892087;3573.10672331917;3583.6953858922025;3588.659077387644;3596.982015089934;4515.5484451554585;4669.7665116744365;5079.111155547801;5241.95160967808;5299.629025968184;4671.808688784372;4681.705964077578;4710.199266124767;4733.881652958669;4735.526447355272;4697.78859951303;4962.106820772259;5697.097488220582;5735;5758.3665796762325;3598.298331825295;3623.710103191738;3817.6945844332386;3886.9222615547815;3926.332523471017;5240.580753539708;5331.44019877915;6016.2479690038745;6044.69776511177;6076.513878889678;3225.919317028775;3485.755997080231;3737.831797569116;3819.560750513671;3972.682185425159;3356.1945133167865;3391.6269210386836;3764.698824094066;3791.7345785795283;4072.857449989236;3776.339140650375;4055.8174882129783;4517.457905051415;4648.697834640738;4652.697574657636;5798.6520808750765;5809.941900581025;5817.080363480041;5863.589548731633;5868.647881127146;1226.2213494430923;1255.4162314523608;1269.6191142657167;1287.4270949427603;1298.7272672738832;851.6933903794629;882.4308321132834;883.1345281624044;884.6992022712309;890.5458216309729;4437.489688685842;4529.977350113229;5072.365954255718;5146.356705411824;5190.714510701948;4452.612339069698;4737.692050016738;4868.440129385166;4875.269279405831;5780.335261444988;4625.710273414614;5119.6160287978555;5119.667221630582;5133.383993920754;5201.1678131498775;2594.5848664213636;2909.2145120817263;2927.3852490977133;2933.3839893622153;2943.367176057115;5632.436756324335;5729.455701708331;5821.53427725781;5866.50134738552;5950.523958083343;4951.950480495149;5030.8239211627415;5244.423113457517;5265.867838414759;5799.8550036249435;4567.588916997486;4865.759231395971;5236.633435659467;5506.697131657797;5802.564807639359;5034.597232221487;5163.612729045261;5273;5653.2085508028085;5679.37526872052;4450.954025803998;4600.953990460052;4720.409948756405;5024.001118886731;5399.406065332889;4705.976470117627;5412.512873841373;5503.559715222853;5751.194832723339;5756.165356023437;3463.94804077934;3490.965090349064;3594.7843129412317;3676.963230367662;3789.6020917804335;4694.201985662377;4758.714477131383;5029.59763218949;5047.899042019212;5612.85967850807;5048.015636950906;5385.219143224289;5820.30156381237;5842.853928651818;5845.298564172156;5136.8202112925865;5776.393478684678;5781.971090144522;5785.8553536161935;5814.912776308274;4586.738646872003;4675.438947326206;4889.457482109355;5247.02929957959;5384.246205531307;5754.079347304411;5760.740766665426;5778.248827652378;5784.334801497714;5785.4214578541805;5593.440655934537;5608.719564021864;5633.577481688972;5648.418442836012;5676.914846277226 07:47:00 INFO - PROCESS | 6724 | |5;yui.html;604.7641419846261;731.8024133483959;749.1983577571997;754.8334735783391;794.5669610062517;14008.299585020746;14812.703745925075;14836.258187090629;14836.55490335289;14876.330565124568;13444.3950022249;13466.5286714965;13506.986975976792;13512.189268643888;13531.391087401065;560.2073066610761;622.5455417545195;643.5077165968038;685.6880119545594;830.0330115415534;819.2831272636444;823.9382046346518;830.3606223208126;832.488019867783;837.3426859914968;852.5396286005549;855.5508358111979;882.788130848595;884.8849649545582;885.7519894429538;703.9753608623673;864.7405778266526;876.9868451973193;881.4270724029368;882.6645874567623;650.7722297195991;651.5178767711907;654.0222367560527;654.4175683641563;655.5312951239839;13561.779439850363;13745.587632371045;13845.723085538233;14098.506552270725;14195.503157389545;10720.410377429238;13620.455181792717;13834;14002.369893354722;14025.78961315581;10875.45622718861;10902.127829773599;11314;11372.943135284353;12061.276323420623;3688.5389326334207;3754.155315054118;3795.905102372435;3821.388577827562;3848.057225979625;93845;98349;99530.0046999528;100128.49935750275;102196.97803021948;10654.573817047387;10872.293300935415;10926.836097458545;10933.343999360064;10956.506957186946;21419.678704819442;21425.571488570455;21440.892795536234;21478.033488492743;21671.56656866854;7456.4780465367985;7567.545947243073;7591.127020392725;7756.728514501965;7768.339691126204;4194.769287689206;4221.134667393177;4269.594388533084;4297.935530967038;4326.372675961968;3383.9492407613907;3499.5975462821593;3505.106197919565;3512.3150985557572;3656.9760467069264;656.461701404853;670.510527315053;682.1677553384862;695.5965539986896;698.1587187439123;582.2314544800906;633.6704913445075;642.5437939063224;650.8568115014689;658.4074333100248;8555.443896146731;9035.954820225857;9122.179003889682;9336.533173341306;9395.258058412952;605.2706488681129;606.1483615520201;636.8662580857979;649.302000349626;663.8805015097232;3624.619414961391;4362.411074504965;4377.898608667857;4585.358049873021;4611.76941152941;116923;120175;120242;122351;125319;60427.69786151041;61563.692181538805;80331.99170020796;83826.58086709527;105256;81509.59245203855;84977.99999999876;86127;87439.1256087431;90700;9449.43303401798;9570.56932438028;9820.017998200408;9826.950865245628;9873.555689994111;53383.73308133434;53546.464535355706;54427;55051.44948550463;55728.72135639458;72572.63713681398;74498;76467.61766191134;77741;78088;10018.799624007333;10036.849447258443;10455.491168744778;12062.51749929993;12280.078994075302;13577.52478663242;14187.219702916438;14367.92816035955;14414.279286035446;14416.927915360357;12240.449179786752;13339.733205336033;13363.26502042397;13824;13879.583612491642;13141.605751827461;13160.736785264433;13311;13450.99117566167;13754.724905502035;10414.739631509274;12785.424655890321;12945.223286602833;13115.737685246433;13561.050726449053;10767.3539587625;11082.61210857616;11328.830067549157;11410.657680269605;11551.42242887869;9143.445614245462;10871.565137394417;10944.069754070993;11276.3797991108;11343.659690209306 07:47:00 INFO - PROCESS | 6724 | __end_tp_report 07:47:00 INFO - PROCESS | 6724 | __start_cc_report 07:47:00 INFO - PROCESS | 6724 | _x_x_mozilla_cycle_collect,380 07:47:00 INFO - PROCESS | 6724 | __end_cc_report 07:47:00 INFO - PROCESS | 6724 | __startTimestamp1479138420520__endTimestamp 07:47:00 INFO - PROCESS | 6724 | 07:47:00 INFO - PROCESS | 6724 | ------- Summary: start ------- 07:47:00 INFO - PROCESS | 6724 | Number of tests: 6 07:47:00 INFO - PROCESS | 6724 | 07:47:00 INFO - PROCESS | 6724 | [#0] dojo.html Cycles:165 Average:5771.83 Median:6062.45 stddev:671.15 (11.1%) stddev-sans-first:645.68 07:47:00 INFO - PROCESS | 6724 | Values: 3346.7 3359.6 3359.9 3371.6 3385.9 4782.0 5720.7 5790.5 5827.1 5828.2 5046.2 5117.8 5647.5 5828.3 5896.9 5299.0 5325.4 5341.5 5341.7 5385.6 5405.1 5407.1 5416.9 5441.9 5451.2 5219.7 5239.0 5382.3 5382.9 5386.1 4593.6 5092.9 5312.7 5317.3 5343.7 5142.6 5260.8 5279.3 5279.6 5310.3 5752.6 5772.2 5890.0 5942.0 5959.9 6097.7 6223.7 6229.5 6265.2 6297.2 6168.0 6277.8 6300.9 6312.3 6316.5 5788.2 5882.3 5885.8 5897.8 5903.9 6244.9 6253.9 6269.8 6273.5 6288.8 6154.4 6205.0 6207.6 6223.5 6235.8 6249.3 6264.5 6280.7 6281.4 6309.5 6125.9 6134.4 6258.5 6258.6 6281.0 6268.4 6283.8 6305.6 6323.7 6334.1 4398.7 4862.0 4882.6 4955.1 4978.4 4405.4 4425.5 4427.6 4457.2 4507.4 5995.3 6026.9 6030.4 6051.6 6078.5 6166.1 6306.3 6314.8 6322.7 6428.5 5872.2 5887.8 5922.3 5943.6 5953.0 5420.4 5430.9 5466.9 5486.9 5533.2 6222.3 6417.6 6436.6 6457.8 6459.3 6179.5 6213.2 6258.0 6264.7 6306.6 6220.6 6287.6 6472.6 6504.1 6508.4 5069.7 6121.7 6154.6 6223.3 6223.6 6271.7 6282.1 6291.9 6297.4 6308.0 6171.2 6179.9 6194.2 6248.7 6257.5 6010.4 6084.7 6112.3 6147.0 6179.8 5006.7 5037.5 5523.2 5766.1 6104.2 6124.0 6145.4 6168.5 6192.1 6210.0 5000.0 5703.7 6062.5 6074.5 6109.5 07:47:00 INFO - PROCESS | 6724 | 07:47:00 INFO - PROCESS | 6724 | [#1] ext.html Cycles:180 Average:58705.60 Median:18342.81 stddev:124694.54 (679.8%) stddev-sans-first:124967.63 07:47:00 INFO - PROCESS | 6724 | Values: 449.4 512.1 516.5 552.1 581.6 37996.2 39133.6 40289.8 40337.6 40393.0 40927.6 41866.0 41893.0 41897.6 41988.8 8499.5 8701.5 9210.7 11173.3 11497.8 11392.0 11475.9 11476.4 11492.8 11532.3 11474.3 11530.1 11532.4 11532.9 11541.1 11396.1 11454.9 11467.2 11473.9 11484.7 11336.0 11457.0 11458.9 11462.8 11481.5 38954.0 39364.2 39371.6 39379.6 39612.0 3300.0 3345.1 3358.5 3383.8 3397.7 25089.4 28611.7 29234.9 29467.6 29603.9 3179.8 3243.8 3251.4 3254.4 3295.3 507235.0 510230.0 514864.4 514919.0 515092.0 62194.7 62511.7 62542.4 62960.0 64388.0 50499.7 50986.7 58858.7 61489.7 63720.7 12969.5 13460.9 14528.4 16139.8 16181.0 11206.8 12082.0 12439.8 12576.9 12816.8 15344.3 19097.2 19160.0 19175.9 19248.2 7777.5 7882.0 7888.4 7943.2 7943.4 862.3 956.2 1005.8 1019.3 1037.7 29815.4 29887.1 30002.0 30118.6 30412.4 988.2 1025.5 1086.8 1117.8 1163.0 14997.3 15075.4 15613.0 15632.2 15645.8 187237.0 188253.0 188460.0 188962.1 189264.0 57132.4 57178.0 57299.7 57798.0 57801.1 1171.2 1305.1 1347.4 1352.6 1352.7 24097.0 29174.7 29832.4 30016.5 30526.4 50380.2 50905.5 50948.5 50968.7 51008.2 583560.0 592259.0 592481.0 596350.0 598147.0 26470.8 26685.1 27219.7 27255.5 28107.7 46197.8 46475.1 46618.3 46866.5 47311.5 37361.6 38090.6 39661.8 41081.4 41570.0 38267.6 38634.8 39371.0 39454.6 39748.2 37082.6 37130.9 37893.0 37940.6 38302.4 33340.5 34617.8 34697.1 34940.5 34990.0 18994.0 24490.6 25707.1 25979.2 26495.6 07:47:00 INFO - PROCESS | 6724 | 07:47:00 INFO - PROCESS | 6724 | [#2] jquery.html Cycles:180 Average:33174.74 Median:428.43 stddev:96648.53 (22558.6%) stddev-sans-first:96888.19 07:47:00 INFO - PROCESS | 6724 | Values: 333.6 377.9 380.5 391.8 401.8 17277.8 19066.0 20014.4 20680.0 20842.9 19661.2 19878.9 20306.1 20433.7 20728.5 5769.8 5854.9 6031.1 6049.6 6105.1 6063.0 6111.8 6126.9 6134.7 6150.6 3843.2 3965.8 6007.5 6111.9 6160.8 5126.3 5936.2 6043.0 6123.7 6141.2 5824.5 5844.8 5894.9 5895.7 5912.9 13221.7 13294.7 13299.3 13496.5 13527.7 2158.2 2180.3 2264.9 2429.5 2766.7 9323.6 10069.3 10531.2 10534.7 11545.0 1680.3 1872.4 1915.7 1920.8 1948.1 124051.0 124131.0 127982.0 128983.4 131083.7 9440.3 10101.3 10186.1 10296.8 10309.8 12467.4 12589.1 12636.5 12695.6 12711.4 7941.7 8018.6 8069.2 8160.8 8171.0 8286.3 8307.9 8327.6 8390.0 8458.9 3842.7 3967.9 3969.8 4021.7 4041.8 1868.8 1921.8 1932.8 1947.7 1958.2 346.8 406.6 414.3 415.9 417.9 11508.5 11814.7 11878.3 11945.5 11955.0 425.6 434.0 439.8 443.1 447.1 5942.7 6111.8 6131.0 6276.5 6291.9 469658.0 523926.0 599082.0 629648.0 631649.0 99971.0 122170.6 133826.0 138863.6 144257.0 431.3 449.0 449.8 450.7 450.9 11021.0 11397.6 11542.0 11646.0 11646.8 53051.7 53124.5 53393.9 55496.0 56416.0 62005.1 77944.2 79209.6 79702.0 80219.0 6891.0 7383.2 7434.2 7459.7 7827.2 10275.7 12366.6 12376.7 12394.3 12661.3 9250.0 9258.4 9586.2 10003.7 10568.0 8677.7 9104.6 9718.3 9769.1 9877.5 8164.3 9808.0 10074.3 10090.0 10322.5 6964.4 7359.1 7424.6 7720.8 7851.5 9138.2 9329.2 9907.7 9918.0 10096.3 07:47:00 INFO - PROCESS | 6724 | 07:47:00 INFO - PROCESS | 6724 | [#3] mootools.html Cycles:180 Average:5513.93 Median:5270.97 stddev:3018.15 (57.3%) stddev-sans-first:3011.35 07:47:00 INFO - PROCESS | 6724 | Values: 1474.7 1474.8 1683.2 1782.1 1807.2 7623.6 7893.4 8231.6 8638.7 8658.5 8091.2 8377.1 8418.9 8520.3 8542.8 2344.6 2350.8 2361.6 2366.3 2372.7 2359.9 2386.4 2395.6 2397.2 2408.0 2342.9 2347.4 2348.3 2370.4 2373.0 2314.4 2315.8 2337.7 2341.8 2348.3 2000.8 2310.1 2378.7 2382.7 2386.6 8405.8 8515.4 8528.7 8596.0 8596.4 5101.9 5113.3 5252.7 5262.2 5266.9 2807.7 3300.7 3404.4 3519.7 3540.4 1023.7 1027.6 1089.4 1100.3 1114.3 11038.2 11075.2 11107.4 11157.2 11160.8 4526.7 4627.7 4694.2 4696.6 4791.5 9571.3 9654.8 9656.2 9665.3 9666.6 5275.0 5282.9 5284.8 5290.8 5303.9 4326.1 4345.5 4792.0 4864.4 4868.7 3814.9 3843.1 3868.6 3934.3 3942.8 1265.7 1267.3 1270.7 1279.9 1283.0 770.4 774.5 880.5 910.6 934.0 5623.2 5712.3 5845.6 5864.1 5870.2 912.2 939.9 940.5 965.3 983.5 3760.5 3885.6 3894.9 3921.6 3928.4 9937.1 10386.4 10455.7 10458.9 10472.8 8067.5 8100.8 9410.7 10409.4 10443.7 7364.9 7965.4 8031.5 8101.6 8146.0 5680.7 5729.0 5910.9 5928.7 5969.2 9223.4 9502.7 9542.1 9667.5 9786.3 9525.7 9604.0 9612.2 9741.1 9888.4 6586.6 6626.5 7109.7 7167.1 7292.3 8002.9 8076.9 8113.1 8113.2 8113.7 7059.5 7091.5 7271.4 7276.9 7340.6 6927.8 7093.9 7104.8 7128.9 7238.6 6730.1 6895.2 6897.7 6902.4 6928.8 4502.4 4514.7 4592.9 4644.6 4894.1 4878.4 4915.2 4951.3 5089.4 5130.1 07:47:00 INFO - PROCESS | 6724 | 07:47:00 INFO - PROCESS | 6724 | [#4] prototype.html Cycles:180 Average:4463.02 Median:4882.36 stddev:1371.42 (28.1%) stddev-sans-first:1338.53 07:47:00 INFO - PROCESS | 6724 | Values: 262.0 287.1 297.5 312.9 316.5 5202.3 5254.9 5419.8 5519.6 5539.7 4700.3 5251.7 5356.9 5374.9 5401.2 3838.9 3854.3 3907.6 4191.6 4260.0 3838.7 4060.8 4236.4 4238.0 4273.8 3755.9 4066.4 4076.8 4130.1 4135.9 3446.2 4096.8 4122.5 4131.6 4159.3 3568.0 3573.1 3583.7 3588.7 3597.0 4515.5 4669.8 5079.1 5242.0 5299.6 4671.8 4681.7 4710.2 4733.9 4735.5 4697.8 4962.1 5697.1 5735.0 5758.4 3598.3 3623.7 3817.7 3886.9 3926.3 5240.6 5331.4 6016.2 6044.7 6076.5 3225.9 3485.8 3737.8 3819.6 3972.7 3356.2 3391.6 3764.7 3791.7 4072.9 3776.3 4055.8 4517.5 4648.7 4652.7 5798.7 5809.9 5817.1 5863.6 5868.6 1226.2 1255.4 1269.6 1287.4 1298.7 851.7 882.4 883.1 884.7 890.5 4437.5 4530.0 5072.4 5146.4 5190.7 4452.6 4737.7 4868.4 4875.3 5780.3 4625.7 5119.6 5119.7 5133.4 5201.2 2594.6 2909.2 2927.4 2933.4 2943.4 5632.4 5729.5 5821.5 5866.5 5950.5 4952.0 5030.8 5244.4 5265.9 5799.9 4567.6 4865.8 5236.6 5506.7 5802.6 5034.6 5163.6 5273.0 5653.2 5679.4 4451.0 4601.0 4720.4 5024.0 5399.4 4706.0 5412.5 5503.6 5751.2 5756.2 3463.9 3491.0 3594.8 3677.0 3789.6 4694.2 4758.7 5029.6 5047.9 5612.9 5048.0 5385.2 5820.3 5842.9 5845.3 5136.8 5776.4 5782.0 5785.9 5814.9 4586.7 4675.4 4889.5 5247.0 5384.2 5754.1 5760.7 5778.2 5784.3 5785.4 5593.4 5608.7 5633.6 5648.4 5676.9 07:47:00 INFO - PROCESS | 6724 | 07:47:00 INFO - PROCESS | 6724 | [#5] yui.html Cycles:180 Average:20797.17 Median:4283.76 stddev:30936.88 (722.2%) stddev-sans-first:30986.51 07:47:00 INFO - PROCESS | 6724 | Values: 604.8 731.8 749.2 754.8 794.6 14008.3 14812.7 14836.3 14836.6 14876.3 13444.4 13466.5 13507.0 13512.2 13531.4 560.2 622.5 643.5 685.7 830.0 819.3 823.9 830.4 832.5 837.3 852.5 855.6 882.8 884.9 885.8 704.0 864.7 877.0 881.4 882.7 650.8 651.5 654.0 654.4 655.5 13561.8 13745.6 13845.7 14098.5 14195.5 10720.4 13620.5 13834.0 14002.4 14025.8 10875.5 10902.1 11314.0 11372.9 12061.3 3688.5 3754.2 3795.9 3821.4 3848.1 93845.0 98349.0 99530.0 100128.5 102197.0 10654.6 10872.3 10926.8 10933.3 10956.5 21419.7 21425.6 21440.9 21478.0 21671.6 7456.5 7567.5 7591.1 7756.7 7768.3 4194.8 4221.1 4269.6 4297.9 4326.4 3383.9 3499.6 3505.1 3512.3 3657.0 656.5 670.5 682.2 695.6 698.2 582.2 633.7 642.5 650.9 658.4 8555.4 9036.0 9122.2 9336.5 9395.3 605.3 606.1 636.9 649.3 663.9 3624.6 4362.4 4377.9 4585.4 4611.8 116923.0 120175.0 120242.0 122351.0 125319.0 60427.7 61563.7 80332.0 83826.6 105256.0 81509.6 84978.0 86127.0 87439.1 90700.0 9449.4 9570.6 9820.0 9827.0 9873.6 53383.7 53546.5 54427.0 55051.4 55728.7 72572.6 74498.0 76467.6 77741.0 78088.0 10018.8 10036.8 10455.5 12062.5 12280.1 13577.5 14187.2 14367.9 14414.3 14416.9 12240.4 13339.7 13363.3 13824.0 13879.6 13141.6 13160.7 13311.0 13451.0 13754.7 10414.7 12785.4 12945.2 13115.7 13561.1 10767.4 11082.6 11328.8 11410.7 11551.4 9143.4 10871.6 10944.1 11276.4 11343.7 07:47:00 INFO - PROCESS | 6724 | -------- Summary: end -------- 07:47:00 INFO - PROCESS | 6724 | 07:47:01 INFO - TEST-INFO | 6724: exit 0 07:47:01 INFO - TEST-OK | dromaeo_css | took 1117110ms 07:47:01 INFO - TEST-START | kraken 07:47:01 INFO - operating with platform_type : mac_ 07:47:01 INFO - Initialising browser for kraken test... 07:47:01 INFO - TEST-INFO | started process 6729 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpBcYI8B/profile http://localhost:61829/getInfo.html) 07:47:02 ERROR - PROCESS | 6729 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 07:47:04 INFO - PROCESS | 6729 | __metrics Screen width/height:1600/1200 07:47:04 INFO - PROCESS | 6729 | colorDepth:24 07:47:04 INFO - PROCESS | 6729 | Browser inner width/height: 1024/689 07:47:04 INFO - PROCESS | 6729 | __metrics 07:47:04 ERROR - PROCESS | 6729 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 07:47:04 INFO - TEST-INFO | 6729: exit 0 07:47:04 INFO - Browser initialized. 07:47:04 INFO - Running cycle 1/1 for kraken test... 07:47:04 INFO - TEST-INFO | started process 6732 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpBcYI8B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:47:17 INFO - PROCESS | 6732 | RSS: Main: 223879168 07:47:17 INFO - PROCESS | 6732 | 07:47:20 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61829/tests/kraken/driver.html?testName=audio-beat-detection) 07:47:21 INFO - PROCESS | 6732 | RSS: Main: 236498944 07:47:21 INFO - PROCESS | 6732 | 07:47:23 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61829/tests/kraken/driver.html?testName=audio-dft) 07:47:24 INFO - PROCESS | 6732 | RSS: Main: 244006912 07:47:24 INFO - PROCESS | 6732 | 07:47:27 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61829/tests/kraken/driver.html?testName=audio-fft) 07:47:28 INFO - PROCESS | 6732 | RSS: Main: 247660544 07:47:28 INFO - PROCESS | 6732 | 07:47:30 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61829/tests/kraken/driver.html?testName=audio-oscillator) 07:47:30 INFO - PROCESS | 6732 | RSS: Main: 247902208 07:47:30 INFO - PROCESS | 6732 | 07:47:32 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61829/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:47:33 INFO - PROCESS | 6732 | RSS: Main: 249360384 07:47:33 INFO - PROCESS | 6732 | 07:47:36 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61829/tests/kraken/driver.html?testName=imaging-darkroom) 07:47:37 INFO - PROCESS | 6732 | RSS: Main: 253140992 07:47:37 INFO - PROCESS | 6732 | 07:47:40 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61829/tests/kraken/driver.html?testName=imaging-desaturate) 07:47:41 INFO - PROCESS | 6732 | RSS: Main: 252805120 07:47:41 INFO - PROCESS | 6732 | 07:47:44 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61829/tests/kraken/driver.html?testName=json-parse-financial) 07:47:44 INFO - PROCESS | 6732 | RSS: Main: 252612608 07:47:44 INFO - PROCESS | 6732 | 07:47:46 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61829/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:47:47 INFO - PROCESS | 6732 | RSS: Main: 251736064 07:47:47 INFO - PROCESS | 6732 | 07:47:49 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61829/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:47:49 INFO - PROCESS | 6732 | RSS: Main: 251817984 07:47:49 INFO - PROCESS | 6732 | 07:47:52 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61829/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:47:52 INFO - PROCESS | 6732 | RSS: Main: 251871232 07:47:52 INFO - PROCESS | 6732 | 07:47:55 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61829/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:47:56 INFO - PROCESS | 6732 | RSS: Main: 252153856 07:47:56 INFO - PROCESS | 6732 | 07:47:59 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61829/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:48:00 INFO - PROCESS | 6732 | RSS: Main: 252039168 07:48:00 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:61829/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61829/tests/kraken/driver.html?testName=ai-astar) 07:48:02 INFO - PROCESS | 6732 | RSS: Main: 253825024 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | __start_tp_report 07:48:02 INFO - PROCESS | 6732 | _x_x_mozilla_page_load 07:48:02 INFO - PROCESS | 6732 | _x_x_mozilla_page_load_details 07:48:02 INFO - PROCESS | 6732 | |i|pagename|runs| 07:48:02 INFO - PROCESS | 6732 | |0;ai-astar;107;117;121;118;117;116;117;116;117;114 07:48:02 INFO - PROCESS | 6732 | |1;audio-beat-detection;160;118;136;153;117;119;115;119;164;111 07:48:02 INFO - PROCESS | 6732 | |2;audio-dft;200;198;202;202;198;197;198;200;203;202 07:48:02 INFO - PROCESS | 6732 | |3;audio-fft;86;59;61;86;67;56;58;84;107;84 07:48:02 INFO - PROCESS | 6732 | |4;audio-oscillator;98;103;104;72;69;72;88;69;72;87 07:48:02 INFO - PROCESS | 6732 | |5;imaging-gaussian-blur;104;113;90;124;89;122;135;87;88;85 07:48:02 INFO - PROCESS | 6732 | |6;imaging-darkroom;89;150;153;116;150;111;150;90;120;104 07:48:02 INFO - PROCESS | 6732 | |7;imaging-desaturate;85;131;135;113;91;88;92;120;77;73 07:48:02 INFO - PROCESS | 6732 | |8;json-parse-financial;78;90;55;58;60;57;59;60;59;60 07:48:02 INFO - PROCESS | 6732 | |9;json-stringify-tinderbox;71;85;73;81;76;72;70;63;97;67 07:48:02 INFO - PROCESS | 6732 | |10;stanford-crypto-aes;94;78;106;76;87;75;104;104;106;103 07:48:02 INFO - PROCESS | 6732 | |11;stanford-crypto-ccm;170;159;165;166;149;160;160;162;211;249 07:48:02 INFO - PROCESS | 6732 | |12;stanford-crypto-pbkdf2;171;174;179;219;172;168;166;158;228;232 07:48:02 INFO - PROCESS | 6732 | |13;stanford-crypto-sha256-iterative;69;69;68;69;69;66;66;68;67;70 07:48:02 INFO - PROCESS | 6732 | __end_tp_report 07:48:02 INFO - PROCESS | 6732 | __start_cc_report 07:48:02 INFO - PROCESS | 6732 | _x_x_mozilla_cycle_collect,1020 07:48:02 INFO - PROCESS | 6732 | __end_cc_report 07:48:02 INFO - PROCESS | 6732 | __startTimestamp1479138482282__endTimestamp 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | ------- Summary: start ------- 07:48:02 INFO - PROCESS | 6732 | Number of tests: 14 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#0] ai-astar Cycles:10 Average:116.00 Median:117.00 stddev:3.62 (3.1%) stddev-sans-first:1.87 07:48:02 INFO - PROCESS | 6732 | Values: 107.0 117.0 121.0 118.0 117.0 116.0 117.0 116.0 117.0 114.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:127.50 stddev:20.41 (16.0%) stddev-sans-first:18.79 07:48:02 INFO - PROCESS | 6732 | Values: 160.0 118.0 136.0 153.0 117.0 119.0 115.0 119.0 164.0 111.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#2] audio-dft Cycles:10 Average:200.00 Median:201.00 stddev:2.16 (1.1%) stddev-sans-first:2.29 07:48:02 INFO - PROCESS | 6732 | Values: 200.0 198.0 202.0 202.0 198.0 197.0 198.0 200.0 203.0 202.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#3] audio-fft Cycles:10 Average:74.80 Median:75.50 stddev:16.98 (22.5%) stddev-sans-first:17.52 07:48:02 INFO - PROCESS | 6732 | Values: 86.0 59.0 61.0 86.0 67.0 56.0 58.0 84.0 107.0 84.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#4] audio-oscillator Cycles:10 Average:83.40 Median:72.00 stddev:14.38 (20.0%) stddev-sans-first:14.25 07:48:02 INFO - PROCESS | 6732 | Values: 98.0 103.0 104.0 72.0 69.0 72.0 88.0 69.0 72.0 87.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#5] imaging-gaussian-blur Cycles:10 Average:103.70 Median:86.00 stddev:18.54 (21.6%) stddev-sans-first:19.66 07:48:02 INFO - PROCESS | 6732 | Values: 104.0 113.0 90.0 124.0 89.0 122.0 135.0 87.0 88.0 85.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#6] imaging-darkroom Cycles:10 Average:123.30 Median:150.00 stddev:25.59 (17.1%) stddev-sans-first:23.94 07:48:02 INFO - PROCESS | 6732 | Values: 89.0 150.0 153.0 116.0 150.0 111.0 150.0 90.0 120.0 104.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#7] imaging-desaturate Cycles:10 Average:100.50 Median:81.00 stddev:22.42 (27.7%) stddev-sans-first:23.07 07:48:02 INFO - PROCESS | 6732 | Values: 85.0 131.0 135.0 113.0 91.0 88.0 92.0 120.0 77.0 73.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:60.00 stddev:11.23 (18.7%) stddev-sans-first:10.63 07:48:02 INFO - PROCESS | 6732 | Values: 78.0 90.0 55.0 58.0 60.0 57.0 59.0 60.0 59.0 60.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#9] json-stringify-tinderbox Cycles:10 Average:75.50 Median:74.50 stddev:9.89 (13.3%) stddev-sans-first:10.36 07:48:02 INFO - PROCESS | 6732 | Values: 71.0 85.0 73.0 81.0 76.0 72.0 70.0 63.0 97.0 67.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#10] stanford-crypto-aes Cycles:10 Average:93.30 Median:75.50 stddev:13.14 (17.4%) stddev-sans-first:13.94 07:48:02 INFO - PROCESS | 6732 | Values: 94.0 78.0 106.0 76.0 87.0 75.0 104.0 104.0 106.0 103.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#11] stanford-crypto-ccm Cycles:10 Average:175.10 Median:165.50 stddev:30.78 (18.6%) stddev-sans-first:32.60 07:48:02 INFO - PROCESS | 6732 | Values: 170.0 159.0 165.0 166.0 149.0 160.0 160.0 162.0 211.0 249.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.70 Median:176.50 stddev:28.06 (15.9%) stddev-sans-first:29.18 07:48:02 INFO - PROCESS | 6732 | Values: 171.0 174.0 179.0 219.0 172.0 168.0 166.0 158.0 228.0 232.0 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:69.00 stddev:1.37 (2.0%) stddev-sans-first:1.41 07:48:02 INFO - PROCESS | 6732 | Values: 69.0 69.0 68.0 69.0 69.0 66.0 66.0 68.0 67.0 70.0 07:48:02 INFO - PROCESS | 6732 | -------- Summary: end -------- 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | 07:48:02 INFO - PROCESS | 6732 | ###!!! [Parent][MessageChannel] Error: (msgtype=0xEC0015,name=PVRManager::Msg_NotifyVSync) Channel error: cannot send/recv 07:48:02 INFO - PROCESS | 6732 | 07:48:03 INFO - TEST-INFO | 6732: exit 0 07:48:03 INFO - TEST-OK | kraken | took 62008ms 07:48:03 INFO - SUITE-END | took 1179s 07:48:03 INFO - Completed test suite (00:19:39) 07:48:03 INFO - javascript benchmark 07:48:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7019.180342951351, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3346.665333466653, 3359.5800524934384, 3359.8824041158564, 3371.629120796712, 3385.9153521161975, 4782.0, 5720.742566584503, 5790.478856902878, 5827.096799996008, 5828.213191219179, 5046.243063540471, 5117.797317630363, 5647.463490968362, 5828.271466066742, 5896.852578685535, 5298.973505132479, 5325.360956685203, 5341.4658534146665, 5341.65279256847, 5385.623006389555, 5405.108157154084, 5407.107827208506, 5416.864578385533, 5441.891162176754, 5451.236826844245, 5219.71291578963, 5239.0, 5382.327209098863, 5382.91925621116, 5386.084365657828, 4593.6095431888, 5092.930484598221, 5312.73436328184, 5317.308749862493, 5343.7328133593555, 5142.562882155022, 5260.763265653017, 5279.260903473517, 5279.60402969775, 5310.309659744208, 5752.568557358215, 5772.191893134965, 5890.0, 5942.0, 5959.910601340984, 6097.664628445478, 6223.705643106242, 6229.4704950079295, 6265.185525881606, 6297.2128483939505, 6168.013117901114, 6277.811665650038, 6300.873982520324, 6312.337204593543, 6316.494680425601, 5788.247527821399, 5882.264716910387, 5885.823425297248, 5897.8230653080045, 5903.852403689899, 6244.875102497925, 6253.874922501524, 6269.811905642838, 6273.498120150377, 6288.842778930563, 6154.415330543591, 6204.968975155095, 6207.565470417027, 6223.502119830403, 6235.781747638811, 6249.25008998923, 6264.5301602379095, 6280.7487700491465, 6281.434670879551, 6309.526785491014, 6125.937554509019, 6134.436587033474, 6258.468030217391, 6258.561900666909, 6281.026440901667, 6268.404501572343, 6283.7800676977, 6305.621662700252, 6323.652199129092, 6334.113224148623, 4398.73607583546, 4861.975690121527, 4882.584980276716, 4955.058538877602, 4978.376547023497, 4405.405270288534, 4425.513193548707, 4427.645788336926, 4457.24226881431, 4507.4365704286965, 5995.250593675791, 6026.9095963560585, 6030.427109424598, 6051.606645568024, 6078.513718902477, 6166.136740856284, 6306.306306306303, 6314.810555683245, 6322.652254125976, 6428.5178611604315, 5872.17789509469, 5887.823365298962, 5922.318933322636, 5943.64338139713, 5953.017752070947, 5420.430854760272, 5430.945690543044, 5466.863328416822, 5486.890262194814, 5533.22534845122, 6222.346653601307, 6417.614943103429, 6436.60256026207, 6457.838554036092, 6459.257185423548, 6179.536534759821, 6213.1922850029205, 6258.030005349178, 6264.68676566179, 6306.621602703853, 6220.564560480723, 6287.559870808999, 6472.644004579794, 6504.056911747864, 6508.381703738138, 5069.670471419346, 6121.696863452088, 6154.569180157346, 6223.3465486123305, 6223.564350495421, 6271.717772700331, 6282.120503129474, 6291.937080629135, 6297.370262973666, 6308.0, 6171.197744293213, 6179.876402472015, 6194.163787888758, 6248.687565621792, 6257.468115210076, 6010.398960104129, 6084.726187321669, 6112.266528016667, 6146.9550176471, 6179.783707570214, 5006.7246301452315, 5037.546620804145, 5523.1991361253195, 5766.077427611562, 6104.17593624855, 6124.0, 6145.354737752472, 6168.475679567287, 6192.133101365903, 6209.96895015522, 5000.0, 5703.657780533182, 6062.454379105901, 6074.544409169418, 6109.541784366279], "value": 5728.887418262981, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.4157595126336, 512.0731476028388, 516.5428595692813, 552.1303946284603, 581.6016029020121, 37996.24007519848, 39133.60866391335, 40289.798551007276, 40337.59662403379, 40393.0, 40927.59072409275, 41866.0, 41893.0, 41897.58102418982, 41988.79005604968, 8499.490030598154, 8701.47791132531, 9210.723678289645, 11173.329600224013, 11497.770044599103, 11392.0, 11475.942620286887, 11476.426178691074, 11492.827607585892, 11532.250403723772, 11474.311541307548, 11530.135239857002, 11532.423378831025, 11532.884671153264, 11541.134414918874, 11396.14528910335, 11454.885451145548, 11467.197296189228, 11473.885261147449, 11484.71288217793, 11335.979761821392, 11457.0, 11458.885411145866, 11462.770744585145, 11481.483333250024, 38954.0, 39364.212715745525, 39371.60628393737, 39379.60620393788, 39612.0, 3299.967000330014, 3345.1135449106005, 3358.546596209505, 3383.7631365804405, 3397.677220664033, 25089.372765681004, 28611.713882861113, 29234.853825730734, 29467.557986630218, 29603.851980739957, 3179.77741558091, 3243.8053716777067, 3251.3822373748912, 3254.4304746669236, 3295.340931813647, 507235.0, 510230.0, 514864.42567787296, 514919.0, 515092.0, 62194.68902655548, 62511.6874415625, 62542.374576254566, 62960.0, 64388.0, 50499.747501262995, 50986.725331867, 58858.70570647119, 61489.69255153696, 63720.68139659272, 12969.54606588765, 13460.865391346157, 14528.418863245563, 16139.789515786362, 16181.0, 11206.831897521544, 12082.033437324993, 12439.813402798965, 12576.937115314366, 12816.80774788379, 15344.309506072253, 19097.2361105557, 19160.0, 19175.904120479307, 19248.23007079729, 7777.455578109478, 7881.960590197013, 7888.368930485547, 7943.180171039314, 7943.404244681672, 862.2584577263548, 956.1967946924477, 1005.8440941654055, 1019.2814066083394, 1037.7301901505512, 29815.39568057822, 29887.103386898427, 30002.0, 30118.630708292054, 30412.391752165277, 988.2094324540338, 1025.5077562769911, 1086.782643471293, 1117.7708569743183, 1163.0, 14997.325120369609, 15075.437175680161, 15613.0, 15632.2183890805, 15645.765313520305, 187237.0, 188253.0, 188460.0, 188962.05518972318, 189264.0, 57132.42867571354, 57178.0, 57299.71350143223, 57798.0, 57801.132983005285, 1171.244547267008, 1305.112523484021, 1347.4340776873737, 1352.5671785028696, 1352.7226918481688, 24097.0, 29174.68713907922, 29832.403351932404, 30016.549751753304, 30526.389472209987, 50380.24429633632, 50905.490945090074, 50948.490515094374, 50968.74515627398, 51008.234876477625, 583560.0, 592259.0, 592481.0, 596350.0, 598147.0, 26470.80881360382, 26685.066022689112, 27219.727802721718, 27255.454890902467, 28107.718922811328, 46197.76901115473, 46475.07049859052, 46618.300725489826, 46866.53133468622, 47311.52688473071, 37361.626383736904, 38090.61909380871, 39661.80169099251, 41081.4188787051, 41570.0, 38267.6173238264, 38634.806825965694, 39371.0, 39454.60545394624, 39748.2050358997, 37082.55649190777, 37130.88607341784, 37893.0, 37940.62059379481, 38302.42546361751, 33340.499892501146, 34617.82691086528, 34697.121151539584, 34940.47589286214, 34989.95030149099, 18994.0, 24490.63264051005, 25707.10025149111, 25979.22062338133, 26495.60256596114], "value": 19574.882345064616, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.6146750503169, 377.9414190800425, 380.51484357444264, 391.8001819072272, 401.78460158022, 17277.827221727795, 19066.0, 20014.399568012985, 20680.0, 20842.89578552109, 19661.21355145796, 19878.906660133685, 20306.086226119824, 20433.693494597555, 20728.48178795531, 5769.826905192851, 5854.88290234195, 6031.065184896348, 6049.637021778685, 6105.145279660851, 6062.96968515159, 6111.816645500619, 6126.908096378558, 6134.693265336738, 6150.569460137792, 3843.2121415109973, 3965.8017099145072, 6007.519398448114, 6111.938880611181, 6160.753569857223, 5126.333576635032, 5936.168936348915, 6043.002904520748, 6123.724432400552, 6141.17094192285, 5824.514748739044, 5844.824655260349, 5894.8821023579285, 5895.675737834417, 5912.852178695524, 13221.669458263525, 13294.667633309246, 13299.335033248395, 13496.5276215332, 13527.661808454768, 2158.190678495564, 2180.269609680759, 2264.8562475949707, 2429.465517586128, 2766.7233276672173, 9323.601459781019, 10069.345492542961, 10531.210159238088, 10534.736631584195, 11545.0, 1680.269082948921, 1872.4008317338457, 1915.6934890417606, 1920.8271255587065, 1948.1428171604591, 124051.0, 124131.0, 127982.0, 128983.35508322399, 131083.68916310713, 9440.339176257596, 10101.292909496411, 10186.08325250731, 10296.794064118676, 10309.793804123876, 12467.376631168405, 12589.118761686777, 12636.494540218288, 12695.55565555201, 12711.427985740664, 7941.682332706742, 8018.639161237758, 8069.233422824822, 8160.83678326442, 8170.959145204355, 8286.254237118688, 8307.875381869277, 8327.583620818934, 8389.958050209832, 8458.87311690325, 3842.7126912484073, 3967.861124860674, 3969.7816620085596, 4021.718479706451, 4041.777702226347, 1868.7852895617957, 1921.7693876734882, 1932.8260456558978, 1947.7175809507542, 1958.2460752610348, 346.7832604622111, 406.5507614086451, 414.2646801926604, 415.89394704350195, 417.92477354076556, 11508.539658413738, 11814.704632384088, 11878.346690931909, 11945.522179112739, 11955.0, 425.5659227587843, 433.9631131353807, 439.75593545582507, 443.09829496974146, 447.0790172245185, 5942.70286485674, 6111.8472038198515, 6130.988386916198, 6276.497880169667, 6291.905621415683, 469658.0, 523926.0, 599082.0, 629648.0, 631649.0, 99971.00028999762, 122170.55658886772, 133826.0, 138863.6113638871, 144257.0, 431.29913889928866, 449.0300949948162, 449.8088312467254, 450.7160488891977, 450.8850243188032, 11021.013252676545, 11397.601083962021, 11542.0, 11646.010089142517, 11646.825297620373, 53051.73474132604, 53124.46875531195, 53393.932121358135, 55496.0, 56416.0, 62005.06992395027, 77944.22055779597, 79209.60395197987, 79702.0, 80219.0, 6891.0, 7383.224761400191, 7434.219406962407, 7459.738909138154, 7827.178146294557, 10275.7431064224, 12366.62900112998, 12376.690582735144, 12394.256344619353, 12661.303628300531, 9250.0, 9258.35191536586, 9586.233101352014, 10003.699889003341, 10568.0, 8677.739667809976, 9104.590293436944, 9718.319717619699, 9769.120779129627, 9877.456739879375, 8164.346852251924, 9807.950960245153, 10074.345167564084, 10090.0, 10322.535485903303, 6964.408025317702, 7359.080114985627, 7424.552212318762, 7720.8455830884195, 7851.489653172525, 9138.223251023737, 9329.16037556623, 9907.702768916944, 9917.950410247902, 10096.34373765703], "value": 8123.85844980995, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.7419201639711, 1474.7861560073788, 1683.150009245331, 1782.1089455272363, 1807.240958797304, 7623.58070306133, 7893.407994400414, 8231.58842057897, 8638.697645582399, 8658.480491170534, 8091.231333023368, 8377.120402357756, 8418.873716894252, 8520.275776558985, 8542.829143417128, 2344.566255242781, 2350.800181984525, 2361.551305252005, 2366.25462979161, 2372.6678265042906, 2359.8820058997067, 2386.4033991502124, 2395.6167013277877, 2397.2328854766483, 2407.987960060206, 2342.9414264643433, 2347.424880904181, 2348.2720356689397, 2370.442945907719, 2372.95254094918, 2314.36355002374, 2315.8378913476063, 2337.742848286687, 2341.789238968501, 2348.342464110052, 2000.829929455998, 2310.145246258895, 2378.690770199863, 2382.737898831127, 2386.618141097416, 8405.831883362298, 8515.403921725481, 8528.658853645848, 8595.95702021492, 8596.441231319945, 5101.8724531887, 5113.335266415379, 5252.65857719247, 5262.236975638511, 5266.947330526722, 2807.663080430341, 3300.7029367356813, 3404.42124838778, 3519.718422526217, 3540.398132317512, 1023.698009087321, 1027.563285603623, 1089.4226060188114, 1100.3397961223202, 1114.2979922648678, 11038.227324087316, 11075.16936229778, 11107.389093599848, 11157.163212758995, 11160.832587511275, 4526.660500462479, 4627.676062675648, 4694.178518759203, 4696.553827386393, 4791.47293797682, 9571.33000689945, 9654.75863103428, 9656.22750179984, 9665.275104367201, 9666.613335466644, 5275.0, 5282.920756188661, 5284.762185701576, 5290.788368465295, 5303.920441193385, 4326.069894972596, 4345.456817897762, 4791.952080479151, 4864.416270047617, 4868.683535570177, 3814.923701525954, 3843.0968722350226, 3868.6131386861653, 3934.252492026506, 3942.8225729842225, 1265.7152140768437, 1267.3219827392302, 1270.6505710929384, 1279.9232046077266, 1282.967211394836, 770.445279398832, 774.5159275452842, 880.4761167105562, 910.6220918318955, 934.0472717827778, 5623.212750214973, 5712.257406537206, 5845.561582881301, 5864.091065884794, 5870.236869207061, 912.1790388650268, 939.8684184214216, 940.4874343482818, 965.3194497878978, 983.5328219095873, 3760.4547340636, 3885.611438856148, 3894.92889455402, 3921.588233235469, 3928.3714605663527, 9937.055979681918, 10386.42874641902, 10455.738606534745, 10458.895411045793, 10472.842907356397, 8067.515949043076, 8100.8379832403025, 9410.717678469657, 10409.375437473624, 10443.738906527398, 7364.852702945911, 7965.442419030613, 8031.477953932859, 8101.554414507141, 8146.0, 5680.715964201856, 5729.0, 5910.852228694317, 5928.703564821828, 5969.164316995711, 9223.44659320443, 9502.714918552178, 9542.141207291377, 9667.516624168904, 9786.266030047633, 9525.666601668909, 9603.991580884185, 9612.1829644481, 9741.123298903132, 9888.357256778287, 6586.550958789058, 6626.536142470173, 7109.680064397011, 7167.104111986002, 7292.34368906801, 8002.919605853368, 8076.878846817185, 8113.107558168715, 8113.18868113184, 8113.6754529818145, 7059.541129826544, 7091.539049961736, 7271.41828653717, 7276.854462910606, 7340.596267205355, 6927.757528486681, 7093.893591596234, 7104.751333703295, 7128.85742285141, 7238.6380680966795, 6730.057791909038, 6895.207051189177, 6897.655117244218, 6902.413294869993, 6928.757493487704, 4502.369668246513, 4514.7291162530355, 4592.862214133581, 4644.58198762113, 4894.14352488306, 4878.365812444359, 4915.21356582945, 4951.282064100757, 5089.3638295213095, 5130.076586214517], "value": 4472.097678616389, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.9751123643256, 287.054156554154, 297.48683520926403, 312.8513955870961, 316.4809712072202, 5202.271681964528, 5254.947450525494, 5419.8374048778505, 5519.586031047667, 5539.695316757577, 4700.341952126696, 5251.684898906068, 5356.892862142755, 5374.876923615169, 5401.216823560591, 3838.8848334550007, 3854.3447613905564, 3907.609239076098, 4191.559886211942, 4260.0, 3838.6776000519703, 4060.7563546187325, 4236.385724070008, 4238.025254191522, 4273.786310684469, 3755.906102347436, 4066.369712694522, 4076.8369265229503, 4130.070734084837, 4135.8759237222785, 3446.1556918555116, 4096.815643296059, 4122.536499542664, 4131.648809851151, 4159.313713237312, 3567.9821600892087, 3573.10672331917, 3583.6953858922025, 3588.659077387644, 3596.982015089934, 4515.5484451554585, 4669.7665116744365, 5079.111155547801, 5241.95160967808, 5299.629025968184, 4671.808688784372, 4681.705964077578, 4710.199266124767, 4733.881652958669, 4735.526447355272, 4697.78859951303, 4962.106820772259, 5697.097488220582, 5735.0, 5758.3665796762325, 3598.298331825295, 3623.710103191738, 3817.6945844332386, 3886.9222615547815, 3926.332523471017, 5240.580753539708, 5331.44019877915, 6016.2479690038745, 6044.69776511177, 6076.513878889678, 3225.919317028775, 3485.755997080231, 3737.831797569116, 3819.560750513671, 3972.682185425159, 3356.1945133167865, 3391.6269210386836, 3764.698824094066, 3791.7345785795283, 4072.857449989236, 3776.339140650375, 4055.8174882129783, 4517.457905051415, 4648.697834640738, 4652.697574657636, 5798.6520808750765, 5809.941900581025, 5817.080363480041, 5863.589548731633, 5868.647881127146, 1226.2213494430923, 1255.4162314523608, 1269.6191142657167, 1287.4270949427603, 1298.7272672738832, 851.6933903794629, 882.4308321132834, 883.1345281624044, 884.6992022712309, 890.5458216309729, 4437.489688685842, 4529.977350113229, 5072.365954255718, 5146.356705411824, 5190.714510701948, 4452.612339069698, 4737.692050016738, 4868.440129385166, 4875.269279405831, 5780.335261444988, 4625.710273414614, 5119.6160287978555, 5119.667221630582, 5133.383993920754, 5201.1678131498775, 2594.5848664213636, 2909.2145120817263, 2927.3852490977133, 2933.3839893622153, 2943.367176057115, 5632.436756324335, 5729.455701708331, 5821.53427725781, 5866.50134738552, 5950.523958083343, 4951.950480495149, 5030.8239211627415, 5244.423113457517, 5265.867838414759, 5799.8550036249435, 4567.588916997486, 4865.759231395971, 5236.633435659467, 5506.697131657797, 5802.564807639359, 5034.597232221487, 5163.612729045261, 5273.0, 5653.2085508028085, 5679.37526872052, 4450.954025803998, 4600.953990460052, 4720.409948756405, 5024.001118886731, 5399.406065332889, 4705.976470117627, 5412.512873841373, 5503.559715222853, 5751.194832723339, 5756.165356023437, 3463.94804077934, 3490.965090349064, 3594.7843129412317, 3676.963230367662, 3789.6020917804335, 4694.201985662377, 4758.714477131383, 5029.59763218949, 5047.899042019212, 5612.85967850807, 5048.015636950906, 5385.219143224289, 5820.30156381237, 5842.853928651818, 5845.298564172156, 5136.8202112925865, 5776.393478684678, 5781.971090144522, 5785.8553536161935, 5814.912776308274, 4586.738646872003, 4675.438947326206, 4889.457482109355, 5247.02929957959, 5384.246205531307, 5754.079347304411, 5760.740766665426, 5778.248827652378, 5784.334801497714, 5785.4214578541805, 5593.440655934537, 5608.719564021864, 5633.577481688972, 5648.418442836012, 5676.914846277226], "value": 4023.3917140732397, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.7641419846261, 731.8024133483959, 749.1983577571997, 754.8334735783391, 794.5669610062517, 14008.299585020746, 14812.703745925075, 14836.258187090629, 14836.55490335289, 14876.330565124568, 13444.3950022249, 13466.5286714965, 13506.986975976792, 13512.189268643888, 13531.391087401065, 560.2073066610761, 622.5455417545195, 643.5077165968038, 685.6880119545594, 830.0330115415534, 819.2831272636444, 823.9382046346518, 830.3606223208126, 832.488019867783, 837.3426859914968, 852.5396286005549, 855.5508358111979, 882.788130848595, 884.8849649545582, 885.7519894429538, 703.9753608623673, 864.7405778266526, 876.9868451973193, 881.4270724029368, 882.6645874567623, 650.7722297195991, 651.5178767711907, 654.0222367560527, 654.4175683641563, 655.5312951239839, 13561.779439850363, 13745.587632371045, 13845.723085538233, 14098.506552270725, 14195.503157389545, 10720.410377429238, 13620.455181792717, 13834.0, 14002.369893354722, 14025.78961315581, 10875.45622718861, 10902.127829773599, 11314.0, 11372.943135284353, 12061.276323420623, 3688.5389326334207, 3754.155315054118, 3795.905102372435, 3821.388577827562, 3848.057225979625, 93845.0, 98349.0, 99530.0046999528, 100128.49935750275, 102196.97803021948, 10654.573817047387, 10872.293300935415, 10926.836097458545, 10933.343999360064, 10956.506957186946, 21419.678704819442, 21425.571488570455, 21440.892795536234, 21478.033488492743, 21671.56656866854, 7456.4780465367985, 7567.545947243073, 7591.127020392725, 7756.728514501965, 7768.339691126204, 4194.769287689206, 4221.134667393177, 4269.594388533084, 4297.935530967038, 4326.372675961968, 3383.9492407613907, 3499.5975462821593, 3505.106197919565, 3512.3150985557572, 3656.9760467069264, 656.461701404853, 670.510527315053, 682.1677553384862, 695.5965539986896, 698.1587187439123, 582.2314544800906, 633.6704913445075, 642.5437939063224, 650.8568115014689, 658.4074333100248, 8555.443896146731, 9035.954820225857, 9122.179003889682, 9336.533173341306, 9395.258058412952, 605.2706488681129, 606.1483615520201, 636.8662580857979, 649.302000349626, 663.8805015097232, 3624.619414961391, 4362.411074504965, 4377.898608667857, 4585.358049873021, 4611.76941152941, 116923.0, 120175.0, 120242.0, 122351.0, 125319.0, 60427.69786151041, 61563.692181538805, 80331.99170020796, 83826.58086709527, 105256.0, 81509.59245203855, 84977.99999999876, 86127.0, 87439.1256087431, 90700.0, 9449.43303401798, 9570.56932438028, 9820.017998200408, 9826.950865245628, 9873.555689994111, 53383.73308133434, 53546.464535355706, 54427.0, 55051.44948550463, 55728.72135639458, 72572.63713681398, 74498.0, 76467.61766191134, 77741.0, 78088.0, 10018.799624007333, 10036.849447258443, 10455.491168744778, 12062.51749929993, 12280.078994075302, 13577.52478663242, 14187.219702916438, 14367.92816035955, 14414.279286035446, 14416.927915360357, 12240.449179786752, 13339.733205336033, 13363.26502042397, 13824.0, 13879.583612491642, 13141.605751827461, 13160.736785264433, 13311.0, 13450.99117566167, 13754.724905502035, 10414.739631509274, 12785.424655890321, 12945.223286602833, 13115.737685246433, 13561.050726449053, 10767.3539587625, 11082.61210857616, 11328.830067549157, 11410.657680269605, 11551.42242887869, 9143.445614245462, 10871.565137394417, 10944.069754070993, 11276.3797991108, 11343.659690209306], "value": 7295.217084171639, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1595.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 121.0, 118.0, 117.0, 116.0, 117.0, 116.0, 117.0, 114.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 118.0, 136.0, 153.0, 117.0, 119.0, 115.0, 119.0, 164.0, 111.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 198.0, 202.0, 202.0, 198.0, 197.0, 198.0, 200.0, 203.0, 202.0], "value": 200.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 59.0, 61.0, 86.0, 67.0, 56.0, 58.0, 84.0, 107.0, 84.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 103.0, 104.0, 72.0, 69.0, 72.0, 88.0, 69.0, 72.0, 87.0], "value": 83.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 90.0, 124.0, 89.0, 122.0, 135.0, 87.0, 88.0, 85.0], "value": 103.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 150.0, 153.0, 116.0, 150.0, 111.0, 150.0, 90.0, 120.0, 104.0], "value": 123.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 131.0, 135.0, 113.0, 91.0, 88.0, 92.0, 120.0, 77.0, 73.0], "value": 100.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 90.0, 55.0, 58.0, 60.0, 57.0, 59.0, 60.0, 59.0, 60.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 85.0, 73.0, 81.0, 76.0, 72.0, 70.0, 63.0, 97.0, 67.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 78.0, 106.0, 76.0, 87.0, 75.0, 104.0, 104.0, 106.0, 103.0], "value": 93.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 159.0, 165.0, 166.0, 149.0, 160.0, 160.0, 162.0, 211.0, 249.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 174.0, 179.0, 219.0, 172.0, 168.0, 166.0, 158.0, 228.0, 232.0], "value": 186.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 69.0, 69.0, 66.0, 66.0, 68.0, 67.0, 70.0], "value": 68.1, "unit": "score"}]}]} 07:48:03 INFO - Return code: 0 07:48:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:48:03 ERROR - # TBPL SUCCESS # 07:48:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:48:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:48:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:48:03 INFO - 2016-11-14 07:48:03.619 screenresolution[6737:128629] starting screenresolution argv=screenresolution get 07:48:03 INFO - 2016-11-14 07:48:03.622 screenresolution[6737:128629] Display 0: 1600x1200x32@60 07:48:03 INFO - 2016-11-14 07:48:03.628 screenresolution[6738:128632] starting screenresolution argv=screenresolution list 07:48:03 INFO - Available Modes on Display 0 07:48:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:48:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:48:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:48:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:48:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:48:03 INFO - Intel Iris: 07:48:03 INFO - Chipset Model: Intel Iris 07:48:03 INFO - Type: GPU 07:48:03 INFO - Bus: Built-In 07:48:03 INFO - VRAM (Dynamic, Max): 1536 MB 07:48:03 INFO - Vendor: Intel (0x8086) 07:48:03 INFO - Device ID: 0x0a2e 07:48:03 INFO - Revision ID: 0x0009 07:48:03 INFO - Displays: 07:48:03 INFO - Display: 07:48:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:48:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:48:03 INFO - Display Serial Number: 1600x1200 60 07:48:03 INFO - Main Display: Yes 07:48:03 INFO - Mirror: Off 07:48:03 INFO - Online: Yes 07:48:03 INFO - Rotation: Supported 07:48:03 INFO - Return code: 0 07:48:03 INFO - Running post-action listener: _resource_record_post_action 07:48:03 INFO - [mozharness: 2016-11-14 15:48:03.866894Z] Finished run-tests step (success) 07:48:03 INFO - Running post-run listener: _resource_record_post_run 07:48:03 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 541184; Write bytes: 656744448; Read time: 5; Write time: 1894 07:48:03 INFO - TinderboxPrint: CPU usage
54.3% 07:48:03 INFO - TinderboxPrint: I/O read bytes / time
541,184 / 5 07:48:03 INFO - TinderboxPrint: I/O write bytes / time
656,744,448 / 1,894 07:48:03 INFO - TinderboxPrint: CPU idle
2,214.8 (45.7%) 07:48:03 INFO - TinderboxPrint: CPU system
986.0 (20.3%) 07:48:03 INFO - TinderboxPrint: CPU user
1,649.0 (34.0%) 07:48:03 INFO - TinderboxPrint: Swap in / out
353,120,256 / 0 07:48:03 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 353878528; Write bytes: 355145216; Read time: 27622; Write time: 733 07:48:03 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 495616; Write bytes: 298898432; Read time: 4; Write time: 1145 07:48:04 INFO - Running post-run listener: _upload_blobber_files 07:48:04 INFO - Blob upload gear active. 07:48:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:48:04 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 07:48:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:48:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:48:04 INFO - (blobuploader) - INFO - Open directory for files ... 07:48:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:48:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:48:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:48:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:48:05 INFO - (blobuploader) - INFO - Done attempting. 07:48:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:48:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:48:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:48:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:48:06 INFO - (blobuploader) - INFO - Done attempting. 07:48:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:48:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:48:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:48:09 INFO - (blobuploader) - INFO - Done attempting. 07:48:09 INFO - (blobuploader) - INFO - Iteration through files over. 07:48:09 INFO - Return code: 0 07:48:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:48:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:48:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c04a4a075cea684d36778c00516f52729fc179405f6d70543a1a7e2029149021e613e7bd03535482ef6177524d3587f7235ffac16750c91ef4c9249caa2c2d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf7d30942012ed2970df0645243bfd2d46010a9563942f14af56bbd5164aeefddace82e6b043791a2f1994b858c2261e61c64768685a763d9899fe69c545da93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e11b8888c8ef3792d7a5de83863d817351c00353b76ac38109e6418d90cff0e4c4f23f4d199c52e8a3363911a890a74ee181ad112b8d83061da78a22f7a4b38c"} 07:48:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:48:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:48:09 INFO - Contents: 07:48:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c04a4a075cea684d36778c00516f52729fc179405f6d70543a1a7e2029149021e613e7bd03535482ef6177524d3587f7235ffac16750c91ef4c9249caa2c2d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf7d30942012ed2970df0645243bfd2d46010a9563942f14af56bbd5164aeefddace82e6b043791a2f1994b858c2261e61c64768685a763d9899fe69c545da93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e11b8888c8ef3792d7a5de83863d817351c00353b76ac38109e6418d90cff0e4c4f23f4d199c52e8a3363911a890a74ee181ad112b8d83061da78a22f7a4b38c"} 07:48:09 INFO - Running post-run listener: copy_logs_to_upload_dir 07:48:09 INFO - Copying logs to upload dir... 07:48:09 INFO - mkdir: /builds/slave/test/build/upload/logs 07:48:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.029064 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 07:48:09.214428) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 07:48:09.217882) ========= 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.Jr2fA19arr/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.QlMR1JiYYx/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c04a4a075cea684d36778c00516f52729fc179405f6d70543a1a7e2029149021e613e7bd03535482ef6177524d3587f7235ffac16750c91ef4c9249caa2c2d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf7d30942012ed2970df0645243bfd2d46010a9563942f14af56bbd5164aeefddace82e6b043791a2f1994b858c2261e61c64768685a763d9899fe69c545da93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e11b8888c8ef3792d7a5de83863d817351c00353b76ac38109e6418d90cff0e4c4f23f4d199c52e8a3363911a890a74ee181ad112b8d83061da78a22f7a4b38c"} build_url:https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011222 build_url: 'https://queue.taskcluster.net/v1/task/fJ6zpvxKR02zKbBpLTN4EQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c04a4a075cea684d36778c00516f52729fc179405f6d70543a1a7e2029149021e613e7bd03535482ef6177524d3587f7235ffac16750c91ef4c9249caa2c2d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf7d30942012ed2970df0645243bfd2d46010a9563942f14af56bbd5164aeefddace82e6b043791a2f1994b858c2261e61c64768685a763d9899fe69c545da93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e11b8888c8ef3792d7a5de83863d817351c00353b76ac38109e6418d90cff0e4c4f23f4d199c52e8a3363911a890a74ee181ad112b8d83061da78a22f7a4b38c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 07:48:09.250205) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:48:09.250490) ========= 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.Jr2fA19arr/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.QlMR1JiYYx/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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.005287 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 07:48:09.298925) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 07:48:09.299192) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 07:48:09.299461) ========= ========= Total master_lag: 0.18 =========