builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0255 starttime: 1473553037.43 results: success (0) buildid: 20160910153416 builduid: 962aace4c67f41a6a25de8cbd1e5d9b7 revision: bef797e6c129249a0ac331be21e04487f2719fc5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:17.432931) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:17.433327) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:17.433594) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:17.561916) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:17.562235) ========= 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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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-10 17:17:17-- 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% 23.8M=0s 2016-09-10 17:17:18 (23.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.511639 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:18.107456) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:18.107826) ========= 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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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.026381 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:18.158119) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-10 17:17:18.158479) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bef797e6c129249a0ac331be21e04487f2719fc5 --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 bef797e6c129249a0ac331be21e04487f2719fc5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gzuDKzzN1C/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.IcKeP4cAwF/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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-10 17:17:18,246 truncating revision to first 12 chars 2016-09-10 17:17:18,246 Setting DEBUG logging. 2016-09-10 17:17:18,246 attempt 1/10 2016-09-10 17:17:18,246 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bef797e6c129?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-10 17:17:19,556 unpacking tar archive at: mozilla-central-bef797e6c129/testing/mozharness/ program finished with exit code 0 elapsedTime=1.570810 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-10 17:17:19.752687) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:19.752990) ========= script_repo_revision: bef797e6c129249a0ac331be21e04487f2719fc5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:19.756410) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:19.756727) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 17:17:19.780216) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-10 17:17:19.780613) ========= /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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:17:19 INFO - MultiFileLogger online at 20160910 17:17:19 in /builds/slave/test 17:17:19 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 17:17:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:17:19 INFO - {'append_to_log': False, 17:17:19 INFO - 'base_work_dir': '/builds/slave/test', 17:17:19 INFO - 'blob_upload_branch': 'Firefox', 17:17:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:17:19 INFO - 'branch': 'Firefox', 17:17:19 INFO - 'buildbot_json_path': 'buildprops.json', 17:17:19 INFO - 'config_files': ('talos/mac_config.py',), 17:17:19 INFO - 'default_actions': ('clobber', 17:17:19 INFO - 'read-buildbot-config', 17:17:19 INFO - 'download-and-extract', 17:17:19 INFO - 'populate-webroot', 17:17:19 INFO - 'create-virtualenv', 17:17:19 INFO - 'install', 17:17:19 INFO - 'run-tests'), 17:17:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:17:19 INFO - 'download_minidump_stackwalk': True, 17:17:19 INFO - 'download_symbols': 'ondemand', 17:17:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:17:19 INFO - 'tooltool.py': '/tools/tooltool.py', 17:17:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:17:19 INFO - '/tools/misc-python/virtualenv.py')}, 17:17:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:17:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:17:19 INFO - 'installer_path': 'installer.exe', 17:17:19 INFO - 'log_level': 'info', 17:17:19 INFO - 'log_name': 'talos', 17:17:19 INFO - 'log_to_console': True, 17:17:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:17:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:17:19 INFO - 'opt_config_files': (), 17:17:19 INFO - 'pip_index': False, 17:17:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:17:19 INFO - 'cmd': ('bash', 17:17:19 INFO - '-c', 17:17:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:17:19 INFO - 'enabled': True, 17:17:19 INFO - 'halt_on_failure': False, 17:17:19 INFO - 'name': 'check_screen_resolution'},), 17:17:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:17:19 INFO - 'cmd': ('bash', 17:17:19 INFO - '-c', 17:17:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:17:19 INFO - 'enabled': True, 17:17:19 INFO - 'halt_on_failure': False, 17:17:19 INFO - 'name': 'check_screen_resolution'},), 17:17:19 INFO - 'run_cmd_checks_enabled': True, 17:17:19 INFO - 'sps_profile': False, 17:17:19 INFO - 'sps_profile_interval': 0, 17:17:19 INFO - 'suite': 'dromaeojs-e10s', 17:17:19 INFO - 'system_bits': '32', 17:17:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:17:19 INFO - 'title': 't-yosemite-r7-0255', 17:17:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:17:19 INFO - 'use_talos_json': True, 17:17:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:17:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:17:19 INFO - 'work_dir': 'build'} 17:17:19 INFO - [mozharness: 2016-09-11 00:17:19.984896Z] Running clobber step. 17:17:19 INFO - Running pre-action listener: _resource_record_pre_action 17:17:19 INFO - Running main action method: clobber 17:17:19 INFO - rmtree: /builds/slave/test/build 17:17:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:17:22 INFO - Running post-action listener: _resource_record_post_action 17:17:22 INFO - [mozharness: 2016-09-11 00:17:22.037821Z] Finished clobber step (success) 17:17:22 INFO - [mozharness: 2016-09-11 00:17:22.037961Z] Running read-buildbot-config step. 17:17:22 INFO - Running pre-action listener: _resource_record_pre_action 17:17:22 INFO - Running main action method: read_buildbot_config 17:17:22 INFO - Using buildbot properties: 17:17:22 INFO - { 17:17:22 INFO - "project": "", 17:17:22 INFO - "product": "firefox", 17:17:22 INFO - "script_repo_revision": "production", 17:17:22 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:17:22 INFO - "repository": "", 17:17:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:17:22 INFO - "buildid": "20160910153416", 17:17:22 INFO - "pgo_build": "False", 17:17:22 INFO - "basedir": "/builds/slave/test", 17:17:22 INFO - "buildnumber": 65, 17:17:22 INFO - "slavename": "t-yosemite-r7-0255", 17:17:22 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:17:22 INFO - "platform": "yosemite_r7", 17:17:22 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:17:22 INFO - "branch": "mozilla-central", 17:17:22 INFO - "repo_path": "mozilla-central", 17:17:22 INFO - "revision": "bef797e6c129249a0ac331be21e04487f2719fc5", 17:17:22 INFO - "stage_platform": "macosx64", 17:17:22 INFO - "builduid": "962aace4c67f41a6a25de8cbd1e5d9b7", 17:17:22 INFO - "slavebuilddir": "test" 17:17:22 INFO - } 17:17:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 17:17:22 INFO - Running post-action listener: _resource_record_post_action 17:17:22 INFO - [mozharness: 2016-09-11 00:17:22.041447Z] Finished read-buildbot-config step (success) 17:17:22 INFO - [mozharness: 2016-09-11 00:17:22.041563Z] Running download-and-extract step. 17:17:22 INFO - Running pre-action listener: _resource_record_pre_action 17:17:22 INFO - Running main action method: download_and_extract 17:17:22 INFO - mkdir: /builds/slave/test/build/tests 17:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:22 INFO - trying https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 17:17:22 INFO - Downloading https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/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 17:17:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/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 17:17:22 INFO - Downloaded 1199 bytes. 17:17:22 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 17:17:22 INFO - Using the following test package requirements: 17:17:22 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:17:22 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:17:22 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 17:17:22 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:17:22 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 17:17:22 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:17:22 INFO - u'jsshell-mac.zip'], 17:17:22 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:17:22 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 17:17:22 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:17:22 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:17:22 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 17:17:22 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:17:22 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 17:17:22 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:17:22 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 17:17:22 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:17:22 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 17:17:22 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:17:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:17:23 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 17:17:30 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 17:17:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:17:30 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 17:17:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 17:17:38 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 17:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:38 INFO - trying https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:17:38 INFO - Downloading https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 17:17:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/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 17:17:42 INFO - Downloaded 111662354 bytes. 17:17:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:17:42 INFO - mkdir: /builds/slave/test/properties 17:17:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:17:42 INFO - Writing to file /builds/slave/test/properties/build_url 17:17:42 INFO - Contents: 17:17:42 INFO - build_url:https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:17:43 INFO - Running post-action listener: _resource_record_post_action 17:17:43 INFO - Running post-action listener: set_extra_try_arguments 17:17:43 INFO - [mozharness: 2016-09-11 00:17:43.018159Z] Finished download-and-extract step (success) 17:17:43 INFO - [mozharness: 2016-09-11 00:17:43.018291Z] Running populate-webroot step. 17:17:43 INFO - Running pre-action listener: _resource_record_pre_action 17:17:43 INFO - Running main action method: populate_webroot 17:17:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:17:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:17:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:17:43 INFO - '--tppagecycles', 17:17:43 INFO - '7'], 17:17:43 INFO - 'tests': ['tsvgm']}}, 17:17:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:17:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:17:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:17:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:17:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:17:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:17:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:43 INFO - 'talos_options': ['--disable-e10s'], 17:17:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:43 INFO - 'talos_options': ['--disable-e10s'], 17:17:43 INFO - 'tests': ['damp', 'tps']}, 17:17:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:43 INFO - 'tests': ['damp', 'tps']}, 17:17:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:17:43 INFO - 'tests': ['dromaeo_dom']}, 17:17:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:17:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:17:43 INFO - 'tests': ['basic_compositor_video']}, 17:17:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:17:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:17:43 INFO - 'tests': ['a11yr', 17:17:43 INFO - 'ts_paint', 17:17:43 INFO - 'tpaint', 17:17:43 INFO - 'sessionrestore', 17:17:43 INFO - 'sessionrestore_no_auto_restore', 17:17:43 INFO - 'tabpaint']}, 17:17:43 INFO - 'other-e10s': {'tests': ['a11yr', 17:17:43 INFO - 'ts_paint', 17:17:43 INFO - 'tpaint', 17:17:43 INFO - 'sessionrestore', 17:17:43 INFO - 'sessionrestore_no_auto_restore', 17:17:43 INFO - 'tabpaint']}, 17:17:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:17:43 INFO - 'ts_paint', 17:17:43 INFO - 'tpaint', 17:17:43 INFO - 'sessionrestore', 17:17:43 INFO - 'sessionrestore_no_auto_restore', 17:17:43 INFO - 'tabpaint']}, 17:17:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:17:43 INFO - 'ts_paint', 17:17:43 INFO - 'tpaint', 17:17:43 INFO - 'sessionrestore', 17:17:43 INFO - 'sessionrestore_no_auto_restore', 17:17:43 INFO - 'tabpaint']}, 17:17:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:17:43 INFO - 'tests': ['a11yr', 17:17:43 INFO - 'ts_paint', 17:17:43 INFO - 'tpaint', 17:17:43 INFO - 'sessionrestore', 17:17:43 INFO - 'sessionrestore_no_auto_restore', 17:17:43 INFO - 'tabpaint']}, 17:17:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:17:43 INFO - 'tests': ['a11yr', 17:17:43 INFO - 'ts_paint', 17:17:43 INFO - 'tpaint', 17:17:43 INFO - 'sessionrestore', 17:17:43 INFO - 'sessionrestore_no_auto_restore', 17:17:43 INFO - 'tabpaint']}, 17:17:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:17:43 INFO - 'tests': ['tsvgx', 17:17:43 INFO - 'tsvgr_opacity', 17:17:43 INFO - 'tart', 17:17:43 INFO - 'tscrollx', 17:17:43 INFO - 'cart']}, 17:17:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:17:43 INFO - 'tsvgr_opacity', 17:17:43 INFO - 'tart', 17:17:43 INFO - 'tscrollx', 17:17:43 INFO - 'cart']}, 17:17:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:43 INFO - 'talos_options': ['--disable-e10s'], 17:17:43 INFO - 'tests': ['tp5o']}, 17:17:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:43 INFO - 'tests': ['tp5o']}, 17:17:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:43 INFO - 'talos_options': ['--disable-e10s', 17:17:43 INFO - '--xperf_path', 17:17:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:43 INFO - 'tests': ['tp5n']}, 17:17:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:17:43 INFO - 'talos_options': ['--xperf_path', 17:17:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:43 INFO - 'tests': ['tp5n']}}, 17:17:43 INFO - 'talos.zip': {'path': '', 17:17:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:17:43 INFO - Running post-action listener: _resource_record_post_action 17:17:43 INFO - [mozharness: 2016-09-11 00:17:43.036912Z] Finished populate-webroot step (success) 17:17:43 INFO - [mozharness: 2016-09-11 00:17:43.037033Z] Running create-virtualenv step. 17:17:43 INFO - Running pre-action listener: _resource_record_pre_action 17:17:43 INFO - Running main action method: create_virtualenv 17:17:43 INFO - Creating virtualenv /builds/slave/test/build/venv 17:17:43 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:17:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:17:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:17:43 INFO - Using real prefix '/tools/python27' 17:17:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:17:43 INFO - Installing distribute.............................................................................................................................................................................................done. 17:17:46 INFO - Installing pip.................done. 17:17:46 INFO - Return code: 0 17:17:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:46 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 0x10249a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9728587b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022aed30>, '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 0x1020d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022a6b20>, '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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:17:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzuDKzzN1C/Render', 17:17:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:46 INFO - 'HOME': '/Users/cltbld', 17:17:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:46 INFO - 'LOGNAME': 'cltbld', 17:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:46 INFO - 'MOZ_NO_REMOTE': '1', 17:17:46 INFO - 'NO_EM_RESTART': '1', 17:17:46 INFO - 'PAGER': '/bin/cat', 17:17:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:46 INFO - 'PWD': '/builds/slave/test', 17:17:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:46 INFO - 'SHELL': '/bin/bash', 17:17:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IcKeP4cAwF/Listeners', 17:17:46 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 17:17:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:46 INFO - 'USER': 'cltbld', 17:17:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:46 INFO - 'XPC_FLAGS': '0x0', 17:17:46 INFO - 'XPC_SERVICE_NAME': '0', 17:17:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:17:47 INFO - Cleaning up... 17:17:47 INFO - Return code: 0 17:17:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10249a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9728587b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022aed30>, '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 0x1020d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022a6b20>, '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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:17:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzuDKzzN1C/Render', 17:17:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:47 INFO - 'HOME': '/Users/cltbld', 17:17:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:47 INFO - 'LOGNAME': 'cltbld', 17:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:47 INFO - 'MOZ_NO_REMOTE': '1', 17:17:47 INFO - 'NO_EM_RESTART': '1', 17:17:47 INFO - 'PAGER': '/bin/cat', 17:17:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:47 INFO - 'PWD': '/builds/slave/test', 17:17:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:47 INFO - 'SHELL': '/bin/bash', 17:17:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IcKeP4cAwF/Listeners', 17:17:47 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 17:17:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:47 INFO - 'USER': 'cltbld', 17:17:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:47 INFO - 'XPC_FLAGS': '0x0', 17:17:47 INFO - 'XPC_SERVICE_NAME': '0', 17:17:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:47 INFO - Downloading/unpacking psutil>=3.1.1 17:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:17:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:50 INFO - Installing collected packages: psutil 17:17:50 INFO - Running setup.py install for psutil 17:17:50 INFO - building 'psutil._psutil_osx' extension 17:17:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:17:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:17:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:17:51 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:17:51 INFO - building 'psutil._psutil_posix' extension 17:17:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:17:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:17:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:17:51 INFO - ^ 17:17:51 INFO - 1 warning generated. 17:17:51 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:17:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:52 INFO - Successfully installed psutil 17:17:52 INFO - Cleaning up... 17:17:52 INFO - Return code: 0 17:17:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:52 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 0x10249a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9728587b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022aed30>, '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 0x1020d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022a6b20>, '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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzuDKzzN1C/Render', 17:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:52 INFO - 'HOME': '/Users/cltbld', 17:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:52 INFO - 'LOGNAME': 'cltbld', 17:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:52 INFO - 'MOZ_NO_REMOTE': '1', 17:17:52 INFO - 'NO_EM_RESTART': '1', 17:17:52 INFO - 'PAGER': '/bin/cat', 17:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:52 INFO - 'PWD': '/builds/slave/test', 17:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:52 INFO - 'SHELL': '/bin/bash', 17:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IcKeP4cAwF/Listeners', 17:17:52 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 17:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:52 INFO - 'USER': 'cltbld', 17:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:52 INFO - 'XPC_FLAGS': '0x0', 17:17:52 INFO - 'XPC_SERVICE_NAME': '0', 17:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:17:54 INFO - Installing collected packages: mozsystemmonitor 17:17:54 INFO - Running setup.py install for mozsystemmonitor 17:17:55 INFO - Successfully installed mozsystemmonitor 17:17:55 INFO - Cleaning up... 17:17:55 INFO - Return code: 0 17:17:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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', '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 0x10249a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9728587b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022aed30>, '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 0x1020d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022a6b20>, '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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:17: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 jsonschema==2.5.1 17:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzuDKzzN1C/Render', 17:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:55 INFO - 'HOME': '/Users/cltbld', 17:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:55 INFO - 'LOGNAME': 'cltbld', 17:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:55 INFO - 'MOZ_NO_REMOTE': '1', 17:17:55 INFO - 'NO_EM_RESTART': '1', 17:17:55 INFO - 'PAGER': '/bin/cat', 17:17:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:55 INFO - 'PWD': '/builds/slave/test', 17:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:55 INFO - 'SHELL': '/bin/bash', 17:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IcKeP4cAwF/Listeners', 17:17:55 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 17:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:55 INFO - 'USER': 'cltbld', 17:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:55 INFO - 'XPC_FLAGS': '0x0', 17:17:55 INFO - 'XPC_SERVICE_NAME': '0', 17:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:55 INFO - Downloading/unpacking jsonschema==2.5.1 17:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:17:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:58 INFO - Downloading functools32-3.2.3-2.tar.gz 17:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:17:58 INFO - warning: no files found matching '*.txt' 17:17:58 INFO - no previously-included directories found matching 'build' 17:17:58 INFO - no previously-included directories found matching 'dist' 17:17:58 INFO - no previously-included directories found matching '.git*' 17:17:58 INFO - Installing collected packages: jsonschema, functools32 17:17:58 INFO - Running setup.py install for functools32 17:17:58 INFO - warning: no files found matching '*.txt' 17:17:58 INFO - no previously-included directories found matching 'build' 17:17:58 INFO - no previously-included directories found matching 'dist' 17:17:58 INFO - no previously-included directories found matching '.git*' 17:17:58 INFO - Successfully installed jsonschema functools32 17:17:58 INFO - Cleaning up... 17:17:58 INFO - Return code: 0 17:17:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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', '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 0x10249a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9728587b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022aed30>, '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 0x1020d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022a6b20>, '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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:17: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 functools32==3.2.3-2 17:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzuDKzzN1C/Render', 17:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:58 INFO - 'HOME': '/Users/cltbld', 17:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:58 INFO - 'LOGNAME': 'cltbld', 17:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:58 INFO - 'MOZ_NO_REMOTE': '1', 17:17:58 INFO - 'NO_EM_RESTART': '1', 17:17:58 INFO - 'PAGER': '/bin/cat', 17:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:58 INFO - 'PWD': '/builds/slave/test', 17:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:58 INFO - 'SHELL': '/bin/bash', 17:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IcKeP4cAwF/Listeners', 17:17:58 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 17:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:58 INFO - 'USER': 'cltbld', 17:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:58 INFO - 'XPC_FLAGS': '0x0', 17:17:58 INFO - 'XPC_SERVICE_NAME': '0', 17:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:17:58 INFO - Cleaning up... 17:17:58 INFO - Return code: 0 17:17:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:17: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')]} 17:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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')]} 17:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17: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', '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 0x10249a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9728587b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022aed30>, '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 0x1020d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022a6b20>, '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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:17: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 blobuploader==1.2.4 17:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzuDKzzN1C/Render', 17:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:58 INFO - 'HOME': '/Users/cltbld', 17:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:58 INFO - 'LOGNAME': 'cltbld', 17:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:58 INFO - 'MOZ_NO_REMOTE': '1', 17:17:58 INFO - 'NO_EM_RESTART': '1', 17:17:58 INFO - 'PAGER': '/bin/cat', 17:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:58 INFO - 'PWD': '/builds/slave/test', 17:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:58 INFO - 'SHELL': '/bin/bash', 17:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IcKeP4cAwF/Listeners', 17:17:58 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 17:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:58 INFO - 'USER': 'cltbld', 17:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:58 INFO - 'XPC_FLAGS': '0x0', 17:17:58 INFO - 'XPC_SERVICE_NAME': '0', 17:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:59 INFO - Downloading/unpacking blobuploader==1.2.4 17:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:18:01 INFO - Downloading blobuploader-1.2.4.tar.gz 17:18:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:18:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:18:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:18:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:18:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:18:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:18:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:18:02 INFO - Downloading docopt-0.6.1.tar.gz 17:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:18:02 INFO - Installing collected packages: blobuploader, requests, docopt 17:18:02 INFO - Running setup.py install for blobuploader 17:18:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:18:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:18:02 INFO - Running setup.py install for requests 17:18:03 INFO - Running setup.py install for docopt 17:18:03 INFO - Successfully installed blobuploader requests docopt 17:18:03 INFO - Cleaning up... 17:18:03 INFO - Return code: 0 17:18:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:03 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 0x10249a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9728587b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022aed30>, '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 0x1020d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022a6b20>, '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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:18:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:18:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzuDKzzN1C/Render', 17:18:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:03 INFO - 'HOME': '/Users/cltbld', 17:18:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:03 INFO - 'LOGNAME': 'cltbld', 17:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:03 INFO - 'MOZ_NO_REMOTE': '1', 17:18:03 INFO - 'NO_EM_RESTART': '1', 17:18:03 INFO - 'PAGER': '/bin/cat', 17:18:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:03 INFO - 'PWD': '/builds/slave/test', 17:18:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:03 INFO - 'SHELL': '/bin/bash', 17:18:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IcKeP4cAwF/Listeners', 17:18:03 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 17:18:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:03 INFO - 'USER': 'cltbld', 17:18:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:03 INFO - 'XPC_FLAGS': '0x0', 17:18:03 INFO - 'XPC_SERVICE_NAME': '0', 17:18:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:18:04 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-qHTF98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:18:04 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-jBO6MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:18:04 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-UTc0d9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:18:04 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-mZwGF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:18:04 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-LaEXi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:18:04 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-f7Axvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:18:04 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-UsfIy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:18:04 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-NUgVpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:18:04 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-XmKANA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:18:04 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-Cudron-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:18:05 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-W7GAMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:18:05 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-2Hej_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:18:05 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-GuYGc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:18:05 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-r3a30m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:05 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-RLFVif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:18:05 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-raV4PP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:18:05 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-UtbN0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:18:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:18:05 INFO - Running setup.py install for manifestparser 17:18:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:18:05 INFO - Running setup.py install for mozcrash 17:18:06 INFO - Running setup.py install for mozdebug 17:18:06 INFO - Running setup.py install for mozdevice 17:18:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:18:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:18:06 INFO - Running setup.py install for mozfile 17:18:06 INFO - Running setup.py install for mozhttpd 17:18:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:18:06 INFO - Running setup.py install for mozinfo 17:18:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:18:06 INFO - Running setup.py install for mozInstall 17:18:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:18:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:18:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:18:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:18:06 INFO - Running setup.py install for mozleak 17:18:07 INFO - Running setup.py install for mozlog 17:18:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:18:07 INFO - Running setup.py install for moznetwork 17:18:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:18:07 INFO - Running setup.py install for mozprocess 17:18:07 INFO - Running setup.py install for mozprofile 17:18:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:18:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:18:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:18:07 INFO - Running setup.py install for mozrunner 17:18:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:18:07 INFO - Running setup.py install for mozscreenshot 17:18:08 INFO - Running setup.py install for moztest 17:18:08 INFO - Running setup.py install for mozversion 17:18:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:18:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:18:08 INFO - Cleaning up... 17:18:08 INFO - Return code: 0 17:18:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:08 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 0x10249a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9728587b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022aed30>, '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 0x1020d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022a6b20>, '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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:18:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:18:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzuDKzzN1C/Render', 17:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:08 INFO - 'HOME': '/Users/cltbld', 17:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:08 INFO - 'LOGNAME': 'cltbld', 17:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:08 INFO - 'MOZ_NO_REMOTE': '1', 17:18:08 INFO - 'NO_EM_RESTART': '1', 17:18:08 INFO - 'PAGER': '/bin/cat', 17:18:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:08 INFO - 'PWD': '/builds/slave/test', 17:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:08 INFO - 'SHELL': '/bin/bash', 17:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IcKeP4cAwF/Listeners', 17:18:08 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 17:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:08 INFO - 'USER': 'cltbld', 17:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:08 INFO - 'XPC_FLAGS': '0x0', 17:18:08 INFO - 'XPC_SERVICE_NAME': '0', 17:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:18:08 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-mJp1Xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:18:08 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-CCeWpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:18:08 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)) 17:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:18:08 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-W3Y5Qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:18:08 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-sApoTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:18:09 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-Yrbj3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:18:09 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-Rpkkan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:18:09 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-YkYeo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:18:09 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-0nNuWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:18:09 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-VN7Ck0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:18:09 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-fzAGLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:18:09 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)) 17:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:18:09 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-L0HhpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:18:09 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-SJgz4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:18:09 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-3KSKiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:18:09 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-ihBVdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:18:10 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)) 17:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:10 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-mKOJRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:18:10 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-_QhtLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:18:10 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)) 17:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:18:10 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-hhXRL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:18:10 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)) 17:18:10 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)) 17:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:18:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:18:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:18:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:18:12 INFO - Downloading blessings-1.6.tar.gz 17:18:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:18:12 INFO - Installing collected packages: blessings 17:18:12 INFO - Running setup.py install for blessings 17:18:13 INFO - Successfully installed blessings 17:18:13 INFO - Cleaning up... 17:18:13 INFO - Return code: 0 17:18:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:18:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:18:13 INFO - Reading from file tmpfile_stdout 17:18:13 INFO - Current package versions: 17:18:13 INFO - blessings == 1.6 17:18:13 INFO - blobuploader == 1.2.4 17:18:13 INFO - docopt == 0.6.1 17:18:13 INFO - functools32 == 3.2.3-2 17:18:13 INFO - jsonschema == 2.5.1 17:18:13 INFO - manifestparser == 1.1 17:18:13 INFO - mozInstall == 1.12 17:18:13 INFO - mozcrash == 0.17 17:18:13 INFO - mozdebug == 0.1 17:18:13 INFO - mozdevice == 0.48 17:18:13 INFO - mozfile == 1.2 17:18:13 INFO - mozhttpd == 0.7 17:18:13 INFO - mozinfo == 0.9 17:18:13 INFO - mozleak == 0.1 17:18:13 INFO - mozlog == 3.3 17:18:13 INFO - moznetwork == 0.27 17:18:13 INFO - mozprocess == 0.23 17:18:13 INFO - mozprofile == 0.28 17:18:13 INFO - mozrunner == 6.12 17:18:13 INFO - mozscreenshot == 0.1 17:18:13 INFO - mozsystemmonitor == 0.3 17:18:13 INFO - moztest == 0.7 17:18:13 INFO - mozversion == 1.4 17:18:13 INFO - psutil == 3.1.1 17:18:13 INFO - requests == 1.2.3 17:18:13 INFO - wsgiref == 0.1.2 17:18:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:13 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 0x10249a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9728587b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022aed30>, '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 0x1020d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022a6b20>, '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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:18:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzuDKzzN1C/Render', 17:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:13 INFO - 'HOME': '/Users/cltbld', 17:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:13 INFO - 'LOGNAME': 'cltbld', 17:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:13 INFO - 'MOZ_NO_REMOTE': '1', 17:18:13 INFO - 'NO_EM_RESTART': '1', 17:18:13 INFO - 'PAGER': '/bin/cat', 17:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:13 INFO - 'PWD': '/builds/slave/test', 17:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:13 INFO - 'SHELL': '/bin/bash', 17:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IcKeP4cAwF/Listeners', 17:18:13 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 17:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:13 INFO - 'USER': 'cltbld', 17:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:13 INFO - 'XPC_FLAGS': '0x0', 17:18:13 INFO - 'XPC_SERVICE_NAME': '0', 17:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:18:13 INFO - Cleaning up... 17:18:13 INFO - Return code: 0 17:18:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:13 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 0x10249a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102410570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9728587b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022aed30>, '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 0x1020d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022a6b20>, '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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:18:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzuDKzzN1C/Render', 17:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:13 INFO - 'HOME': '/Users/cltbld', 17:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:13 INFO - 'LOGNAME': 'cltbld', 17:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:13 INFO - 'MOZ_NO_REMOTE': '1', 17:18:13 INFO - 'NO_EM_RESTART': '1', 17:18:13 INFO - 'PAGER': '/bin/cat', 17:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:13 INFO - 'PWD': '/builds/slave/test', 17:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:13 INFO - 'SHELL': '/bin/bash', 17:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IcKeP4cAwF/Listeners', 17:18:13 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 17:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:13 INFO - 'USER': 'cltbld', 17:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:13 INFO - 'XPC_FLAGS': '0x0', 17:18:13 INFO - 'XPC_SERVICE_NAME': '0', 17:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:18:13 INFO - Cleaning up... 17:18:14 INFO - Return code: 0 17:18:14 INFO - Running post-action listener: _resource_record_post_action 17:18:14 INFO - Running post-action listener: _start_resource_monitoring 17:18:14 INFO - Starting resource monitoring. 17:18:14 INFO - [mozharness: 2016-09-11 00:18:14.033478Z] Finished create-virtualenv step (success) 17:18:14 INFO - [mozharness: 2016-09-11 00:18:14.034481Z] Running install step. 17:18:14 INFO - Running pre-action listener: _resource_record_pre_action 17:18:14 INFO - Running main action method: install 17:18:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:18:14 INFO - Reading from file tmpfile_stdout 17:18:14 INFO - Detecting whether we're running mozinstall >=1.0... 17:18:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:18:14 INFO - Reading from file tmpfile_stdout 17:18:14 INFO - Output received: 17:18:14 INFO - Usage: mozinstall [options] installer 17:18:14 INFO - Options: 17:18:14 INFO - -h, --help show this help message and exit 17:18:14 INFO - -d DEST, --destination=DEST 17:18:14 INFO - Directory to install application into. [default: 17:18:14 INFO - "/builds/slave/test"] 17:18:14 INFO - --app=APP Application being installed. [default: firefox] 17:18:14 INFO - mkdir: /builds/slave/test/build/application 17:18:14 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'] 17:18:14 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 17:18:48 INFO - Reading from file tmpfile_stdout 17:18:48 INFO - Output received: 17:18:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:18:48 INFO - Running post-action listener: _resource_record_post_action 17:18:48 INFO - [mozharness: 2016-09-11 00:18:48.468793Z] Finished install step (success) 17:18:48 INFO - [mozharness: 2016-09-11 00:18:48.468957Z] Running run-tests step. 17:18:48 INFO - Running pre-action listener: _resource_record_pre_action 17:18:48 INFO - Running main action method: run_tests 17:18:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:18:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:18:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:18:48 INFO - 2016-09-10 17:18:48.483 screenresolution[1652:8437] starting screenresolution argv=screenresolution get 17:18:48 INFO - 2016-09-10 17:18:48.486 screenresolution[1652:8437] Display 0: 1600x1200x32@60 17:18:48 INFO - 2016-09-10 17:18:48.492 screenresolution[1653:8439] starting screenresolution argv=screenresolution list 17:18:48 INFO - Available Modes on Display 0 17:18:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:18:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:18:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:18:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:18:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:18:48 INFO - Intel Iris: 17:18:48 INFO - Chipset Model: Intel Iris 17:18:48 INFO - Type: GPU 17:18:48 INFO - Bus: Built-In 17:18:48 INFO - VRAM (Dynamic, Max): 1536 MB 17:18:48 INFO - Vendor: Intel (0x8086) 17:18:48 INFO - Device ID: 0x0a2e 17:18:48 INFO - Revision ID: 0x0009 17:18:48 INFO - Displays: 17:18:48 INFO - Display: 17:18:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:18:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:18:48 INFO - Display Serial Number: 1600x1200 60 17:18:48 INFO - Main Display: Yes 17:18:48 INFO - Mirror: Off 17:18:48 INFO - Online: Yes 17:18:48 INFO - Rotation: Supported 17:18:48 INFO - Return code: 0 17:18:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:18:48 INFO - Python 2.7.3 17:18:48 INFO - Return code: 0 17:18:48 INFO - grabbing minidump binary from tooltool 17:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:48 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 0x1022aed30>, '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 0x1020d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022a6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:18:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:18:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:18:48 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:18:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:18:48 INFO - Return code: 0 17:18:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:18:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:18:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:18:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:18:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:18:48 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0255', '--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 17:18:48 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0255 --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 17:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gzuDKzzN1C/Render', 17:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:48 INFO - 'HOME': '/Users/cltbld', 17:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:48 INFO - 'LOGNAME': 'cltbld', 17:18:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:18:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:48 INFO - 'MOZ_NO_REMOTE': '1', 17:18:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:18:48 INFO - 'NO_EM_RESTART': '1', 17:18:48 INFO - 'PAGER': '/bin/cat', 17:18:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:48 INFO - 'PWD': '/builds/slave/test', 17:18:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:48 INFO - 'SHELL': '/bin/bash', 17:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IcKeP4cAwF/Listeners', 17:18:48 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 17:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:48 INFO - 'USER': 'cltbld', 17:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:48 INFO - 'XPC_FLAGS': '0x0', 17:18:48 INFO - 'XPC_SERVICE_NAME': '0', 17:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:48 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0255', '--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 17:18:49 INFO - mozversion application_buildid: 20160910153416 17:18:49 INFO - mozversion application_changeset: bef797e6c129249a0ac331be21e04487f2719fc5 17:18:49 INFO - mozversion application_display_name: Nightly 17:18:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:18:49 INFO - mozversion application_name: Firefox 17:18:49 INFO - mozversion application_remotingname: firefox 17:18:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:18:49 INFO - mozversion application_vendor: Mozilla 17:18:49 INFO - mozversion application_version: 51.0a1 17:18:49 INFO - mozversion platform_buildid: 20160910153416 17:18:49 INFO - mozversion platform_changeset: bef797e6c129249a0ac331be21e04487f2719fc5 17:18:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:18:49 INFO - mozversion platform_version: 51.0a1 17:18:49 INFO - using testdate: 1473553129 17:18:49 INFO - actual date: 1473553129 17:18:49 INFO - starting webserver on 'localhost:49285' 17:18:49 INFO - SUITE-START | Running 2 tests 17:18:49 INFO - TEST-START | dromaeo_css 17:18:49 INFO - operating with platform_type : mac_ 17:18:49 INFO - Initialising browser for dromaeo_css test... 17:18:49 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmpu8y39J/profile http://localhost:49285/getInfo.html) 17:18:52 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 17:18:52 INFO - PROCESS | 1663 | colorDepth:24 17:18:52 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 17:18:52 INFO - PROCESS | 1663 | __metrics 17:18:52 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 17:18:52 INFO - TEST-INFO | 1663: exit 0 17:18:52 INFO - Browser initialized. 17:18:52 INFO - Running cycle 1/1 for dromaeo_css test... 17:18:52 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmpu8y39J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:19:04 INFO - PROCESS | 1667 | RSS: Main: 244461568 17:19:04 INFO - PROCESS | 1667 | 17:19:54 INFO - PROCESS | 1667 | 1473553194470 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] 17:22:06 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49285/tests/dromaeo/cssquery-ext.html) 17:22:08 INFO - PROCESS | 1667 | RSS: Main: 266907648 17:22:08 INFO - PROCESS | 1667 | 17:25:10 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-ext.html (next: http://localhost:49285/tests/dromaeo/cssquery-jquery.html) 17:25:11 INFO - PROCESS | 1667 | RSS: Main: 273760256 17:25:11 INFO - PROCESS | 1667 | 17:28:12 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49285/tests/dromaeo/cssquery-mootools.html) 17:28:13 INFO - PROCESS | 1667 | RSS: Main: 274370560 17:28:13 INFO - PROCESS | 1667 | 17:31:15 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49285/tests/dromaeo/cssquery-prototype.html) 17:31:15 INFO - PROCESS | 1667 | RSS: Main: 268763136 17:31:15 INFO - PROCESS | 1667 | 17:34:18 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49285/tests/dromaeo/cssquery-yui.html) 17:34:19 INFO - PROCESS | 1667 | RSS: Main: 273948672 17:34:19 INFO - PROCESS | 1667 | 17:37:22 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-yui.html (next: http://localhost:49285/tests/dromaeo/cssquery-dojo.html) 17:37:22 INFO - PROCESS | 1667 | RSS: Main: 277807104 17:37:22 INFO - PROCESS | 1667 | 17:37:22 INFO - PROCESS | 1667 | __start_tp_report 17:37:22 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 17:37:22 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 17:37:22 INFO - PROCESS | 1667 | |i|pagename|runs| 17:37:22 INFO - PROCESS | 1667 | |0;dojo.html;3381.32373525295;3395.3888300105978;3427.263138425238;3441.2773317603287;3443.8966830995073;4882.536159064892;5488.807891723791;5663.7168141592965;5764.25064741583;5803.448672376117;5738.340090889549;5739.942600573993;5775.1337299405;5851.122331650266;5906.940930590693;5162.070827251093;5207.713575753332;5269.657472264291;5309.256704061435;5335.253064570944;5308.5487733542695;5325.334333208349;5336.519713225809;5355.6251062425645;5395.7859481616715;5168.586513078944;5215.608829337815;5246.422893481733;5256.448072952361;5289.920651190235;4258.829646814123;4377.5403582623685;5294.285271488377;5295.841124766263;5312.096943519611;5189.351331083614;5239.869003274948;5243.6067294952645;5277.287566178555;5285.550728188108;5026;5615.66306021636;5680.5455563554815;5687.146927960797;5716.456936591017;6080.270367555878;6177.452497400357;6204.906926396107;6220.1602783624085;6276.246850377984;6195.845103872394;6204.565680402374;6208.472279856218;6218.657973811439;6225.3774622537385;4865.781039853215;5897.64614123154;5919.999999999957;5939.198208241875;5960.4635582797755;5608.663480191201;6314.7710384312795;6320.115183874262;6352.174217351807;6353.682315884187;6195.504359651217;6197.628142311476;6200.100985357098;6210.099535567318;6580.769673061515;6103.6104255942055;6119.929102025404;6146.815595532052;6171.56799024064;6174.197354343906;6161.353057928943;6173.938260617426;6177.660228687465;6181.320054793969;6199.876002479925;6137;6161.96919015411;6174.197354343996;6195.19462469885;6210.161628180228;4206.915861682749;4270.701050926467;4874.878128046827;4919.680220785637;4945.159322915113;3789.621037896188;4411.228035093845;4446.510883802779;4462;4536.750478723702;5008.474110218447;5824.271966004249;5904.645721256738;5912.438318359749;5928.992071347881;5739.852029594098;5919.230500034968;6177.876442471125;6240.563160578716;6241.001439769615;5278.683279003272;5828.213191219131;5919.644821310735;5937.52499800015;5948.375420580864;5016.297718319438;5412.134058550613;5431.429699881534;5436.510714035756;5440.102383106743;6198.845028874314;6256.937430625636;6308.716107775161;6343.238811342576;6374.426301632875;6136.85608443425;6186.690665466619;6220.875582488416;6239.1577137085915;6268.279147897868;6375.7449702013255;6383.840403989938;6385.776497822554;6390.840228994312;6414.166158399378;6073.483753880793;6083.361247068994;6094.0482387643915;6105.581053426346;6231.1276421300145;6158.538109641705;6169.444749972524;6178.073289006685;6184.381561843779;6199.349068347939;6000.669963151894;6026.0659597762415;6065.636061836304;6085.330613632408;6101.511879049576;4938.580220681139;5874.794382196603;6017.127516509994;6078.422549857756;6126.050462178369;5053.787091098036;5951.791687290924;6016.909746353896;6081.361457046946;6129.540284478771;5332.200169974535;5586.16207568868;5855.853603659943;6185.319614842274;6199.225096862892;5809.331926828469;5942.1383899335215;6045.093236014633;6046.244219472566;6054.122152287981 17:37:22 INFO - PROCESS | 1667 | |1;ext.html;446.4999200894998;498.5662473647927;531.9015982043322;534.126702840855;535.8981793459243;39386.409203861964;42347.57652423483;42404.78797606016;42452.99999999996;42481.57518424815;42751;43261.78369108158;43778.562214377846;44074.559254407526;44187.89530261745;9740.318177727544;9935.950320248388;9944.5524951377;9949.403035817837;9950.502474876263;8381.329493640525;9723.659671911484;9874.851877221812;9877.950610246939;9885.209183265322;9913.058259465375;9922;9932.155766759834;9948.25388095892;9978.850317245247;9863.753406164831;9900.455474948874;9901.702948911545;9920.255980801468;9929.851052234222;9639.084286992724;9700.223982081416;9770.51147442625;9844.704658860246;9846.852297215475;34209.31581368384;35432.64567354319;42711.145777084595;43110.56889431097;43282.56717432817;2677.4243537639304;2812.5921741347593;2841.4885320642275;3338.3156452927333;3480.7911525308564;25246.13255942895;31273.687263127304;31777.682223177933;31933.52099718506;33151.171220719436;3197.2646291352885;3199.2641692410875;3201.439748044083;3216.565763621928;3355.899323020313;434172;444911;446546;447566;451462;61340.693296534126;61534.38465615376;61600;61608.38391616116;61620.38379616236;34356.65643343585;35516;35620.82189589035;63058.684706576176;63061.68469157717;16532.256048477608;16710.749338759928;16735.58161045959;16739.16304184786;16744;12663.176893501894;12677.873221267853;12777;12777.744445111231;12781.936090319488;19040.61918761617;19041.238350466105;19440.125194366286;19475.318363857197;19580;6084.361142080106;7840.568768717775;7963.800904977306;7982.201779821971;8031.718889838828;835.703325319515;900.4192295969063;927.5918595817818;983.6901376066517;1010.161565900301;24995.12517061894;27690;29664.703352966193;30322;30366.54450183249;802.4823988527368;870.815690660701;998.0169533009979;1035.5443604813859;1049.574922156528;13068.803967940488;15461.304241309168;15526.844731552539;15717.292721827545;15894.602634933988;702320;705845;709414;709462;709872;58057.70971145117;58086.4191358081;58542.4145758537;58718.0537925661;58789.41210587925;1313.7766579681477;1344.509254122258;1365.6722386627139;1367.2548461088732;1367.500862185315;28414.00550980706;29146.27134321659;29204.561931570617;29777.5533367004;29780.404391912474;49966;50198.74900625474;50342;50392;50398.49601503938;605094;608980;609098;609681.6127355034;610842;26101.608475873258;26646.600300995113;26711.86644066845;26782.19653410401;26984;46884.82787930329;47396.2890556635;47873.76063119662;48203;48498;37774.43338349982;39453;39513.407298891114;40352;40481;38488.845334640006;39117.80441097776;39382.60617393789;39909.40135898022;40363;27338.45323093567;37955.24089518139;38693.806530967166;39276.30493578358;39286;29843.552346714383;34871.65128348785;34955.126121847155;35078.29843403168;35524.822375887954;25451.10921117752;25786.226413207634;25793.484130317665;25999;26135.607965880146 17:37:22 INFO - PROCESS | 1667 | |2;jquery.html;366.06653034761354;373.4323827781773;375.4574639645712;379.7835233916668;400.90979529605823;16470.42353517624;19162.041897905103;20958.476038099056;21274.893625531888;21523;19999.50001249966;20366.389008329774;20635.484112897186;20825.895870520664;20946.685799713017;5080.9584035272865;5179.844604661847;5943.762249510014;6012.75948962041;6072;6000.399960003986;6016.428439298282;6131.3868613138775;6131.448169664729;6140.35526269742;5925.674087925162;5956.808638272341;6013.098035294719;6049.939500604982;6054.424829641199;5705.942940570582;5857.355690874022;5859.4140585941705;5964.135200395962;6006.5795394322195;5152;5216.634835561512;5219;5220.321358223445;5234.973825130888;25624.74375256261;25739.742602574108;25740;25751.35621609456;26093.347666308306;2249.831262655291;2311.664808602743;2338.3452633262546;2356.6229403295392;3059.938801223985;14751.336189871481;15537.611559710984;15553.455629052929;15957.281922313523;16126.113063781488;1746.493516880116;1849.7780266367988;1889.896055716935;1920.5582715975404;1927.1616846671743;119969.80030199673;121188;121604;121875;124996;10503.949605039588;10548.630797922036;10937.726556835984;11047.613333533287;11179.385133817717;13582.86417135816;13698.041137120505;13716.931415342859;13732.107413018322;13739.24434156111;8238.917610823815;8389.916100839035;8524.577066730486;8634.525101119372;8654.956725216334;7039.36645701879;8443.746687599381;8608.827823443497;8704.825903481895;8843.850299461157;6503.154589903283;6567;6610.272869984298;6617.966910165418;6651;1869.5886904880904;1872.7565416495768;1873.7470441490498;1875.577994951125;1878.164216923456;807.0275318241622;859.3426029087752;883.3728053082255;963.8626420823356;1063.6436793674052;19468.902655486632;21359.893200533897;22213.333599992027;22329.665055024187;22368.440788980406;927.5872236854669;981.5779214937503;1003.4029752297372;1031.417249254169;1065.0042210533177;11583.247088939192;11634.127440442;11651.766964660828;11821.645350639494;11885.762284754257;544393.2780336073;567981;598959;605273;606206.9689651524;121354;122116.38941805233;122247;123328.38335808444;124518;990.4354517924713;995.6963126246565;1028.2134167361974;1031.3244824639874;1032.8554002439664;15894.841051589336;17988.55028624295;19087.713684294467;19637.70543441878;19768.110435030172;51502.48497515127;51685.48314516957;52110.95778084493;53416;54062.72968635132;73128.17179570554;74340;74452.25547744453;75463.62268188623;76031.61984190044;5421.972890135524;5668.064769313182;6596.637184954681;7667.463277570517;8046.436749427484;12649.747005060031;12744.42650080767;13086.411111499814;13099.279539625411;13622.250776207404;10802.135829133493;10841.566337346734;11185.944070279596;11191.16066295015;11321.377324247245;8842.381033327605;10681.57273709043;10986.560537578407;11042.779144417227;11485.253458525169;10942.23404361687;11027.22809403334;11156.944215278872;11411;11467;7720.691172353043;7921.881171782313;7970;8097.1093179749005;8112.269895709414;10867.347959122479;10918.399488027917;11124.941885751163;11261.042811361127;11338.546458141582 17:37:22 INFO - PROCESS | 1667 | |3;mootools.html;1302.4594793160838;1478.0762023735165;1630.6983208106494;1811.3841293960052;1815.8819676721012;7453.701851925924;7990.800229994253;8262.504249745005;9023.864642030367;9040.095990400956;8503.872441913361;8803.031666516694;8824.735257942257;8879.866801997976;8884.689035883745;2388.7252965908883;2419.4072452249225;2419.830611857162;2423.5395274897714;2431.3070774829175;2380.785729284364;2403.2790162951133;2405.1341517053934;2418.117387153685;2433.6592876997233;2385.654080158376;2396.1373905394044;2402.0992127952018;2408.735039145701;2409.759024097585;2038.2458490358588;2346.343023953296;2379.643053541974;2381.3808409813487;2381.8094552435764;2370.3481542575755;2385.1175065225802;2389.474315650554;2399.3042017814814;2400.1719406804623;8867.379283450096;8936.553172341357;8953.507557084358;8973;8993.10068993102;5243.501867322599;5410.945890541084;5412.07994640912;5422.403535611119;5422.783088676449;3172.952405713916;3406.744494162948;3666.8716594919315;3749.1189570451197;3750.49368335274;1088.2817340555196;1091.2743025887776;1092.4100985467917;1103.586155191803;1109.4009235013084;11545.365004924726;11715.297082175011;11785.823212651818;11788.17482776206;11803.645890623295;4554.681172317906;4744.4069491313585;4840.273958906122;4860.003699241647;4860.052289803525;8658.653653853902;8788.692395766118;9817.656382026595;10087;10087.697369078793;5321;5376.38171610262;5381.219723140122;5395.865103372368;5405.378381486101;4425.269830478;4568.474625418119;4748.501407352178;4892.021595680807;4924.556789888928;3353.2119951811305;3920.5102061216553;4044.656204222615;4072.4095006223934;4125.64931980785;1122.5678113926033;1294.3981048812348;1305.9869401306055;1306.2293246984325;1307.6534718299658;772.169917338863;870.9782255443538;905.9954700226457;907.0793144957873;949.2358651285643;5837.970810146007;5913.290405151323;5992.550558708114;6030.547708921848;6089.391060893875;703.0368395298372;800.9799755006171;823.3783493462397;880.872273520336;883.6067949762422;3582.3193593217206;3985.242803867256;4018.0758425562285;4018.256622524784;4117.5882411758585;9033.77415564616;10598.25812193139;10888.074513666426;11004.724881878017;11042.668719938414;10988.780224395627;10996.725081873017;10998.175136864767;11004.944975275072;11084.778304434027;8230.382721295808;8298.170182981654;8330.8333833323;8359.498430094092;8399.076101628816;5616.859578510406;5821.650700957956;5939.970300148471;5941.37615550361;6018.458338749533;8410.705625303084;9688.806223875625;10084.193264538966;10251.794964100527;10309;10221.74445638865;10335.483225838527;10337.9483102584;10357.533910974174;10365.740856478347;6378.266499352634;6625.105610742488;6836.692348844414;7117.3238542338395;7207.5675459472595;8309.83380332378;8602.569871506525;8618;8627.43921645091;8630;7426.851462970819;7513.2862378074;7584.582847943196;7645.808854778675;7748.225177482207;7365.9631701841145;7445.329920307198;7516;7518.624068796429;7693.192214817587;7016;7018.017477553249;7025.402840758388;7073.752418665322;7122.074130363019;4975.850724478271;5080.1363768160245;5117.744112794271;5132.769025393941;5177.2492988515705;5082.847514574569;5093.974530127326;5158.277841102174;5186.8962620746615;5237.135872580981 17:37:22 INFO - PROCESS | 1667 | |4;prototype.html;302.9394121175764;304.5097393196953;311.397445942102;315.90364938693716;315.9715625593698;4760.642951778613;4808.278758186265;5129.512696293851;5407.918881216785;5454.836354909354;4817.411028783552;5104.412992505863;5508.807191748289;5521.641093328931;5602.187682785993;4232.5767423257585;4235.110626768383;4259.10558782656;4266.8293268269235;4280.914381712364;3761.0973366392004;4103.322951712964;4151.813168407414;4279.679024073207;4312.762798046107;4208.894777630553;4227.661787057028;4231.4710661167355;4249.532551419356;4251.957480425187;3484.8083355415442;4224.613093602135;4237.322028475444;4267.1892340455215;4282.528921818597;3682.3739964206147;3697.5193224880863;3698.7965661888584;3705.1474262868564;3717.182219911615;5189.42916279209;5450.400455949843;5455.427180146067;5462.781488740446;5491.28613280271;4283.850065247701;4346.6088052075465;4370.475542934836;4842.297866809293;4856.830010949635;5589.723241741899;5888.5289176865745;5897.203877476529;5909.852253693649;5941.494972927307;3364.680355366236;3537.567285249478;4036.3138266494766;4038.6567141792984;4064.817083231232;6144.446999770043;6233.968830155865;6252.5927517905475;6281.864166183565;6300.590461620026;4120.91758164841;4131.938020929689;4136.710430269913;4137.213929353413;4158.708890377645;3694.630536946284;3785.9432108518395;3964.969108031875;4076.9592304077173;4131.400946862748;4037.555868854424;4808.447028591757;4818.4217893852965;4844.297576851337;4856.68431551955;5970.611600473649;5975.910361344583;6001.459868611759;6027.367126451747;6049.791825983572;1242.490578862662;1318.8417389913272;1331.5605850069453;1389.6039628705973;1402.5511836212313;813.9593020348959;827.9793005174798;847.720252316734;853.8164294676675;856.7044369692446;3948.802559871995;4052.5744796796503;4707.599854012447;4717.551832575899;4732.361131247307;4274.829006839754;4967.875803104951;5162.8641698826195;5174.767135478913;5177.223416487557;4126.42230087788;4347.782610869507;4529.754317562697;4695.389599352063;4708.576228139415;2116.883116883117;2474.4308808974038;2626.6060090986507;2686.8790904409348;2751.7110703376256;4408.625266852289;4815.2054910940005;4843.733594652257;5016.072026675077;5334.7065911374475;4597.563231493003;4837.201861692852;5215.348081489814;5229.463710100598;5323.098911093463;5074.594032477393;5578.302712160898;5970.31341395745;6412.6793660317735;6456.289808121197;5187.610929180251;6220.066989951543;6247.718852651732;6279.403456671609;6325.746970121144;5501.726472748868;5587.525060369914;5778.36437991829;6078.209832721718;6089;5018.44797072329;5186.144286192736;5697.772089116389;5868.970655146868;6438.356164383524;3720.441933709857;3867.2458870521104;3977.980110099431;3986.541547721933;4111.157212771434;4992.8751781205765;5534.647774695658;5648.181013753065;6101.542384321105;6152.661603611844;4948.956391819077;5006.499350064964;5873.911891321719;5972.970135149297;6010.188624535632;5575.581831362583;5766.452187042224;5810.767569297181;5965.254343207099;6011.6693581853415;5155.43290238081;5847.298324201123;5876.911846322223;5877.882442351043;5921.282827979838;4786.425628924551;5744.798932037359;5899.873067274351;5949.851253718692;6014.939850601613;4737.739424331695;5688;5743.540516758566;5802.535797136304;5829.358770535153 17:37:22 INFO - PROCESS | 1667 | |5;yui.html;610.7967304410313;642.5437939063265;708.1396103733962;714.367784510708;771.5486440432345;10842.783144337118;11262.54949802008;15129.016613920116;15882.602934926617;16089.275982580783;14505;14606.707865842676;14621.049631773927;14623.464536223677;14686.045407048561;679.6907407129769;691.339770519153;854.2824027816648;856.3106699107216;870.2428886868433;844.2275318083977;856.644492535597;867.9931279715531;871.3595507302127;871.8474267003281;883.0683628771643;918.8851393575803;919.3932004876783;924.6809850601566;925.5002298758662;915.1946287267162;918.4397517514311;920.4293338130335;922.5525620074259;924.0297687428207;644.5294934697697;646.0599827251338;681.7681988124061;683.8119517132778;686.306830101601;15091.622709432242;15132.621684457867;15403.07581545111;15552.91129620927;15554.455594054154;14588.489402870848;14691.853081469155;14735;14771.852281477155;14865.405383784635;11922.463489143009;12015.21901076436;12039.819402708967;12153.966912812422;12192.268463892195;3709.55485341758;3762.6801721853676;3782.7730336179916;3903.3559462688904;3920.7059470539825;123436;124604;125863.37068314692;126908.36545817212;130625.34687326598;11309.264897781617;11346.149038822121;11355.54577816878;11371.943140284246;11729.88270117305;23707.644385334235;23787.40531486727;24123;24128.87935560311;24205.39486512816;8103.392245581605;8128;8185.549794761345;8189.426740128253;8246;4295.72077814941;4438.622717069021;4514.796834142471;4529;4636.79134438951;3671.7980511072146;3689.9631003690156;3762.6049264827348;3786.905327366783;3789.810509474515;663.4195079305607;668.1481111582771;673.9393454589012;681.6285124607101;693.6358411833727;661.1900421983032;662.8607992321666;676.5568552598056;682.846359569093;695.5513693667556;8423.073461919184;8447.493150410995;9392.906070939203;9396.906030939603;9973.800523989625;511.6290689250264;641.9422251997343;679.9762008329784;683.6513378177166;685.0990946904805;4697.976510117427;4706.552877476634;4714.85233158046;4727.314539391769;4746.382970213919;102135;111161.44419278014;130380;130583;168461.2250171435;134077;139439;142121;142837.28581357026;147733.52266477197;108630;114683.85316146899;118802.81197188263;120723;124191;9107.59015844302;9108.765160141691;9110.498922559058;9113.635454581743;9321.347505674808;56992.30076992433;58215;65892.34107658862;67334.66332668305;68039;86384;94535.52732236295;98137.50931245583;100189;103045.48477257865;12010.879891200977;12160.96631786308;12258.387080646111;12360.629181124581;12372.752544949231;13133.737325253633;13200.221852468001;13337;13383.39774710121;13390.799138012742;12198.756024879276;12226.388680566113;12445.813312800134;12460.626181214579;12785.680357991125;12194.390280486117;12217.755644887231;12294.569690060805;12527.498900043896;12664.87335126637;10968;11269.81666924961;12053.51785928553;12078.415048711842;12515.37423128858;9031.59868822543;10258;10418.374897505872;10457.38585318135;10616.84074738864;8296.917030829614;10357.741056473347;10487.94756026215;10500.947495262475;10548.947255263674 17:37:22 INFO - PROCESS | 1667 | __end_tp_report 17:37:22 INFO - PROCESS | 1667 | __start_cc_report 17:37:22 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,382 17:37:22 INFO - PROCESS | 1667 | __end_cc_report 17:37:22 INFO - PROCESS | 1667 | __startTimestamp1473554242402__endTimestamp 17:37:22 INFO - PROCESS | 1667 | 17:37:22 INFO - PROCESS | 1667 | ------- Summary: start ------- 17:37:22 INFO - PROCESS | 1667 | Number of tests: 6 17:37:22 INFO - PROCESS | 1667 | 17:37:22 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5695.63 Median:5939.20 stddev:677.16 (11.4%) stddev-sans-first:654.45 17:37:22 INFO - PROCESS | 1667 | Values: 3381.3 3395.4 3427.3 3441.3 3443.9 4882.5 5488.8 5663.7 5764.3 5803.4 5738.3 5739.9 5775.1 5851.1 5906.9 5162.1 5207.7 5269.7 5309.3 5335.3 5308.5 5325.3 5336.5 5355.6 5395.8 5168.6 5215.6 5246.4 5256.4 5289.9 4258.8 4377.5 5294.3 5295.8 5312.1 5189.4 5239.9 5243.6 5277.3 5285.6 5026.0 5615.7 5680.5 5687.1 5716.5 6080.3 6177.5 6204.9 6220.2 6276.2 6195.8 6204.6 6208.5 6218.7 6225.4 4865.8 5897.6 5920.0 5939.2 5960.5 5608.7 6314.8 6320.1 6352.2 6353.7 6195.5 6197.6 6200.1 6210.1 6580.8 6103.6 6119.9 6146.8 6171.6 6174.2 6161.4 6173.9 6177.7 6181.3 6199.9 6137.0 6162.0 6174.2 6195.2 6210.2 4206.9 4270.7 4874.9 4919.7 4945.2 3789.6 4411.2 4446.5 4462.0 4536.8 5008.5 5824.3 5904.6 5912.4 5929.0 5739.9 5919.2 6177.9 6240.6 6241.0 5278.7 5828.2 5919.6 5937.5 5948.4 5016.3 5412.1 5431.4 5436.5 5440.1 6198.8 6256.9 6308.7 6343.2 6374.4 6136.9 6186.7 6220.9 6239.2 6268.3 6375.7 6383.8 6385.8 6390.8 6414.2 6073.5 6083.4 6094.0 6105.6 6231.1 6158.5 6169.4 6178.1 6184.4 6199.3 6000.7 6026.1 6065.6 6085.3 6101.5 4938.6 5874.8 6017.1 6078.4 6126.1 5053.8 5951.8 6016.9 6081.4 6129.5 5332.2 5586.2 5855.9 6185.3 6199.2 5809.3 5942.1 6045.1 6046.2 6054.1 17:37:22 INFO - PROCESS | 1667 | 17:37:22 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:71434.46 Median:42428.89 stddev:159967.04 (377.0%) stddev-sans-first:160327.00 17:37:22 INFO - PROCESS | 1667 | Values: 446.5 498.6 531.9 534.1 535.9 39386.4 42347.6 42404.8 42453.0 42481.6 42751.0 43261.8 43778.6 44074.6 44187.9 9740.3 9936.0 9944.6 9949.4 9950.5 8381.3 9723.7 9874.9 9878.0 9885.2 9913.1 9922.0 9932.2 9948.3 9978.9 9863.8 9900.5 9901.7 9920.3 9929.9 9639.1 9700.2 9770.5 9844.7 9846.9 34209.3 35432.6 42711.1 43110.6 43282.6 2677.4 2812.6 2841.5 3338.3 3480.8 25246.1 31273.7 31777.7 31933.5 33151.2 3197.3 3199.3 3201.4 3216.6 3355.9 434172.0 444911.0 446546.0 447566.0 451462.0 61340.7 61534.4 61600.0 61608.4 61620.4 34356.7 35516.0 35620.8 63058.7 63061.7 16532.3 16710.7 16735.6 16739.2 16744.0 12663.2 12677.9 12777.0 12777.7 12781.9 19040.6 19041.2 19440.1 19475.3 19580.0 6084.4 7840.6 7963.8 7982.2 8031.7 835.7 900.4 927.6 983.7 1010.2 24995.1 27690.0 29664.7 30322.0 30366.5 802.5 870.8 998.0 1035.5 1049.6 13068.8 15461.3 15526.8 15717.3 15894.6 702320.0 705845.0 709414.0 709462.0 709872.0 58057.7 58086.4 58542.4 58718.1 58789.4 1313.8 1344.5 1365.7 1367.3 1367.5 28414.0 29146.3 29204.6 29777.6 29780.4 49966.0 50198.7 50342.0 50392.0 50398.5 605094.0 608980.0 609098.0 609681.6 610842.0 26101.6 26646.6 26711.9 26782.2 26984.0 46884.8 47396.3 47873.8 48203.0 48498.0 37774.4 39453.0 39513.4 40352.0 40481.0 38488.8 39117.8 39382.6 39909.4 40363.0 27338.5 37955.2 38693.8 39276.3 39286.0 29843.6 34871.7 34955.1 35078.3 35524.8 25451.1 25786.2 25793.5 25999.0 26135.6 17:37:22 INFO - PROCESS | 1667 | 17:37:22 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:34665.00 Median:12309.14 stddev:97659.18 (793.4%) stddev-sans-first:97899.18 17:37:22 INFO - PROCESS | 1667 | Values: 366.1 373.4 375.5 379.8 400.9 16470.4 19162.0 20958.5 21274.9 21523.0 19999.5 20366.4 20635.5 20825.9 20946.7 5081.0 5179.8 5943.8 6012.8 6072.0 6000.4 6016.4 6131.4 6131.4 6140.4 5925.7 5956.8 6013.1 6049.9 6054.4 5705.9 5857.4 5859.4 5964.1 6006.6 5152.0 5216.6 5219.0 5220.3 5235.0 25624.7 25739.7 25740.0 25751.4 26093.3 2249.8 2311.7 2338.3 2356.6 3059.9 14751.3 15537.6 15553.5 15957.3 16126.1 1746.5 1849.8 1889.9 1920.6 1927.2 119969.8 121188.0 121604.0 121875.0 124996.0 10503.9 10548.6 10937.7 11047.6 11179.4 13582.9 13698.0 13716.9 13732.1 13739.2 8238.9 8389.9 8524.6 8634.5 8655.0 7039.4 8443.7 8608.8 8704.8 8843.9 6503.2 6567.0 6610.3 6618.0 6651.0 1869.6 1872.8 1873.7 1875.6 1878.2 807.0 859.3 883.4 963.9 1063.6 19468.9 21359.9 22213.3 22329.7 22368.4 927.6 981.6 1003.4 1031.4 1065.0 11583.2 11634.1 11651.8 11821.6 11885.8 544393.3 567981.0 598959.0 605273.0 606207.0 121354.0 122116.4 122247.0 123328.4 124518.0 990.4 995.7 1028.2 1031.3 1032.9 15894.8 17988.6 19087.7 19637.7 19768.1 51502.5 51685.5 52111.0 53416.0 54062.7 73128.2 74340.0 74452.3 75463.6 76031.6 5422.0 5668.1 6596.6 7667.5 8046.4 12649.7 12744.4 13086.4 13099.3 13622.3 10802.1 10841.6 11185.9 11191.2 11321.4 8842.4 10681.6 10986.6 11042.8 11485.3 10942.2 11027.2 11156.9 11411.0 11467.0 7720.7 7921.9 7970.0 8097.1 8112.3 10867.3 10918.4 11124.9 11261.0 11338.5 17:37:22 INFO - PROCESS | 1667 | 17:37:22 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5724.17 Median:5081.49 stddev:3204.36 (63.1%) stddev-sans-first:3196.12 17:37:22 INFO - PROCESS | 1667 | Values: 1302.5 1478.1 1630.7 1811.4 1815.9 7453.7 7990.8 8262.5 9023.9 9040.1 8503.9 8803.0 8824.7 8879.9 8884.7 2388.7 2419.4 2419.8 2423.5 2431.3 2380.8 2403.3 2405.1 2418.1 2433.7 2385.7 2396.1 2402.1 2408.7 2409.8 2038.2 2346.3 2379.6 2381.4 2381.8 2370.3 2385.1 2389.5 2399.3 2400.2 8867.4 8936.6 8953.5 8973.0 8993.1 5243.5 5410.9 5412.1 5422.4 5422.8 3173.0 3406.7 3666.9 3749.1 3750.5 1088.3 1091.3 1092.4 1103.6 1109.4 11545.4 11715.3 11785.8 11788.2 11803.6 4554.7 4744.4 4840.3 4860.0 4860.1 8658.7 8788.7 9817.7 10087.0 10087.7 5321.0 5376.4 5381.2 5395.9 5405.4 4425.3 4568.5 4748.5 4892.0 4924.6 3353.2 3920.5 4044.7 4072.4 4125.6 1122.6 1294.4 1306.0 1306.2 1307.7 772.2 871.0 906.0 907.1 949.2 5838.0 5913.3 5992.6 6030.5 6089.4 703.0 801.0 823.4 880.9 883.6 3582.3 3985.2 4018.1 4018.3 4117.6 9033.8 10598.3 10888.1 11004.7 11042.7 10988.8 10996.7 10998.2 11004.9 11084.8 8230.4 8298.2 8330.8 8359.5 8399.1 5616.9 5821.7 5940.0 5941.4 6018.5 8410.7 9688.8 10084.2 10251.8 10309.0 10221.7 10335.5 10337.9 10357.5 10365.7 6378.3 6625.1 6836.7 7117.3 7207.6 8309.8 8602.6 8618.0 8627.4 8630.0 7426.9 7513.3 7584.6 7645.8 7748.2 7366.0 7445.3 7516.0 7518.6 7693.2 7016.0 7018.0 7025.4 7073.8 7122.1 4975.9 5080.1 5117.7 5132.8 5177.2 5082.8 5094.0 5158.3 5186.9 5237.1 17:37:22 INFO - PROCESS | 1667 | 17:37:22 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4600.41 Median:4902.89 stddev:1447.34 (29.5%) stddev-sans-first:1415.00 17:37:22 INFO - PROCESS | 1667 | Values: 302.9 304.5 311.4 315.9 316.0 4760.6 4808.3 5129.5 5407.9 5454.8 4817.4 5104.4 5508.8 5521.6 5602.2 4232.6 4235.1 4259.1 4266.8 4280.9 3761.1 4103.3 4151.8 4279.7 4312.8 4208.9 4227.7 4231.5 4249.5 4252.0 3484.8 4224.6 4237.3 4267.2 4282.5 3682.4 3697.5 3698.8 3705.1 3717.2 5189.4 5450.4 5455.4 5462.8 5491.3 4283.9 4346.6 4370.5 4842.3 4856.8 5589.7 5888.5 5897.2 5909.9 5941.5 3364.7 3537.6 4036.3 4038.7 4064.8 6144.4 6234.0 6252.6 6281.9 6300.6 4120.9 4131.9 4136.7 4137.2 4158.7 3694.6 3785.9 3965.0 4077.0 4131.4 4037.6 4808.4 4818.4 4844.3 4856.7 5970.6 5975.9 6001.5 6027.4 6049.8 1242.5 1318.8 1331.6 1389.6 1402.6 814.0 828.0 847.7 853.8 856.7 3948.8 4052.6 4707.6 4717.6 4732.4 4274.8 4967.9 5162.9 5174.8 5177.2 4126.4 4347.8 4529.8 4695.4 4708.6 2116.9 2474.4 2626.6 2686.9 2751.7 4408.6 4815.2 4843.7 5016.1 5334.7 4597.6 4837.2 5215.3 5229.5 5323.1 5074.6 5578.3 5970.3 6412.7 6456.3 5187.6 6220.1 6247.7 6279.4 6325.7 5501.7 5587.5 5778.4 6078.2 6089.0 5018.4 5186.1 5697.8 5869.0 6438.4 3720.4 3867.2 3978.0 3986.5 4111.2 4992.9 5534.6 5648.2 6101.5 6152.7 4949.0 5006.5 5873.9 5973.0 6010.2 5575.6 5766.5 5810.8 5965.3 6011.7 5155.4 5847.3 5876.9 5877.9 5921.3 4786.4 5744.8 5899.9 5949.9 6014.9 4737.7 5688.0 5743.5 5802.5 5829.4 17:37:22 INFO - PROCESS | 1667 | 17:37:22 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:25321.64 Median:3680.88 stddev:40961.32 (1112.8%) stddev-sans-first:41034.21 17:37:22 INFO - PROCESS | 1667 | Values: 610.8 642.5 708.1 714.4 771.5 10842.8 11262.5 15129.0 15882.6 16089.3 14505.0 14606.7 14621.0 14623.5 14686.0 679.7 691.3 854.3 856.3 870.2 844.2 856.6 868.0 871.4 871.8 883.1 918.9 919.4 924.7 925.5 915.2 918.4 920.4 922.6 924.0 644.5 646.1 681.8 683.8 686.3 15091.6 15132.6 15403.1 15552.9 15554.5 14588.5 14691.9 14735.0 14771.9 14865.4 11922.5 12015.2 12039.8 12154.0 12192.3 3709.6 3762.7 3782.8 3903.4 3920.7 123436.0 124604.0 125863.4 126908.4 130625.3 11309.3 11346.1 11355.5 11371.9 11729.9 23707.6 23787.4 24123.0 24128.9 24205.4 8103.4 8128.0 8185.5 8189.4 8246.0 4295.7 4438.6 4514.8 4529.0 4636.8 3671.8 3690.0 3762.6 3786.9 3789.8 663.4 668.1 673.9 681.6 693.6 661.2 662.9 676.6 682.8 695.6 8423.1 8447.5 9392.9 9396.9 9973.8 511.6 641.9 680.0 683.7 685.1 4698.0 4706.6 4714.9 4727.3 4746.4 102135.0 111161.4 130380.0 130583.0 168461.2 134077.0 139439.0 142121.0 142837.3 147733.5 108630.0 114683.9 118802.8 120723.0 124191.0 9107.6 9108.8 9110.5 9113.6 9321.3 56992.3 58215.0 65892.3 67334.7 68039.0 86384.0 94535.5 98137.5 100189.0 103045.5 12010.9 12161.0 12258.4 12360.6 12372.8 13133.7 13200.2 13337.0 13383.4 13390.8 12198.8 12226.4 12445.8 12460.6 12785.7 12194.4 12217.8 12294.6 12527.5 12664.9 10968.0 11269.8 12053.5 12078.4 12515.4 9031.6 10258.0 10418.4 10457.4 10616.8 8296.9 10357.7 10487.9 10500.9 10548.9 17:37:22 INFO - PROCESS | 1667 | -------- Summary: end -------- 17:37:22 INFO - PROCESS | 1667 | 17:37:22 INFO - TEST-INFO | 1667: exit 0 17:37:22 INFO - TEST-OK | dromaeo_css | took 1113913ms 17:37:22 INFO - TEST-START | kraken 17:37:22 INFO - operating with platform_type : mac_ 17:37:22 INFO - Initialising browser for kraken test... 17:37:23 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmpan_HAZ/profile http://localhost:49285/getInfo.html) 17:37:25 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 17:37:25 INFO - PROCESS | 1676 | colorDepth:24 17:37:25 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 17:37:25 INFO - PROCESS | 1676 | __metrics 17:37:25 ERROR - PROCESS | 1676 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 17:37:26 INFO - TEST-INFO | 1676: exit 0 17:37:26 INFO - Browser initialized. 17:37:26 INFO - Running cycle 1/1 for kraken test... 17:37:26 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmpan_HAZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:37:38 INFO - PROCESS | 1678 | RSS: Main: 244477952 17:37:38 INFO - PROCESS | 1678 | 17:37:41 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-beat-detection) 17:37:42 INFO - PROCESS | 1678 | RSS: Main: 256958464 17:37:42 INFO - PROCESS | 1678 | 17:37:45 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-dft) 17:37:45 INFO - PROCESS | 1678 | RSS: Main: 265572352 17:37:45 INFO - PROCESS | 1678 | 17:37:49 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-fft) 17:37:49 INFO - PROCESS | 1678 | RSS: Main: 262311936 17:37:49 INFO - PROCESS | 1678 | 17:37:51 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-oscillator) 17:37:52 INFO - PROCESS | 1678 | RSS: Main: 263479296 17:37:52 INFO - PROCESS | 1678 | 17:37:54 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:37:55 INFO - PROCESS | 1678 | RSS: Main: 265228288 17:37:55 INFO - PROCESS | 1678 | 17:37:57 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-darkroom) 17:37:58 INFO - PROCESS | 1678 | RSS: Main: 265551872 17:37:58 INFO - PROCESS | 1678 | 17:38:01 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-desaturate) 17:38:02 INFO - PROCESS | 1678 | RSS: Main: 265834496 17:38:02 INFO - PROCESS | 1678 | 17:38:05 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49285/tests/kraken/driver.html?testName=json-parse-financial) 17:38:05 INFO - PROCESS | 1678 | RSS: Main: 266166272 17:38:05 INFO - PROCESS | 1678 | 17:38:07 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49285/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:38:08 INFO - PROCESS | 1678 | RSS: Main: 265588736 17:38:08 INFO - PROCESS | 1678 | 17:38:10 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:38:10 INFO - PROCESS | 1678 | RSS: Main: 265760768 17:38:10 INFO - PROCESS | 1678 | 17:38:13 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:38:13 INFO - PROCESS | 1678 | RSS: Main: 266108928 17:38:13 INFO - PROCESS | 1678 | 17:38:16 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:38:17 INFO - PROCESS | 1678 | RSS: Main: 266203136 17:38:17 INFO - PROCESS | 1678 | 17:38:20 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:38:21 INFO - PROCESS | 1678 | RSS: Main: 266395648 17:38:21 INFO - PROCESS | 1678 | 17:38:22 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49285/tests/kraken/driver.html?testName=ai-astar) 17:38:23 INFO - PROCESS | 1678 | RSS: Main: 268414976 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | __start_tp_report 17:38:23 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 17:38:23 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 17:38:23 INFO - PROCESS | 1678 | |i|pagename|runs| 17:38:23 INFO - PROCESS | 1678 | |0;ai-astar;116;115;108;117;118;115;101;117;115;116 17:38:23 INFO - PROCESS | 1678 | |1;audio-beat-detection;138;112;134;159;109;133;131;110;158;125 17:38:23 INFO - PROCESS | 1678 | |2;audio-dft;197;220;273;259;196;196;196;197;195;277 17:38:23 INFO - PROCESS | 1678 | |3;audio-fft;70;82;76;144;58;61;57;74;144;86 17:38:23 INFO - PROCESS | 1678 | |4;audio-oscillator;68;72;101;74;99;72;110;55;74;133 17:38:23 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;89;87;88;86;90;85;90;87;90;86 17:38:23 INFO - PROCESS | 1678 | |6;imaging-darkroom;100;95;94;94;135;141;128;152;96;118 17:38:23 INFO - PROCESS | 1678 | |7;imaging-desaturate;109;86;151;129;90;99;139;89;82;146 17:38:23 INFO - PROCESS | 1678 | |8;json-parse-financial;57;61;59;59;57;57;56;57;57;57 17:38:23 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;66;77;60;89;120;111;83;72;70;68 17:38:23 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;89;90;87;85;82;73;76;112;110;108 17:38:23 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;148;165;155;149;149;140;144;159;285;142 17:38:23 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;192;174;166;173;174;156;231;231;174;221 17:38:23 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;68;67;67;68;70;70;95;58;76;93 17:38:23 INFO - PROCESS | 1678 | __end_tp_report 17:38:23 INFO - PROCESS | 1678 | __start_cc_report 17:38:23 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,956 17:38:23 INFO - PROCESS | 1678 | __end_cc_report 17:38:23 INFO - PROCESS | 1678 | __startTimestamp1473554303221__endTimestamp 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | ------- Summary: start ------- 17:38:23 INFO - PROCESS | 1678 | Number of tests: 14 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:113.80 Median:116.00 stddev:5.27 (4.5%) stddev-sans-first:5.53 17:38:23 INFO - PROCESS | 1678 | Values: 116.0 115.0 108.0 117.0 118.0 115.0 101.0 117.0 115.0 116.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:133.50 stddev:17.94 (13.4%) stddev-sans-first:18.84 17:38:23 INFO - PROCESS | 1678 | Values: 138.0 112.0 134.0 159.0 109.0 133.0 131.0 110.0 158.0 125.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:220.60 Median:208.50 stddev:34.94 (16.8%) stddev-sans-first:36.00 17:38:23 INFO - PROCESS | 1678 | Values: 197.0 220.0 273.0 259.0 196.0 196.0 196.0 197.0 195.0 277.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:85.20 Median:72.00 stddev:32.47 (45.1%) stddev-sans-first:33.97 17:38:23 INFO - PROCESS | 1678 | Values: 70.0 82.0 76.0 144.0 58.0 61.0 57.0 74.0 144.0 86.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:85.80 Median:72.00 stddev:23.91 (33.2%) stddev-sans-first:24.47 17:38:23 INFO - PROCESS | 1678 | Values: 68.0 72.0 101.0 74.0 99.0 72.0 110.0 55.0 74.0 133.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:88.50 stddev:1.87 (2.1%) stddev-sans-first:1.94 17:38:23 INFO - PROCESS | 1678 | Values: 89.0 87.0 88.0 86.0 90.0 85.0 90.0 87.0 90.0 86.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:115.30 Median:123.00 stddev:22.34 (18.2%) stddev-sans-first:22.99 17:38:23 INFO - PROCESS | 1678 | Values: 100.0 95.0 94.0 94.0 135.0 141.0 128.0 152.0 96.0 118.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:112.00 Median:84.00 stddev:26.80 (31.9%) stddev-sans-first:28.40 17:38:23 INFO - PROCESS | 1678 | Values: 109.0 86.0 151.0 129.0 90.0 99.0 139.0 89.0 82.0 146.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:57.00 stddev:1.49 (2.6%) stddev-sans-first:1.56 17:38:23 INFO - PROCESS | 1678 | Values: 57.0 61.0 59.0 59.0 57.0 57.0 56.0 57.0 57.0 57.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:81.60 Median:71.00 stddev:19.83 (27.9%) stddev-sans-first:20.21 17:38:23 INFO - PROCESS | 1678 | Values: 66.0 77.0 60.0 89.0 120.0 111.0 83.0 72.0 70.0 68.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:83.50 stddev:14.05 (16.8%) stddev-sans-first:14.88 17:38:23 INFO - PROCESS | 1678 | Values: 89.0 90.0 87.0 85.0 82.0 73.0 76.0 112.0 110.0 108.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:163.60 Median:152.00 stddev:43.35 (28.5%) stddev-sans-first:45.61 17:38:23 INFO - PROCESS | 1678 | Values: 148.0 165.0 155.0 149.0 149.0 140.0 144.0 159.0 285.0 142.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.20 Median:183.00 stddev:28.11 (15.4%) stddev-sans-first:29.79 17:38:23 INFO - PROCESS | 1678 | Values: 192.0 174.0 166.0 173.0 174.0 156.0 231.0 231.0 174.0 221.0 17:38:23 INFO - PROCESS | 1678 | 17:38:23 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:70.00 stddev:11.82 (16.9%) stddev-sans-first:12.39 17:38:23 INFO - PROCESS | 1678 | Values: 68.0 67.0 67.0 68.0 70.0 70.0 95.0 58.0 76.0 93.0 17:38:23 INFO - PROCESS | 1678 | -------- Summary: end -------- 17:38:23 INFO - PROCESS | 1678 | 17:38:24 INFO - TEST-INFO | 1678: exit 0 17:38:24 INFO - TEST-OK | kraken | took 61071ms 17:38:24 INFO - SUITE-END | took 1174s 17:38:24 INFO - Completed test suite (00:19:35) 17:38:24 INFO - javascript benchmark 17:38:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7374.645938709334, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3381.32373525295, 3395.3888300105978, 3427.263138425238, 3441.2773317603287, 3443.8966830995073, 4882.536159064892, 5488.807891723791, 5663.7168141592965, 5764.25064741583, 5803.448672376117, 5738.340090889549, 5739.942600573993, 5775.1337299405, 5851.122331650266, 5906.940930590693, 5162.070827251093, 5207.713575753332, 5269.657472264291, 5309.256704061435, 5335.253064570944, 5308.5487733542695, 5325.334333208349, 5336.519713225809, 5355.6251062425645, 5395.7859481616715, 5168.586513078944, 5215.608829337815, 5246.422893481733, 5256.448072952361, 5289.920651190235, 4258.829646814123, 4377.5403582623685, 5294.285271488377, 5295.841124766263, 5312.096943519611, 5189.351331083614, 5239.869003274948, 5243.6067294952645, 5277.287566178555, 5285.550728188108, 5026.0, 5615.66306021636, 5680.5455563554815, 5687.146927960797, 5716.456936591017, 6080.270367555878, 6177.452497400357, 6204.906926396107, 6220.1602783624085, 6276.246850377984, 6195.845103872394, 6204.565680402374, 6208.472279856218, 6218.657973811439, 6225.3774622537385, 4865.781039853215, 5897.64614123154, 5919.999999999957, 5939.198208241875, 5960.4635582797755, 5608.663480191201, 6314.7710384312795, 6320.115183874262, 6352.174217351807, 6353.682315884187, 6195.504359651217, 6197.628142311476, 6200.100985357098, 6210.099535567318, 6580.769673061515, 6103.6104255942055, 6119.929102025404, 6146.815595532052, 6171.56799024064, 6174.197354343906, 6161.353057928943, 6173.938260617426, 6177.660228687465, 6181.320054793969, 6199.876002479925, 6137.0, 6161.96919015411, 6174.197354343996, 6195.19462469885, 6210.161628180228, 4206.915861682749, 4270.701050926467, 4874.878128046827, 4919.680220785637, 4945.159322915113, 3789.621037896188, 4411.228035093845, 4446.510883802779, 4462.0, 4536.750478723702, 5008.474110218447, 5824.271966004249, 5904.645721256738, 5912.438318359749, 5928.992071347881, 5739.852029594098, 5919.230500034968, 6177.876442471125, 6240.563160578716, 6241.001439769615, 5278.683279003272, 5828.213191219131, 5919.644821310735, 5937.52499800015, 5948.375420580864, 5016.297718319438, 5412.134058550613, 5431.429699881534, 5436.510714035756, 5440.102383106743, 6198.845028874314, 6256.937430625636, 6308.716107775161, 6343.238811342576, 6374.426301632875, 6136.85608443425, 6186.690665466619, 6220.875582488416, 6239.1577137085915, 6268.279147897868, 6375.7449702013255, 6383.840403989938, 6385.776497822554, 6390.840228994312, 6414.166158399378, 6073.483753880793, 6083.361247068994, 6094.0482387643915, 6105.581053426346, 6231.1276421300145, 6158.538109641705, 6169.444749972524, 6178.073289006685, 6184.381561843779, 6199.349068347939, 6000.669963151894, 6026.0659597762415, 6065.636061836304, 6085.330613632408, 6101.511879049576, 4938.580220681139, 5874.794382196603, 6017.127516509994, 6078.422549857756, 6126.050462178369, 5053.787091098036, 5951.791687290924, 6016.909746353896, 6081.361457046946, 6129.540284478771, 5332.200169974535, 5586.16207568868, 5855.853603659943, 6185.319614842274, 6199.225096862892, 5809.331926828469, 5942.1383899335215, 6045.093236014633, 6046.244219472566, 6054.122152287981], "value": 5653.739235401339, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [446.4999200894998, 498.5662473647927, 531.9015982043322, 534.126702840855, 535.8981793459243, 39386.409203861964, 42347.57652423483, 42404.78797606016, 42452.99999999996, 42481.57518424815, 42751.0, 43261.78369108158, 43778.562214377846, 44074.559254407526, 44187.89530261745, 9740.318177727544, 9935.950320248388, 9944.5524951377, 9949.403035817837, 9950.502474876263, 8381.329493640525, 9723.659671911484, 9874.851877221812, 9877.950610246939, 9885.209183265322, 9913.058259465375, 9922.0, 9932.155766759834, 9948.25388095892, 9978.850317245247, 9863.753406164831, 9900.455474948874, 9901.702948911545, 9920.255980801468, 9929.851052234222, 9639.084286992724, 9700.223982081416, 9770.51147442625, 9844.704658860246, 9846.852297215475, 34209.31581368384, 35432.64567354319, 42711.145777084595, 43110.56889431097, 43282.56717432817, 2677.4243537639304, 2812.5921741347593, 2841.4885320642275, 3338.3156452927333, 3480.7911525308564, 25246.13255942895, 31273.687263127304, 31777.682223177933, 31933.52099718506, 33151.171220719436, 3197.2646291352885, 3199.2641692410875, 3201.439748044083, 3216.565763621928, 3355.899323020313, 434172.0, 444911.0, 446546.0, 447566.0, 451462.0, 61340.693296534126, 61534.38465615376, 61600.0, 61608.38391616116, 61620.38379616236, 34356.65643343585, 35516.0, 35620.82189589035, 63058.684706576176, 63061.68469157717, 16532.256048477608, 16710.749338759928, 16735.58161045959, 16739.16304184786, 16744.0, 12663.176893501894, 12677.873221267853, 12777.0, 12777.744445111231, 12781.936090319488, 19040.61918761617, 19041.238350466105, 19440.125194366286, 19475.318363857197, 19580.0, 6084.361142080106, 7840.568768717775, 7963.800904977306, 7982.201779821971, 8031.718889838828, 835.703325319515, 900.4192295969063, 927.5918595817818, 983.6901376066517, 1010.161565900301, 24995.12517061894, 27690.0, 29664.703352966193, 30322.0, 30366.54450183249, 802.4823988527368, 870.815690660701, 998.0169533009979, 1035.5443604813859, 1049.574922156528, 13068.803967940488, 15461.304241309168, 15526.844731552539, 15717.292721827545, 15894.602634933988, 702320.0, 705845.0, 709414.0, 709462.0, 709872.0, 58057.70971145117, 58086.4191358081, 58542.4145758537, 58718.0537925661, 58789.41210587925, 1313.7766579681477, 1344.509254122258, 1365.6722386627139, 1367.2548461088732, 1367.500862185315, 28414.00550980706, 29146.27134321659, 29204.561931570617, 29777.5533367004, 29780.404391912474, 49966.0, 50198.74900625474, 50342.0, 50392.0, 50398.49601503938, 605094.0, 608980.0, 609098.0, 609681.6127355034, 610842.0, 26101.608475873258, 26646.600300995113, 26711.86644066845, 26782.19653410401, 26984.0, 46884.82787930329, 47396.2890556635, 47873.76063119662, 48203.0, 48498.0, 37774.43338349982, 39453.0, 39513.407298891114, 40352.0, 40481.0, 38488.845334640006, 39117.80441097776, 39382.60617393789, 39909.40135898022, 40363.0, 27338.45323093567, 37955.24089518139, 38693.806530967166, 39276.30493578358, 39286.0, 29843.552346714383, 34871.65128348785, 34955.126121847155, 35078.29843403168, 35524.822375887954, 25451.10921117752, 25786.226413207634, 25793.484130317665, 25999.0, 26135.607965880146], "value": 19812.44817059798, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.06653034761354, 373.4323827781773, 375.4574639645712, 379.7835233916668, 400.90979529605823, 16470.42353517624, 19162.041897905103, 20958.476038099056, 21274.893625531888, 21523.0, 19999.50001249966, 20366.389008329774, 20635.484112897186, 20825.895870520664, 20946.685799713017, 5080.9584035272865, 5179.844604661847, 5943.762249510014, 6012.75948962041, 6072.0, 6000.399960003986, 6016.428439298282, 6131.3868613138775, 6131.448169664729, 6140.35526269742, 5925.674087925162, 5956.808638272341, 6013.098035294719, 6049.939500604982, 6054.424829641199, 5705.942940570582, 5857.355690874022, 5859.4140585941705, 5964.135200395962, 6006.5795394322195, 5152.0, 5216.634835561512, 5219.0, 5220.321358223445, 5234.973825130888, 25624.74375256261, 25739.742602574108, 25740.0, 25751.35621609456, 26093.347666308306, 2249.831262655291, 2311.664808602743, 2338.3452633262546, 2356.6229403295392, 3059.938801223985, 14751.336189871481, 15537.611559710984, 15553.455629052929, 15957.281922313523, 16126.113063781488, 1746.493516880116, 1849.7780266367988, 1889.896055716935, 1920.5582715975404, 1927.1616846671743, 119969.80030199673, 121188.0, 121604.0, 121875.0, 124996.0, 10503.949605039588, 10548.630797922036, 10937.726556835984, 11047.613333533287, 11179.385133817717, 13582.86417135816, 13698.041137120505, 13716.931415342859, 13732.107413018322, 13739.24434156111, 8238.917610823815, 8389.916100839035, 8524.577066730486, 8634.525101119372, 8654.956725216334, 7039.36645701879, 8443.746687599381, 8608.827823443497, 8704.825903481895, 8843.850299461157, 6503.154589903283, 6567.0, 6610.272869984298, 6617.966910165418, 6651.0, 1869.5886904880904, 1872.7565416495768, 1873.7470441490498, 1875.577994951125, 1878.164216923456, 807.0275318241622, 859.3426029087752, 883.3728053082255, 963.8626420823356, 1063.6436793674052, 19468.902655486632, 21359.893200533897, 22213.333599992027, 22329.665055024187, 22368.440788980406, 927.5872236854669, 981.5779214937503, 1003.4029752297372, 1031.417249254169, 1065.0042210533177, 11583.247088939192, 11634.127440442, 11651.766964660828, 11821.645350639494, 11885.762284754257, 544393.2780336073, 567981.0, 598959.0, 605273.0, 606206.9689651524, 121354.0, 122116.38941805233, 122247.0, 123328.38335808444, 124518.0, 990.4354517924713, 995.6963126246565, 1028.2134167361974, 1031.3244824639874, 1032.8554002439664, 15894.841051589336, 17988.55028624295, 19087.713684294467, 19637.70543441878, 19768.110435030172, 51502.48497515127, 51685.48314516957, 52110.95778084493, 53416.0, 54062.72968635132, 73128.17179570554, 74340.0, 74452.25547744453, 75463.62268188623, 76031.61984190044, 5421.972890135524, 5668.064769313182, 6596.637184954681, 7667.463277570517, 8046.436749427484, 12649.747005060031, 12744.42650080767, 13086.411111499814, 13099.279539625411, 13622.250776207404, 10802.135829133493, 10841.566337346734, 11185.944070279596, 11191.16066295015, 11321.377324247245, 8842.381033327605, 10681.57273709043, 10986.560537578407, 11042.779144417227, 11485.253458525169, 10942.23404361687, 11027.22809403334, 11156.944215278872, 11411.0, 11467.0, 7720.691172353043, 7921.881171782313, 7970.0, 8097.1093179749005, 8112.269895709414, 10867.347959122479, 10918.399488027917, 11124.941885751163, 11261.042811361127, 11338.546458141582], "value": 9717.747434275609, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.4594793160838, 1478.0762023735165, 1630.6983208106494, 1811.3841293960052, 1815.8819676721012, 7453.701851925924, 7990.800229994253, 8262.504249745005, 9023.864642030367, 9040.095990400956, 8503.872441913361, 8803.031666516694, 8824.735257942257, 8879.866801997976, 8884.689035883745, 2388.7252965908883, 2419.4072452249225, 2419.830611857162, 2423.5395274897714, 2431.3070774829175, 2380.785729284364, 2403.2790162951133, 2405.1341517053934, 2418.117387153685, 2433.6592876997233, 2385.654080158376, 2396.1373905394044, 2402.0992127952018, 2408.735039145701, 2409.759024097585, 2038.2458490358588, 2346.343023953296, 2379.643053541974, 2381.3808409813487, 2381.8094552435764, 2370.3481542575755, 2385.1175065225802, 2389.474315650554, 2399.3042017814814, 2400.1719406804623, 8867.379283450096, 8936.553172341357, 8953.507557084358, 8973.0, 8993.10068993102, 5243.501867322599, 5410.945890541084, 5412.07994640912, 5422.403535611119, 5422.783088676449, 3172.952405713916, 3406.744494162948, 3666.8716594919315, 3749.1189570451197, 3750.49368335274, 1088.2817340555196, 1091.2743025887776, 1092.4100985467917, 1103.586155191803, 1109.4009235013084, 11545.365004924726, 11715.297082175011, 11785.823212651818, 11788.17482776206, 11803.645890623295, 4554.681172317906, 4744.4069491313585, 4840.273958906122, 4860.003699241647, 4860.052289803525, 8658.653653853902, 8788.692395766118, 9817.656382026595, 10087.0, 10087.697369078793, 5321.0, 5376.38171610262, 5381.219723140122, 5395.865103372368, 5405.378381486101, 4425.269830478, 4568.474625418119, 4748.501407352178, 4892.021595680807, 4924.556789888928, 3353.2119951811305, 3920.5102061216553, 4044.656204222615, 4072.4095006223934, 4125.64931980785, 1122.5678113926033, 1294.3981048812348, 1305.9869401306055, 1306.2293246984325, 1307.6534718299658, 772.169917338863, 870.9782255443538, 905.9954700226457, 907.0793144957873, 949.2358651285643, 5837.970810146007, 5913.290405151323, 5992.550558708114, 6030.547708921848, 6089.391060893875, 703.0368395298372, 800.9799755006171, 823.3783493462397, 880.872273520336, 883.6067949762422, 3582.3193593217206, 3985.242803867256, 4018.0758425562285, 4018.256622524784, 4117.5882411758585, 9033.77415564616, 10598.25812193139, 10888.074513666426, 11004.724881878017, 11042.668719938414, 10988.780224395627, 10996.725081873017, 10998.175136864767, 11004.944975275072, 11084.778304434027, 8230.382721295808, 8298.170182981654, 8330.8333833323, 8359.498430094092, 8399.076101628816, 5616.859578510406, 5821.650700957956, 5939.970300148471, 5941.37615550361, 6018.458338749533, 8410.705625303084, 9688.806223875625, 10084.193264538966, 10251.794964100527, 10309.0, 10221.74445638865, 10335.483225838527, 10337.9483102584, 10357.533910974174, 10365.740856478347, 6378.266499352634, 6625.105610742488, 6836.692348844414, 7117.3238542338395, 7207.5675459472595, 8309.83380332378, 8602.569871506525, 8618.0, 8627.43921645091, 8630.0, 7426.851462970819, 7513.2862378074, 7584.582847943196, 7645.808854778675, 7748.225177482207, 7365.9631701841145, 7445.329920307198, 7516.0, 7518.624068796429, 7693.192214817587, 7016.0, 7018.017477553249, 7025.402840758388, 7073.752418665322, 7122.074130363019, 4975.850724478271, 5080.1363768160245, 5117.744112794271, 5132.769025393941, 5177.2492988515705, 5082.847514574569, 5093.974530127326, 5158.277841102174, 5186.8962620746615, 5237.135872580981], "value": 4591.448111529511, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.9394121175764, 304.5097393196953, 311.397445942102, 315.90364938693716, 315.9715625593698, 4760.642951778613, 4808.278758186265, 5129.512696293851, 5407.918881216785, 5454.836354909354, 4817.411028783552, 5104.412992505863, 5508.807191748289, 5521.641093328931, 5602.187682785993, 4232.5767423257585, 4235.110626768383, 4259.10558782656, 4266.8293268269235, 4280.914381712364, 3761.0973366392004, 4103.322951712964, 4151.813168407414, 4279.679024073207, 4312.762798046107, 4208.894777630553, 4227.661787057028, 4231.4710661167355, 4249.532551419356, 4251.957480425187, 3484.8083355415442, 4224.613093602135, 4237.322028475444, 4267.1892340455215, 4282.528921818597, 3682.3739964206147, 3697.5193224880863, 3698.7965661888584, 3705.1474262868564, 3717.182219911615, 5189.42916279209, 5450.400455949843, 5455.427180146067, 5462.781488740446, 5491.28613280271, 4283.850065247701, 4346.6088052075465, 4370.475542934836, 4842.297866809293, 4856.830010949635, 5589.723241741899, 5888.5289176865745, 5897.203877476529, 5909.852253693649, 5941.494972927307, 3364.680355366236, 3537.567285249478, 4036.3138266494766, 4038.6567141792984, 4064.817083231232, 6144.446999770043, 6233.968830155865, 6252.5927517905475, 6281.864166183565, 6300.590461620026, 4120.91758164841, 4131.938020929689, 4136.710430269913, 4137.213929353413, 4158.708890377645, 3694.630536946284, 3785.9432108518395, 3964.969108031875, 4076.9592304077173, 4131.400946862748, 4037.555868854424, 4808.447028591757, 4818.4217893852965, 4844.297576851337, 4856.68431551955, 5970.611600473649, 5975.910361344583, 6001.459868611759, 6027.367126451747, 6049.791825983572, 1242.490578862662, 1318.8417389913272, 1331.5605850069453, 1389.6039628705973, 1402.5511836212313, 813.9593020348959, 827.9793005174798, 847.720252316734, 853.8164294676675, 856.7044369692446, 3948.802559871995, 4052.5744796796503, 4707.599854012447, 4717.551832575899, 4732.361131247307, 4274.829006839754, 4967.875803104951, 5162.8641698826195, 5174.767135478913, 5177.223416487557, 4126.42230087788, 4347.782610869507, 4529.754317562697, 4695.389599352063, 4708.576228139415, 2116.883116883117, 2474.4308808974038, 2626.6060090986507, 2686.8790904409348, 2751.7110703376256, 4408.625266852289, 4815.2054910940005, 4843.733594652257, 5016.072026675077, 5334.7065911374475, 4597.563231493003, 4837.201861692852, 5215.348081489814, 5229.463710100598, 5323.098911093463, 5074.594032477393, 5578.302712160898, 5970.31341395745, 6412.6793660317735, 6456.289808121197, 5187.610929180251, 6220.066989951543, 6247.718852651732, 6279.403456671609, 6325.746970121144, 5501.726472748868, 5587.525060369914, 5778.36437991829, 6078.209832721718, 6089.0, 5018.44797072329, 5186.144286192736, 5697.772089116389, 5868.970655146868, 6438.356164383524, 3720.441933709857, 3867.2458870521104, 3977.980110099431, 3986.541547721933, 4111.157212771434, 4992.8751781205765, 5534.647774695658, 5648.181013753065, 6101.542384321105, 6152.661603611844, 4948.956391819077, 5006.499350064964, 5873.911891321719, 5972.970135149297, 6010.188624535632, 5575.581831362583, 5766.452187042224, 5810.767569297181, 5965.254343207099, 6011.6693581853415, 5155.43290238081, 5847.298324201123, 5876.911846322223, 5877.882442351043, 5921.282827979838, 4786.425628924551, 5744.798932037359, 5899.873067274351, 5949.851253718692, 6014.939850601613, 4737.739424331695, 5688.0, 5743.540516758566, 5802.535797136304, 5829.358770535153], "value": 4135.441710273317, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.7967304410313, 642.5437939063265, 708.1396103733962, 714.367784510708, 771.5486440432345, 10842.783144337118, 11262.54949802008, 15129.016613920116, 15882.602934926617, 16089.275982580783, 14505.0, 14606.707865842676, 14621.049631773927, 14623.464536223677, 14686.045407048561, 679.6907407129769, 691.339770519153, 854.2824027816648, 856.3106699107216, 870.2428886868433, 844.2275318083977, 856.644492535597, 867.9931279715531, 871.3595507302127, 871.8474267003281, 883.0683628771643, 918.8851393575803, 919.3932004876783, 924.6809850601566, 925.5002298758662, 915.1946287267162, 918.4397517514311, 920.4293338130335, 922.5525620074259, 924.0297687428207, 644.5294934697697, 646.0599827251338, 681.7681988124061, 683.8119517132778, 686.306830101601, 15091.622709432242, 15132.621684457867, 15403.07581545111, 15552.91129620927, 15554.455594054154, 14588.489402870848, 14691.853081469155, 14735.0, 14771.852281477155, 14865.405383784635, 11922.463489143009, 12015.21901076436, 12039.819402708967, 12153.966912812422, 12192.268463892195, 3709.55485341758, 3762.6801721853676, 3782.7730336179916, 3903.3559462688904, 3920.7059470539825, 123436.0, 124604.0, 125863.37068314692, 126908.36545817212, 130625.34687326598, 11309.264897781617, 11346.149038822121, 11355.54577816878, 11371.943140284246, 11729.88270117305, 23707.644385334235, 23787.40531486727, 24123.0, 24128.87935560311, 24205.39486512816, 8103.392245581605, 8128.0, 8185.549794761345, 8189.426740128253, 8246.0, 4295.72077814941, 4438.622717069021, 4514.796834142471, 4529.0, 4636.79134438951, 3671.7980511072146, 3689.9631003690156, 3762.6049264827348, 3786.905327366783, 3789.810509474515, 663.4195079305607, 668.1481111582771, 673.9393454589012, 681.6285124607101, 693.6358411833727, 661.1900421983032, 662.8607992321666, 676.5568552598056, 682.846359569093, 695.5513693667556, 8423.073461919184, 8447.493150410995, 9392.906070939203, 9396.906030939603, 9973.800523989625, 511.6290689250264, 641.9422251997343, 679.9762008329784, 683.6513378177166, 685.0990946904805, 4697.976510117427, 4706.552877476634, 4714.85233158046, 4727.314539391769, 4746.382970213919, 102135.0, 111161.44419278014, 130380.0, 130583.0, 168461.2250171435, 134077.0, 139439.0, 142121.0, 142837.28581357026, 147733.52266477197, 108630.0, 114683.85316146899, 118802.81197188263, 120723.0, 124191.0, 9107.59015844302, 9108.765160141691, 9110.498922559058, 9113.635454581743, 9321.347505674808, 56992.30076992433, 58215.0, 65892.34107658862, 67334.66332668305, 68039.0, 86384.0, 94535.52732236295, 98137.50931245583, 100189.0, 103045.48477257865, 12010.879891200977, 12160.96631786308, 12258.387080646111, 12360.629181124581, 12372.752544949231, 13133.737325253633, 13200.221852468001, 13337.0, 13383.39774710121, 13390.799138012742, 12198.756024879276, 12226.388680566113, 12445.813312800134, 12460.626181214579, 12785.680357991125, 12194.390280486117, 12217.755644887231, 12294.569690060805, 12527.498900043896, 12664.87335126637, 10968.0, 11269.81666924961, 12053.51785928553, 12078.415048711842, 12515.37423128858, 9031.59868822543, 10258.0, 10418.374897505872, 10457.38585318135, 10616.84074738864, 8296.917030829614, 10357.741056473347, 10487.94756026215, 10500.947495262475, 10548.947255263674], "value": 7781.986107061844, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1607.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 108.0, 117.0, 118.0, 115.0, 101.0, 117.0, 115.0, 116.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 112.0, 134.0, 159.0, 109.0, 133.0, 131.0, 110.0, 158.0, 125.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 220.0, 273.0, 259.0, 196.0, 196.0, 196.0, 197.0, 195.0, 277.0], "value": 220.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 76.0, 144.0, 58.0, 61.0, 57.0, 74.0, 144.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 101.0, 74.0, 99.0, 72.0, 110.0, 55.0, 74.0, 133.0], "value": 85.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 86.0, 90.0, 85.0, 90.0, 87.0, 90.0, 86.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 95.0, 94.0, 94.0, 135.0, 141.0, 128.0, 152.0, 96.0, 118.0], "value": 115.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 86.0, 151.0, 129.0, 90.0, 99.0, 139.0, 89.0, 82.0, 146.0], "value": 112.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 59.0, 59.0, 57.0, 57.0, 56.0, 57.0, 57.0, 57.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 77.0, 60.0, 89.0, 120.0, 111.0, 83.0, 72.0, 70.0, 68.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 87.0, 85.0, 82.0, 73.0, 76.0, 112.0, 110.0, 108.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 165.0, 155.0, 149.0, 149.0, 140.0, 144.0, 159.0, 285.0, 142.0], "value": 163.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 174.0, 166.0, 173.0, 174.0, 156.0, 231.0, 231.0, 174.0, 221.0], "value": 189.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 70.0, 70.0, 95.0, 58.0, 76.0, 93.0], "value": 73.2, "unit": "score"}]}]} 17:38:24 INFO - Return code: 0 17:38:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:38:24 ERROR - # TBPL SUCCESS # 17:38:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:38:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:38:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:38:24 INFO - 2016-09-10 17:38:24.527 screenresolution[1683:11245] starting screenresolution argv=screenresolution get 17:38:24 INFO - 2016-09-10 17:38:24.530 screenresolution[1683:11245] Display 0: 1600x1200x32@60 17:38:24 INFO - 2016-09-10 17:38:24.536 screenresolution[1684:11248] starting screenresolution argv=screenresolution list 17:38:24 INFO - Available Modes on Display 0 17:38:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:38:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:38:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:38:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:38:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:38:24 INFO - Intel Iris: 17:38:24 INFO - Chipset Model: Intel Iris 17:38:24 INFO - Type: GPU 17:38:24 INFO - Bus: Built-In 17:38:24 INFO - VRAM (Dynamic, Max): 1536 MB 17:38:24 INFO - Vendor: Intel (0x8086) 17:38:24 INFO - Device ID: 0x0a2e 17:38:24 INFO - Revision ID: 0x0009 17:38:24 INFO - Displays: 17:38:24 INFO - Display: 17:38:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:38:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:38:24 INFO - Display Serial Number: 1600x1200 60 17:38:24 INFO - Main Display: Yes 17:38:24 INFO - Mirror: Off 17:38:24 INFO - Online: Yes 17:38:24 INFO - Rotation: Supported 17:38:24 INFO - Return code: 0 17:38:24 INFO - Running post-action listener: _resource_record_post_action 17:38:24 INFO - [mozharness: 2016-09-11 00:38:24.771309Z] Finished run-tests step (success) 17:38:24 INFO - Running post-run listener: _resource_record_post_run 17:38:24 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 31898624; Write bytes: 708132864; Read time: 196; Write time: 2004 17:38:24 INFO - TinderboxPrint: CPU usage
54.4% 17:38:24 INFO - TinderboxPrint: I/O read bytes / time
31,898,624 / 196 17:38:24 INFO - TinderboxPrint: I/O write bytes / time
708,132,864 / 2,004 17:38:24 INFO - TinderboxPrint: CPU idle
2,206.5 (45.6%) 17:38:24 INFO - TinderboxPrint: CPU system
978.3 (20.2%) 17:38:24 INFO - TinderboxPrint: CPU user
1,657.0 (34.2%) 17:38:24 INFO - TinderboxPrint: Swap in / out
434,278,400 / 0 17:38:24 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398641664; Write bytes: 393762816; Read time: 29591; Write time: 807 17:38:24 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 29850624; Write bytes: 297670656; Read time: 175; Write time: 1150 17:38:25 INFO - Running post-run listener: _upload_blobber_files 17:38:25 INFO - Blob upload gear active. 17:38:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:38:25 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 17:38:25 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'] 17:38:25 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 17:38:25 INFO - (blobuploader) - INFO - Open directory for files ... 17:38:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:38:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:38:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:26 INFO - (blobuploader) - INFO - Done attempting. 17:38:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:38:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:38:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:27 INFO - (blobuploader) - INFO - Done attempting. 17:38:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:38:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:38:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:29 INFO - (blobuploader) - INFO - Done attempting. 17:38:29 INFO - (blobuploader) - INFO - Iteration through files over. 17:38:29 INFO - Return code: 0 17:38:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:38:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:38:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04ecee59bc8892d0f83a8170d1db98c7b77fbf2ecff82a89ebc530876d1acb3bf7b9711a7c92f0ca61aeb9e59ddde4d36c3d19c4accda8406af3190f9d6e0dfe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ea5b349e1e99506b2cd17c7c97ed4759cf52d1e698b7ebdb545af011ff555121250d8b92e96aa6090a165be9e58db56bbe593b03d5c049c54e3f37834516492", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f5563b94c65819874556bdce41478915393ffc3a0442d78430f1859e31e35939ba9dbf2dc110e05145652afe6906ac24bd3cebfc2d9bf557340f771e77b3b02"} 17:38:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:38:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:38:29 INFO - Contents: 17:38:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04ecee59bc8892d0f83a8170d1db98c7b77fbf2ecff82a89ebc530876d1acb3bf7b9711a7c92f0ca61aeb9e59ddde4d36c3d19c4accda8406af3190f9d6e0dfe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ea5b349e1e99506b2cd17c7c97ed4759cf52d1e698b7ebdb545af011ff555121250d8b92e96aa6090a165be9e58db56bbe593b03d5c049c54e3f37834516492", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f5563b94c65819874556bdce41478915393ffc3a0442d78430f1859e31e35939ba9dbf2dc110e05145652afe6906ac24bd3cebfc2d9bf557340f771e77b3b02"} 17:38:29 INFO - Running post-run listener: copy_logs_to_upload_dir 17:38:29 INFO - Copying logs to upload dir... 17:38:29 INFO - mkdir: /builds/slave/test/build/upload/logs 17:38:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.065314 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-10 17:38:29.883223) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 17:38:29.886720) ========= 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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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/04ecee59bc8892d0f83a8170d1db98c7b77fbf2ecff82a89ebc530876d1acb3bf7b9711a7c92f0ca61aeb9e59ddde4d36c3d19c4accda8406af3190f9d6e0dfe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ea5b349e1e99506b2cd17c7c97ed4759cf52d1e698b7ebdb545af011ff555121250d8b92e96aa6090a165be9e58db56bbe593b03d5c049c54e3f37834516492", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f5563b94c65819874556bdce41478915393ffc3a0442d78430f1859e31e35939ba9dbf2dc110e05145652afe6906ac24bd3cebfc2d9bf557340f771e77b3b02"} build_url:https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010973 build_url: 'https://queue.taskcluster.net/v1/task/JqaRDUeYQXeib672JGzDPg/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/04ecee59bc8892d0f83a8170d1db98c7b77fbf2ecff82a89ebc530876d1acb3bf7b9711a7c92f0ca61aeb9e59ddde4d36c3d19c4accda8406af3190f9d6e0dfe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ea5b349e1e99506b2cd17c7c97ed4759cf52d1e698b7ebdb545af011ff555121250d8b92e96aa6090a165be9e58db56bbe593b03d5c049c54e3f37834516492", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f5563b94c65819874556bdce41478915393ffc3a0442d78430f1859e31e35939ba9dbf2dc110e05145652afe6906ac24bd3cebfc2d9bf557340f771e77b3b02"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 17:38:29.919853) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 17:38:29.920235) ========= 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.gzuDKzzN1C/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.IcKeP4cAwF/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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.004946 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 17:38:29.969058) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 17:38:29.969387) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 17:38:29.969711) ========= ========= Total master_lag: 0.18 =========