builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0086 starttime: 1473337941.82 results: success (0) buildid: 20160908030434 builduid: a74f24c3da434357805cc38db4a97495 revision: 938ce16be25f9c551c19ef8938e8717ed3d41ff5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:21.815873) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:21.816853) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:21.817416) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:21.842533) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:21.842848) ========= 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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-09-08 05:32:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.1M=0.001s 2016-09-08 05:32:22 (21.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163889 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:22.030209) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:22.030582) ========= 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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.027852 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:22.079946) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 05:32:22.080348) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 938ce16be25f9c551c19ef8938e8717ed3d41ff5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 938ce16be25f9c551c19ef8938e8717ed3d41ff5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7JpAZmlKkZ/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.moLiIly5Qm/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-09-08 05:32:22,152 truncating revision to first 12 chars 2016-09-08 05:32:22,152 Setting DEBUG logging. 2016-09-08 05:32:22,152 attempt 1/10 2016-09-08 05:32:22,152 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/938ce16be25f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 05:32:23,239 unpacking tar archive at: mozilla-central-938ce16be25f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.369372 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 05:32:23.475096) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:23.475877) ========= script_repo_revision: 938ce16be25f9c551c19ef8938e8717ed3d41ff5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:23.476810) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:23.477388) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 05:32:23.496885) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-09-08 05:32:23.497591) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7JpAZmlKkZ/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.moLiIly5Qm/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:32:23 INFO - MultiFileLogger online at 20160908 05:32:23 in /builds/slave/test 05:32:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:32:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:32:23 INFO - {'append_to_log': False, 05:32:23 INFO - 'base_work_dir': '/builds/slave/test', 05:32:23 INFO - 'blob_upload_branch': 'Firefox', 05:32:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:32:23 INFO - 'branch': 'Firefox', 05:32:23 INFO - 'buildbot_json_path': 'buildprops.json', 05:32:23 INFO - 'config_files': ('talos/mac_config.py',), 05:32:23 INFO - 'default_actions': ('clobber', 05:32:23 INFO - 'read-buildbot-config', 05:32:23 INFO - 'download-and-extract', 05:32:23 INFO - 'populate-webroot', 05:32:23 INFO - 'create-virtualenv', 05:32:23 INFO - 'install', 05:32:23 INFO - 'run-tests'), 05:32:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:32:23 INFO - 'download_minidump_stackwalk': True, 05:32:23 INFO - 'download_symbols': 'ondemand', 05:32:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:32:23 INFO - 'tooltool.py': '/tools/tooltool.py', 05:32:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:32:23 INFO - '/tools/misc-python/virtualenv.py')}, 05:32:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:32:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:32:23 INFO - 'installer_path': 'installer.exe', 05:32:23 INFO - 'log_level': 'info', 05:32:23 INFO - 'log_name': 'talos', 05:32:23 INFO - 'log_to_console': True, 05:32:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:32:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:32:23 INFO - 'opt_config_files': (), 05:32:23 INFO - 'pip_index': False, 05:32:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:32:23 INFO - 'cmd': ('bash', 05:32:23 INFO - '-c', 05:32:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:32:23 INFO - 'enabled': True, 05:32:23 INFO - 'halt_on_failure': False, 05:32:23 INFO - 'name': 'check_screen_resolution'},), 05:32:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:32:23 INFO - 'cmd': ('bash', 05:32:23 INFO - '-c', 05:32:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:32:23 INFO - 'enabled': True, 05:32:23 INFO - 'halt_on_failure': False, 05:32:23 INFO - 'name': 'check_screen_resolution'},), 05:32:23 INFO - 'run_cmd_checks_enabled': True, 05:32:23 INFO - 'sps_profile': False, 05:32:23 INFO - 'sps_profile_interval': 0, 05:32:23 INFO - 'suite': 'dromaeojs-e10s', 05:32:23 INFO - 'system_bits': '32', 05:32:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:32:23 INFO - 'title': 't-yosemite-r7-0086', 05:32:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:32:23 INFO - 'use_talos_json': True, 05:32:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:32:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:32:23 INFO - 'work_dir': 'build'} 05:32:23 INFO - [mozharness: 2016-09-08 12:32:23.689094Z] Running clobber step. 05:32:23 INFO - Running pre-action listener: _resource_record_pre_action 05:32:23 INFO - Running main action method: clobber 05:32:23 INFO - rmtree: /builds/slave/test/build 05:32:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:32:25 INFO - Running post-action listener: _resource_record_post_action 05:32:25 INFO - [mozharness: 2016-09-08 12:32:25.830412Z] Finished clobber step (success) 05:32:25 INFO - [mozharness: 2016-09-08 12:32:25.830595Z] Running read-buildbot-config step. 05:32:25 INFO - Running pre-action listener: _resource_record_pre_action 05:32:25 INFO - Running main action method: read_buildbot_config 05:32:25 INFO - Using buildbot properties: 05:32:25 INFO - { 05:32:25 INFO - "project": "", 05:32:25 INFO - "product": "firefox", 05:32:25 INFO - "script_repo_revision": "production", 05:32:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:32:25 INFO - "repository": "", 05:32:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:32:25 INFO - "buildid": "20160908030434", 05:32:25 INFO - "stage_platform": "macosx64", 05:32:25 INFO - "basedir": "/builds/slave/test", 05:32:25 INFO - "nightly_build": "True", 05:32:25 INFO - "buildnumber": 79, 05:32:25 INFO - "slavename": "t-yosemite-r7-0086", 05:32:25 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:32:25 INFO - "platform": "yosemite_r7", 05:32:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:32:25 INFO - "branch": "mozilla-central", 05:32:25 INFO - "repo_path": "mozilla-central", 05:32:25 INFO - "revision": "938ce16be25f9c551c19ef8938e8717ed3d41ff5", 05:32:25 INFO - "pgo_build": "False", 05:32:25 INFO - "builduid": "a74f24c3da434357805cc38db4a97495", 05:32:25 INFO - "slavebuilddir": "test" 05:32:25 INFO - } 05:32:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:32:25 INFO - Running post-action listener: _resource_record_post_action 05:32:25 INFO - [mozharness: 2016-09-08 12:32:25.834014Z] Finished read-buildbot-config step (success) 05:32:25 INFO - [mozharness: 2016-09-08 12:32:25.834151Z] Running download-and-extract step. 05:32:25 INFO - Running pre-action listener: _resource_record_pre_action 05:32:25 INFO - Running main action method: download_and_extract 05:32:25 INFO - mkdir: /builds/slave/test/build/tests 05:32:25 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')]} 05:32:25 INFO - trying https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:32:25 INFO - Downloading https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:32:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 05:32:26 INFO - Downloaded 1199 bytes. 05:32:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:32:26 INFO - Using the following test package requirements: 05:32:26 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:32:26 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:32:26 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:32:26 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:32:26 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:32:26 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:32:26 INFO - u'jsshell-mac.zip'], 05:32:26 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:32:26 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:32:26 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:32:26 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:32:26 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:32:26 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:32:26 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:32:26 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:32:26 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:32:26 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:32:26 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:32:26 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:32:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:32:27 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:32:34 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:32:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:32:35 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:32:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:32:42 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:32:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:43 INFO - trying https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:32:43 INFO - Downloading https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:32:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 05:32:46 INFO - Downloaded 111630976 bytes. 05:32:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:32:46 INFO - mkdir: /builds/slave/test/properties 05:32:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:32:46 INFO - Writing to file /builds/slave/test/properties/build_url 05:32:46 INFO - Contents: 05:32:46 INFO - build_url:https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:32:46 INFO - Running post-action listener: _resource_record_post_action 05:32:46 INFO - Running post-action listener: set_extra_try_arguments 05:32:46 INFO - [mozharness: 2016-09-08 12:32:46.950937Z] Finished download-and-extract step (success) 05:32:46 INFO - [mozharness: 2016-09-08 12:32:46.951064Z] Running populate-webroot step. 05:32:46 INFO - Running pre-action listener: _resource_record_pre_action 05:32:46 INFO - Running main action method: populate_webroot 05:32:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:32:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:32:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:32:46 INFO - '--tppagecycles', 05:32:46 INFO - '7'], 05:32:46 INFO - 'tests': ['tsvgm']}}, 05:32:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:32:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:32:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:32:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:32:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:32:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:32:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:46 INFO - 'talos_options': ['--disable-e10s'], 05:32:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:46 INFO - 'talos_options': ['--disable-e10s'], 05:32:46 INFO - 'tests': ['damp', 'tps']}, 05:32:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:46 INFO - 'tests': ['damp', 'tps']}, 05:32:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:32:46 INFO - 'tests': ['dromaeo_dom']}, 05:32:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:32:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:32:46 INFO - 'tests': ['basic_compositor_video']}, 05:32:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:32:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:32:46 INFO - 'tests': ['a11yr', 05:32:46 INFO - 'ts_paint', 05:32:46 INFO - 'tpaint', 05:32:46 INFO - 'sessionrestore', 05:32:46 INFO - 'sessionrestore_no_auto_restore', 05:32:46 INFO - 'tabpaint']}, 05:32:46 INFO - 'other-e10s': {'tests': ['a11yr', 05:32:46 INFO - 'ts_paint', 05:32:46 INFO - 'tpaint', 05:32:46 INFO - 'sessionrestore', 05:32:46 INFO - 'sessionrestore_no_auto_restore', 05:32:46 INFO - 'tabpaint']}, 05:32:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:32:46 INFO - 'ts_paint', 05:32:46 INFO - 'tpaint', 05:32:46 INFO - 'sessionrestore', 05:32:46 INFO - 'sessionrestore_no_auto_restore', 05:32:46 INFO - 'tabpaint']}, 05:32:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:32:46 INFO - 'ts_paint', 05:32:46 INFO - 'tpaint', 05:32:46 INFO - 'sessionrestore', 05:32:46 INFO - 'sessionrestore_no_auto_restore', 05:32:46 INFO - 'tabpaint']}, 05:32:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:32:46 INFO - 'tests': ['a11yr', 05:32:46 INFO - 'ts_paint', 05:32:46 INFO - 'tpaint', 05:32:46 INFO - 'sessionrestore', 05:32:46 INFO - 'sessionrestore_no_auto_restore', 05:32:46 INFO - 'tabpaint']}, 05:32:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:32:46 INFO - 'tests': ['a11yr', 05:32:46 INFO - 'ts_paint', 05:32:46 INFO - 'tpaint', 05:32:46 INFO - 'sessionrestore', 05:32:46 INFO - 'sessionrestore_no_auto_restore', 05:32:46 INFO - 'tabpaint']}, 05:32:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:32:46 INFO - 'tests': ['tsvgx', 05:32:46 INFO - 'tsvgr_opacity', 05:32:46 INFO - 'tart', 05:32:46 INFO - 'tscrollx', 05:32:46 INFO - 'cart']}, 05:32:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:32:46 INFO - 'tsvgr_opacity', 05:32:46 INFO - 'tart', 05:32:46 INFO - 'tscrollx', 05:32:46 INFO - 'cart']}, 05:32:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:46 INFO - 'talos_options': ['--disable-e10s'], 05:32:46 INFO - 'tests': ['tp5o']}, 05:32:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:46 INFO - 'tests': ['tp5o']}, 05:32:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:46 INFO - 'talos_options': ['--disable-e10s', 05:32:46 INFO - '--xperf_path', 05:32:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:46 INFO - 'tests': ['tp5n']}, 05:32:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:46 INFO - 'talos_options': ['--xperf_path', 05:32:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:46 INFO - 'tests': ['tp5n']}}, 05:32:46 INFO - 'talos.zip': {'path': '', 05:32:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:32:46 INFO - Running post-action listener: _resource_record_post_action 05:32:46 INFO - [mozharness: 2016-09-08 12:32:46.969656Z] Finished populate-webroot step (success) 05:32:46 INFO - [mozharness: 2016-09-08 12:32:46.969748Z] Running create-virtualenv step. 05:32:46 INFO - Running pre-action listener: _resource_record_pre_action 05:32:46 INFO - Running main action method: create_virtualenv 05:32:46 INFO - Creating virtualenv /builds/slave/test/build/venv 05:32:46 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 05:32:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:32:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:32:47 INFO - Using real prefix '/tools/python27' 05:32:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:32:47 INFO - Installing distribute.............................................................................................................................................................................................done. 05:32:50 INFO - Installing pip.................done. 05:32:50 INFO - Return code: 0 05:32:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:32: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')]} 05:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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')]} 05:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:50 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 0x10c64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d3ac8e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2d30>, '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 0x10c317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aab20>, '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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:32:50 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 05:32:50 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 05:32:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7JpAZmlKkZ/Render', 05:32:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:50 INFO - 'HOME': '/Users/cltbld', 05:32:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:50 INFO - 'LOGNAME': 'cltbld', 05:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:50 INFO - 'MOZ_NO_REMOTE': '1', 05:32:50 INFO - 'NO_EM_RESTART': '1', 05:32:50 INFO - 'PAGER': '/bin/cat', 05:32:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:50 INFO - 'PWD': '/builds/slave/test', 05:32:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:50 INFO - 'SHELL': '/bin/bash', 05:32:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.moLiIly5Qm/Listeners', 05:32:50 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:32:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:50 INFO - 'USER': 'cltbld', 05:32:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:50 INFO - 'XPC_FLAGS': '0x0', 05:32:50 INFO - 'XPC_SERVICE_NAME': '0', 05:32:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:50 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 05:32:50 INFO - Cleaning up... 05:32:50 INFO - Return code: 0 05:32:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:32: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')]} 05:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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')]} 05:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:50 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 0x10c64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d3ac8e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2d30>, '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 0x10c317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aab20>, '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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:32:50 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 05:32:50 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 05:32:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7JpAZmlKkZ/Render', 05:32:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:50 INFO - 'HOME': '/Users/cltbld', 05:32:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:50 INFO - 'LOGNAME': 'cltbld', 05:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:50 INFO - 'MOZ_NO_REMOTE': '1', 05:32:50 INFO - 'NO_EM_RESTART': '1', 05:32:50 INFO - 'PAGER': '/bin/cat', 05:32:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:50 INFO - 'PWD': '/builds/slave/test', 05:32:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:50 INFO - 'SHELL': '/bin/bash', 05:32:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.moLiIly5Qm/Listeners', 05:32:50 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:32:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:50 INFO - 'USER': 'cltbld', 05:32:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:50 INFO - 'XPC_FLAGS': '0x0', 05:32:50 INFO - 'XPC_SERVICE_NAME': '0', 05:32:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:51 INFO - Downloading/unpacking psutil>=3.1.1 05:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:32:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:54 INFO - Installing collected packages: psutil 05:32:54 INFO - Running setup.py install for psutil 05:32:54 INFO - building 'psutil._psutil_osx' extension 05:32:54 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 05:32:54 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 05:32:54 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 05:32:55 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 05:32:55 INFO - building 'psutil._psutil_posix' extension 05:32:55 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 05:32:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:32:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:32:55 INFO - ^ 05:32:55 INFO - 1 warning generated. 05:32:55 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 05:32:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:55 INFO - Successfully installed psutil 05:32:55 INFO - Cleaning up... 05:32:55 INFO - Return code: 0 05:32:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d3ac8e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2d30>, '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 0x10c317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aab20>, '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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:32:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:32:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:32:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7JpAZmlKkZ/Render', 05:32:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:55 INFO - 'HOME': '/Users/cltbld', 05:32:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:55 INFO - 'LOGNAME': 'cltbld', 05:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:55 INFO - 'MOZ_NO_REMOTE': '1', 05:32:55 INFO - 'NO_EM_RESTART': '1', 05:32:55 INFO - 'PAGER': '/bin/cat', 05:32:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:55 INFO - 'PWD': '/builds/slave/test', 05:32:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:55 INFO - 'SHELL': '/bin/bash', 05:32:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.moLiIly5Qm/Listeners', 05:32:55 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:32:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:55 INFO - 'USER': 'cltbld', 05:32:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:55 INFO - 'XPC_FLAGS': '0x0', 05:32:55 INFO - 'XPC_SERVICE_NAME': '0', 05:32:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:32:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:32:58 INFO - Installing collected packages: mozsystemmonitor 05:32:58 INFO - Running setup.py install for mozsystemmonitor 05:32:58 INFO - Successfully installed mozsystemmonitor 05:32:58 INFO - Cleaning up... 05:32:58 INFO - Return code: 0 05:32:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:32:58 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')]} 05:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:58 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')]} 05:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:58 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 0x10c64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d3ac8e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2d30>, '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 0x10c317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aab20>, '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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:32:58 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 05:32:58 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 05:32:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7JpAZmlKkZ/Render', 05:32:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:58 INFO - 'HOME': '/Users/cltbld', 05:32:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:58 INFO - 'LOGNAME': 'cltbld', 05:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:58 INFO - 'MOZ_NO_REMOTE': '1', 05:32:58 INFO - 'NO_EM_RESTART': '1', 05:32:58 INFO - 'PAGER': '/bin/cat', 05:32:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:58 INFO - 'PWD': '/builds/slave/test', 05:32:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:58 INFO - 'SHELL': '/bin/bash', 05:32:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.moLiIly5Qm/Listeners', 05:32:58 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:32:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:58 INFO - 'USER': 'cltbld', 05:32:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:58 INFO - 'XPC_FLAGS': '0x0', 05:32:58 INFO - 'XPC_SERVICE_NAME': '0', 05:32:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:58 INFO - Downloading/unpacking jsonschema==2.5.1 05:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:33:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:33:01 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 05:33:01 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 05:33:01 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 05:33:01 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 05:33:01 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 05:33:01 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 05:33:01 INFO - Downloading functools32-3.2.3-2.tar.gz 05:33:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:33:01 INFO - warning: no files found matching '*.txt' 05:33:01 INFO - no previously-included directories found matching 'build' 05:33:01 INFO - no previously-included directories found matching 'dist' 05:33:01 INFO - no previously-included directories found matching '.git*' 05:33:01 INFO - Installing collected packages: jsonschema, functools32 05:33:01 INFO - Running setup.py install for functools32 05:33:01 INFO - warning: no files found matching '*.txt' 05:33:01 INFO - no previously-included directories found matching 'build' 05:33:01 INFO - no previously-included directories found matching 'dist' 05:33:01 INFO - no previously-included directories found matching '.git*' 05:33:01 INFO - Successfully installed jsonschema functools32 05:33:01 INFO - Cleaning up... 05:33:01 INFO - Return code: 0 05:33:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d3ac8e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2d30>, '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 0x10c317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aab20>, '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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:33:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:33:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7JpAZmlKkZ/Render', 05:33:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:01 INFO - 'HOME': '/Users/cltbld', 05:33:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:01 INFO - 'LOGNAME': 'cltbld', 05:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:01 INFO - 'MOZ_NO_REMOTE': '1', 05:33:01 INFO - 'NO_EM_RESTART': '1', 05:33:01 INFO - 'PAGER': '/bin/cat', 05:33:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:01 INFO - 'PWD': '/builds/slave/test', 05:33:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:01 INFO - 'SHELL': '/bin/bash', 05:33:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.moLiIly5Qm/Listeners', 05:33:01 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:33:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:01 INFO - 'USER': 'cltbld', 05:33:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:01 INFO - 'XPC_FLAGS': '0x0', 05:33:01 INFO - 'XPC_SERVICE_NAME': '0', 05:33:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:33:02 INFO - Cleaning up... 05:33:02 INFO - Return code: 0 05:33:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:33:02 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')]} 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:02 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')]} 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:02 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 0x10c64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d3ac8e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2d30>, '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 0x10c317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aab20>, '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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:33:02 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 05:33:02 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 05:33:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7JpAZmlKkZ/Render', 05:33:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:02 INFO - 'HOME': '/Users/cltbld', 05:33:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:02 INFO - 'LOGNAME': 'cltbld', 05:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:02 INFO - 'MOZ_NO_REMOTE': '1', 05:33:02 INFO - 'NO_EM_RESTART': '1', 05:33:02 INFO - 'PAGER': '/bin/cat', 05:33:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:02 INFO - 'PWD': '/builds/slave/test', 05:33:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:02 INFO - 'SHELL': '/bin/bash', 05:33:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.moLiIly5Qm/Listeners', 05:33:02 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:33:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:02 INFO - 'USER': 'cltbld', 05:33:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:02 INFO - 'XPC_FLAGS': '0x0', 05:33:02 INFO - 'XPC_SERVICE_NAME': '0', 05:33:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:02 INFO - Downloading/unpacking blobuploader==1.2.4 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:33:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:33:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:33:04 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 05:33:04 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 05:33:04 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 05:33:04 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 05:33:04 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 05:33:04 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 05:33:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:33:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:33:05 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 05:33:05 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 05:33:05 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 05:33:05 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 05:33:05 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 05:33:05 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 05:33:06 INFO - Downloading docopt-0.6.1.tar.gz 05:33:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:33:06 INFO - Installing collected packages: blobuploader, requests, docopt 05:33:06 INFO - Running setup.py install for blobuploader 05:33:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:33:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:33:06 INFO - Running setup.py install for requests 05:33:06 INFO - Running setup.py install for docopt 05:33:06 INFO - Successfully installed blobuploader requests docopt 05:33:06 INFO - Cleaning up... 05:33:06 INFO - Return code: 0 05:33:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33:06 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')]} 05:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:06 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')]} 05:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:06 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 0x10c64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d3ac8e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2d30>, '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 0x10c317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aab20>, '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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:33:06 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 05:33:06 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 05:33:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7JpAZmlKkZ/Render', 05:33:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:06 INFO - 'HOME': '/Users/cltbld', 05:33:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:06 INFO - 'LOGNAME': 'cltbld', 05:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:06 INFO - 'MOZ_NO_REMOTE': '1', 05:33:06 INFO - 'NO_EM_RESTART': '1', 05:33:06 INFO - 'PAGER': '/bin/cat', 05:33:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:06 INFO - 'PWD': '/builds/slave/test', 05:33:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:06 INFO - 'SHELL': '/bin/bash', 05:33:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.moLiIly5Qm/Listeners', 05:33:06 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:33:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:06 INFO - 'USER': 'cltbld', 05:33:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:06 INFO - 'XPC_FLAGS': '0x0', 05:33:06 INFO - 'XPC_SERVICE_NAME': '0', 05:33:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:33:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-DbyFCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:33:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-E1mK8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:33:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-PHbilH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:33:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-w56P_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:33:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-1rArEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:33:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-28qrWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:33:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Gxho9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:33:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-iE3qOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:33:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-GPeICm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:33:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-kLXvVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:33:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-gRSfOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:33:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-3z1h3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:33:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-K9dT1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:33:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-jMPAwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-eL6mUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:33:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-PsqYMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:33:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-219Xpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:33:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:33:09 INFO - Running setup.py install for manifestparser 05:33:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:33:09 INFO - Running setup.py install for mozcrash 05:33:09 INFO - Running setup.py install for mozdebug 05:33:09 INFO - Running setup.py install for mozdevice 05:33:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:33:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:33:09 INFO - Running setup.py install for mozfile 05:33:09 INFO - Running setup.py install for mozhttpd 05:33:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:33:09 INFO - Running setup.py install for mozinfo 05:33:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozInstall 05:33:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozleak 05:33:10 INFO - Running setup.py install for mozlog 05:33:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for moznetwork 05:33:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Running setup.py install for mozprocess 05:33:10 INFO - Running setup.py install for mozprofile 05:33:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:33:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:33:11 INFO - Running setup.py install for mozrunner 05:33:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:33:11 INFO - Running setup.py install for mozscreenshot 05:33:11 INFO - Running setup.py install for moztest 05:33:11 INFO - Running setup.py install for mozversion 05:33:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:33:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:33:11 INFO - Cleaning up... 05:33:11 INFO - Return code: 0 05:33:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:11 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 0x10c64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d3ac8e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2d30>, '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 0x10c317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aab20>, '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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:33:11 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 05:33:11 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 05:33:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7JpAZmlKkZ/Render', 05:33:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:11 INFO - 'HOME': '/Users/cltbld', 05:33:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:11 INFO - 'LOGNAME': 'cltbld', 05:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:11 INFO - 'MOZ_NO_REMOTE': '1', 05:33:11 INFO - 'NO_EM_RESTART': '1', 05:33:11 INFO - 'PAGER': '/bin/cat', 05:33:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:11 INFO - 'PWD': '/builds/slave/test', 05:33:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:11 INFO - 'SHELL': '/bin/bash', 05:33:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.moLiIly5Qm/Listeners', 05:33:11 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:33:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:11 INFO - 'USER': 'cltbld', 05:33:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:11 INFO - 'XPC_FLAGS': '0x0', 05:33:11 INFO - 'XPC_SERVICE_NAME': '0', 05:33:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:33:11 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-bfEfF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:33:12 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)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:33:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-GwGtmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:33:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-SN6Ogq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:33:12 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)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:33:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ahroRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:33:12 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)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:33:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-bitfL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:33:12 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)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:33:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-fmHlTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:33:12 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)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:33:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-aNYQop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:33:12 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)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:33:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-vDzayx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:33:12 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)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:33:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-hn6xkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:33:12 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)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:33:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-T0k980-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 05:33:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:33:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-gFM5EE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:33:13 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)) 05:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:33:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-lZUyr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:33:13 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)) 05:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:33:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-hayXFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:33:13 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)) 05:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:33:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-5vCfhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:33:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 05:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-IkK0Gt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:33:13 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)) 05:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:33:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ZajJsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:33:13 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)) 05:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:33:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ViIyuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:33:13 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)) 05:33:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:33:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:33:13 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)) 05:33:13 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)) 05:33:13 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)) 05:33:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:16 INFO - Downloading blessings-1.6.tar.gz 05:33:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:33:16 INFO - Installing collected packages: blessings 05:33:16 INFO - Running setup.py install for blessings 05:33:16 INFO - Successfully installed blessings 05:33:16 INFO - Cleaning up... 05:33:16 INFO - Return code: 0 05:33:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:33:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:33:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:33:16 INFO - Reading from file tmpfile_stdout 05:33:16 INFO - Current package versions: 05:33:16 INFO - blessings == 1.6 05:33:16 INFO - blobuploader == 1.2.4 05:33:16 INFO - docopt == 0.6.1 05:33:16 INFO - functools32 == 3.2.3-2 05:33:16 INFO - jsonschema == 2.5.1 05:33:16 INFO - manifestparser == 1.1 05:33:16 INFO - mozInstall == 1.12 05:33:16 INFO - mozcrash == 0.17 05:33:16 INFO - mozdebug == 0.1 05:33:16 INFO - mozdevice == 0.48 05:33:16 INFO - mozfile == 1.2 05:33:16 INFO - mozhttpd == 0.7 05:33:16 INFO - mozinfo == 0.9 05:33:16 INFO - mozleak == 0.1 05:33:16 INFO - mozlog == 3.3 05:33:16 INFO - moznetwork == 0.27 05:33:16 INFO - mozprocess == 0.23 05:33:16 INFO - mozprofile == 0.28 05:33:16 INFO - mozrunner == 6.12 05:33:16 INFO - mozscreenshot == 0.1 05:33:16 INFO - mozsystemmonitor == 0.3 05:33:16 INFO - moztest == 0.7 05:33:16 INFO - mozversion == 1.4 05:33:16 INFO - psutil == 3.1.1 05:33:16 INFO - requests == 1.2.3 05:33:16 INFO - wsgiref == 0.1.2 05:33:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:33:16 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')]} 05:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:16 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')]} 05:33:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:16 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 0x10c64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d3ac8e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2d30>, '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 0x10c317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aab20>, '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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:33:16 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 05:33:16 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 05:33:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7JpAZmlKkZ/Render', 05:33:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:16 INFO - 'HOME': '/Users/cltbld', 05:33:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:16 INFO - 'LOGNAME': 'cltbld', 05:33:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:16 INFO - 'MOZ_NO_REMOTE': '1', 05:33:16 INFO - 'NO_EM_RESTART': '1', 05:33:16 INFO - 'PAGER': '/bin/cat', 05:33:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:16 INFO - 'PWD': '/builds/slave/test', 05:33:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:16 INFO - 'SHELL': '/bin/bash', 05:33:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.moLiIly5Qm/Listeners', 05:33:16 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:33:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:16 INFO - 'USER': 'cltbld', 05:33:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:16 INFO - 'XPC_FLAGS': '0x0', 05:33:16 INFO - 'XPC_SERVICE_NAME': '0', 05:33:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:17 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)) 05:33:17 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)) 05:33:17 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)) 05:33:17 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)) 05:33:17 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)) 05:33:17 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)) 05:33:17 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)) 05:33:17 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)) 05:33:17 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)) 05:33:17 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)) 05:33:17 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)) 05:33:17 INFO - Cleaning up... 05:33:17 INFO - Return code: 0 05:33:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:17 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 0x10c64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d3ac8e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2d30>, '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 0x10c317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aab20>, '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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 05:33:17 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 05:33:17 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 05:33:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7JpAZmlKkZ/Render', 05:33:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:17 INFO - 'HOME': '/Users/cltbld', 05:33:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:17 INFO - 'LOGNAME': 'cltbld', 05:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:17 INFO - 'MOZ_NO_REMOTE': '1', 05:33:17 INFO - 'NO_EM_RESTART': '1', 05:33:17 INFO - 'PAGER': '/bin/cat', 05:33:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:17 INFO - 'PWD': '/builds/slave/test', 05:33:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:17 INFO - 'SHELL': '/bin/bash', 05:33:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.moLiIly5Qm/Listeners', 05:33:17 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:33:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:17 INFO - 'USER': 'cltbld', 05:33:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:17 INFO - 'XPC_FLAGS': '0x0', 05:33:17 INFO - 'XPC_SERVICE_NAME': '0', 05:33:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:33:17 INFO - Cleaning up... 05:33:17 INFO - Return code: 0 05:33:17 INFO - Running post-action listener: _resource_record_post_action 05:33:17 INFO - Running post-action listener: _start_resource_monitoring 05:33:17 INFO - Starting resource monitoring. 05:33:17 INFO - [mozharness: 2016-09-08 12:33:17.361767Z] Finished create-virtualenv step (success) 05:33:17 INFO - [mozharness: 2016-09-08 12:33:17.362664Z] Running install step. 05:33:17 INFO - Running pre-action listener: _resource_record_pre_action 05:33:17 INFO - Running main action method: install 05:33:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:33:17 INFO - Reading from file tmpfile_stdout 05:33:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:33:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:33:17 INFO - Reading from file tmpfile_stdout 05:33:17 INFO - Output received: 05:33:17 INFO - Usage: mozinstall [options] installer 05:33:17 INFO - Options: 05:33:17 INFO - -h, --help show this help message and exit 05:33:17 INFO - -d DEST, --destination=DEST 05:33:17 INFO - Directory to install application into. [default: 05:33:17 INFO - "/builds/slave/test"] 05:33:17 INFO - --app=APP Application being installed. [default: firefox] 05:33:17 INFO - mkdir: /builds/slave/test/build/application 05:33:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:33:52 INFO - Reading from file tmpfile_stdout 05:33:52 INFO - Output received: 05:33:52 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:33:52 INFO - Running post-action listener: _resource_record_post_action 05:33:52 INFO - [mozharness: 2016-09-08 12:33:52.080081Z] Finished install step (success) 05:33:52 INFO - [mozharness: 2016-09-08 12:33:52.080231Z] Running run-tests step. 05:33:52 INFO - Running pre-action listener: _resource_record_pre_action 05:33:52 INFO - Running main action method: run_tests 05:33:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:33:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:33:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:33:52 INFO - 2016-09-08 05:33:52.094 screenresolution[1639:7687] starting screenresolution argv=screenresolution get 05:33:52 INFO - 2016-09-08 05:33:52.097 screenresolution[1639:7687] Display 0: 1600x1200x32@60 05:33:52 INFO - 2016-09-08 05:33:52.102 screenresolution[1640:7690] starting screenresolution argv=screenresolution list 05:33:52 INFO - Available Modes on Display 0 05:33:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:33:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:33:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:33:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:33:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:33:52 INFO - Intel Iris: 05:33:52 INFO - Chipset Model: Intel Iris 05:33:52 INFO - Type: GPU 05:33:52 INFO - Bus: Built-In 05:33:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:33:52 INFO - Vendor: Intel (0x8086) 05:33:52 INFO - Device ID: 0x0a2e 05:33:52 INFO - Revision ID: 0x0009 05:33:52 INFO - Displays: 05:33:52 INFO - Display: 05:33:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:33:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:33:52 INFO - Display Serial Number: 1600x1200 60 05:33:52 INFO - Main Display: Yes 05:33:52 INFO - Mirror: Off 05:33:52 INFO - Online: Yes 05:33:52 INFO - Rotation: Supported 05:33:52 INFO - Return code: 0 05:33:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:33:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:33:52 INFO - Python 2.7.3 05:33:52 INFO - Return code: 0 05:33:52 INFO - grabbing minidump binary from tooltool 05:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:52 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5b2d30>, '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 0x10c317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5aab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:33:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:33:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:33:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:33:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:33:52 INFO - Return code: 0 05:33:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:33:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:33:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:33:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:33:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:33:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 05:33:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0086 --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 05:33:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7JpAZmlKkZ/Render', 05:33:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:52 INFO - 'HOME': '/Users/cltbld', 05:33:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:52 INFO - 'LOGNAME': 'cltbld', 05:33:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:33:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:52 INFO - 'MOZ_NO_REMOTE': '1', 05:33:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:33:52 INFO - 'NO_EM_RESTART': '1', 05:33:52 INFO - 'PAGER': '/bin/cat', 05:33:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:52 INFO - 'PWD': '/builds/slave/test', 05:33:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:33:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:52 INFO - 'SHELL': '/bin/bash', 05:33:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.moLiIly5Qm/Listeners', 05:33:52 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 05:33:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:52 INFO - 'USER': 'cltbld', 05:33:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:52 INFO - 'XPC_FLAGS': '0x0', 05:33:52 INFO - 'XPC_SERVICE_NAME': '0', 05:33:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 05:33:52 INFO - mozversion application_buildid: 20160908030434 05:33:52 INFO - mozversion application_changeset: 938ce16be25f9c551c19ef8938e8717ed3d41ff5 05:33:52 INFO - mozversion application_display_name: Nightly 05:33:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:33:52 INFO - mozversion application_name: Firefox 05:33:52 INFO - mozversion application_remotingname: firefox 05:33:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:33:52 INFO - mozversion application_vendor: Mozilla 05:33:52 INFO - mozversion application_version: 51.0a1 05:33:52 INFO - mozversion platform_buildid: 20160908030434 05:33:52 INFO - mozversion platform_changeset: 938ce16be25f9c551c19ef8938e8717ed3d41ff5 05:33:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:33:52 INFO - mozversion platform_version: 51.0a1 05:33:52 INFO - using testdate: 1473338032 05:33:52 INFO - actual date: 1473338032 05:33:52 INFO - starting webserver on 'localhost:49279' 05:33:52 INFO - SUITE-START | Running 2 tests 05:33:52 INFO - TEST-START | dromaeo_css 05:33:52 INFO - operating with platform_type : mac_ 05:33:52 INFO - Initialising browser for dromaeo_css test... 05:33:52 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpuBBc02/profile http://localhost:49279/getInfo.html) 05:33:56 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:33:56 INFO - PROCESS | 1650 | colorDepth:24 05:33:56 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:33:56 INFO - PROCESS | 1650 | __metrics 05:33:56 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 05:33:56 INFO - TEST-INFO | 1650: exit 0 05:33:56 INFO - Browser initialized. 05:33:56 INFO - Running cycle 1/1 for dromaeo_css test... 05:33:56 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpuBBc02/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:34:08 INFO - PROCESS | 1654 | RSS: Main: 243957760 05:34:08 INFO - PROCESS | 1654 | 05:34:58 INFO - PROCESS | 1654 | 1473338098469 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] 05:37:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 05:37:11 INFO - PROCESS | 1654 | RSS: Main: 264167424 05:37:11 INFO - PROCESS | 1654 | 05:40:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 05:40:14 INFO - PROCESS | 1654 | RSS: Main: 271249408 05:40:14 INFO - PROCESS | 1654 | 05:43:16 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 05:43:17 INFO - PROCESS | 1654 | RSS: Main: 274997248 05:43:17 INFO - PROCESS | 1654 | 05:46:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 05:46:19 INFO - PROCESS | 1654 | RSS: Main: 272297984 05:46:19 INFO - PROCESS | 1654 | 05:49:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 05:49:23 INFO - PROCESS | 1654 | RSS: Main: 277692416 05:49:23 INFO - PROCESS | 1654 | 05:52:25 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 05:52:25 INFO - PROCESS | 1654 | RSS: Main: 280526848 05:52:25 INFO - PROCESS | 1654 | 05:52:25 INFO - PROCESS | 1654 | __start_tp_report 05:52:25 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 05:52:25 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 05:52:25 INFO - PROCESS | 1654 | |i|pagename|runs| 05:52:25 INFO - PROCESS | 1654 | |0;dojo.html;2970.7920445568807;3373.79757214567;3377.814220217887;3398.3373242217776;3441.7590768646187;4208.863606826155;5503.559715222783;5615.213870058195;5648.124540696199;5747.42525747425;5635.943640563593;5645.266115404992;5669.9433005669935;5685.317761868581;5688.63023903446;5121.052605268028;5196.688198708071;5237.528622423981;5276.498732620415;5324.547413469841;5097.515736005073;5116.130257856174;5161.587073034167;5198.168293073109;5207.661502002357;5199.168133098724;5214.217867319932;5215.478452154792;5217.295665085203;5219.42586315505;5175.585953123778;5196.714180720059;5213.244079608474;5216.504432078947;5225.033368826779;5025.924611130836;5085.338905942203;5166.457521960216;5181.041507321158;5206.479352064801;5615.157726341041;5686.090225563931;5693.629914055573;5698.7435565399655;5757.769689212427;6185.41238582334;6200.410960958701;6224.751009959597;6226.40849119333;6232.719527621268;6105.763021687103;6211.068339749028;6232.9065064023625;6257.499400048031;6293.433590976834;5717.399673034354;5786.768529258825;5791.971040144772;5795.391483894215;5833.82498525045;6285;6306.085617585516;6326.145970293951;6340.587861788969;6385.808425747235;5909.8227053188475;5978.133170690226;6032.517398608101;6100.572959892765;6121.816345509642;5876.353601103875;6182.041783523561;6190.164327815687;6200.348963358872;6204.81385558434;6064.617589680339;6088.391160883876;6102.877942441126;6114.052321890114;6118.510519158456;5994.6403215807195;6063.999440092335;6106.816795496142;6148.600340977822;6154.661493617805;4952.257161425815;4957.132501812169;4959.107360675113;4973.626977976666;4995.553068382805;3698.537682789651;4447.555244475527;4456.799444025027;4478.48497422794;4487.753173575419;5583.022970980062;5665.291838520185;5778.3643799182055;5796.362400135981;5805.767769289266;6164.907526387108;6285.088662143963;6287.2140982377205;6289.496840252768;6302.590331628521;4983.729149066965;5790.884182316414;5798.478136967693;5821.79623713168;5833.066709326487;5316.6544174628525;5377.78488860449;5389.407165211824;5417;5450.209719590637;6254.124422580933;6260.7495700171485;6268.968655156695;6273.529485288622;6274.6548939808745;5488.792465657549;6021.948693435288;6285.748570057147;6309.400606942333;6313.6843157841;5733.426657334233;5841.890040892217;6248;6267.373262673696;6300.086487459384;6114.449440885012;6170.043643235305;6178.969105154445;6189.5048396129105;6201.937980620317;4524.751138687404;5265.0259701955265;5802.897449484584;6246.968765156145;6262.373762623702;4624.7687615618415;6006.819795406145;6043.4258745420875;6063.818085457443;6074.665893375906;6067.605605635619;6072.514198863992;6085.056816193497;6098.359672234352;6115.908261375994;5095.6241814710565;5725.914111288417;5924.377940316204;5926.703664816656;5991.490723288395;5909.6158749681135;5988.790392336247;6017.729202185999;6027.276726792813;6078.878422431439;5934.317553481233;5945.494632956247;5952.136940143741;5955.106733989936;5982.760689572542 05:52:25 INFO - PROCESS | 1654 | |1;ext.html;485.800821663118;501.7742016092758;517.7100823538816;521.2754271562529;533.9161502150636;36980.07549811253;39263.80368098159;39366.8031659842;39373.212535749306;39497.01257468565;39569.60430395695;39996;40039;40102.39846402306;40146.79559613209;9377.624895004192;9588.664396746115;9601.615935362577;9611.759706007371;9628.037196280386;9528.09483099108;9617.567209475556;9643.566039528203;9667.468289244089;9676.806463870718;9672.516374181298;9703.805923881519;9712.174465170468;9723.076307750753;9732.902670973306;9639.518024098767;9679.709608711715;9681.177099946442;9686.176674982635;9711.028897110304;9480.099390557885;9503.524823758784;9515.762105947337;9524.761880952963;9527.237820974306;37936;38332.80833595842;38439.23121537582;38655.840324790304;38822.61177388246;3217.809410518123;3360.0927749507496;3757.229767897574;3959.1883663848844;4001.899952501182;29435.70564294351;30221.546676799866;30567.541486877715;30607;30958.84520577383;3236.3689080629288;3245.513173024042;3254.1539199807985;3300.719438847701;3346.3474622448634;433958;442956.9999999968;445159;448745;448846;56424;56505.71747141238;56711.43288567144;56797.7160114205;56948.430515695145;33594.664053359644;33710.32579348399;34129.31741365159;35179;35249.64750352463;15989.12059836697;16187.271572779253;16197.4330898418;16201.946873453187;16207.270672819752;12203.572874949334;12371.257724536557;12454.128211025323;12470.376481175905;12501.687457813445;16514.174291285628;17336.913315433343;18676.34632787846;18698.06509674538;18700.877947323203;7721.8455630887065;7808.336291415293;7825;7833.608319583998;7908.565028923349;883.960221790021;979.7991411760572;1069.8555694981078;1090.465671820818;1133.1444759206747;24497.42777008424;27111;29354;30336.54495182574;30421;895.5611750242334;962.1821451766083;1063.0538820449806;1066.4987455895716;1135.6763322453062;14836.62908427298;14875.851241487662;15379.692406151815;15605.141717205663;15831;622772;699926;705619;705918.1153225024;897005.514972421;46984.530154698696;56750.43249567534;57320;57337.713311433174;58611.70694146502;1261.6215135459327;1357.4841560207058;1362.523116909074;1398.272898092986;1398.384710727297;26141.477170456863;27260.774579940142;29946.401071978;30323.241918952204;30417.288887345327;48877;48949.2657610129;48956.53130406093;49080.01839963252;49141.508584913696;470183;480645;482279;482880;484186;25387.238382848544;25679.229623111336;26031.869840650674;26064.609030864172;26771.86614066917;43758.56241437545;44904.101917960805;45210.32184517169;45683.77158114188;47440.28839567478;34018.489722654675;38154.80922595369;38268;38371;39167.020824479616;36209.27581448409;37893.81053094717;38139.61860381361;39065.609343906195;39218.411723824735;37563.55836870595;37739.62260377471;38117.618823811405;38462.615373847024;39509.40735889022;30910.69089309168;33112.84105056312;33879.49180762241;34130.317393651494;34248.14379640529;24735.62896556589;25119.623205651566;25190.62214066827;25547.616785747858;25677.229683109537 05:52:25 INFO - PROCESS | 1654 | |2;jquery.html;319.72184199746215;366.532670844673;370.68862155789134;379.4801122462227;397.2095529895509;19730.01349932503;20118.89940550297;20706.482337941557;20848.166073357086;20857.895710521425;20196.990150492453;20295.188192472284;20761.065752041155;20789.584208315824;21196.682049769264;5257.658252213613;5702.059160238556;5993.640381577108;6251.12484252205;6251.812445626639;6145.539084568675;6160.353162917896;6180.783672571461;6190.81427557174;6196.69016549173;5875.794347197849;6098.390160983889;6124.387561243884;6141.447269745722;6149.631022138664;5779.393163717791;6063.333033366326;6097.603655762384;6101.389861013908;6108.930937085992;4531.456225252958;5254.65844720092;5269.1832765921345;5272.551833094191;5294.179402192666;23618.763812362;25342;25407.364815879566;25496.23511294664;25693.614595781077;1996.0890121042628;2082.8437867160023;2187.496875718578;2252.256755270757;2606.89572417105;9840.966698496626;9925;10027.89972100277;10071.798564028679;10288.022637849392;1800.8829426087264;1801.918913648889;1822.9908850455663;1835.5502901789014;1927.3832373640441;111562.44218778855;116107;116639.41680291544;116682.83317166804;117330;10250.38497690141;10514.73713157162;11693.707657308465;11701.648950531498;11710.180287379972;14195.78706319406;14248.643783905278;14303.713925721428;14304.57086287413;14334.283285835667;7005.1944026436595;8097.2712455879255;8383.077861435237;8387.370947178986;8455.069942306342;6892.724845903524;7664.616769161519;8069.556174410346;8351.874721879176;8554.35842311829;4457.197704413236;4468.486124095771;4482.641388688897;4483.574060464251;4485.887852803706;1901.239504198304;1946.9805301947083;1950.2881448271562;1953.3358658056045;1959.3827944197533;701.7193621640527;857.7426771968385;860.3332417376458;933.6638810028385;950.4677380666849;16479;20096;21534.892325538272;22309.888450557966;22467.213647522585;883.8939327280767;921.7695576105973;941.9920684867263;960.2078285414422;971.6307803034942;10778.245522813328;10924.726881828017;11379.54481820718;11426.143039272089;11458.94270528642;574269;576544;586547;587133;587661;132833;133738;136199.31900340435;136919.31540342234;139283;923.7090316550265;1007.3250921882357;1011.185995273797;1033.4574348467115;1033.9224558158169;19076.1415736295;19126.521836954187;19439.3196238131;19509.902450487658;19551.826890386623;46217;48078.27882581694;52324.21513677222;55386;55812.883742325736;77829.61085194538;82785;83143.5842820782;83849.58075209585;84897.57551212204;6124.724387402489;7912.0505539335645;7914.16901225363;7954.5624990626075;8267.917320826955;10373.896261037595;12564.308963007123;12916.22502649844;13134.211947283193;13352.465901363837;8402.579871006303;11380.54477820911;11455.198136130392;11486.94256528712;12057.613374461698;9347.579358928728;9825.4104753715;11141.72145696364;11192.776144477228;11497.712557186138;9900.25748068912;11408.885911141115;11457.942710286396;11640.941795291308;11825.704357391134;7117.822054448473;7221.891671625035;7367.3369396752405;7403.333699967029;7497.212792656693;10764.138868890313;11290.887091128983;11321.71695707614;11627.883721163018;11719.531218751154 05:52:25 INFO - PROCESS | 1654 | |3;mootools.html;1509.486774496671;1537.8077740282465;1763.6208215233721;1861.972070418943;1891.4609336339147;8050.798730031737;8538.87191692125;8745.212930836224;9127.315451341152;9130.589123489442;8740.213380795727;8938.597763100659;8941.41880777749;8963.10368963105;8964.50695211763;2363.7045369328835;2368.5854975379325;2376.7385587585395;2412.6260429633435;2417.371483414316;2381.4284571702838;2384.7496012918655;2413.613821788514;2419.0686585664557;2419.1895714935517;2376.95246095078;2399.8680072596;2408.2654790288957;2409.8795060247007;2418.1657328221736;2372.1223147435558;2374.9643755343595;2379.4884099918513;2391.5695174868515;2398.70016247969;2349.6827928229814;2372;2398.1486572266767;2398.4603464220586;2399.2082612737754;9005.414648047856;9102.408343457653;9108.225800806873;9124.726258212264;9124.771880703036;5302.390225124122;5458;5465.453454654543;5465.836024919259;5485.61600687952;3338.5659864217496;3455.4125798614045;3662.7436079474182;3674.3018826422895;3746.9437958430644;1078.827387617985;1078.9406582637953;1092.9890701092968;1098.9889301842325;1104.1553211792984;11679;11810.996065334457;11906.642800715992;11933.403329833474;11955.342456164908;4319.956800431955;4606.355110284563;4714.104320179155;4851.611871050308;4920.950790492049;10023.599056037678;10125.493725313705;10130.442825644514;10140.391576505433;10146.188304935587;4698.976505117453;5435.510804027656;5438.347398312228;5440.455954404607;5473.452654734495;4277.337012763027;4447.955520444819;4781.354517140211;4997.725125118153;5002.2496625506465;3993.301172294895;4041.413994970713;4064.918701625951;4153.563875793058;4155.480564929384;1051.3954476176077;1092.6940456672144;1277.9329260067948;1301.8437787465564;1314.842218933734;791.3985371117992;891.3270480786964;895.3866601378077;921.7465197070725;942.8067246214647;5129.7178655173575;5755.65466072037;5797.130430435384;6059.6364218147055;6073.058675905242;439.83726021372297;446.97988590513506;453.81847261095163;454.8908262017092;461.95842374186486;3960.0793615096654;3987.4816273884207;4096.5903409658795;4104.753714777123;4116.415180155602;10557.47212639365;10853.457327133612;10862.511186996604;10869.782604347869;10911.454427278763;10602.25784195099;10632.25574209798;10761;10871.891281087088;10882.347059176474;8150.592470376458;8239.423240373237;8352.206540378655;8356.623951922178;8403.873941890877;5835.212246346859;5940.524758019435;5944.613600115978;5948.137520059654;5986.790462333797;9725.367851089657;10084.445355505517;10175.236857235588;10228.028337307944;10252.333598316085;10220.693379198636;10291.639792607222;10309.948450257702;10327.48362581883;10360.896391036295;6927.376536111774;7311.245301127585;7467.282525019167;7518.774436766906;7649.082110146818;8484.703035393732;8492.174182550782;8495.872561911452;8507.914920850711;8535.3598480113;6393.6483493408305;7558.546487210785;7606.277403646644;7620.580868052089;7667.846643067219;7363;7425.368843648134;7498.625068746432;7540.509866858637;7554;5532.529734972572;6972.7908162753165;7009.474289428212;7029.929700703132;7046.189688185924;5006.87482812918;5025.748712564284;5126.487351264844;5170.1986192140275;5172.793088276427;5085.745712714275;5135;5203.687778733137;5225.242339860775;5278.181881808326 05:52:25 INFO - PROCESS | 1654 | |4;prototype.html;291.87595272009406;299.1146207226609;305.3084763011778;306.4835751758286;307.1951487103788;5262.526372626463;5404.648697834638;5479.4794494523;5591.8322450326505;5596.692181929997;4212.515560710519;5055.292259083732;5431;5570.830125673603;5626.718664066791;3698.7965661888584;4210.3894935234375;4220;4226.175895700339;4229;3525.5416795816636;3680.8527658893613;3730.459083432914;4215.49414070312;4254.404383386328;3384.8984530464004;4043.2722110020186;4102.3026085565525;4127.463429754128;4137.586241375869;3592.1738000259957;3612.963870361289;3703.981480092609;4078.9802549362657;4082.122343696105;3355.882544110969;3552.0764601203614;3566;3578.6421357864265;3581.3732596795717;4728.621710263171;4993.450720420751;5109.540141387256;5255.054090263751;5435.157550579666;4659.951510910052;4674.859754207379;4677.158111539921;4689.742064186468;4695.906081878377;4921.827736029221;5746.569007324468;5767.769289228426;5774.855628609318;5824.388439213906;3371.157210697326;3571.4107172316535;3813.3707938190164;3877.9917221522323;3884.6698030667426;4709.293605959134;5411.648242864201;5985.940140598582;6097.810926869262;6168.537359698041;3601.6218297078954;3916.4321173429694;3927.5286965564314;3940.271049855786;3948.881533553998;3855.9421608675893;3889.86385476507;3953.288408086572;3969;3993.560708322082;3862.8261728222296;4639.953600463952;4665.836695715634;4679.508651591602;4695.436547614308;5126.256692779526;5815.41845815415;5833.387494313036;5888.676122813286;5916.372997425657;1058.2909450668071;1189.6668932698858;1266.803645434959;1341.745467987435;1349.6760777413351;880.300161371711;883.9734807955772;906.4425378392241;908.9772755681029;909.7452713240303;4883.876708357097;5379.946200537945;5409.56723462122;5433.495395469899;5448.645838020516;5254.527092561611;5695.582650409721;5846.953566251778;5871.383504731942;6031.638101713824;4901.09329773993;5009.373828271466;5073.797048118107;5377.247185394048;5394.811181608625;2547.783438407719;3115.8753649854207;3148.087054754096;3167.635721892012;3180.3321302526265;5133.974330128325;5221.295125158131;5704.34400043992;6174.135621013062;6211.565190436626;5807.251624423375;6113.541484388688;6153.600015998945;6185.577317217066;6204.875902481925;5499.890002199933;6213.720382582795;6223.439890409885;6236.906446403307;6238.656873871981;4784.736839473723;5325.653832500875;5745.597808153389;5966.642001479925;6034.879302414015;4797.760111994457;5879.882402352015;5926.496247818811;5940.6435613863305;5946.940530594812;5753.798617048383;6177.413145751147;6206.503479721521;6225.844353891189;6246.562740608114;3746.4193050077283;3752.3245815753426;4003.959960400359;4051.716379853382;4073.2260870434525;4858.5384388483035;5934.940650593611;5970.373110823301;6039.607425517327;6040.909386359121;5798.15926690619;5848.520130522333;5854.707264636836;5876.764929402776;5934.376890426443;5880.26496687914;5892.528597712087;5919.378465261086;5922.703864806656;5929.797909129022;5518.834434966958;5710.885782284248;5723.7996670116345;5751.194832723506;5859.619124756877;4716.952830471651;5407.513323800877;5896.734646940812;5899.292084949833;5933.554983376178;4841.782119804547;5401.459854014567;5767.365589785204;5788.855278618068;5808.9668615984065 05:52:25 INFO - PROCESS | 1654 | |5;yui.html;747.2751431111819;756.4629113329536;787.933025692816;801.8636831738604;840.8318336332735;13043.478260869553;13553.661158471043;14957.17735524547;15746.763798543017;15773.684526309467;12027;14065.789013164786;14173.433062677506;14308.284585770696;14325.570232893004;667.853072324088;820.6143112737004;843.9746807595782;865.6104752861206;870.3254977392539;842.1578421578422;845.1928408370022;859.5401460218784;860.513809697522;860.6557377049199;868.6785889220997;870.9738707838775;895.5432729308035;912.6805618033701;919.3426699909563;864.6627815152095;865.212656482601;865.4980111535294;904.764761162096;910.1444642036464;638.936106389362;646.9288378278433;669.2270427656016;671.3722669304167;675.9662016899135;15304.158271295075;15447.768283475756;15575.610609734735;15623.453179138676;15641.983271087343;12147;14275.14349139055;14291.642708932255;14383.712325753531;14716;11975.401229938469;11986.280823150639;11992.220505667105;11993.760124797454;12046.819297710541;3785.5268091488565;3801.733878628497;3819.312523745725;3833.8083095845095;3945.723799334019;112209.43895280472;112961;114494.85505144842;114608;115499;10986.670399888017;11033.282836615756;11039;11061.668149955514;11494.942525287488;22773.772262277496;22782.65826012611;22788.658170127463;22814.65778013331;22889.656655150186;7669.578173200527;7883.36933045355;8034.718784842619;8129.187081291823;8240;4246.384274280212;4364.41080454141;4463.308187230984;4522.9597192645215;4575.816967321336;3341.7159541438764;3549.716022718176;3578.409562422223;3595.3708101082207;3614.626441952041;644.3305375884294;646.6572716460283;651.9380658837403;651.9608823470606;662.6355504472521;632.5003247434568;644.3331152257391;662.3806740697368;674.4132604633902;697.6686074114755;7591.848163036819;8514.872276915852;8530.848335474755;9313;9576.952115239379;493.8444390017133;568.499720246189;589.2928485817037;669.5882032550044;675.5777638975641;4394.209042372404;4667.8599642010795;4682.789274482657;4683.320918466803;4711.222648263067;82640.58679706563;109523;133655.33172334076;134329;155762;111909.44045279884;112699.43650281696;114793.13017174638;118027.40986295014;119367;85050.5747471271;94466.52766736259;96196;98154;105595.4720226394;7980.920190798018;9681.135999760623;9715.07706767856;10013.94853540397;10145.797084058426;40698.79650601728;58804.529886753175;58982.70508647429;59176.112358313796;61050;61381.69309153426;62909;65786.67106664435;87939;88410;11363.431828408711;11510;11534.1926065178;11741.530338786353;13138.343082845628;14680.632984175481;14684.25863370795;14985.100893946397;15121.395144194548;15298.464553740565;13666.316684165553;13788.310584470937;13800.309984500534;14142.646433839236;14245.430182792572;13578.185308881499;13886.305684715926;14021.649458763204;14048;14058.086224395382;12883.42024608876;12962.481500740241;13316.267605281802;13326.800097998344;13616.114952528054;10950.014498695156;11054.447277636247;11066.944665276944;11322.37726924994;11449.313041217554;9920.61111444384;10204.744881378025;10730.356178628996;11246.325220486797;11646.94176529112 05:52:25 INFO - PROCESS | 1654 | __end_tp_report 05:52:25 INFO - PROCESS | 1654 | __start_cc_report 05:52:25 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,387 05:52:25 INFO - PROCESS | 1654 | __end_cc_report 05:52:25 INFO - PROCESS | 1654 | __startTimestamp1473339145773__endTimestamp 05:52:25 INFO - PROCESS | 1654 | 05:52:25 INFO - PROCESS | 1654 | ------- Summary: start ------- 05:52:25 INFO - PROCESS | 1654 | Number of tests: 6 05:52:25 INFO - PROCESS | 1654 | 05:52:25 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5664.26 Median:5833.82 stddev:660.98 (11.3%) stddev-sans-first:628.32 05:52:25 INFO - PROCESS | 1654 | Values: 2970.8 3373.8 3377.8 3398.3 3441.8 4208.9 5503.6 5615.2 5648.1 5747.4 5635.9 5645.3 5669.9 5685.3 5688.6 5121.1 5196.7 5237.5 5276.5 5324.5 5097.5 5116.1 5161.6 5198.2 5207.7 5199.2 5214.2 5215.5 5217.3 5219.4 5175.6 5196.7 5213.2 5216.5 5225.0 5025.9 5085.3 5166.5 5181.0 5206.5 5615.2 5686.1 5693.6 5698.7 5757.8 6185.4 6200.4 6224.8 6226.4 6232.7 6105.8 6211.1 6232.9 6257.5 6293.4 5717.4 5786.8 5792.0 5795.4 5833.8 6285.0 6306.1 6326.1 6340.6 6385.8 5909.8 5978.1 6032.5 6100.6 6121.8 5876.4 6182.0 6190.2 6200.3 6204.8 6064.6 6088.4 6102.9 6114.1 6118.5 5994.6 6064.0 6106.8 6148.6 6154.7 4952.3 4957.1 4959.1 4973.6 4995.6 3698.5 4447.6 4456.8 4478.5 4487.8 5583.0 5665.3 5778.4 5796.4 5805.8 6164.9 6285.1 6287.2 6289.5 6302.6 4983.7 5790.9 5798.5 5821.8 5833.1 5316.7 5377.8 5389.4 5417.0 5450.2 6254.1 6260.7 6269.0 6273.5 6274.7 5488.8 6021.9 6285.7 6309.4 6313.7 5733.4 5841.9 6248.0 6267.4 6300.1 6114.4 6170.0 6179.0 6189.5 6201.9 4524.8 5265.0 5802.9 6247.0 6262.4 4624.8 6006.8 6043.4 6063.8 6074.7 6067.6 6072.5 6085.1 6098.4 6115.9 5095.6 5725.9 5924.4 5926.7 5991.5 5909.6 5988.8 6017.7 6027.3 6078.9 5934.3 5945.5 5952.1 5955.1 5982.8 05:52:25 INFO - PROCESS | 1654 | 05:52:25 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:67305.06 Median:38944.11 stddev:152042.20 (390.4%) stddev-sans-first:152385.95 05:52:25 INFO - PROCESS | 1654 | Values: 485.8 501.8 517.7 521.3 533.9 36980.1 39263.8 39366.8 39373.2 39497.0 39569.6 39996.0 40039.0 40102.4 40146.8 9377.6 9588.7 9601.6 9611.8 9628.0 9528.1 9617.6 9643.6 9667.5 9676.8 9672.5 9703.8 9712.2 9723.1 9732.9 9639.5 9679.7 9681.2 9686.2 9711.0 9480.1 9503.5 9515.8 9524.8 9527.2 37936.0 38332.8 38439.2 38655.8 38822.6 3217.8 3360.1 3757.2 3959.2 4001.9 29435.7 30221.5 30567.5 30607.0 30958.8 3236.4 3245.5 3254.2 3300.7 3346.3 433958.0 442957.0 445159.0 448745.0 448846.0 56424.0 56505.7 56711.4 56797.7 56948.4 33594.7 33710.3 34129.3 35179.0 35249.6 15989.1 16187.3 16197.4 16201.9 16207.3 12203.6 12371.3 12454.1 12470.4 12501.7 16514.2 17336.9 18676.3 18698.1 18700.9 7721.8 7808.3 7825.0 7833.6 7908.6 884.0 979.8 1069.9 1090.5 1133.1 24497.4 27111.0 29354.0 30336.5 30421.0 895.6 962.2 1063.1 1066.5 1135.7 14836.6 14875.9 15379.7 15605.1 15831.0 622772.0 699926.0 705619.0 705918.1 897005.5 46984.5 56750.4 57320.0 57337.7 58611.7 1261.6 1357.5 1362.5 1398.3 1398.4 26141.5 27260.8 29946.4 30323.2 30417.3 48877.0 48949.3 48956.5 49080.0 49141.5 470183.0 480645.0 482279.0 482880.0 484186.0 25387.2 25679.2 26031.9 26064.6 26771.9 43758.6 44904.1 45210.3 45683.8 47440.3 34018.5 38154.8 38268.0 38371.0 39167.0 36209.3 37893.8 38139.6 39065.6 39218.4 37563.6 37739.6 38117.6 38462.6 39509.4 30910.7 33112.8 33879.5 34130.3 34248.1 24735.6 25119.6 25190.6 25547.6 25677.2 05:52:25 INFO - PROCESS | 1654 | 05:52:25 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34756.96 Median:25594.92 stddev:97598.66 (381.3%) stddev-sans-first:97838.20 05:52:25 INFO - PROCESS | 1654 | Values: 319.7 366.5 370.7 379.5 397.2 19730.0 20118.9 20706.5 20848.2 20857.9 20197.0 20295.2 20761.1 20789.6 21196.7 5257.7 5702.1 5993.6 6251.1 6251.8 6145.5 6160.4 6180.8 6190.8 6196.7 5875.8 6098.4 6124.4 6141.4 6149.6 5779.4 6063.3 6097.6 6101.4 6108.9 4531.5 5254.7 5269.2 5272.6 5294.2 23618.8 25342.0 25407.4 25496.2 25693.6 1996.1 2082.8 2187.5 2252.3 2606.9 9841.0 9925.0 10027.9 10071.8 10288.0 1800.9 1801.9 1823.0 1835.6 1927.4 111562.4 116107.0 116639.4 116682.8 117330.0 10250.4 10514.7 11693.7 11701.6 11710.2 14195.8 14248.6 14303.7 14304.6 14334.3 7005.2 8097.3 8383.1 8387.4 8455.1 6892.7 7664.6 8069.6 8351.9 8554.4 4457.2 4468.5 4482.6 4483.6 4485.9 1901.2 1947.0 1950.3 1953.3 1959.4 701.7 857.7 860.3 933.7 950.5 16479.0 20096.0 21534.9 22309.9 22467.2 883.9 921.8 942.0 960.2 971.6 10778.2 10924.7 11379.5 11426.1 11458.9 574269.0 576544.0 586547.0 587133.0 587661.0 132833.0 133738.0 136199.3 136919.3 139283.0 923.7 1007.3 1011.2 1033.5 1033.9 19076.1 19126.5 19439.3 19509.9 19551.8 46217.0 48078.3 52324.2 55386.0 55812.9 77829.6 82785.0 83143.6 83849.6 84897.6 6124.7 7912.1 7914.2 7954.6 8267.9 10373.9 12564.3 12916.2 13134.2 13352.5 8402.6 11380.5 11455.2 11486.9 12057.6 9347.6 9825.4 11141.7 11192.8 11497.7 9900.3 11408.9 11457.9 11640.9 11825.7 7117.8 7221.9 7367.3 7403.3 7497.2 10764.1 11290.9 11321.7 11627.9 11719.5 05:52:25 INFO - PROCESS | 1654 | 05:52:25 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5764.15 Median:2530.62 stddev:3264.59 (129.0%) stddev-sans-first:3258.09 05:52:25 INFO - PROCESS | 1654 | Values: 1509.5 1537.8 1763.6 1862.0 1891.5 8050.8 8538.9 8745.2 9127.3 9130.6 8740.2 8938.6 8941.4 8963.1 8964.5 2363.7 2368.6 2376.7 2412.6 2417.4 2381.4 2384.7 2413.6 2419.1 2419.2 2377.0 2399.9 2408.3 2409.9 2418.2 2372.1 2375.0 2379.5 2391.6 2398.7 2349.7 2372.0 2398.1 2398.5 2399.2 9005.4 9102.4 9108.2 9124.7 9124.8 5302.4 5458.0 5465.5 5465.8 5485.6 3338.6 3455.4 3662.7 3674.3 3746.9 1078.8 1078.9 1093.0 1099.0 1104.2 11679.0 11811.0 11906.6 11933.4 11955.3 4320.0 4606.4 4714.1 4851.6 4921.0 10023.6 10125.5 10130.4 10140.4 10146.2 4699.0 5435.5 5438.3 5440.5 5473.5 4277.3 4448.0 4781.4 4997.7 5002.2 3993.3 4041.4 4064.9 4153.6 4155.5 1051.4 1092.7 1277.9 1301.8 1314.8 791.4 891.3 895.4 921.7 942.8 5129.7 5755.7 5797.1 6059.6 6073.1 439.8 447.0 453.8 454.9 462.0 3960.1 3987.5 4096.6 4104.8 4116.4 10557.5 10853.5 10862.5 10869.8 10911.5 10602.3 10632.3 10761.0 10871.9 10882.3 8150.6 8239.4 8352.2 8356.6 8403.9 5835.2 5940.5 5944.6 5948.1 5986.8 9725.4 10084.4 10175.2 10228.0 10252.3 10220.7 10291.6 10309.9 10327.5 10360.9 6927.4 7311.2 7467.3 7518.8 7649.1 8484.7 8492.2 8495.9 8507.9 8535.4 6393.6 7558.5 7606.3 7620.6 7667.8 7363.0 7425.4 7498.6 7540.5 7554.0 5532.5 6972.8 7009.5 7029.9 7046.2 5006.9 5025.7 5126.5 5170.2 5172.8 5085.7 5135.0 5203.7 5225.2 5278.2 05:52:25 INFO - PROCESS | 1654 | 05:52:25 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4665.03 Median:5258.79 stddev:1480.72 (28.2%) stddev-sans-first:1448.04 05:52:25 INFO - PROCESS | 1654 | Values: 291.9 299.1 305.3 306.5 307.2 5262.5 5404.6 5479.5 5591.8 5596.7 4212.5 5055.3 5431.0 5570.8 5626.7 3698.8 4210.4 4220.0 4226.2 4229.0 3525.5 3680.9 3730.5 4215.5 4254.4 3384.9 4043.3 4102.3 4127.5 4137.6 3592.2 3613.0 3704.0 4079.0 4082.1 3355.9 3552.1 3566.0 3578.6 3581.4 4728.6 4993.5 5109.5 5255.1 5435.2 4660.0 4674.9 4677.2 4689.7 4695.9 4921.8 5746.6 5767.8 5774.9 5824.4 3371.2 3571.4 3813.4 3878.0 3884.7 4709.3 5411.6 5985.9 6097.8 6168.5 3601.6 3916.4 3927.5 3940.3 3948.9 3855.9 3889.9 3953.3 3969.0 3993.6 3862.8 4640.0 4665.8 4679.5 4695.4 5126.3 5815.4 5833.4 5888.7 5916.4 1058.3 1189.7 1266.8 1341.7 1349.7 880.3 884.0 906.4 909.0 909.7 4883.9 5379.9 5409.6 5433.5 5448.6 5254.5 5695.6 5847.0 5871.4 6031.6 4901.1 5009.4 5073.8 5377.2 5394.8 2547.8 3115.9 3148.1 3167.6 3180.3 5134.0 5221.3 5704.3 6174.1 6211.6 5807.3 6113.5 6153.6 6185.6 6204.9 5499.9 6213.7 6223.4 6236.9 6238.7 4784.7 5325.7 5745.6 5966.6 6034.9 4797.8 5879.9 5926.5 5940.6 5946.9 5753.8 6177.4 6206.5 6225.8 6246.6 3746.4 3752.3 4004.0 4051.7 4073.2 4858.5 5934.9 5970.4 6039.6 6040.9 5798.2 5848.5 5854.7 5876.8 5934.4 5880.3 5892.5 5919.4 5922.7 5929.8 5518.8 5710.9 5723.8 5751.2 5859.6 4717.0 5407.5 5896.7 5899.3 5933.6 4841.8 5401.5 5767.4 5788.9 5809.0 05:52:25 INFO - PROCESS | 1654 | 05:52:25 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:22790.70 Median:3700.08 stddev:35228.91 (952.1%) stddev-sans-first:35288.85 05:52:25 INFO - PROCESS | 1654 | Values: 747.3 756.5 787.9 801.9 840.8 13043.5 13553.7 14957.2 15746.8 15773.7 12027.0 14065.8 14173.4 14308.3 14325.6 667.9 820.6 844.0 865.6 870.3 842.2 845.2 859.5 860.5 860.7 868.7 871.0 895.5 912.7 919.3 864.7 865.2 865.5 904.8 910.1 638.9 646.9 669.2 671.4 676.0 15304.2 15447.8 15575.6 15623.5 15642.0 12147.0 14275.1 14291.6 14383.7 14716.0 11975.4 11986.3 11992.2 11993.8 12046.8 3785.5 3801.7 3819.3 3833.8 3945.7 112209.4 112961.0 114494.9 114608.0 115499.0 10986.7 11033.3 11039.0 11061.7 11494.9 22773.8 22782.7 22788.7 22814.7 22889.7 7669.6 7883.4 8034.7 8129.2 8240.0 4246.4 4364.4 4463.3 4523.0 4575.8 3341.7 3549.7 3578.4 3595.4 3614.6 644.3 646.7 651.9 652.0 662.6 632.5 644.3 662.4 674.4 697.7 7591.8 8514.9 8530.8 9313.0 9577.0 493.8 568.5 589.3 669.6 675.6 4394.2 4667.9 4682.8 4683.3 4711.2 82640.6 109523.0 133655.3 134329.0 155762.0 111909.4 112699.4 114793.1 118027.4 119367.0 85050.6 94466.5 96196.0 98154.0 105595.5 7980.9 9681.1 9715.1 10013.9 10145.8 40698.8 58804.5 58982.7 59176.1 61050.0 61381.7 62909.0 65786.7 87939.0 88410.0 11363.4 11510.0 11534.2 11741.5 13138.3 14680.6 14684.3 14985.1 15121.4 15298.5 13666.3 13788.3 13800.3 14142.6 14245.4 13578.2 13886.3 14021.6 14048.0 14058.1 12883.4 12962.5 13316.3 13326.8 13616.1 10950.0 11054.4 11066.9 11322.4 11449.3 9920.6 10204.7 10730.4 11246.3 11646.9 05:52:25 INFO - PROCESS | 1654 | -------- Summary: end -------- 05:52:25 INFO - PROCESS | 1654 | 05:52:26 INFO - TEST-INFO | 1654: exit 0 05:52:26 INFO - TEST-OK | dromaeo_css | took 1113709ms 05:52:26 INFO - TEST-START | kraken 05:52:26 INFO - operating with platform_type : mac_ 05:52:26 INFO - Initialising browser for kraken test... 05:52:26 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpEpZX4m/profile http://localhost:49279/getInfo.html) 05:52:29 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 05:52:29 INFO - PROCESS | 1665 | colorDepth:24 05:52:29 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 05:52:29 INFO - PROCESS | 1665 | __metrics 05:52:29 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 05:52:30 INFO - TEST-INFO | 1665: exit 0 05:52:30 INFO - Browser initialized. 05:52:30 INFO - Running cycle 1/1 for kraken test... 05:52:30 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpEpZX4m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:42 INFO - PROCESS | 1668 | RSS: Main: 244015104 05:52:42 INFO - PROCESS | 1668 | 05:52:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 05:52:45 INFO - PROCESS | 1668 | RSS: Main: 256704512 05:52:45 INFO - PROCESS | 1668 | 05:52:48 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 05:52:49 INFO - PROCESS | 1668 | RSS: Main: 265494528 05:52:49 INFO - PROCESS | 1668 | 05:52:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 05:52:53 INFO - PROCESS | 1668 | RSS: Main: 265506816 05:52:53 INFO - PROCESS | 1668 | 05:52:55 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 05:52:56 INFO - PROCESS | 1668 | RSS: Main: 266362880 05:52:56 INFO - PROCESS | 1668 | 05:52:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:52:58 INFO - PROCESS | 1668 | RSS: Main: 268079104 05:52:58 INFO - PROCESS | 1668 | 05:53:01 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 05:53:02 INFO - PROCESS | 1668 | RSS: Main: 268709888 05:53:02 INFO - PROCESS | 1668 | 05:53:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 05:53:05 INFO - PROCESS | 1668 | RSS: Main: 268894208 05:53:05 INFO - PROCESS | 1668 | 05:53:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 05:53:09 INFO - PROCESS | 1668 | RSS: Main: 269033472 05:53:09 INFO - PROCESS | 1668 | 05:53:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:53:11 INFO - PROCESS | 1668 | RSS: Main: 268771328 05:53:11 INFO - PROCESS | 1668 | 05:53:13 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:53:14 INFO - PROCESS | 1668 | RSS: Main: 268550144 05:53:14 INFO - PROCESS | 1668 | 05:53:16 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:53:17 INFO - PROCESS | 1668 | RSS: Main: 268910592 05:53:17 INFO - PROCESS | 1668 | 05:53:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:53:20 INFO - PROCESS | 1668 | RSS: Main: 268943360 05:53:20 INFO - PROCESS | 1668 | 05:53:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:53:24 INFO - PROCESS | 1668 | RSS: Main: 268947456 05:53:24 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 05:53:26 INFO - PROCESS | 1668 | RSS: Main: 270692352 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | __start_tp_report 05:53:26 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 05:53:26 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 05:53:26 INFO - PROCESS | 1668 | |i|pagename|runs| 05:53:26 INFO - PROCESS | 1668 | |0;ai-astar;112;123;118;114;115;116;118;117;115;131 05:53:26 INFO - PROCESS | 1668 | |1;audio-beat-detection;140;112;132;162;144;165;134;111;156;110 05:53:26 INFO - PROCESS | 1668 | |2;audio-dft;201;203;230;263;275;201;222;275;236;195 05:53:26 INFO - PROCESS | 1668 | |3;audio-fft;72;101;90;175;84;59;61;62;129;59 05:53:26 INFO - PROCESS | 1668 | |4;audio-oscillator;69;73;117;73;74;113;103;63;85;98 05:53:26 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;91;84;118;102;94;77;79;105;88;117 05:53:26 INFO - PROCESS | 1668 | |6;imaging-darkroom;99;94;96;94;96;101;95;113;97;150 05:53:26 INFO - PROCESS | 1668 | |7;imaging-desaturate;98;96;92;90;92;126;87;142;143;109 05:53:26 INFO - PROCESS | 1668 | |8;json-parse-financial;55;56;58;58;56;58;59;79;46;49 05:53:26 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;64;94;73;68;75;75;75;70;70;99 05:53:26 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;111;106;89;88;88;86;86;88;90;89 05:53:26 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;136;140;140;160;157;163;157;159;194;144 05:53:26 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;171;172;171;171;172;174;158;240;180;174 05:53:26 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;70;67;69;69;68;67;68;69;60;78 05:53:26 INFO - PROCESS | 1668 | __end_tp_report 05:53:26 INFO - PROCESS | 1668 | __start_cc_report 05:53:26 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,971 05:53:26 INFO - PROCESS | 1668 | __end_cc_report 05:53:26 INFO - PROCESS | 1668 | __startTimestamp1473339206458__endTimestamp 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | ------- Summary: start ------- 05:53:26 INFO - PROCESS | 1668 | Number of tests: 14 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:117.90 Median:117.50 stddev:5.47 (4.7%) stddev-sans-first:5.36 05:53:26 INFO - PROCESS | 1668 | Values: 112.0 123.0 118.0 114.0 115.0 116.0 118.0 117.0 115.0 131.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:136.60 Median:142.00 stddev:20.79 (14.6%) stddev-sans-first:22.02 05:53:26 INFO - PROCESS | 1668 | Values: 140.0 112.0 132.0 162.0 144.0 165.0 134.0 111.0 156.0 110.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:230.10 Median:233.00 stddev:31.40 (13.5%) stddev-sans-first:31.49 05:53:26 INFO - PROCESS | 1668 | Values: 201.0 203.0 230.0 263.0 275.0 201.0 222.0 275.0 236.0 195.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:89.20 Median:61.50 stddev:37.66 (61.2%) stddev-sans-first:39.43 05:53:26 INFO - PROCESS | 1668 | Values: 72.0 101.0 90.0 175.0 84.0 59.0 61.0 62.0 129.0 59.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:73.00 stddev:19.49 (26.7%) stddev-sans-first:19.58 05:53:26 INFO - PROCESS | 1668 | Values: 69.0 73.0 117.0 73.0 74.0 113.0 103.0 63.0 85.0 98.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:81.50 stddev:14.63 (18.0%) stddev-sans-first:15.43 05:53:26 INFO - PROCESS | 1668 | Values: 91.0 84.0 118.0 102.0 94.0 77.0 79.0 105.0 88.0 117.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:103.50 Median:95.50 stddev:17.28 (18.1%) stddev-sans-first:18.25 05:53:26 INFO - PROCESS | 1668 | Values: 99.0 94.0 96.0 94.0 96.0 101.0 95.0 113.0 97.0 150.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:107.50 Median:91.00 stddev:21.67 (23.8%) stddev-sans-first:22.71 05:53:26 INFO - PROCESS | 1668 | Values: 98.0 96.0 92.0 90.0 92.0 126.0 87.0 142.0 143.0 109.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:8.69 (15.0%) stddev-sans-first:9.18 05:53:26 INFO - PROCESS | 1668 | Values: 55.0 56.0 58.0 58.0 56.0 58.0 59.0 79.0 46.0 49.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:76.30 Median:75.00 stddev:11.27 (15.0%) stddev-sans-first:11.05 05:53:26 INFO - PROCESS | 1668 | Values: 64.0 94.0 73.0 68.0 75.0 75.0 75.0 70.0 70.0 99.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:92.10 Median:88.00 stddev:8.81 (10.0%) stddev-sans-first:6.14 05:53:26 INFO - PROCESS | 1668 | Values: 111.0 106.0 89.0 88.0 88.0 86.0 86.0 88.0 90.0 89.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:155.00 Median:158.00 stddev:16.89 (10.7%) stddev-sans-first:16.45 05:53:26 INFO - PROCESS | 1668 | Values: 136.0 140.0 140.0 160.0 157.0 163.0 157.0 159.0 194.0 144.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.30 Median:173.00 stddev:22.36 (12.9%) stddev-sans-first:23.56 05:53:26 INFO - PROCESS | 1668 | Values: 171.0 172.0 171.0 171.0 172.0 174.0 158.0 240.0 180.0 174.0 05:53:26 INFO - PROCESS | 1668 | 05:53:26 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:4.35 (6.3%) stddev-sans-first:4.58 05:53:26 INFO - PROCESS | 1668 | Values: 70.0 67.0 69.0 69.0 68.0 67.0 68.0 69.0 60.0 78.0 05:53:26 INFO - PROCESS | 1668 | -------- Summary: end -------- 05:53:26 INFO - PROCESS | 1668 | 05:53:27 INFO - TEST-INFO | 1668: exit 0 05:53:27 INFO - TEST-OK | kraken | took 60887ms 05:53:27 INFO - SUITE-END | took 1174s 05:53:27 INFO - Completed test suite (00:19:34) 05:53:27 INFO - javascript benchmark 05:53:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7288.012178150098, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2970.7920445568807, 3373.79757214567, 3377.814220217887, 3398.3373242217776, 3441.7590768646187, 4208.863606826155, 5503.559715222783, 5615.213870058195, 5648.124540696199, 5747.42525747425, 5635.943640563593, 5645.266115404992, 5669.9433005669935, 5685.317761868581, 5688.63023903446, 5121.052605268028, 5196.688198708071, 5237.528622423981, 5276.498732620415, 5324.547413469841, 5097.515736005073, 5116.130257856174, 5161.587073034167, 5198.168293073109, 5207.661502002357, 5199.168133098724, 5214.217867319932, 5215.478452154792, 5217.295665085203, 5219.42586315505, 5175.585953123778, 5196.714180720059, 5213.244079608474, 5216.504432078947, 5225.033368826779, 5025.924611130836, 5085.338905942203, 5166.457521960216, 5181.041507321158, 5206.479352064801, 5615.157726341041, 5686.090225563931, 5693.629914055573, 5698.7435565399655, 5757.769689212427, 6185.41238582334, 6200.410960958701, 6224.751009959597, 6226.40849119333, 6232.719527621268, 6105.763021687103, 6211.068339749028, 6232.9065064023625, 6257.499400048031, 6293.433590976834, 5717.399673034354, 5786.768529258825, 5791.971040144772, 5795.391483894215, 5833.82498525045, 6285.0, 6306.085617585516, 6326.145970293951, 6340.587861788969, 6385.808425747235, 5909.8227053188475, 5978.133170690226, 6032.517398608101, 6100.572959892765, 6121.816345509642, 5876.353601103875, 6182.041783523561, 6190.164327815687, 6200.348963358872, 6204.81385558434, 6064.617589680339, 6088.391160883876, 6102.877942441126, 6114.052321890114, 6118.510519158456, 5994.6403215807195, 6063.999440092335, 6106.816795496142, 6148.600340977822, 6154.661493617805, 4952.257161425815, 4957.132501812169, 4959.107360675113, 4973.626977976666, 4995.553068382805, 3698.537682789651, 4447.555244475527, 4456.799444025027, 4478.48497422794, 4487.753173575419, 5583.022970980062, 5665.291838520185, 5778.3643799182055, 5796.362400135981, 5805.767769289266, 6164.907526387108, 6285.088662143963, 6287.2140982377205, 6289.496840252768, 6302.590331628521, 4983.729149066965, 5790.884182316414, 5798.478136967693, 5821.79623713168, 5833.066709326487, 5316.6544174628525, 5377.78488860449, 5389.407165211824, 5417.0, 5450.209719590637, 6254.124422580933, 6260.7495700171485, 6268.968655156695, 6273.529485288622, 6274.6548939808745, 5488.792465657549, 6021.948693435288, 6285.748570057147, 6309.400606942333, 6313.6843157841, 5733.426657334233, 5841.890040892217, 6248.0, 6267.373262673696, 6300.086487459384, 6114.449440885012, 6170.043643235305, 6178.969105154445, 6189.5048396129105, 6201.937980620317, 4524.751138687404, 5265.0259701955265, 5802.897449484584, 6246.968765156145, 6262.373762623702, 4624.7687615618415, 6006.819795406145, 6043.4258745420875, 6063.818085457443, 6074.665893375906, 6067.605605635619, 6072.514198863992, 6085.056816193497, 6098.359672234352, 6115.908261375994, 5095.6241814710565, 5725.914111288417, 5924.377940316204, 5926.703664816656, 5991.490723288395, 5909.6158749681135, 5988.790392336247, 6017.729202185999, 6027.276726792813, 6078.878422431439, 5934.317553481233, 5945.494632956247, 5952.136940143741, 5955.106733989936, 5982.760689572542], "value": 5623.741043727065, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.800821663118, 501.7742016092758, 517.7100823538816, 521.2754271562529, 533.9161502150636, 36980.07549811253, 39263.80368098159, 39366.8031659842, 39373.212535749306, 39497.01257468565, 39569.60430395695, 39996.0, 40039.0, 40102.39846402306, 40146.79559613209, 9377.624895004192, 9588.664396746115, 9601.615935362577, 9611.759706007371, 9628.037196280386, 9528.09483099108, 9617.567209475556, 9643.566039528203, 9667.468289244089, 9676.806463870718, 9672.516374181298, 9703.805923881519, 9712.174465170468, 9723.076307750753, 9732.902670973306, 9639.518024098767, 9679.709608711715, 9681.177099946442, 9686.176674982635, 9711.028897110304, 9480.099390557885, 9503.524823758784, 9515.762105947337, 9524.761880952963, 9527.237820974306, 37936.0, 38332.80833595842, 38439.23121537582, 38655.840324790304, 38822.61177388246, 3217.809410518123, 3360.0927749507496, 3757.229767897574, 3959.1883663848844, 4001.899952501182, 29435.70564294351, 30221.546676799866, 30567.541486877715, 30607.0, 30958.84520577383, 3236.3689080629288, 3245.513173024042, 3254.1539199807985, 3300.719438847701, 3346.3474622448634, 433958.0, 442956.9999999968, 445159.0, 448745.0, 448846.0, 56424.0, 56505.71747141238, 56711.43288567144, 56797.7160114205, 56948.430515695145, 33594.664053359644, 33710.32579348399, 34129.31741365159, 35179.0, 35249.64750352463, 15989.12059836697, 16187.271572779253, 16197.4330898418, 16201.946873453187, 16207.270672819752, 12203.572874949334, 12371.257724536557, 12454.128211025323, 12470.376481175905, 12501.687457813445, 16514.174291285628, 17336.913315433343, 18676.34632787846, 18698.06509674538, 18700.877947323203, 7721.8455630887065, 7808.336291415293, 7825.0, 7833.608319583998, 7908.565028923349, 883.960221790021, 979.7991411760572, 1069.8555694981078, 1090.465671820818, 1133.1444759206747, 24497.42777008424, 27111.0, 29354.0, 30336.54495182574, 30421.0, 895.5611750242334, 962.1821451766083, 1063.0538820449806, 1066.4987455895716, 1135.6763322453062, 14836.62908427298, 14875.851241487662, 15379.692406151815, 15605.141717205663, 15831.0, 622772.0, 699926.0, 705619.0, 705918.1153225024, 897005.514972421, 46984.530154698696, 56750.43249567534, 57320.0, 57337.713311433174, 58611.70694146502, 1261.6215135459327, 1357.4841560207058, 1362.523116909074, 1398.272898092986, 1398.384710727297, 26141.477170456863, 27260.774579940142, 29946.401071978, 30323.241918952204, 30417.288887345327, 48877.0, 48949.2657610129, 48956.53130406093, 49080.01839963252, 49141.508584913696, 470183.0, 480645.0, 482279.0, 482880.0, 484186.0, 25387.238382848544, 25679.229623111336, 26031.869840650674, 26064.609030864172, 26771.86614066917, 43758.56241437545, 44904.101917960805, 45210.32184517169, 45683.77158114188, 47440.28839567478, 34018.489722654675, 38154.80922595369, 38268.0, 38371.0, 39167.020824479616, 36209.27581448409, 37893.81053094717, 38139.61860381361, 39065.609343906195, 39218.411723824735, 37563.55836870595, 37739.62260377471, 38117.618823811405, 38462.615373847024, 39509.40735889022, 30910.69089309168, 33112.84105056312, 33879.49180762241, 34130.317393651494, 34248.14379640529, 24735.62896556589, 25119.623205651566, 25190.62214066827, 25547.616785747858, 25677.229683109537], "value": 19343.46626090515, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.72184199746215, 366.532670844673, 370.68862155789134, 379.4801122462227, 397.2095529895509, 19730.01349932503, 20118.89940550297, 20706.482337941557, 20848.166073357086, 20857.895710521425, 20196.990150492453, 20295.188192472284, 20761.065752041155, 20789.584208315824, 21196.682049769264, 5257.658252213613, 5702.059160238556, 5993.640381577108, 6251.12484252205, 6251.812445626639, 6145.539084568675, 6160.353162917896, 6180.783672571461, 6190.81427557174, 6196.69016549173, 5875.794347197849, 6098.390160983889, 6124.387561243884, 6141.447269745722, 6149.631022138664, 5779.393163717791, 6063.333033366326, 6097.603655762384, 6101.389861013908, 6108.930937085992, 4531.456225252958, 5254.65844720092, 5269.1832765921345, 5272.551833094191, 5294.179402192666, 23618.763812362, 25342.0, 25407.364815879566, 25496.23511294664, 25693.614595781077, 1996.0890121042628, 2082.8437867160023, 2187.496875718578, 2252.256755270757, 2606.89572417105, 9840.966698496626, 9925.0, 10027.89972100277, 10071.798564028679, 10288.022637849392, 1800.8829426087264, 1801.918913648889, 1822.9908850455663, 1835.5502901789014, 1927.3832373640441, 111562.44218778855, 116107.0, 116639.41680291544, 116682.83317166804, 117330.0, 10250.38497690141, 10514.73713157162, 11693.707657308465, 11701.648950531498, 11710.180287379972, 14195.78706319406, 14248.643783905278, 14303.713925721428, 14304.57086287413, 14334.283285835667, 7005.1944026436595, 8097.2712455879255, 8383.077861435237, 8387.370947178986, 8455.069942306342, 6892.724845903524, 7664.616769161519, 8069.556174410346, 8351.874721879176, 8554.35842311829, 4457.197704413236, 4468.486124095771, 4482.641388688897, 4483.574060464251, 4485.887852803706, 1901.239504198304, 1946.9805301947083, 1950.2881448271562, 1953.3358658056045, 1959.3827944197533, 701.7193621640527, 857.7426771968385, 860.3332417376458, 933.6638810028385, 950.4677380666849, 16479.0, 20096.0, 21534.892325538272, 22309.888450557966, 22467.213647522585, 883.8939327280767, 921.7695576105973, 941.9920684867263, 960.2078285414422, 971.6307803034942, 10778.245522813328, 10924.726881828017, 11379.54481820718, 11426.143039272089, 11458.94270528642, 574269.0, 576544.0, 586547.0, 587133.0, 587661.0, 132833.0, 133738.0, 136199.31900340435, 136919.31540342234, 139283.0, 923.7090316550265, 1007.3250921882357, 1011.185995273797, 1033.4574348467115, 1033.9224558158169, 19076.1415736295, 19126.521836954187, 19439.3196238131, 19509.902450487658, 19551.826890386623, 46217.0, 48078.27882581694, 52324.21513677222, 55386.0, 55812.883742325736, 77829.61085194538, 82785.0, 83143.5842820782, 83849.58075209585, 84897.57551212204, 6124.724387402489, 7912.0505539335645, 7914.16901225363, 7954.5624990626075, 8267.917320826955, 10373.896261037595, 12564.308963007123, 12916.22502649844, 13134.211947283193, 13352.465901363837, 8402.579871006303, 11380.54477820911, 11455.198136130392, 11486.94256528712, 12057.613374461698, 9347.579358928728, 9825.4104753715, 11141.72145696364, 11192.776144477228, 11497.712557186138, 9900.25748068912, 11408.885911141115, 11457.942710286396, 11640.941795291308, 11825.704357391134, 7117.822054448473, 7221.891671625035, 7367.3369396752405, 7403.333699967029, 7497.212792656693, 10764.138868890313, 11290.887091128983, 11321.71695707614, 11627.883721163018, 11719.531218751154], "value": 9468.861077418072, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1509.486774496671, 1537.8077740282465, 1763.6208215233721, 1861.972070418943, 1891.4609336339147, 8050.798730031737, 8538.87191692125, 8745.212930836224, 9127.315451341152, 9130.589123489442, 8740.213380795727, 8938.597763100659, 8941.41880777749, 8963.10368963105, 8964.50695211763, 2363.7045369328835, 2368.5854975379325, 2376.7385587585395, 2412.6260429633435, 2417.371483414316, 2381.4284571702838, 2384.7496012918655, 2413.613821788514, 2419.0686585664557, 2419.1895714935517, 2376.95246095078, 2399.8680072596, 2408.2654790288957, 2409.8795060247007, 2418.1657328221736, 2372.1223147435558, 2374.9643755343595, 2379.4884099918513, 2391.5695174868515, 2398.70016247969, 2349.6827928229814, 2372.0, 2398.1486572266767, 2398.4603464220586, 2399.2082612737754, 9005.414648047856, 9102.408343457653, 9108.225800806873, 9124.726258212264, 9124.771880703036, 5302.390225124122, 5458.0, 5465.453454654543, 5465.836024919259, 5485.61600687952, 3338.5659864217496, 3455.4125798614045, 3662.7436079474182, 3674.3018826422895, 3746.9437958430644, 1078.827387617985, 1078.9406582637953, 1092.9890701092968, 1098.9889301842325, 1104.1553211792984, 11679.0, 11810.996065334457, 11906.642800715992, 11933.403329833474, 11955.342456164908, 4319.956800431955, 4606.355110284563, 4714.104320179155, 4851.611871050308, 4920.950790492049, 10023.599056037678, 10125.493725313705, 10130.442825644514, 10140.391576505433, 10146.188304935587, 4698.976505117453, 5435.510804027656, 5438.347398312228, 5440.455954404607, 5473.452654734495, 4277.337012763027, 4447.955520444819, 4781.354517140211, 4997.725125118153, 5002.2496625506465, 3993.301172294895, 4041.413994970713, 4064.918701625951, 4153.563875793058, 4155.480564929384, 1051.3954476176077, 1092.6940456672144, 1277.9329260067948, 1301.8437787465564, 1314.842218933734, 791.3985371117992, 891.3270480786964, 895.3866601378077, 921.7465197070725, 942.8067246214647, 5129.7178655173575, 5755.65466072037, 5797.130430435384, 6059.6364218147055, 6073.058675905242, 439.83726021372297, 446.97988590513506, 453.81847261095163, 454.8908262017092, 461.95842374186486, 3960.0793615096654, 3987.4816273884207, 4096.5903409658795, 4104.753714777123, 4116.415180155602, 10557.47212639365, 10853.457327133612, 10862.511186996604, 10869.782604347869, 10911.454427278763, 10602.25784195099, 10632.25574209798, 10761.0, 10871.891281087088, 10882.347059176474, 8150.592470376458, 8239.423240373237, 8352.206540378655, 8356.623951922178, 8403.873941890877, 5835.212246346859, 5940.524758019435, 5944.613600115978, 5948.137520059654, 5986.790462333797, 9725.367851089657, 10084.445355505517, 10175.236857235588, 10228.028337307944, 10252.333598316085, 10220.693379198636, 10291.639792607222, 10309.948450257702, 10327.48362581883, 10360.896391036295, 6927.376536111774, 7311.245301127585, 7467.282525019167, 7518.774436766906, 7649.082110146818, 8484.703035393732, 8492.174182550782, 8495.872561911452, 8507.914920850711, 8535.3598480113, 6393.6483493408305, 7558.546487210785, 7606.277403646644, 7620.580868052089, 7667.846643067219, 7363.0, 7425.368843648134, 7498.625068746432, 7540.509866858637, 7554.0, 5532.529734972572, 6972.7908162753165, 7009.474289428212, 7029.929700703132, 7046.189688185924, 5006.87482812918, 5025.748712564284, 5126.487351264844, 5170.1986192140275, 5172.793088276427, 5085.745712714275, 5135.0, 5203.687778733137, 5225.242339860775, 5278.181881808326], "value": 4546.813293993899, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.87595272009406, 299.1146207226609, 305.3084763011778, 306.4835751758286, 307.1951487103788, 5262.526372626463, 5404.648697834638, 5479.4794494523, 5591.8322450326505, 5596.692181929997, 4212.515560710519, 5055.292259083732, 5431.0, 5570.830125673603, 5626.718664066791, 3698.7965661888584, 4210.3894935234375, 4220.0, 4226.175895700339, 4229.0, 3525.5416795816636, 3680.8527658893613, 3730.459083432914, 4215.49414070312, 4254.404383386328, 3384.8984530464004, 4043.2722110020186, 4102.3026085565525, 4127.463429754128, 4137.586241375869, 3592.1738000259957, 3612.963870361289, 3703.981480092609, 4078.9802549362657, 4082.122343696105, 3355.882544110969, 3552.0764601203614, 3566.0, 3578.6421357864265, 3581.3732596795717, 4728.621710263171, 4993.450720420751, 5109.540141387256, 5255.054090263751, 5435.157550579666, 4659.951510910052, 4674.859754207379, 4677.158111539921, 4689.742064186468, 4695.906081878377, 4921.827736029221, 5746.569007324468, 5767.769289228426, 5774.855628609318, 5824.388439213906, 3371.157210697326, 3571.4107172316535, 3813.3707938190164, 3877.9917221522323, 3884.6698030667426, 4709.293605959134, 5411.648242864201, 5985.940140598582, 6097.810926869262, 6168.537359698041, 3601.6218297078954, 3916.4321173429694, 3927.5286965564314, 3940.271049855786, 3948.881533553998, 3855.9421608675893, 3889.86385476507, 3953.288408086572, 3969.0, 3993.560708322082, 3862.8261728222296, 4639.953600463952, 4665.836695715634, 4679.508651591602, 4695.436547614308, 5126.256692779526, 5815.41845815415, 5833.387494313036, 5888.676122813286, 5916.372997425657, 1058.2909450668071, 1189.6668932698858, 1266.803645434959, 1341.745467987435, 1349.6760777413351, 880.300161371711, 883.9734807955772, 906.4425378392241, 908.9772755681029, 909.7452713240303, 4883.876708357097, 5379.946200537945, 5409.56723462122, 5433.495395469899, 5448.645838020516, 5254.527092561611, 5695.582650409721, 5846.953566251778, 5871.383504731942, 6031.638101713824, 4901.09329773993, 5009.373828271466, 5073.797048118107, 5377.247185394048, 5394.811181608625, 2547.783438407719, 3115.8753649854207, 3148.087054754096, 3167.635721892012, 3180.3321302526265, 5133.974330128325, 5221.295125158131, 5704.34400043992, 6174.135621013062, 6211.565190436626, 5807.251624423375, 6113.541484388688, 6153.600015998945, 6185.577317217066, 6204.875902481925, 5499.890002199933, 6213.720382582795, 6223.439890409885, 6236.906446403307, 6238.656873871981, 4784.736839473723, 5325.653832500875, 5745.597808153389, 5966.642001479925, 6034.879302414015, 4797.760111994457, 5879.882402352015, 5926.496247818811, 5940.6435613863305, 5946.940530594812, 5753.798617048383, 6177.413145751147, 6206.503479721521, 6225.844353891189, 6246.562740608114, 3746.4193050077283, 3752.3245815753426, 4003.959960400359, 4051.716379853382, 4073.2260870434525, 4858.5384388483035, 5934.940650593611, 5970.373110823301, 6039.607425517327, 6040.909386359121, 5798.15926690619, 5848.520130522333, 5854.707264636836, 5876.764929402776, 5934.376890426443, 5880.26496687914, 5892.528597712087, 5919.378465261086, 5922.703864806656, 5929.797909129022, 5518.834434966958, 5710.885782284248, 5723.7996670116345, 5751.194832723506, 5859.619124756877, 4716.952830471651, 5407.513323800877, 5896.734646940812, 5899.292084949833, 5933.554983376178, 4841.782119804547, 5401.459854014567, 5767.365589785204, 5788.855278618068, 5808.9668615984065], "value": 4185.48851786783, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.2751431111819, 756.4629113329536, 787.933025692816, 801.8636831738604, 840.8318336332735, 13043.478260869553, 13553.661158471043, 14957.17735524547, 15746.763798543017, 15773.684526309467, 12027.0, 14065.789013164786, 14173.433062677506, 14308.284585770696, 14325.570232893004, 667.853072324088, 820.6143112737004, 843.9746807595782, 865.6104752861206, 870.3254977392539, 842.1578421578422, 845.1928408370022, 859.5401460218784, 860.513809697522, 860.6557377049199, 868.6785889220997, 870.9738707838775, 895.5432729308035, 912.6805618033701, 919.3426699909563, 864.6627815152095, 865.212656482601, 865.4980111535294, 904.764761162096, 910.1444642036464, 638.936106389362, 646.9288378278433, 669.2270427656016, 671.3722669304167, 675.9662016899135, 15304.158271295075, 15447.768283475756, 15575.610609734735, 15623.453179138676, 15641.983271087343, 12147.0, 14275.14349139055, 14291.642708932255, 14383.712325753531, 14716.0, 11975.401229938469, 11986.280823150639, 11992.220505667105, 11993.760124797454, 12046.819297710541, 3785.5268091488565, 3801.733878628497, 3819.312523745725, 3833.8083095845095, 3945.723799334019, 112209.43895280472, 112961.0, 114494.85505144842, 114608.0, 115499.0, 10986.670399888017, 11033.282836615756, 11039.0, 11061.668149955514, 11494.942525287488, 22773.772262277496, 22782.65826012611, 22788.658170127463, 22814.65778013331, 22889.656655150186, 7669.578173200527, 7883.36933045355, 8034.718784842619, 8129.187081291823, 8240.0, 4246.384274280212, 4364.41080454141, 4463.308187230984, 4522.9597192645215, 4575.816967321336, 3341.7159541438764, 3549.716022718176, 3578.409562422223, 3595.3708101082207, 3614.626441952041, 644.3305375884294, 646.6572716460283, 651.9380658837403, 651.9608823470606, 662.6355504472521, 632.5003247434568, 644.3331152257391, 662.3806740697368, 674.4132604633902, 697.6686074114755, 7591.848163036819, 8514.872276915852, 8530.848335474755, 9313.0, 9576.952115239379, 493.8444390017133, 568.499720246189, 589.2928485817037, 669.5882032550044, 675.5777638975641, 4394.209042372404, 4667.8599642010795, 4682.789274482657, 4683.320918466803, 4711.222648263067, 82640.58679706563, 109523.0, 133655.33172334076, 134329.0, 155762.0, 111909.44045279884, 112699.43650281696, 114793.13017174638, 118027.40986295014, 119367.0, 85050.5747471271, 94466.52766736259, 96196.0, 98154.0, 105595.4720226394, 7980.920190798018, 9681.135999760623, 9715.07706767856, 10013.94853540397, 10145.797084058426, 40698.79650601728, 58804.529886753175, 58982.70508647429, 59176.112358313796, 61050.0, 61381.69309153426, 62909.0, 65786.67106664435, 87939.0, 88410.0, 11363.431828408711, 11510.0, 11534.1926065178, 11741.530338786353, 13138.343082845628, 14680.632984175481, 14684.25863370795, 14985.100893946397, 15121.395144194548, 15298.464553740565, 13666.316684165553, 13788.310584470937, 13800.309984500534, 14142.646433839236, 14245.430182792572, 13578.185308881499, 13886.305684715926, 14021.649458763204, 14048.0, 14058.086224395382, 12883.42024608876, 12962.481500740241, 13316.267605281802, 13326.800097998344, 13616.114952528054, 10950.014498695156, 11054.447277636247, 11066.944665276944, 11322.37726924994, 11449.313041217554, 9920.61111444384, 10204.744881378025, 10730.356178628996, 11246.325220486797, 11646.94176529112], "value": 7643.632834126777, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1594.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 123.0, 118.0, 114.0, 115.0, 116.0, 118.0, 117.0, 115.0, 131.0], "value": 117.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 112.0, 132.0, 162.0, 144.0, 165.0, 134.0, 111.0, 156.0, 110.0], "value": 136.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 203.0, 230.0, 263.0, 275.0, 201.0, 222.0, 275.0, 236.0, 195.0], "value": 230.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 101.0, 90.0, 175.0, 84.0, 59.0, 61.0, 62.0, 129.0, 59.0], "value": 89.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 117.0, 73.0, 74.0, 113.0, 103.0, 63.0, 85.0, 98.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 84.0, 118.0, 102.0, 94.0, 77.0, 79.0, 105.0, 88.0, 117.0], "value": 95.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 96.0, 94.0, 96.0, 101.0, 95.0, 113.0, 97.0, 150.0], "value": 103.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 92.0, 90.0, 92.0, 126.0, 87.0, 142.0, 143.0, 109.0], "value": 107.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 58.0, 58.0, 56.0, 58.0, 59.0, 79.0, 46.0, 49.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 94.0, 73.0, 68.0, 75.0, 75.0, 75.0, 70.0, 70.0, 99.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 89.0, 88.0, 88.0, 86.0, 86.0, 88.0, 90.0, 89.0], "value": 92.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 140.0, 140.0, 160.0, 157.0, 163.0, 157.0, 159.0, 194.0, 144.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 171.0, 171.0, 172.0, 174.0, 158.0, 240.0, 180.0, 174.0], "value": 178.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 69.0, 69.0, 68.0, 67.0, 68.0, 69.0, 60.0, 78.0], "value": 68.5, "unit": "score"}]}]} 05:53:27 INFO - Return code: 0 05:53:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:53:27 ERROR - # TBPL SUCCESS # 05:53:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:53:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:53:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:53:27 INFO - 2016-09-08 05:53:27.766 screenresolution[1673:10834] starting screenresolution argv=screenresolution get 05:53:27 INFO - 2016-09-08 05:53:27.768 screenresolution[1673:10834] Display 0: 1600x1200x32@60 05:53:27 INFO - 2016-09-08 05:53:27.774 screenresolution[1674:10837] starting screenresolution argv=screenresolution list 05:53:27 INFO - Available Modes on Display 0 05:53:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:53:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:53:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:53:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:53:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:53:28 INFO - Intel Iris: 05:53:28 INFO - Chipset Model: Intel Iris 05:53:28 INFO - Type: GPU 05:53:28 INFO - Bus: Built-In 05:53:28 INFO - VRAM (Dynamic, Max): 1536 MB 05:53:28 INFO - Vendor: Intel (0x8086) 05:53:28 INFO - Device ID: 0x0a2e 05:53:28 INFO - Revision ID: 0x0009 05:53:28 INFO - Displays: 05:53:28 INFO - Display: 05:53:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:53:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:53:28 INFO - Display Serial Number: 1600x1200 60 05:53:28 INFO - Main Display: Yes 05:53:28 INFO - Mirror: Off 05:53:28 INFO - Online: Yes 05:53:28 INFO - Rotation: Supported 05:53:28 INFO - Return code: 0 05:53:28 INFO - Running post-action listener: _resource_record_post_action 05:53:28 INFO - [mozharness: 2016-09-08 12:53:28.011406Z] Finished run-tests step (success) 05:53:28 INFO - Running post-run listener: _resource_record_post_run 05:53:28 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 39289856; Write bytes: 707005952; Read time: 387; Write time: 1797 05:53:28 INFO - TinderboxPrint: CPU usage
54.5% 05:53:28 INFO - TinderboxPrint: I/O read bytes / time
39,289,856 / 387 05:53:28 INFO - TinderboxPrint: I/O write bytes / time
707,005,952 / 1,797 05:53:28 INFO - TinderboxPrint: CPU idle
2,200.9 (45.5%) 05:53:28 INFO - TinderboxPrint: CPU system
982.0 (20.3%) 05:53:28 INFO - TinderboxPrint: CPU user
1,658.8 (34.3%) 05:53:28 INFO - TinderboxPrint: Swap in / out
444,878,848 / 0 05:53:28 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392137216; Write bytes: 383608320; Read time: 29427; Write time: 634 05:53:28 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 38306816; Write bytes: 299272192; Read time: 370; Write time: 1115 05:53:28 INFO - Running post-run listener: _upload_blobber_files 05:53:28 INFO - Blob upload gear active. 05:53:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:53:28 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 05:53:28 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:53:28 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:53:28 INFO - (blobuploader) - INFO - Open directory for files ... 05:53:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:53:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:53:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:29 INFO - (blobuploader) - INFO - Done attempting. 05:53:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:53:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:53:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:30 INFO - (blobuploader) - INFO - Done attempting. 05:53:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:53:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:53:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:32 INFO - (blobuploader) - INFO - Done attempting. 05:53:32 INFO - (blobuploader) - INFO - Iteration through files over. 05:53:32 INFO - Return code: 0 05:53:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:53:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:53:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f76f32ea5f27a7fd6e7e9dc00ea880b1026d423e3f1707078b9b7b68316c781e5bcf0d44ea9a496d3942ebe27df7e26ab7a97061404dee038791443ef8f91d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b27e3cd96fa2aa01200ed2e5e0d7cfab991711cdb4dabaa0d4280e52f62a47fb1b4fb08bf5e5bd177ffe86730affc31d22f11df8ba2def813f7a96fa1e1f46ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee590b54caa6da13e31d88eb265011104ab91b3cfe078d8230f78439f0f3523ce3da616aecb786cf7723ac36509267cf9e42366dbd749fc63fe8567ff8c66196"} 05:53:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:53:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:53:32 INFO - Contents: 05:53:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f76f32ea5f27a7fd6e7e9dc00ea880b1026d423e3f1707078b9b7b68316c781e5bcf0d44ea9a496d3942ebe27df7e26ab7a97061404dee038791443ef8f91d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b27e3cd96fa2aa01200ed2e5e0d7cfab991711cdb4dabaa0d4280e52f62a47fb1b4fb08bf5e5bd177ffe86730affc31d22f11df8ba2def813f7a96fa1e1f46ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee590b54caa6da13e31d88eb265011104ab91b3cfe078d8230f78439f0f3523ce3da616aecb786cf7723ac36509267cf9e42366dbd749fc63fe8567ff8c66196"} 05:53:32 INFO - Running post-run listener: copy_logs_to_upload_dir 05:53:32 INFO - Copying logs to upload dir... 05:53:32 INFO - mkdir: /builds/slave/test/build/upload/logs 05:53:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.379761 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-09-08 05:53:32.922238) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 05:53:32.925973) ========= 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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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/Firefox/sha512/f76f32ea5f27a7fd6e7e9dc00ea880b1026d423e3f1707078b9b7b68316c781e5bcf0d44ea9a496d3942ebe27df7e26ab7a97061404dee038791443ef8f91d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b27e3cd96fa2aa01200ed2e5e0d7cfab991711cdb4dabaa0d4280e52f62a47fb1b4fb08bf5e5bd177ffe86730affc31d22f11df8ba2def813f7a96fa1e1f46ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee590b54caa6da13e31d88eb265011104ab91b3cfe078d8230f78439f0f3523ce3da616aecb786cf7723ac36509267cf9e42366dbd749fc63fe8567ff8c66196"} build_url:https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011144 build_url: 'https://queue.taskcluster.net/v1/task/MVNsXoaYR7upDGvKC9TRsQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f76f32ea5f27a7fd6e7e9dc00ea880b1026d423e3f1707078b9b7b68316c781e5bcf0d44ea9a496d3942ebe27df7e26ab7a97061404dee038791443ef8f91d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b27e3cd96fa2aa01200ed2e5e0d7cfab991711cdb4dabaa0d4280e52f62a47fb1b4fb08bf5e5bd177ffe86730affc31d22f11df8ba2def813f7a96fa1e1f46ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee590b54caa6da13e31d88eb265011104ab91b3cfe078d8230f78439f0f3523ce3da616aecb786cf7723ac36509267cf9e42366dbd749fc63fe8567ff8c66196"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 05:53:32.960012) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:53:32.960390) ========= 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.7JpAZmlKkZ/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.moLiIly5Qm/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.004999 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 05:53:33.009780) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 05:53:33.010130) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 05:53:33.010516) ========= ========= Total master_lag: 0.18 =========