builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0176 starttime: 1473268394.59 results: success (0) buildid: 20160907082120 builduid: d33f6c600360482da7f7644cd1acb751 revision: 95acb9299fafdc69463c49860caf367e4fbcc8e3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.588166) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.589255) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.589582) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.672165) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.672446) ========= 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.eogJCqZdsq/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.yDKgCDUvFh/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-07 10:13:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2016-09-07 10:13:14 (14.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.194565 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.893257) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.893526) ========= 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.eogJCqZdsq/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.yDKgCDUvFh/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.027730 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:14.942492) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 10:13:14.942803) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 95acb9299fafdc69463c49860caf367e4fbcc8e3 --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 95acb9299fafdc69463c49860caf367e4fbcc8e3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eogJCqZdsq/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.yDKgCDUvFh/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-07 10:13:15,016 truncating revision to first 12 chars 2016-09-07 10:13:15,016 Setting DEBUG logging. 2016-09-07 10:13:15,016 attempt 1/10 2016-09-07 10:13:15,016 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/95acb9299faf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 10:13:16,024 unpacking tar archive at: mozilla-central-95acb9299faf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.270543 ========= master_lag: 0.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 10:13:16.484907) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:16.485165) ========= script_repo_revision: 95acb9299fafdc69463c49860caf367e4fbcc8e3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:16.485528) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:16.485814) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 10:13:16.528820) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-07 10:13:16.529097) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.eogJCqZdsq/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.yDKgCDUvFh/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 10:13:16 INFO - MultiFileLogger online at 20160907 10:13:16 in /builds/slave/test 10:13:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:13:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:13:16 INFO - {'append_to_log': False, 10:13:16 INFO - 'base_work_dir': '/builds/slave/test', 10:13:16 INFO - 'blob_upload_branch': 'Firefox', 10:13:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:13:16 INFO - 'branch': 'Firefox', 10:13:16 INFO - 'buildbot_json_path': 'buildprops.json', 10:13:16 INFO - 'config_files': ('talos/mac_config.py',), 10:13:16 INFO - 'default_actions': ('clobber', 10:13:16 INFO - 'read-buildbot-config', 10:13:16 INFO - 'download-and-extract', 10:13:16 INFO - 'populate-webroot', 10:13:16 INFO - 'create-virtualenv', 10:13:16 INFO - 'install', 10:13:16 INFO - 'run-tests'), 10:13:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:13:16 INFO - 'download_minidump_stackwalk': True, 10:13:16 INFO - 'download_symbols': 'ondemand', 10:13:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:13:16 INFO - 'tooltool.py': '/tools/tooltool.py', 10:13:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:13:16 INFO - '/tools/misc-python/virtualenv.py')}, 10:13:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:13:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:13:16 INFO - 'installer_path': 'installer.exe', 10:13:16 INFO - 'log_level': 'info', 10:13:16 INFO - 'log_name': 'talos', 10:13:16 INFO - 'log_to_console': True, 10:13:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:13:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:13:16 INFO - 'opt_config_files': (), 10:13:16 INFO - 'pip_index': False, 10:13:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:13:16 INFO - 'cmd': ('bash', 10:13:16 INFO - '-c', 10:13:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:13:16 INFO - 'enabled': True, 10:13:16 INFO - 'halt_on_failure': False, 10:13:16 INFO - 'name': 'check_screen_resolution'},), 10:13:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:13:16 INFO - 'cmd': ('bash', 10:13:16 INFO - '-c', 10:13:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:13:16 INFO - 'enabled': True, 10:13:16 INFO - 'halt_on_failure': False, 10:13:16 INFO - 'name': 'check_screen_resolution'},), 10:13:16 INFO - 'run_cmd_checks_enabled': True, 10:13:16 INFO - 'sps_profile': False, 10:13:16 INFO - 'sps_profile_interval': 0, 10:13:16 INFO - 'suite': 'dromaeojs', 10:13:16 INFO - 'system_bits': '32', 10:13:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:13:16 INFO - 'title': 't-yosemite-r7-0176', 10:13:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:13:16 INFO - 'use_talos_json': True, 10:13:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:13:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:13:16 INFO - 'work_dir': 'build'} 10:13:16 INFO - [mozharness: 2016-09-07 17:13:16.748397Z] Running clobber step. 10:13:16 INFO - Running pre-action listener: _resource_record_pre_action 10:13:16 INFO - Running main action method: clobber 10:13:16 INFO - rmtree: /builds/slave/test/build 10:13:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:13:18 INFO - Running post-action listener: _resource_record_post_action 10:13:18 INFO - [mozharness: 2016-09-07 17:13:18.869423Z] Finished clobber step (success) 10:13:18 INFO - [mozharness: 2016-09-07 17:13:18.869575Z] Running read-buildbot-config step. 10:13:18 INFO - Running pre-action listener: _resource_record_pre_action 10:13:18 INFO - Running main action method: read_buildbot_config 10:13:18 INFO - Using buildbot properties: 10:13:18 INFO - { 10:13:18 INFO - "project": "", 10:13:18 INFO - "product": "firefox", 10:13:18 INFO - "script_repo_revision": "production", 10:13:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:13:18 INFO - "repository": "", 10:13:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:13:18 INFO - "buildid": "20160907082120", 10:13:18 INFO - "pgo_build": "False", 10:13:18 INFO - "basedir": "/builds/slave/test", 10:13:18 INFO - "buildnumber": 27, 10:13:18 INFO - "slavename": "t-yosemite-r7-0176", 10:13:18 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:13:18 INFO - "platform": "yosemite_r7", 10:13:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:13:18 INFO - "branch": "mozilla-central", 10:13:18 INFO - "repo_path": "mozilla-central", 10:13:18 INFO - "revision": "95acb9299fafdc69463c49860caf367e4fbcc8e3", 10:13:18 INFO - "stage_platform": "macosx64", 10:13:18 INFO - "builduid": "d33f6c600360482da7f7644cd1acb751", 10:13:18 INFO - "slavebuilddir": "test" 10:13:18 INFO - } 10:13:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:13:18 INFO - Running post-action listener: _resource_record_post_action 10:13:18 INFO - [mozharness: 2016-09-07 17:13:18.872981Z] Finished read-buildbot-config step (success) 10:13:18 INFO - [mozharness: 2016-09-07 17:13:18.873114Z] Running download-and-extract step. 10:13:18 INFO - Running pre-action listener: _resource_record_pre_action 10:13:18 INFO - Running main action method: download_and_extract 10:13:18 INFO - mkdir: /builds/slave/test/build/tests 10:13:18 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')]} 10:13:18 INFO - trying https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:13:18 INFO - Downloading https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/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 10:13:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/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 10:13:19 INFO - Downloaded 1199 bytes. 10:13:19 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:13:19 INFO - Using the following test package requirements: 10:13:19 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:13:19 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:19 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:13:19 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:19 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:13:19 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:19 INFO - u'jsshell-mac.zip'], 10:13:19 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:19 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:13:19 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:13:19 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:19 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:13:19 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:19 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:13:19 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:19 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:13:19 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:13:19 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:13:19 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:13:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:13:20 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:13:27 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 10:13:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:13:28 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:13:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:13:35 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:13:35 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')]} 10:13:35 INFO - trying https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:13:35 INFO - Downloading https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:13:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/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 10:13:38 INFO - Downloaded 111562979 bytes. 10:13:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:13:38 INFO - mkdir: /builds/slave/test/properties 10:13:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:13:38 INFO - Writing to file /builds/slave/test/properties/build_url 10:13:38 INFO - Contents: 10:13:38 INFO - build_url:https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:13:39 INFO - Running post-action listener: _resource_record_post_action 10:13:39 INFO - Running post-action listener: set_extra_try_arguments 10:13:39 INFO - [mozharness: 2016-09-07 17:13:39.337819Z] Finished download-and-extract step (success) 10:13:39 INFO - [mozharness: 2016-09-07 17:13:39.337934Z] Running populate-webroot step. 10:13:39 INFO - Running pre-action listener: _resource_record_pre_action 10:13:39 INFO - Running main action method: populate_webroot 10:13:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:13:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:13:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:13:39 INFO - '--tppagecycles', 10:13:39 INFO - '7'], 10:13:39 INFO - 'tests': ['tsvgm']}}, 10:13:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:13:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:13:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:13:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:13:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:13:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:13:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:39 INFO - 'talos_options': ['--disable-e10s'], 10:13:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:39 INFO - 'talos_options': ['--disable-e10s'], 10:13:39 INFO - 'tests': ['damp', 'tps']}, 10:13:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:39 INFO - 'tests': ['damp', 'tps']}, 10:13:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:13:39 INFO - 'tests': ['dromaeo_dom']}, 10:13:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:13:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:13:39 INFO - 'tests': ['basic_compositor_video']}, 10:13:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:13:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:13:39 INFO - 'tests': ['a11yr', 10:13:39 INFO - 'ts_paint', 10:13:39 INFO - 'tpaint', 10:13:39 INFO - 'sessionrestore', 10:13:39 INFO - 'sessionrestore_no_auto_restore', 10:13:39 INFO - 'tabpaint']}, 10:13:39 INFO - 'other-e10s': {'tests': ['a11yr', 10:13:39 INFO - 'ts_paint', 10:13:39 INFO - 'tpaint', 10:13:39 INFO - 'sessionrestore', 10:13:39 INFO - 'sessionrestore_no_auto_restore', 10:13:39 INFO - 'tabpaint']}, 10:13:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:13:39 INFO - 'ts_paint', 10:13:39 INFO - 'tpaint', 10:13:39 INFO - 'sessionrestore', 10:13:39 INFO - 'sessionrestore_no_auto_restore', 10:13:39 INFO - 'tabpaint']}, 10:13:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:13:39 INFO - 'ts_paint', 10:13:39 INFO - 'tpaint', 10:13:39 INFO - 'sessionrestore', 10:13:39 INFO - 'sessionrestore_no_auto_restore', 10:13:39 INFO - 'tabpaint']}, 10:13:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:13:39 INFO - 'tests': ['a11yr', 10:13:39 INFO - 'ts_paint', 10:13:39 INFO - 'tpaint', 10:13:39 INFO - 'sessionrestore', 10:13:39 INFO - 'sessionrestore_no_auto_restore', 10:13:39 INFO - 'tabpaint']}, 10:13:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:13:39 INFO - 'tests': ['a11yr', 10:13:39 INFO - 'ts_paint', 10:13:39 INFO - 'tpaint', 10:13:39 INFO - 'sessionrestore', 10:13:39 INFO - 'sessionrestore_no_auto_restore', 10:13:39 INFO - 'tabpaint']}, 10:13:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:13:39 INFO - 'tests': ['tsvgx', 10:13:39 INFO - 'tsvgr_opacity', 10:13:39 INFO - 'tart', 10:13:39 INFO - 'tscrollx', 10:13:39 INFO - 'cart']}, 10:13:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:13:39 INFO - 'tsvgr_opacity', 10:13:39 INFO - 'tart', 10:13:39 INFO - 'tscrollx', 10:13:39 INFO - 'cart']}, 10:13:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:39 INFO - 'talos_options': ['--disable-e10s'], 10:13:39 INFO - 'tests': ['tp5o']}, 10:13:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:39 INFO - 'tests': ['tp5o']}, 10:13:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:39 INFO - 'talos_options': ['--disable-e10s', 10:13:39 INFO - '--xperf_path', 10:13:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:39 INFO - 'tests': ['tp5n']}, 10:13:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:39 INFO - 'talos_options': ['--xperf_path', 10:13:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:39 INFO - 'tests': ['tp5n']}}, 10:13:39 INFO - 'talos.zip': {'path': '', 10:13:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:13:39 INFO - Running post-action listener: _resource_record_post_action 10:13:39 INFO - [mozharness: 2016-09-07 17:13:39.357010Z] Finished populate-webroot step (success) 10:13:39 INFO - [mozharness: 2016-09-07 17:13:39.357140Z] Running create-virtualenv step. 10:13:39 INFO - Running pre-action listener: _resource_record_pre_action 10:13:39 INFO - Running main action method: create_virtualenv 10:13:39 INFO - Creating virtualenv /builds/slave/test/build/venv 10:13:39 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 10:13:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:13:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:13:39 INFO - Using real prefix '/tools/python27' 10:13:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:13:40 INFO - Installing distribute.............................................................................................................................................................................................done. 10:13:43 INFO - Installing pip.................done. 10:13:43 INFO - Return code: 0 10:13:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:43 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 0x107737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d357e0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769bd30>, '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 0x107400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107693b20>, '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.eogJCqZdsq/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.yDKgCDUvFh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 10:13:43 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 10:13:43 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 10:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eogJCqZdsq/Render', 10:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:43 INFO - 'HOME': '/Users/cltbld', 10:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:43 INFO - 'LOGNAME': 'cltbld', 10:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:43 INFO - 'MOZ_NO_REMOTE': '1', 10:13:43 INFO - 'NO_EM_RESTART': '1', 10:13:43 INFO - 'PAGER': '/bin/cat', 10:13:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:43 INFO - 'PWD': '/builds/slave/test', 10:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:43 INFO - 'SHELL': '/bin/bash', 10:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yDKgCDUvFh/Listeners', 10:13:43 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 10:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:43 INFO - 'USER': 'cltbld', 10:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:43 INFO - 'XPC_FLAGS': '0x0', 10:13:43 INFO - 'XPC_SERVICE_NAME': '0', 10:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:43 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 10:13:43 INFO - Cleaning up... 10:13:43 INFO - Return code: 0 10:13:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:43 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 0x107737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d357e0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769bd30>, '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 0x107400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107693b20>, '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.eogJCqZdsq/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.yDKgCDUvFh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 10:13:43 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 10:13:43 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 10:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eogJCqZdsq/Render', 10:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:43 INFO - 'HOME': '/Users/cltbld', 10:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:43 INFO - 'LOGNAME': 'cltbld', 10:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:43 INFO - 'MOZ_NO_REMOTE': '1', 10:13:43 INFO - 'NO_EM_RESTART': '1', 10:13:43 INFO - 'PAGER': '/bin/cat', 10:13:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:43 INFO - 'PWD': '/builds/slave/test', 10:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:43 INFO - 'SHELL': '/bin/bash', 10:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yDKgCDUvFh/Listeners', 10:13:43 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 10:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:43 INFO - 'USER': 'cltbld', 10:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:43 INFO - 'XPC_FLAGS': '0x0', 10:13:43 INFO - 'XPC_SERVICE_NAME': '0', 10:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:43 INFO - Downloading/unpacking psutil>=3.1.1 10:13:43 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 10:13:43 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 10:13:43 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 10:13:43 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 10:13:43 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 10:13:43 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 10:13:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:13:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:13:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:13:47 INFO - Installing collected packages: psutil 10:13:47 INFO - Running setup.py install for psutil 10:13:47 INFO - building 'psutil._psutil_osx' extension 10:13:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:13:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:13:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:13:48 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 10:13:48 INFO - building 'psutil._psutil_posix' extension 10:13:48 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 10:13:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:13:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:13:48 INFO - ^ 10:13:48 INFO - 1 warning generated. 10:13:48 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 10:13:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:13:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:13:48 INFO - Successfully installed psutil 10:13:48 INFO - Cleaning up... 10:13:48 INFO - Return code: 0 10:13:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:13: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')]} 10:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13: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')]} 10:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:48 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 0x107737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d357e0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769bd30>, '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 0x107400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107693b20>, '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.eogJCqZdsq/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.yDKgCDUvFh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 10:13:48 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 10:13:48 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 10:13:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eogJCqZdsq/Render', 10:13:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:48 INFO - 'HOME': '/Users/cltbld', 10:13:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:48 INFO - 'LOGNAME': 'cltbld', 10:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:48 INFO - 'MOZ_NO_REMOTE': '1', 10:13:48 INFO - 'NO_EM_RESTART': '1', 10:13:48 INFO - 'PAGER': '/bin/cat', 10:13:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:48 INFO - 'PWD': '/builds/slave/test', 10:13:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:48 INFO - 'SHELL': '/bin/bash', 10:13:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yDKgCDUvFh/Listeners', 10:13:48 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 10:13:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:48 INFO - 'USER': 'cltbld', 10:13:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:48 INFO - 'XPC_FLAGS': '0x0', 10:13:48 INFO - 'XPC_SERVICE_NAME': '0', 10:13:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:13:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:13:51 INFO - Installing collected packages: mozsystemmonitor 10:13:51 INFO - Running setup.py install for mozsystemmonitor 10:13:51 INFO - Successfully installed mozsystemmonitor 10:13:51 INFO - Cleaning up... 10:13:51 INFO - Return code: 0 10:13:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d357e0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769bd30>, '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 0x107400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107693b20>, '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.eogJCqZdsq/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.yDKgCDUvFh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 10:13:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:13:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eogJCqZdsq/Render', 10:13:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:51 INFO - 'HOME': '/Users/cltbld', 10:13:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:51 INFO - 'LOGNAME': 'cltbld', 10:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:51 INFO - 'MOZ_NO_REMOTE': '1', 10:13:51 INFO - 'NO_EM_RESTART': '1', 10:13:51 INFO - 'PAGER': '/bin/cat', 10:13:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:51 INFO - 'PWD': '/builds/slave/test', 10:13:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:51 INFO - 'SHELL': '/bin/bash', 10:13:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yDKgCDUvFh/Listeners', 10:13:51 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 10:13:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:51 INFO - 'USER': 'cltbld', 10:13:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:51 INFO - 'XPC_FLAGS': '0x0', 10:13:51 INFO - 'XPC_SERVICE_NAME': '0', 10:13:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:51 INFO - Downloading/unpacking jsonschema==2.5.1 10:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:13:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:13:54 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 10:13:54 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 10:13:54 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 10:13:54 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 10:13:54 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 10:13:54 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 10:13:54 INFO - Downloading functools32-3.2.3-2.tar.gz 10:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:13:54 INFO - warning: no files found matching '*.txt' 10:13:54 INFO - no previously-included directories found matching 'build' 10:13:54 INFO - no previously-included directories found matching 'dist' 10:13:54 INFO - no previously-included directories found matching '.git*' 10:13:54 INFO - Installing collected packages: jsonschema, functools32 10:13:54 INFO - Running setup.py install for functools32 10:13:54 INFO - warning: no files found matching '*.txt' 10:13:54 INFO - no previously-included directories found matching 'build' 10:13:54 INFO - no previously-included directories found matching 'dist' 10:13:54 INFO - no previously-included directories found matching '.git*' 10:13:54 INFO - Successfully installed jsonschema functools32 10:13:54 INFO - Cleaning up... 10:13:54 INFO - Return code: 0 10:13:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:13:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d357e0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769bd30>, '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 0x107400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107693b20>, '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.eogJCqZdsq/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.yDKgCDUvFh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 10:13:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:13:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:13:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eogJCqZdsq/Render', 10:13:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:54 INFO - 'HOME': '/Users/cltbld', 10:13:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:54 INFO - 'LOGNAME': 'cltbld', 10:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:54 INFO - 'MOZ_NO_REMOTE': '1', 10:13:54 INFO - 'NO_EM_RESTART': '1', 10:13:54 INFO - 'PAGER': '/bin/cat', 10:13:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:54 INFO - 'PWD': '/builds/slave/test', 10:13:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:54 INFO - 'SHELL': '/bin/bash', 10:13:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yDKgCDUvFh/Listeners', 10:13:54 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 10:13:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:54 INFO - 'USER': 'cltbld', 10:13:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:54 INFO - 'XPC_FLAGS': '0x0', 10:13:54 INFO - 'XPC_SERVICE_NAME': '0', 10:13:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:13:55 INFO - Cleaning up... 10:13:55 INFO - Return code: 0 10:13:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:13: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')]} 10:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13: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')]} 10:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13: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', '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 0x107737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d357e0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769bd30>, '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 0x107400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107693b20>, '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.eogJCqZdsq/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.yDKgCDUvFh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 10:13: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:13: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 blobuploader==1.2.4 10:13:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eogJCqZdsq/Render', 10:13:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:55 INFO - 'HOME': '/Users/cltbld', 10:13:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:55 INFO - 'LOGNAME': 'cltbld', 10:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:55 INFO - 'MOZ_NO_REMOTE': '1', 10:13:55 INFO - 'NO_EM_RESTART': '1', 10:13:55 INFO - 'PAGER': '/bin/cat', 10:13:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:55 INFO - 'PWD': '/builds/slave/test', 10:13:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:55 INFO - 'SHELL': '/bin/bash', 10:13:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yDKgCDUvFh/Listeners', 10:13:55 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 10:13:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:55 INFO - 'USER': 'cltbld', 10:13:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:55 INFO - 'XPC_FLAGS': '0x0', 10:13:55 INFO - 'XPC_SERVICE_NAME': '0', 10:13:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:55 INFO - Downloading/unpacking blobuploader==1.2.4 10:13: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 10:13: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 10:13: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 10:13: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 10:13: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 10:13: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 10:13:57 INFO - Downloading blobuploader-1.2.4.tar.gz 10:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:13:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:13:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:59 INFO - Downloading docopt-0.6.1.tar.gz 10:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:13:59 INFO - Installing collected packages: blobuploader, requests, docopt 10:13:59 INFO - Running setup.py install for blobuploader 10:13:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:13:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:13:59 INFO - Running setup.py install for requests 10:13:59 INFO - Running setup.py install for docopt 10:13:59 INFO - Successfully installed blobuploader requests docopt 10:13:59 INFO - Cleaning up... 10:14:00 INFO - Return code: 0 10:14:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:14:00 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')]} 10:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:00 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')]} 10:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:00 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 0x107737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d357e0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769bd30>, '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 0x107400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107693b20>, '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.eogJCqZdsq/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.yDKgCDUvFh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 10:14:00 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 10:14:00 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 10:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eogJCqZdsq/Render', 10:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:00 INFO - 'HOME': '/Users/cltbld', 10:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:00 INFO - 'LOGNAME': 'cltbld', 10:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:00 INFO - 'MOZ_NO_REMOTE': '1', 10:14:00 INFO - 'NO_EM_RESTART': '1', 10:14:00 INFO - 'PAGER': '/bin/cat', 10:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:00 INFO - 'PWD': '/builds/slave/test', 10:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:00 INFO - 'SHELL': '/bin/bash', 10:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yDKgCDUvFh/Listeners', 10:14:00 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 10:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:00 INFO - 'USER': 'cltbld', 10:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:00 INFO - 'XPC_FLAGS': '0x0', 10:14:00 INFO - 'XPC_SERVICE_NAME': '0', 10:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:14:00 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-wSfGA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:14:00 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-ZeGGJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:14:00 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-JwirsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:14:00 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-9kvGIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:14:00 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-QFqNhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:14:00 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-0Imfbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:14:00 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-0D0VOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:14:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-ASpxni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:14:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-m6Zop2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:14:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-AOCkSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:14:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-axSSJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:14:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-DEYUWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:14:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-RWll_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:14:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-n8JIkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:14:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-c3MK20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:14:01 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-ccauBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:14:02 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-KZt0J8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:14:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:14:02 INFO - Running setup.py install for manifestparser 10:14:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:14:02 INFO - Running setup.py install for mozcrash 10:14:02 INFO - Running setup.py install for mozdebug 10:14:02 INFO - Running setup.py install for mozdevice 10:14:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:14:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:14:02 INFO - Running setup.py install for mozfile 10:14:02 INFO - Running setup.py install for mozhttpd 10:14:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:14:03 INFO - Running setup.py install for mozinfo 10:14:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:14:03 INFO - Running setup.py install for mozInstall 10:14:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:14:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:14:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:14:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:14:03 INFO - Running setup.py install for mozleak 10:14:03 INFO - Running setup.py install for mozlog 10:14:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:14:03 INFO - Running setup.py install for moznetwork 10:14:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:14:03 INFO - Running setup.py install for mozprocess 10:14:03 INFO - Running setup.py install for mozprofile 10:14:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Running setup.py install for mozrunner 10:14:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Running setup.py install for mozscreenshot 10:14:04 INFO - Running setup.py install for moztest 10:14:04 INFO - Running setup.py install for mozversion 10:14:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:14:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:14:04 INFO - Cleaning up... 10:14:04 INFO - Return code: 0 10:14:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d357e0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769bd30>, '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 0x107400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107693b20>, '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.eogJCqZdsq/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.yDKgCDUvFh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 10:14:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:14:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eogJCqZdsq/Render', 10:14:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:04 INFO - 'HOME': '/Users/cltbld', 10:14:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:04 INFO - 'LOGNAME': 'cltbld', 10:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:04 INFO - 'MOZ_NO_REMOTE': '1', 10:14:04 INFO - 'NO_EM_RESTART': '1', 10:14:04 INFO - 'PAGER': '/bin/cat', 10:14:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:04 INFO - 'PWD': '/builds/slave/test', 10:14:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:04 INFO - 'SHELL': '/bin/bash', 10:14:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yDKgCDUvFh/Listeners', 10:14:04 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 10:14:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:04 INFO - 'USER': 'cltbld', 10:14:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:04 INFO - 'XPC_FLAGS': '0x0', 10:14:04 INFO - 'XPC_SERVICE_NAME': '0', 10:14:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:14:05 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-9SDNOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:14:05 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)) 10:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:14:05 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-2Y_Mmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:14:05 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)) 10:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:14:05 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-0dF3Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:14:05 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-6bNYWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:14:05 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-mvE51_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:14:05 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-kQwhPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:14:05 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-T8T2Yb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:14:05 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-8rsFpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:14:05 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-i48lR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:14:05 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-okGz_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:14:06 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)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:14:06 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-YVtyht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:14:06 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)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:14:06 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-G1K6BC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:14:06 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-NCoThp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:14:06 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-8bbl4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:14:06 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)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:14:06 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-0NQ1N9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:14:06 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-U5IpBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:14:06 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-dzI8j8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:14:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:14:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:09 INFO - Downloading blessings-1.6.tar.gz 10:14:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:14:09 INFO - Installing collected packages: blessings 10:14:09 INFO - Running setup.py install for blessings 10:14:09 INFO - Successfully installed blessings 10:14:09 INFO - Cleaning up... 10:14:09 INFO - Return code: 0 10:14:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:14:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:14:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:14:09 INFO - Reading from file tmpfile_stdout 10:14:09 INFO - Current package versions: 10:14:09 INFO - blessings == 1.6 10:14:09 INFO - blobuploader == 1.2.4 10:14:09 INFO - docopt == 0.6.1 10:14:09 INFO - functools32 == 3.2.3-2 10:14:09 INFO - jsonschema == 2.5.1 10:14:09 INFO - manifestparser == 1.1 10:14:09 INFO - mozInstall == 1.12 10:14:09 INFO - mozcrash == 0.17 10:14:09 INFO - mozdebug == 0.1 10:14:09 INFO - mozdevice == 0.48 10:14:09 INFO - mozfile == 1.2 10:14:09 INFO - mozhttpd == 0.7 10:14:09 INFO - mozinfo == 0.9 10:14:09 INFO - mozleak == 0.1 10:14:09 INFO - mozlog == 3.3 10:14:09 INFO - moznetwork == 0.27 10:14:09 INFO - mozprocess == 0.23 10:14:09 INFO - mozprofile == 0.28 10:14:09 INFO - mozrunner == 6.12 10:14:09 INFO - mozscreenshot == 0.1 10:14:09 INFO - mozsystemmonitor == 0.3 10:14:09 INFO - moztest == 0.7 10:14:09 INFO - mozversion == 1.4 10:14:09 INFO - psutil == 3.1.1 10:14:09 INFO - requests == 1.2.3 10:14:09 INFO - wsgiref == 0.1.2 10:14:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:09 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 0x107737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d357e0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769bd30>, '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 0x107400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107693b20>, '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.eogJCqZdsq/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.yDKgCDUvFh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 10:14:09 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 10:14:09 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 10:14:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eogJCqZdsq/Render', 10:14:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:09 INFO - 'HOME': '/Users/cltbld', 10:14:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:09 INFO - 'LOGNAME': 'cltbld', 10:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:09 INFO - 'MOZ_NO_REMOTE': '1', 10:14:09 INFO - 'NO_EM_RESTART': '1', 10:14:09 INFO - 'PAGER': '/bin/cat', 10:14:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:09 INFO - 'PWD': '/builds/slave/test', 10:14:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:09 INFO - 'SHELL': '/bin/bash', 10:14:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yDKgCDUvFh/Listeners', 10:14:09 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 10:14:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:09 INFO - 'USER': 'cltbld', 10:14:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:09 INFO - 'XPC_FLAGS': '0x0', 10:14:09 INFO - 'XPC_SERVICE_NAME': '0', 10:14:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:14:10 INFO - Cleaning up... 10:14:10 INFO - Return code: 0 10:14:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8d357e0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769bd30>, '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 0x107400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107693b20>, '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.eogJCqZdsq/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.yDKgCDUvFh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 10:14:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:14:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:14:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eogJCqZdsq/Render', 10:14:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:10 INFO - 'HOME': '/Users/cltbld', 10:14:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:10 INFO - 'LOGNAME': 'cltbld', 10:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:10 INFO - 'MOZ_NO_REMOTE': '1', 10:14:10 INFO - 'NO_EM_RESTART': '1', 10:14:10 INFO - 'PAGER': '/bin/cat', 10:14:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:10 INFO - 'PWD': '/builds/slave/test', 10:14:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:10 INFO - 'SHELL': '/bin/bash', 10:14:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yDKgCDUvFh/Listeners', 10:14:10 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 10:14:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:10 INFO - 'USER': 'cltbld', 10:14:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:10 INFO - 'XPC_FLAGS': '0x0', 10:14:10 INFO - 'XPC_SERVICE_NAME': '0', 10:14:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:14:10 INFO - Cleaning up... 10:14:10 INFO - Return code: 0 10:14:10 INFO - Running post-action listener: _resource_record_post_action 10:14:10 INFO - Running post-action listener: _start_resource_monitoring 10:14:10 INFO - Starting resource monitoring. 10:14:10 INFO - [mozharness: 2016-09-07 17:14:10.494926Z] Finished create-virtualenv step (success) 10:14:10 INFO - [mozharness: 2016-09-07 17:14:10.495877Z] Running install step. 10:14:10 INFO - Running pre-action listener: _resource_record_pre_action 10:14:10 INFO - Running main action method: install 10:14:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:14:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:14:10 INFO - Reading from file tmpfile_stdout 10:14:10 INFO - Detecting whether we're running mozinstall >=1.0... 10:14:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:14:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:14:10 INFO - Reading from file tmpfile_stdout 10:14:10 INFO - Output received: 10:14:10 INFO - Usage: mozinstall [options] installer 10:14:10 INFO - Options: 10:14:10 INFO - -h, --help show this help message and exit 10:14:10 INFO - -d DEST, --destination=DEST 10:14:10 INFO - Directory to install application into. [default: 10:14:10 INFO - "/builds/slave/test"] 10:14:10 INFO - --app=APP Application being installed. [default: firefox] 10:14:10 INFO - mkdir: /builds/slave/test/build/application 10:14:10 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'] 10:14:10 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 10:14:44 INFO - Reading from file tmpfile_stdout 10:14:44 INFO - Output received: 10:14:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:14:44 INFO - Running post-action listener: _resource_record_post_action 10:14:44 INFO - [mozharness: 2016-09-07 17:14:44.612868Z] Finished install step (success) 10:14:44 INFO - [mozharness: 2016-09-07 17:14:44.613003Z] Running run-tests step. 10:14:44 INFO - Running pre-action listener: _resource_record_pre_action 10:14:44 INFO - Running main action method: run_tests 10:14:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:14:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:14:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:14:44 INFO - 2016-09-07 10:14:44.627 screenresolution[1642:8072] starting screenresolution argv=screenresolution get 10:14:44 INFO - 2016-09-07 10:14:44.630 screenresolution[1642:8072] Display 0: 1600x1200x32@60 10:14:44 INFO - 2016-09-07 10:14:44.635 screenresolution[1643:8075] starting screenresolution argv=screenresolution list 10:14:44 INFO - Available Modes on Display 0 10:14:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:14:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:14:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:14:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:14:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:14:44 INFO - Intel Iris: 10:14:44 INFO - Chipset Model: Intel Iris 10:14:44 INFO - Type: GPU 10:14:44 INFO - Bus: Built-In 10:14:44 INFO - VRAM (Dynamic, Max): 1536 MB 10:14:44 INFO - Vendor: Intel (0x8086) 10:14:44 INFO - Device ID: 0x0a2e 10:14:44 INFO - Revision ID: 0x0009 10:14:44 INFO - Displays: 10:14:44 INFO - Display: 10:14:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:14:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:14:44 INFO - Display Serial Number: 1600x1200 60 10:14:44 INFO - Main Display: Yes 10:14:44 INFO - Mirror: Off 10:14:44 INFO - Online: Yes 10:14:44 INFO - Rotation: Supported 10:14:44 INFO - Return code: 0 10:14:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:14:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:14:44 INFO - Python 2.7.3 10:14:44 INFO - Return code: 0 10:14:44 INFO - grabbing minidump binary from tooltool 10:14:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:44 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 0x10769bd30>, '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 0x107400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107693b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:14:44 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 10:14:44 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 10:14:44 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 10:14:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:14:44 INFO - Return code: 0 10:14:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:14:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:14:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:14:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:14:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:14:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:14:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0176 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:14:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eogJCqZdsq/Render', 10:14:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:45 INFO - 'HOME': '/Users/cltbld', 10:14:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:45 INFO - 'LOGNAME': 'cltbld', 10:14:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:14:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:45 INFO - 'MOZ_NO_REMOTE': '1', 10:14:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:14:45 INFO - 'NO_EM_RESTART': '1', 10:14:45 INFO - 'PAGER': '/bin/cat', 10:14:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:45 INFO - 'PWD': '/builds/slave/test', 10:14:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:14:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:45 INFO - 'SHELL': '/bin/bash', 10:14:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yDKgCDUvFh/Listeners', 10:14:45 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 10:14:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:45 INFO - 'USER': 'cltbld', 10:14:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:45 INFO - 'XPC_FLAGS': '0x0', 10:14:45 INFO - 'XPC_SERVICE_NAME': '0', 10:14:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:14:45 INFO - mozversion application_buildid: 20160907082120 10:14:45 INFO - mozversion application_changeset: 95acb9299fafdc69463c49860caf367e4fbcc8e3 10:14:45 INFO - mozversion application_display_name: Nightly 10:14:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:14:45 INFO - mozversion application_name: Firefox 10:14:45 INFO - mozversion application_remotingname: firefox 10:14:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:14:45 INFO - mozversion application_vendor: Mozilla 10:14:45 INFO - mozversion application_version: 51.0a1 10:14:45 INFO - mozversion platform_buildid: 20160907082120 10:14:45 INFO - mozversion platform_changeset: 95acb9299fafdc69463c49860caf367e4fbcc8e3 10:14:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:14:45 INFO - mozversion platform_version: 51.0a1 10:14:45 INFO - using testdate: 1473268485 10:14:45 INFO - actual date: 1473268485 10:14:45 INFO - starting webserver on 'localhost:49271' 10:14:45 INFO - SUITE-START | Running 2 tests 10:14:45 INFO - TEST-START | dromaeo_css 10:14:45 INFO - operating with platform_type : mac_ 10:14:45 INFO - Initialising browser for dromaeo_css test... 10:14:45 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmp5fxY3k/profile http://localhost:49271/getInfo.html) 10:14:47 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 10:14:47 INFO - PROCESS | 1653 | colorDepth:24 10:14:47 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 10:14:47 INFO - PROCESS | 1653 | __metrics 10:14:48 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:14:48 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:14:48 INFO - TEST-INFO | 1653: exit 0 10:14:48 INFO - Browser initialized. 10:14:48 INFO - Running cycle 1/1 for dromaeo_css test... 10:14:48 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmp5fxY3k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:15:00 INFO - PROCESS | 1656 | RSS: Main: 232402944 10:15:00 INFO - PROCESS | 1656 | 10:15:51 INFO - PROCESS | 1656 | 1473268551931 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] 10:18:04 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 10:18:05 INFO - PROCESS | 1656 | RSS: Main: 373022720 10:18:05 INFO - PROCESS | 1656 | 10:21:08 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 10:21:08 INFO - PROCESS | 1656 | RSS: Main: 367853568 10:21:08 INFO - PROCESS | 1656 | 10:24:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 10:24:11 INFO - PROCESS | 1656 | RSS: Main: 328364032 10:24:11 INFO - PROCESS | 1656 | 10:27:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 10:27:14 INFO - PROCESS | 1656 | RSS: Main: 356372480 10:27:14 INFO - PROCESS | 1656 | 10:30:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 10:30:18 INFO - PROCESS | 1656 | RSS: Main: 322560000 10:30:18 INFO - PROCESS | 1656 | 10:33:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 10:33:22 INFO - PROCESS | 1656 | RSS: Main: 531177472 10:33:22 INFO - PROCESS | 1656 | 10:33:22 INFO - PROCESS | 1656 | __start_tp_report 10:33:22 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 10:33:22 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 10:33:22 INFO - PROCESS | 1656 | |i|pagename|runs| 10:33:22 INFO - PROCESS | 1656 | |0;dojo.html;2774.569941659043;3288.556044933934;3346.9497957530625;3378.628350881404;3415.7779744316617;5557.472040156189;5625.296837895258;5638.492535671789;5700.6579605223715;5702.429757024301;5210.218467229917;5286.154215325549;5651.2653355063885;5699.74351154198;5703.31560212775;5229.764660590273;5243.161094224924;5272;5283.947160528384;5299.390570084451;5291.5766738661005;5291.708956007418;5312.521873031426;5343.839684809443;5345.732713364336;5164.6384753067305;5315.707636080014;5386.48828361307;5388.622796404254;5395.325584301962;5299;5319.335083114611;5326.2543243945875;5338.065838478289;5368.141097424394;5137.38351397831;5153.2270159476;5186;5207.98444303361;5219.42586315505;5621;5634.126710359901;5652.123920792284;5668.433156684298;5800.24596802413;6265.780697675605;6265.843353916143;6280.528960327948;6281.309056003836;6294.7482100715915;5786.334571524287;6099.249254464524;6158.630482171084;6246.219222597176;6309.842253943642;5792.4497172768515;5799.652020878761;5804.448577385184;5818.418158184148;5825.097109947965;6278.089676996901;6290.685465726695;6293.590916590407;6297.874042519215;6308.179936608211;6005.819825405245;6034.818955431344;6072.149899014142;6120.721420533169;6183.134361189437;5277.313949186581;6283.3088360280335;6314.494840412848;6318.147050148263;6318.368163183737;6207.534434917399;6222.844428889223;6252.656103914237;6284.402981716729;6284.560080794392;6102.816915492543;6117.736059405486;6139.584134579375;6252.249730032425;6254.749810007549;4945.604351651931;4946.950530494649;4958.677685950402;4962.6774259673;4973.079980203674;4399.274119770202;4408.360787685768;4413.117376524708;4415.094905544356;4447.221736196153;5569.10894256917;5616.354119276254;5666.575006874501;5676.858078548069;5756.30924289088;6194.969025154845;6246.875562398812;6274.341194174546;6349.809505714836;6357.11000459936;5702.82603394824;5706.629069110494;5713.228714123623;5724.112762521816;5781.046127389018;5331.386890507563;5405.7272722762;5410.296661433988;5468.945310546923;5482.424345443751;6264.906026409699;6270.7805226816845;6274.6548939808745;6299.370062993848;6352.301246862749;6299.96850015747;6335.968320158369;6342.714577843916;6344.143540622142;6347.270063942706;6326.525510586633;6326.746930122928;6331.430171284606;6336.493080553637;6351.555391122577;6012.579119461596;6113.993245360445;6122.6726173278175;6129.297663865929;6301.747930082745;4872.780724867319;6062.848428789139;6267.62394256346;6311.589746666452;6316.399942005502;6141.385861413823;6147.200863887845;6157.538184636081;6158.3225845157895;6159.168512250789;5899.793507227227;5963.642181469126;5964.850878728066;5985.610935289193;6132.724027418688;5900.911486327623;5905.557083218861;5910.527157827278;5914.053751399755;5929;5967.224260846062;6087.4521293083835;6099.2985806632805;6099.329073801967;6099.932511810504;5815.912761308499;5854.795082172274;5904.1143828424365;5912.320083190488;5913.172155898092 10:33:22 INFO - PROCESS | 1656 | |1;ext.html;494.74520621879736;524.3157679228607;538.2760187547747;550.9724364061025;554.0940562180833;29712.554311685315;31159.221019474495;38985.99999999996;39688.80155599218;39796.403053954215;40071;40282;40396;40425.39361909574;40432.79783601078;9727.416355018704;9893.30746847721;9893.703188904345;9896.604135834557;9906.504674766269;9788.90211097887;9892.230175550858;9894.653687120952;9903.158231550291;9905.801883962316;9853.950730246339;9864.112229899307;9929.503524823765;9932.553035113437;9933.950330248375;9776.804463910717;9828.606855725762;9845.360051596623;9846.803063938682;9855.26085543587;9720.854187187198;9765.658201962897;9808;9821;9823.803523929482;38765.41851872224;39028.82913512571;39060.82817515479;39140.41289380661;39292.80353598214;3473.878414255489;3634.4548317752283;3788.5643151037707;3854.5759966403675;4037.9192416151513;29161.125166244834;29777.851110744523;29804.956826511185;29979;30317.393652126833;3186.681331866818;3207.150105222118;3211.42194405007;3215.1962009497624;3313.6189338226172;422811;424284;425377;427470;429148;54654.18018729722;55136;55137.44862551323;55184.72407637936;55249.72375138099;32818.50772238418;33673.3265334692;56701.345318382315;56840;56921.43078569161;15479;15954.361825527081;15956.441524546584;15964.12197329158;15992.120433376276;12177.269363838199;12301.938490307492;12312.507499699912;12318;12368.319742414255;17858.642827143645;17925.014124223293;18068.63862722738;18130.909345453187;18163;7630.465867389341;7768.7669369918995;7810.375169986388;7848.60756962161;7966.561839098905;831.4886344897965;961.4856052012279;963.450833025183;966.120830044656;1059.692689120162;28624.427511449656;29462;29488.262793429905;30134;30152.095437136923;993.4436715439377;1006.7231511334295;1050.422267752733;1061.1616822709973;1101.8599475399456;15158.469453569072;15237;15370.692586148214;15570.688586228212;15707.450239241572;581019;581323.1867681269;582500;582703.0864845733;584330;54587;55301.446985530434;55302.44697552973;55325.446745532834;55582.72208639012;1221.6518292286655;1254.2411840836346;1331.4141777617817;1339.1295657822297;1361.86381361865;26070.696465177043;26947;28551;30734.366264674667;31023.68976310208;46720.53279467162;46941.061178776916;47099;47121.293180601635;47132.057358853315;451011;451165;452752;455131;460453;22783.430414239778;25282.494350112527;25335;25717.3570660735;25750;42151.57848421476;44735.328970066126;44776.55223447724;45066;45967;36225.09437263984;36387.636123638425;36748.26503469969;37231;37369.813150934075;36700.44949325816;36975.44536831896;37213.88358349254;37446.43830342602;37838;35315.64684353226;35541.82229088838;35713;36208.45687314745;36465;27762.583561247;30616.234594135323;33863.492047619795;33986.281097511644;34079.82960085283;20702.792972070085;21733.782662173177;24334.26997190087;24830.130945416822;25348.493030139663 10:33:22 INFO - PROCESS | 1656 | |2;jquery.html;327.4760383386581;348.71405447533033;367.827121253011;372.32051506001545;400.0379088292655;14871.553853384403;16773.24520396582;19758;19897.801021989777;19908.60182796345;11768.764624707523;16015.798815088885;18642.161102750375;19232.038398080076;19450.708239376385;5255.290535777669;5476.123820188771;5480.123180291154;5483.588730845183;5486.231927530149;5239.240310155025;5401.540869026137;5406.459354064601;5448.83653490396;5476.808311709091;5451.236826844245;5454.427285135063;5464.4262352452815;5464.863378415532;5470;4685.2269375553;5233.241180028894;5394.406615272356;5457.536109430703;5467.9610873934225;4524.45706515217;4927.285543596195;4933.53131452515;5305.32882142124;5307.973460132714;14112.576622701336;24304.878475607686;24398;24444.877775611185;24710.629340559906;2176.3688530326185;2302.412884714404;2322.222055611372;2337.3805941425535;2608.3479130217447;9791.118799308026;10337.22470814692;11369.658910232707;13089.47642094315;14355.641108972255;1824.6426575101743;1825.8721889467743;1855.072463768116;1873.4566975577065;1899.164367678217;72915.54168916593;113921;115012;115072;116504.99999999916;9586.185174260127;10799.78400431987;11151.665450036511;11584.710382240512;11666.475008624462;14354.425822967174;14364.569062928129;14374.568762937128;14387;14405.06367086157;8081.959590202129;8330;8352.248297653241;8474.5339006354;8493.957530212308;6763.5265531413315;8194.836103277901;8222.794430139174;8263.834723305501;8400.495970241684;4376.934345984812;4476.932846007313;4481.215787237221;4522.118186953512;4587.472440669299;1844.3913508542148;1895.4503194073563;1896.4689886831434;1897.6868816645099;1899.6390685769936;837.24229572236;855.3200205836375;889.9765269939492;890.4835195586544;931.8788557487619;18712.157952892154;20337;20490.38528844137;21313.89343053275;21348.573028539653;806.983860322802;919.1635611593418;929.972100836976;935.8923723771717;999.8650182225452;9760.414375137514;11223.943880280547;11258.43707814606;11364.772704545863;11409.714757131138;458725;486366;490450;493256;496385;116049;122086;124065;126872.36563817307;127044.45911081726;913.7989642278689;918.1874041473211;935.1209862729149;936.4007035497287;938.0900526489295;18814.811851881306;18953.715694264873;19080.8091919079;19083.896962153205;19159.850408975508;54164.729176355446;54219;55101.72449137729;55241.618959526335;55416;76261.23738762764;76819.23180768345;77603.61198193973;78724;79431.20568794238;5522.6134170607675;6039.305479869871;6815.556988795713;7529.736459223901;8407.831843363221;11106.833397499206;12870.806937895752;12895.355232238539;13338.733225335245;13439.865601343861;11166.16253780986;11449.255798373079;11482.598109066143;11538.769224615293;11618.825717614074;10840.5121769519;11074.667759967213;11084.722881928017;11560.94219528897;11657.18399712012;11055.834162487408;11290.887091129312;11543.018843398406;11618.302901825917;11749.353785541878;7254;7438.218987006503;7494.475386723095;7602.619869006417;7636.809079772828;9962.452065136487;10878.619248326588;11342.432878356214;11524.94237528807;11553.24903881245 10:33:22 INFO - PROCESS | 1656 | |3;mootools.html;1352.114365090866;1378.241966918195;1513.7199618070654;1711.2898147268884;1917.5014496230986;7815.843683126334;8700.521471319084;8919.108089191093;8922.598483068261;9052.140046695553;8785.428947118433;8866.202041816252;8870.911290887105;8885.378023538355;8890.688825891095;2007.8293345065688;2407.181558270188;2408.1932552594903;2409.9397515062087;2416.8429052111646;2355.858648481088;2394.0663141374876;2405.6151015837468;2408.9036438542526;2410.2889647553925;2365.9408514787096;2387.1525608409024;2397.4126339046957;2399.9880000599974;2408.554417432772;2349.917752878641;2373.1338061607476;2375.5367703297866;2376.9778995032043;2391.844530105555;2341.461463863304;2362.4802543440414;2366.3847399676038;2373.133806160756;2373.1456675596773;7323.414126869838;8728.912710872937;8918.420302680304;8929.598168082452;8929.821403571892;5095.668781529226;5286.762095705664;5287.312649355597;5290.65610735301;5300.549453296436;3463.688268055887;3504.56192975878;3692.907677308062;3698.001539584297;3710.109573702319;1040.4693606260785;1080.0441609175857;1081.7566047639298;1094.7099018760034;1098.2092893116942;11678.299302041905;11717.355545444998;11803.111502159612;11807.40962951849;11811.94094029533;4384.583464570861;4476.127155204704;4634.443866735945;4654.930176047362;4655.74393408359;9125.904891413073;9148;9150.954245228731;9153.725388238363;9163.450192988443;5244;5249.265102885599;5252.317198764213;5254.133068043807;5259.552938000236;3898.863539776094;4415.470143582726;4781.760911954389;4878.268259761065;4897.632677549198;3203.8878639247514;3658.762180458262;3773.471713960048;3778.7165962552917;3800.638939300762;1033.994830025845;1124.8638874736575;1133.0708818768696;1139.9601013964636;1141.0814294492848;800.203797221768;856.5032281276845;885.6493846883426;933.4026223217146;936.5317341329335;4670.8365207217585;5880.088586269135;6022.096685497123;6074.635521868702;6233.844153896098;932.4032619123766;935.7426707655313;945.645382981382;959.0888655776901;986.4278718343487;3741.7380783344906;3832.559255685576;3869.6323849234277;3878.1468077023574;3994.8601798936897;10551.103156231806;10813.62152324665;10927.508262128224;10931.398773067558;10939.07017903487;10879.12966962641;10923.836142457869;10949.178811589163;10955.616553420752;10963.780724385468;7755.526449974487;8122.593870306343;8129.79675508117;8151.918480815235;8292.917070829215;5819.679917604572;5883.058710606282;5899.498542623754;5965.463108320272;6003.159557662018;9850.753731156778;9857.309988301036;10096.899031009596;10161.237907156845;10161.79676406453;10019.298649094493;10291.382517049002;10404.739881503023;10450.42522661261;10457.16342692598;6166.876662466815;7461.030066091374;7568.4323675723435;7572.621368931642;7574.431917606092;8154.959225203836;8254.54599997006;8255.298299644597;8260.421770476009;8268.545230012407;7335.376492997942;7429.628518573942;7583.203763604742;7627.313541781266;7682.884756728766;7230.530015548972;7314.561126332437;7320.597367144858;7352.48532602734;7362.7423040193335;6843.315668433117;6978.302169782981;6993.2657071006815;6996.650167491707;6996.720131194696;4254;4995.900081998412;5032.521910418504;5056.191009438472;5081.949180508148;4897.314375987294;5007.549320561167;5014.373203349582;5149.588032957429;5156.510131537498 10:33:22 INFO - PROCESS | 1656 | |4;prototype.html;280.4559155238837;291.96934321896214;311.5995945210404;314.7749359208166;315.6322883840326;5171.301874246976;5270.341207349082;5293.788248470057;5334.386545547263;5362.426035502961;4464.508904020555;4787.521247875211;5223.164293713006;5275.683458992463;5324;4041.919161616766;4052.6018523609455;4066.6949978751636;4076.2255171517318;4078.5105787305565;3897.142628621699;4079.3473044312914;4079.4905884822656;4110.321796903508;4115.176964607075;3303.7687361884678;3700.777953322809;3901.4189693801345;4010.3382941814566;4010.6390424861756;3344.665533446648;4011.7392369495888;4017.8995525111814;4031.21391328691;4038.9192216155807;3504.211552400715;3509.543759311299;3511.1397707561555;3516.5252690886914;3533.699635530983;4113.382992551141;4987.777994391397;5010.448850626428;5039.370078740158;5104.4640312767;4010.4786377770993;4592.242280023792;4596.126735920165;4614.838480653195;4629.166749985001;4880.072786170616;5307.2569840222395;5521.4478552144865;5565.2486914266865;5587.413321601214;3685.5761587417524;3701.1857391373856;3836.271108489378;3866.1881004988986;3889.591592882735;4808.783604737795;4877.488093631782;5577.8605534861545;5742.7415766290615;5919.247549889279;3817.9427308590393;3917.9608203918165;3931.1941052084253;3944.2703099926607;3978.5424676162033;3518.5601799775027;3824.7513911595656;3964.5837187095517;3970.7617542947514;3997.6402123808416;3710.9980305317413;4152.4601801765575;4567.269236922077;4569.314602809539;4588.105319462739;4603.976980115078;4834.057358814997;5047.470015648378;5484.753186106555;5509.256250406224;1183.810590305547;1369.945202191921;1372.9588112356646;1383.5918404070824;1394.8884089272833;790.2808444315646;832.6627715775121;840.2521755637488;846.196113691995;849.0702680564772;3874.85691720943;4037.6567991720444;4324.356744437094;4356.194104090753;4421;4302.827886884552;4557.994482427721;4690.859274221779;4737.573618374363;4791.658335666019;4016.136530645925;4126.649234815014;4305.612494875413;4326.2528294533195;4392.626626736763;2054.719261619601;2209.9668504972437;2355.258945502;2532.341591186268;2532.3919311237496;4914.483979182134;4926.852194434173;4937.506249375034;4939.678920870132;4967.205247160509;4170.395292682544;4875.731834749123;4906.386701662293;4914.771307173207;4935.975320123376;4477.6417886569;4915.591360233531;5199;6090.482309003784;6139.877202456015;5833.82498525045;5836.474717275466;5867.882642347215;5870.796486720126;5874.618149820248;5568.303962004749;5772.422757724194;5797.536197104306;5798.59409841307;5852.648841069549;5728;5880.647161170344;5936.762529498772;5995.310539288038;6026.939730602813;3833.6933045355745;3870.283997460479;3874.5156855393075;3886.222755448865;3895.357266051183;5322.813701520428;5382.407935127211;5814;5821.446962538552;5852.356240813422;3839.404892241707;4779.378680771617;5637.802676906289;5646.2942132233475;5796.507296879812;5626;5627.227423361628;5639.746211420414;5646.35066967304;5671;4651.906961860676;5066.834628035499;5483.024807014882;5525.613207075467;5634.380218176079;5290.338707661543;5576.800987787645;5635.9436405635415;5648.068068768772;5674.375818660027;4644.419447569054;5504.559635229252;5517.10352065169;5518.91721624184;5537.2524709163745 10:33:22 INFO - PROCESS | 1656 | |5;yui.html;707.8301207710149;724.5000949344951;764.2548515197683;799.5083023940279;815.7634286057046;15001.474948376808;15943.840561594381;15960.04239745616;16055.91972040141;16066.27701753421;14370.281485925689;14497.92751036246;14535.273236338193;14546.054506457072;14663.706725865502;685.1538350137577;702.718912435025;836.9288610468118;847.5296210603112;858.1975852577859;828.3787159630277;840.1304649687575;841.3689732700475;842.759813453166;843.569779412501;893.6559424621503;894.1818236313765;895.154079394972;897.443584977315;897.6992707442981;724.4675163754636;745.1691364129011;746.8394295226552;892.2683399612288;892.5849479991803;550.0291984647101;655.4821690864212;669.0065253099144;673.6968364236113;676.5061505101254;14194.077384969944;14212.71574568503;14244.92877535616;14253;14267.714645707028;13292.933535332262;13442.663933401744;13549.187048777105;13728;13970.580882573538;11964.70088247792;12347.567835125818;12350.135490515668;12376.319302438362;12876.935615321863;4448.612227481585;4471.083427897306;4584.426946631671;4618.722876627413;4635.675502714811;106475.40286895703;106693;107666.46166769194;108451.99999999921;111813.44093279562;10647.893521064845;10734.78530429387;10760.847830433788;10784.406857622906;11237.202047672497;20996.790032099485;21113.577728445343;21122;21171.15315387398;21426.250081247083;7787;7868.291853733192;7870.803229919184;7882.842343153219;7889.684412623431;4266.573342665771;4311;4367.082912588328;4367.759773212504;4583.633309335245;3475.4265546184597;3628.8185590720886;3629.6007439181667;3631.5823680276576;3640.7633503822167;663.2704025571833;672.4855485553555;674.1539368093012;682.4506272450725;689.9034135221073;507.1074908161662;515.0882937201208;605.5246631394423;620.2494981072953;634.9174607301113;7754.457187996899;8826.470411775314;8944.552772361356;9001.369904106781;9198.796882200513;504.4325134223998;554.837426667986;570.6148349863909;620.3517324396017;634.1027446163627;4372.256716358227;4379.255526560492;4431.578999995059;4444.933326000112;4590.839320623762;150258.24870875577;150627;151436.2428187852;152250.47749522363;154569;106125;106766;109119;129805.70194298125;137927;72217;79978;87355;87412;103984;9429.764255893657;9666.081722236377;9742.853857192289;9943;9953.452560109263;52166.21750673667;53684.73157634187;59398;59760.70119649374;60875.78248435095;70566;83101.0278766552;90461;90719.54640226757;92664.07335926824;10064.69805905824;10251.48742562854;12972.887063369519;13311.33443327849;13371;14293.285335732964;14346.426142954582;14606.85393146055;14802;15306.540803775904;13531.29260131367;13532.86467135316;13754.587362379145;13851.307434628026;13981.650458738613;11942.641720748392;12229;12572.559960401708;13818.792718109034;14110.223937683526;11714.414279285831;13795.241261730702;14067.648308792363;14090.718185636435;14254.073485223427;11162.665120046411;11394.601188958346;11405.20163588574;11518;12028.09789265791;9068.50123243228;9832.459214743258;11223.214374993999;11585.768284634429;11638.825417618573 10:33:22 INFO - PROCESS | 1656 | __end_tp_report 10:33:22 INFO - PROCESS | 1656 | __start_cc_report 10:33:22 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1462 10:33:22 INFO - PROCESS | 1656 | __end_cc_report 10:33:22 INFO - PROCESS | 1656 | __startTimestamp1473269602284__endTimestamp 10:33:22 INFO - PROCESS | 1656 | 10:33:22 INFO - PROCESS | 1656 | ------- Summary: start ------- 10:33:22 INFO - PROCESS | 1656 | Number of tests: 6 10:33:22 INFO - PROCESS | 1656 | 10:33:22 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5731.30 Median:5904.11 stddev:649.99 (11.0%) stddev-sans-first:609.20 10:33:22 INFO - PROCESS | 1656 | Values: 2774.6 3288.6 3346.9 3378.6 3415.8 5557.5 5625.3 5638.5 5700.7 5702.4 5210.2 5286.2 5651.3 5699.7 5703.3 5229.8 5243.2 5272.0 5283.9 5299.4 5291.6 5291.7 5312.5 5343.8 5345.7 5164.6 5315.7 5386.5 5388.6 5395.3 5299.0 5319.3 5326.3 5338.1 5368.1 5137.4 5153.2 5186.0 5208.0 5219.4 5621.0 5634.1 5652.1 5668.4 5800.2 6265.8 6265.8 6280.5 6281.3 6294.7 5786.3 6099.2 6158.6 6246.2 6309.8 5792.4 5799.7 5804.4 5818.4 5825.1 6278.1 6290.7 6293.6 6297.9 6308.2 6005.8 6034.8 6072.1 6120.7 6183.1 5277.3 6283.3 6314.5 6318.1 6318.4 6207.5 6222.8 6252.7 6284.4 6284.6 6102.8 6117.7 6139.6 6252.2 6254.7 4945.6 4947.0 4958.7 4962.7 4973.1 4399.3 4408.4 4413.1 4415.1 4447.2 5569.1 5616.4 5666.6 5676.9 5756.3 6195.0 6246.9 6274.3 6349.8 6357.1 5702.8 5706.6 5713.2 5724.1 5781.0 5331.4 5405.7 5410.3 5468.9 5482.4 6264.9 6270.8 6274.7 6299.4 6352.3 6300.0 6336.0 6342.7 6344.1 6347.3 6326.5 6326.7 6331.4 6336.5 6351.6 6012.6 6114.0 6122.7 6129.3 6301.7 4872.8 6062.8 6267.6 6311.6 6316.4 6141.4 6147.2 6157.5 6158.3 6159.2 5899.8 5963.6 5964.9 5985.6 6132.7 5900.9 5905.6 5910.5 5914.1 5929.0 5967.2 6087.5 6099.3 6099.3 6099.9 5815.9 5854.8 5904.1 5912.3 5913.2 10:33:22 INFO - PROCESS | 1656 | 10:33:22 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:62072.30 Median:39044.83 stddev:131119.13 (335.8%) stddev-sans-first:131405.44 10:33:22 INFO - PROCESS | 1656 | Values: 494.7 524.3 538.3 551.0 554.1 29712.6 31159.2 38986.0 39688.8 39796.4 40071.0 40282.0 40396.0 40425.4 40432.8 9727.4 9893.3 9893.7 9896.6 9906.5 9788.9 9892.2 9894.7 9903.2 9905.8 9854.0 9864.1 9929.5 9932.6 9934.0 9776.8 9828.6 9845.4 9846.8 9855.3 9720.9 9765.7 9808.0 9821.0 9823.8 38765.4 39028.8 39060.8 39140.4 39292.8 3473.9 3634.5 3788.6 3854.6 4037.9 29161.1 29777.9 29805.0 29979.0 30317.4 3186.7 3207.2 3211.4 3215.2 3313.6 422811.0 424284.0 425377.0 427470.0 429148.0 54654.2 55136.0 55137.4 55184.7 55249.7 32818.5 33673.3 56701.3 56840.0 56921.4 15479.0 15954.4 15956.4 15964.1 15992.1 12177.3 12301.9 12312.5 12318.0 12368.3 17858.6 17925.0 18068.6 18130.9 18163.0 7630.5 7768.8 7810.4 7848.6 7966.6 831.5 961.5 963.5 966.1 1059.7 28624.4 29462.0 29488.3 30134.0 30152.1 993.4 1006.7 1050.4 1061.2 1101.9 15158.5 15237.0 15370.7 15570.7 15707.5 581019.0 581323.2 582500.0 582703.1 584330.0 54587.0 55301.4 55302.4 55325.4 55582.7 1221.7 1254.2 1331.4 1339.1 1361.9 26070.7 26947.0 28551.0 30734.4 31023.7 46720.5 46941.1 47099.0 47121.3 47132.1 451011.0 451165.0 452752.0 455131.0 460453.0 22783.4 25282.5 25335.0 25717.4 25750.0 42151.6 44735.3 44776.6 45066.0 45967.0 36225.1 36387.6 36748.3 37231.0 37369.8 36700.4 36975.4 37213.9 37446.4 37838.0 35315.6 35541.8 35713.0 36208.5 36465.0 27762.6 30616.2 33863.5 33986.3 34079.8 20702.8 21733.8 24334.3 24830.1 25348.5 10:33:22 INFO - PROCESS | 1656 | 10:33:22 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:31155.25 Median:338.10 stddev:81883.85 (24219.2%) stddev-sans-first:82080.84 10:33:22 INFO - PROCESS | 1656 | Values: 327.5 348.7 367.8 372.3 400.0 14871.6 16773.2 19758.0 19897.8 19908.6 11768.8 16015.8 18642.2 19232.0 19450.7 5255.3 5476.1 5480.1 5483.6 5486.2 5239.2 5401.5 5406.5 5448.8 5476.8 5451.2 5454.4 5464.4 5464.9 5470.0 4685.2 5233.2 5394.4 5457.5 5468.0 4524.5 4927.3 4933.5 5305.3 5308.0 14112.6 24304.9 24398.0 24444.9 24710.6 2176.4 2302.4 2322.2 2337.4 2608.3 9791.1 10337.2 11369.7 13089.5 14355.6 1824.6 1825.9 1855.1 1873.5 1899.2 72915.5 113921.0 115012.0 115072.0 116505.0 9586.2 10799.8 11151.7 11584.7 11666.5 14354.4 14364.6 14374.6 14387.0 14405.1 8082.0 8330.0 8352.2 8474.5 8494.0 6763.5 8194.8 8222.8 8263.8 8400.5 4376.9 4476.9 4481.2 4522.1 4587.5 1844.4 1895.5 1896.5 1897.7 1899.6 837.2 855.3 890.0 890.5 931.9 18712.2 20337.0 20490.4 21313.9 21348.6 807.0 919.2 930.0 935.9 999.9 9760.4 11223.9 11258.4 11364.8 11409.7 458725.0 486366.0 490450.0 493256.0 496385.0 116049.0 122086.0 124065.0 126872.4 127044.5 913.8 918.2 935.1 936.4 938.1 18814.8 18953.7 19080.8 19083.9 19159.9 54164.7 54219.0 55101.7 55241.6 55416.0 76261.2 76819.2 77603.6 78724.0 79431.2 5522.6 6039.3 6815.6 7529.7 8407.8 11106.8 12870.8 12895.4 13338.7 13439.9 11166.2 11449.3 11482.6 11538.8 11618.8 10840.5 11074.7 11084.7 11560.9 11657.2 11055.8 11290.9 11543.0 11618.3 11749.4 7254.0 7438.2 7494.5 7602.6 7636.8 9962.5 10878.6 11342.4 11524.9 11553.2 10:33:22 INFO - PROCESS | 1656 | 10:33:22 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5677.22 Median:4946.77 stddev:3215.54 (65.0%) stddev-sans-first:3208.13 10:33:22 INFO - PROCESS | 1656 | Values: 1352.1 1378.2 1513.7 1711.3 1917.5 7815.8 8700.5 8919.1 8922.6 9052.1 8785.4 8866.2 8870.9 8885.4 8890.7 2007.8 2407.2 2408.2 2409.9 2416.8 2355.9 2394.1 2405.6 2408.9 2410.3 2365.9 2387.2 2397.4 2400.0 2408.6 2349.9 2373.1 2375.5 2377.0 2391.8 2341.5 2362.5 2366.4 2373.1 2373.1 7323.4 8728.9 8918.4 8929.6 8929.8 5095.7 5286.8 5287.3 5290.7 5300.5 3463.7 3504.6 3692.9 3698.0 3710.1 1040.5 1080.0 1081.8 1094.7 1098.2 11678.3 11717.4 11803.1 11807.4 11811.9 4384.6 4476.1 4634.4 4654.9 4655.7 9125.9 9148.0 9151.0 9153.7 9163.5 5244.0 5249.3 5252.3 5254.1 5259.6 3898.9 4415.5 4781.8 4878.3 4897.6 3203.9 3658.8 3773.5 3778.7 3800.6 1034.0 1124.9 1133.1 1140.0 1141.1 800.2 856.5 885.6 933.4 936.5 4670.8 5880.1 6022.1 6074.6 6233.8 932.4 935.7 945.6 959.1 986.4 3741.7 3832.6 3869.6 3878.1 3994.9 10551.1 10813.6 10927.5 10931.4 10939.1 10879.1 10923.8 10949.2 10955.6 10963.8 7755.5 8122.6 8129.8 8151.9 8292.9 5819.7 5883.1 5899.5 5965.5 6003.2 9850.8 9857.3 10096.9 10161.2 10161.8 10019.3 10291.4 10404.7 10450.4 10457.2 6166.9 7461.0 7568.4 7572.6 7574.4 8155.0 8254.5 8255.3 8260.4 8268.5 7335.4 7429.6 7583.2 7627.3 7682.9 7230.5 7314.6 7320.6 7352.5 7362.7 6843.3 6978.3 6993.3 6996.7 6996.7 4254.0 4995.9 5032.5 5056.2 5081.9 4897.3 5007.5 5014.4 5149.6 5156.5 10:33:22 INFO - PROCESS | 1656 | 10:33:22 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4378.33 Median:4714.22 stddev:1364.60 (28.9%) stddev-sans-first:1333.32 10:33:22 INFO - PROCESS | 1656 | Values: 280.5 292.0 311.6 314.8 315.6 5171.3 5270.3 5293.8 5334.4 5362.4 4464.5 4787.5 5223.2 5275.7 5324.0 4041.9 4052.6 4066.7 4076.2 4078.5 3897.1 4079.3 4079.5 4110.3 4115.2 3303.8 3700.8 3901.4 4010.3 4010.6 3344.7 4011.7 4017.9 4031.2 4038.9 3504.2 3509.5 3511.1 3516.5 3533.7 4113.4 4987.8 5010.4 5039.4 5104.5 4010.5 4592.2 4596.1 4614.8 4629.2 4880.1 5307.3 5521.4 5565.2 5587.4 3685.6 3701.2 3836.3 3866.2 3889.6 4808.8 4877.5 5577.9 5742.7 5919.2 3817.9 3918.0 3931.2 3944.3 3978.5 3518.6 3824.8 3964.6 3970.8 3997.6 3711.0 4152.5 4567.3 4569.3 4588.1 4604.0 4834.1 5047.5 5484.8 5509.3 1183.8 1369.9 1373.0 1383.6 1394.9 790.3 832.7 840.3 846.2 849.1 3874.9 4037.7 4324.4 4356.2 4421.0 4302.8 4558.0 4690.9 4737.6 4791.7 4016.1 4126.6 4305.6 4326.3 4392.6 2054.7 2210.0 2355.3 2532.3 2532.4 4914.5 4926.9 4937.5 4939.7 4967.2 4170.4 4875.7 4906.4 4914.8 4936.0 4477.6 4915.6 5199.0 6090.5 6139.9 5833.8 5836.5 5867.9 5870.8 5874.6 5568.3 5772.4 5797.5 5798.6 5852.6 5728.0 5880.6 5936.8 5995.3 6026.9 3833.7 3870.3 3874.5 3886.2 3895.4 5322.8 5382.4 5814.0 5821.4 5852.4 3839.4 4779.4 5637.8 5646.3 5796.5 5626.0 5627.2 5639.7 5646.4 5671.0 4651.9 5066.8 5483.0 5525.6 5634.4 5290.3 5576.8 5635.9 5648.1 5674.4 4644.4 5504.6 5517.1 5518.9 5537.3 10:33:22 INFO - PROCESS | 1656 | 10:33:22 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:23566.09 Median:4339.04 stddev:37406.15 (862.1%) stddev-sans-first:37471.71 10:33:22 INFO - PROCESS | 1656 | Values: 707.8 724.5 764.3 799.5 815.8 15001.5 15943.8 15960.0 16055.9 16066.3 14370.3 14497.9 14535.3 14546.1 14663.7 685.2 702.7 836.9 847.5 858.2 828.4 840.1 841.4 842.8 843.6 893.7 894.2 895.2 897.4 897.7 724.5 745.2 746.8 892.3 892.6 550.0 655.5 669.0 673.7 676.5 14194.1 14212.7 14244.9 14253.0 14267.7 13292.9 13442.7 13549.2 13728.0 13970.6 11964.7 12347.6 12350.1 12376.3 12876.9 4448.6 4471.1 4584.4 4618.7 4635.7 106475.4 106693.0 107666.5 108452.0 111813.4 10647.9 10734.8 10760.8 10784.4 11237.2 20996.8 21113.6 21122.0 21171.2 21426.3 7787.0 7868.3 7870.8 7882.8 7889.7 4266.6 4311.0 4367.1 4367.8 4583.6 3475.4 3628.8 3629.6 3631.6 3640.8 663.3 672.5 674.2 682.5 689.9 507.1 515.1 605.5 620.2 634.9 7754.5 8826.5 8944.6 9001.4 9198.8 504.4 554.8 570.6 620.4 634.1 4372.3 4379.3 4431.6 4444.9 4590.8 150258.2 150627.0 151436.2 152250.5 154569.0 106125.0 106766.0 109119.0 129805.7 137927.0 72217.0 79978.0 87355.0 87412.0 103984.0 9429.8 9666.1 9742.9 9943.0 9953.5 52166.2 53684.7 59398.0 59760.7 60875.8 70566.0 83101.0 90461.0 90719.5 92664.1 10064.7 10251.5 12972.9 13311.3 13371.0 14293.3 14346.4 14606.9 14802.0 15306.5 13531.3 13532.9 13754.6 13851.3 13981.7 11942.6 12229.0 12572.6 13818.8 14110.2 11714.4 13795.2 14067.6 14090.7 14254.1 11162.7 11394.6 11405.2 11518.0 12028.1 9068.5 9832.5 11223.2 11585.8 11638.8 10:33:22 INFO - PROCESS | 1656 | -------- Summary: end -------- 10:33:22 INFO - PROCESS | 1656 | 10:33:22 INFO - TEST-INFO | 1656: exit 0 10:33:22 INFO - TEST-OK | dromaeo_css | took 1117712ms 10:33:22 INFO - TEST-START | kraken 10:33:22 INFO - operating with platform_type : mac_ 10:33:22 INFO - Initialising browser for kraken test... 10:33:22 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmp8UaEwP/profile http://localhost:49271/getInfo.html) 10:33:25 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 10:33:25 INFO - PROCESS | 1662 | colorDepth:24 10:33:25 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 10:33:25 INFO - PROCESS | 1662 | __metrics 10:33:25 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:33:25 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:33:26 INFO - TEST-INFO | 1662: exit 0 10:33:26 INFO - Browser initialized. 10:33:26 INFO - Running cycle 1/1 for kraken test... 10:33:26 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmp8UaEwP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:33:38 INFO - PROCESS | 1663 | RSS: Main: 232480768 10:33:38 INFO - PROCESS | 1663 | 10:33:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 10:33:41 INFO - PROCESS | 1663 | RSS: Main: 292081664 10:33:41 INFO - PROCESS | 1663 | 10:33:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 10:33:44 INFO - PROCESS | 1663 | RSS: Main: 287330304 10:33:44 INFO - PROCESS | 1663 | 10:33:48 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 10:33:49 INFO - PROCESS | 1663 | RSS: Main: 371810304 10:33:49 INFO - PROCESS | 1663 | 10:33:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 10:33:51 INFO - PROCESS | 1663 | RSS: Main: 276500480 10:33:51 INFO - PROCESS | 1663 | 10:33:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:33:54 INFO - PROCESS | 1663 | RSS: Main: 282468352 10:33:54 INFO - PROCESS | 1663 | 10:33:57 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 10:33:57 INFO - PROCESS | 1663 | RSS: Main: 321175552 10:33:57 INFO - PROCESS | 1663 | 10:34:00 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 10:34:01 INFO - PROCESS | 1663 | RSS: Main: 322400256 10:34:01 INFO - PROCESS | 1663 | 10:34:04 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 10:34:04 INFO - PROCESS | 1663 | RSS: Main: 320507904 10:34:04 INFO - PROCESS | 1663 | 10:34:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:34:06 INFO - PROCESS | 1663 | RSS: Main: 297496576 10:34:06 INFO - PROCESS | 1663 | 10:34:08 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:34:09 INFO - PROCESS | 1663 | RSS: Main: 304279552 10:34:09 INFO - PROCESS | 1663 | 10:34:11 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:34:12 INFO - PROCESS | 1663 | RSS: Main: 305930240 10:34:12 INFO - PROCESS | 1663 | 10:34:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:34:15 INFO - PROCESS | 1663 | RSS: Main: 318337024 10:34:15 INFO - PROCESS | 1663 | 10:34:18 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:34:19 INFO - PROCESS | 1663 | RSS: Main: 314454016 10:34:19 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 10:34:21 INFO - PROCESS | 1663 | RSS: Main: 358076416 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | __start_tp_report 10:34:21 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 10:34:21 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 10:34:21 INFO - PROCESS | 1663 | |i|pagename|runs| 10:34:21 INFO - PROCESS | 1663 | |0;ai-astar;113;113;117;113;114;113;112;113;112;123 10:34:21 INFO - PROCESS | 1663 | |1;audio-beat-detection;149;109;133;161;107;130;127;109;159;110 10:34:21 INFO - PROCESS | 1663 | |2;audio-dft;185;184;183;182;184;183;185;185;183;183 10:34:21 INFO - PROCESS | 1663 | |3;audio-fft;76;77;98;90;57;63;62;62;160;81 10:34:21 INFO - PROCESS | 1663 | |4;audio-oscillator;82;84;132;68;70;102;144;99;79;111 10:34:21 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;86;83;86;86;132;84;86;84;86;84 10:34:21 INFO - PROCESS | 1663 | |6;imaging-darkroom;93;93;94;94;94;93;95;93;94;105 10:34:21 INFO - PROCESS | 1663 | |7;imaging-desaturate;95;116;86;84;84;83;86;84;83;85 10:34:21 INFO - PROCESS | 1663 | |8;json-parse-financial;54;55;56;55;54;54;55;54;53;55 10:34:21 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;68;73;77;74;75;72;69;72;69;70 10:34:21 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;86;87;88;84;88;82;106;84;90;86 10:34:21 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;141;139;137;137;139;136;136;135;177;170 10:34:21 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;168;170;166;169;169;168;165;221;182;169 10:34:21 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;67;68;70;83;87;68;70;67;64;66 10:34:21 INFO - PROCESS | 1663 | __end_tp_report 10:34:21 INFO - PROCESS | 1663 | __start_cc_report 10:34:21 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2096 10:34:21 INFO - PROCESS | 1663 | __end_cc_report 10:34:21 INFO - PROCESS | 1663 | __startTimestamp1473269661462__endTimestamp 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | ------- Summary: start ------- 10:34:21 INFO - PROCESS | 1663 | Number of tests: 14 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:114.30 Median:113.00 stddev:3.37 (3.0%) stddev-sans-first:3.54 10:34:21 INFO - PROCESS | 1663 | Values: 113.0 113.0 117.0 113.0 114.0 113.0 112.0 113.0 112.0 123.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:131.50 stddev:21.05 (16.0%) stddev-sans-first:21.10 10:34:21 INFO - PROCESS | 1663 | Values: 149.0 109.0 133.0 161.0 107.0 130.0 127.0 109.0 159.0 110.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.06 (0.6%) stddev-sans-first:1.01 10:34:21 INFO - PROCESS | 1663 | Values: 185.0 184.0 183.0 182.0 184.0 183.0 185.0 185.0 183.0 183.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:82.60 Median:76.50 stddev:30.27 (39.6%) stddev-sans-first:32.02 10:34:21 INFO - PROCESS | 1663 | Values: 76.0 77.0 98.0 90.0 57.0 63.0 62.0 62.0 160.0 81.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:74.50 stddev:25.71 (34.5%) stddev-sans-first:26.68 10:34:21 INFO - PROCESS | 1663 | Values: 82.0 84.0 132.0 68.0 70.0 102.0 144.0 99.0 79.0 111.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:86.00 stddev:14.91 (17.3%) stddev-sans-first:15.75 10:34:21 INFO - PROCESS | 1663 | Values: 86.0 83.0 86.0 86.0 132.0 84.0 86.0 84.0 86.0 84.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:94.00 stddev:3.65 (3.9%) stddev-sans-first:3.81 10:34:21 INFO - PROCESS | 1663 | Values: 93.0 93.0 94.0 94.0 94.0 93.0 95.0 93.0 94.0 105.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:88.60 Median:84.50 stddev:10.24 (12.1%) stddev-sans-first:10.60 10:34:21 INFO - PROCESS | 1663 | Values: 95.0 116.0 86.0 84.0 84.0 83.0 86.0 84.0 83.0 85.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:0.85 (1.5%) stddev-sans-first:0.88 10:34:21 INFO - PROCESS | 1663 | Values: 54.0 55.0 56.0 55.0 54.0 54.0 55.0 54.0 53.0 55.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.50 stddev:2.92 (4.0%) stddev-sans-first:2.74 10:34:21 INFO - PROCESS | 1663 | Values: 68.0 73.0 77.0 74.0 75.0 72.0 69.0 72.0 69.0 70.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:86.50 stddev:6.71 (7.8%) stddev-sans-first:7.07 10:34:21 INFO - PROCESS | 1663 | Values: 86.0 87.0 88.0 84.0 88.0 82.0 106.0 84.0 90.0 86.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:139.00 stddev:15.37 (11.1%) stddev-sans-first:16.24 10:34:21 INFO - PROCESS | 1663 | Values: 141.0 139.0 137.0 137.0 139.0 136.0 136.0 135.0 177.0 170.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:169.00 stddev:16.92 (10.0%) stddev-sans-first:17.77 10:34:21 INFO - PROCESS | 1663 | Values: 168.0 170.0 166.0 169.0 169.0 168.0 165.0 221.0 182.0 169.0 10:34:21 INFO - PROCESS | 1663 | 10:34:21 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:69.00 stddev:7.64 (11.1%) stddev-sans-first:7.97 10:34:21 INFO - PROCESS | 1663 | Values: 67.0 68.0 70.0 83.0 87.0 68.0 70.0 67.0 64.0 66.0 10:34:21 INFO - PROCESS | 1663 | -------- Summary: end -------- 10:34:21 INFO - PROCESS | 1663 | 10:34:22 INFO - TEST-INFO | 1663: exit 0 10:34:22 INFO - TEST-OK | kraken | took 59524ms 10:34:22 INFO - SUITE-END | took 1177s 10:34:22 INFO - Completed test suite (00:19:37) 10:34:22 INFO - javascript benchmark 10:34:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2774.569941659043, 3288.556044933934, 3346.9497957530625, 3378.628350881404, 3415.7779744316617, 5557.472040156189, 5625.296837895258, 5638.492535671789, 5700.6579605223715, 5702.429757024301, 5210.218467229917, 5286.154215325549, 5651.2653355063885, 5699.74351154198, 5703.31560212775, 5229.764660590273, 5243.161094224924, 5272.0, 5283.947160528384, 5299.390570084451, 5291.5766738661005, 5291.708956007418, 5312.521873031426, 5343.839684809443, 5345.732713364336, 5164.6384753067305, 5315.707636080014, 5386.48828361307, 5388.622796404254, 5395.325584301962, 5299.0, 5319.335083114611, 5326.2543243945875, 5338.065838478289, 5368.141097424394, 5137.38351397831, 5153.2270159476, 5186.0, 5207.98444303361, 5219.42586315505, 5621.0, 5634.126710359901, 5652.123920792284, 5668.433156684298, 5800.24596802413, 6265.780697675605, 6265.843353916143, 6280.528960327948, 6281.309056003836, 6294.7482100715915, 5786.334571524287, 6099.249254464524, 6158.630482171084, 6246.219222597176, 6309.842253943642, 5792.4497172768515, 5799.652020878761, 5804.448577385184, 5818.418158184148, 5825.097109947965, 6278.089676996901, 6290.685465726695, 6293.590916590407, 6297.874042519215, 6308.179936608211, 6005.819825405245, 6034.818955431344, 6072.149899014142, 6120.721420533169, 6183.134361189437, 5277.313949186581, 6283.3088360280335, 6314.494840412848, 6318.147050148263, 6318.368163183737, 6207.534434917399, 6222.844428889223, 6252.656103914237, 6284.402981716729, 6284.560080794392, 6102.816915492543, 6117.736059405486, 6139.584134579375, 6252.249730032425, 6254.749810007549, 4945.604351651931, 4946.950530494649, 4958.677685950402, 4962.6774259673, 4973.079980203674, 4399.274119770202, 4408.360787685768, 4413.117376524708, 4415.094905544356, 4447.221736196153, 5569.10894256917, 5616.354119276254, 5666.575006874501, 5676.858078548069, 5756.30924289088, 6194.969025154845, 6246.875562398812, 6274.341194174546, 6349.809505714836, 6357.11000459936, 5702.82603394824, 5706.629069110494, 5713.228714123623, 5724.112762521816, 5781.046127389018, 5331.386890507563, 5405.7272722762, 5410.296661433988, 5468.945310546923, 5482.424345443751, 6264.906026409699, 6270.7805226816845, 6274.6548939808745, 6299.370062993848, 6352.301246862749, 6299.96850015747, 6335.968320158369, 6342.714577843916, 6344.143540622142, 6347.270063942706, 6326.525510586633, 6326.746930122928, 6331.430171284606, 6336.493080553637, 6351.555391122577, 6012.579119461596, 6113.993245360445, 6122.6726173278175, 6129.297663865929, 6301.747930082745, 4872.780724867319, 6062.848428789139, 6267.62394256346, 6311.589746666452, 6316.399942005502, 6141.385861413823, 6147.200863887845, 6157.538184636081, 6158.3225845157895, 6159.168512250789, 5899.793507227227, 5963.642181469126, 5964.850878728066, 5985.610935289193, 6132.724027418688, 5900.911486327623, 5905.557083218861, 5910.527157827278, 5914.053751399755, 5929.0, 5967.224260846062, 6087.4521293083835, 6099.2985806632805, 6099.329073801967, 6099.932511810504, 5815.912761308499, 5854.795082172274, 5904.1143828424365, 5912.320083190488, 5913.172155898092], "value": 5688.788311954482, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.74520621879736, 524.3157679228607, 538.2760187547747, 550.9724364061025, 554.0940562180833, 29712.554311685315, 31159.221019474495, 38985.99999999996, 39688.80155599218, 39796.403053954215, 40071.0, 40282.0, 40396.0, 40425.39361909574, 40432.79783601078, 9727.416355018704, 9893.30746847721, 9893.703188904345, 9896.604135834557, 9906.504674766269, 9788.90211097887, 9892.230175550858, 9894.653687120952, 9903.158231550291, 9905.801883962316, 9853.950730246339, 9864.112229899307, 9929.503524823765, 9932.553035113437, 9933.950330248375, 9776.804463910717, 9828.606855725762, 9845.360051596623, 9846.803063938682, 9855.26085543587, 9720.854187187198, 9765.658201962897, 9808.0, 9821.0, 9823.803523929482, 38765.41851872224, 39028.82913512571, 39060.82817515479, 39140.41289380661, 39292.80353598214, 3473.878414255489, 3634.4548317752283, 3788.5643151037707, 3854.5759966403675, 4037.9192416151513, 29161.125166244834, 29777.851110744523, 29804.956826511185, 29979.0, 30317.393652126833, 3186.681331866818, 3207.150105222118, 3211.42194405007, 3215.1962009497624, 3313.6189338226172, 422811.0, 424284.0, 425377.0, 427470.0, 429148.0, 54654.18018729722, 55136.0, 55137.44862551323, 55184.72407637936, 55249.72375138099, 32818.50772238418, 33673.3265334692, 56701.345318382315, 56840.0, 56921.43078569161, 15479.0, 15954.361825527081, 15956.441524546584, 15964.12197329158, 15992.120433376276, 12177.269363838199, 12301.938490307492, 12312.507499699912, 12318.0, 12368.319742414255, 17858.642827143645, 17925.014124223293, 18068.63862722738, 18130.909345453187, 18163.0, 7630.465867389341, 7768.7669369918995, 7810.375169986388, 7848.60756962161, 7966.561839098905, 831.4886344897965, 961.4856052012279, 963.450833025183, 966.120830044656, 1059.692689120162, 28624.427511449656, 29462.0, 29488.262793429905, 30134.0, 30152.095437136923, 993.4436715439377, 1006.7231511334295, 1050.422267752733, 1061.1616822709973, 1101.8599475399456, 15158.469453569072, 15237.0, 15370.692586148214, 15570.688586228212, 15707.450239241572, 581019.0, 581323.1867681269, 582500.0, 582703.0864845733, 584330.0, 54587.0, 55301.446985530434, 55302.44697552973, 55325.446745532834, 55582.72208639012, 1221.6518292286655, 1254.2411840836346, 1331.4141777617817, 1339.1295657822297, 1361.86381361865, 26070.696465177043, 26947.0, 28551.0, 30734.366264674667, 31023.68976310208, 46720.53279467162, 46941.061178776916, 47099.0, 47121.293180601635, 47132.057358853315, 451011.0, 451165.0, 452752.0, 455131.0, 460453.0, 22783.430414239778, 25282.494350112527, 25335.0, 25717.3570660735, 25750.0, 42151.57848421476, 44735.328970066126, 44776.55223447724, 45066.0, 45967.0, 36225.09437263984, 36387.636123638425, 36748.26503469969, 37231.0, 37369.813150934075, 36700.44949325816, 36975.44536831896, 37213.88358349254, 37446.43830342602, 37838.0, 35315.64684353226, 35541.82229088838, 35713.0, 36208.45687314745, 36465.0, 27762.583561247, 30616.234594135323, 33863.492047619795, 33986.281097511644, 34079.82960085283, 20702.792972070085, 21733.782662173177, 24334.26997190087, 24830.130945416822, 25348.493030139663], "value": 19163.24448167437, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.4760383386581, 348.71405447533033, 367.827121253011, 372.32051506001545, 400.0379088292655, 14871.553853384403, 16773.24520396582, 19758.0, 19897.801021989777, 19908.60182796345, 11768.764624707523, 16015.798815088885, 18642.161102750375, 19232.038398080076, 19450.708239376385, 5255.290535777669, 5476.123820188771, 5480.123180291154, 5483.588730845183, 5486.231927530149, 5239.240310155025, 5401.540869026137, 5406.459354064601, 5448.83653490396, 5476.808311709091, 5451.236826844245, 5454.427285135063, 5464.4262352452815, 5464.863378415532, 5470.0, 4685.2269375553, 5233.241180028894, 5394.406615272356, 5457.536109430703, 5467.9610873934225, 4524.45706515217, 4927.285543596195, 4933.53131452515, 5305.32882142124, 5307.973460132714, 14112.576622701336, 24304.878475607686, 24398.0, 24444.877775611185, 24710.629340559906, 2176.3688530326185, 2302.412884714404, 2322.222055611372, 2337.3805941425535, 2608.3479130217447, 9791.118799308026, 10337.22470814692, 11369.658910232707, 13089.47642094315, 14355.641108972255, 1824.6426575101743, 1825.8721889467743, 1855.072463768116, 1873.4566975577065, 1899.164367678217, 72915.54168916593, 113921.0, 115012.0, 115072.0, 116504.99999999916, 9586.185174260127, 10799.78400431987, 11151.665450036511, 11584.710382240512, 11666.475008624462, 14354.425822967174, 14364.569062928129, 14374.568762937128, 14387.0, 14405.06367086157, 8081.959590202129, 8330.0, 8352.248297653241, 8474.5339006354, 8493.957530212308, 6763.5265531413315, 8194.836103277901, 8222.794430139174, 8263.834723305501, 8400.495970241684, 4376.934345984812, 4476.932846007313, 4481.215787237221, 4522.118186953512, 4587.472440669299, 1844.3913508542148, 1895.4503194073563, 1896.4689886831434, 1897.6868816645099, 1899.6390685769936, 837.24229572236, 855.3200205836375, 889.9765269939492, 890.4835195586544, 931.8788557487619, 18712.157952892154, 20337.0, 20490.38528844137, 21313.89343053275, 21348.573028539653, 806.983860322802, 919.1635611593418, 929.972100836976, 935.8923723771717, 999.8650182225452, 9760.414375137514, 11223.943880280547, 11258.43707814606, 11364.772704545863, 11409.714757131138, 458725.0, 486366.0, 490450.0, 493256.0, 496385.0, 116049.0, 122086.0, 124065.0, 126872.36563817307, 127044.45911081726, 913.7989642278689, 918.1874041473211, 935.1209862729149, 936.4007035497287, 938.0900526489295, 18814.811851881306, 18953.715694264873, 19080.8091919079, 19083.896962153205, 19159.850408975508, 54164.729176355446, 54219.0, 55101.72449137729, 55241.618959526335, 55416.0, 76261.23738762764, 76819.23180768345, 77603.61198193973, 78724.0, 79431.20568794238, 5522.6134170607675, 6039.305479869871, 6815.556988795713, 7529.736459223901, 8407.831843363221, 11106.833397499206, 12870.806937895752, 12895.355232238539, 13338.733225335245, 13439.865601343861, 11166.16253780986, 11449.255798373079, 11482.598109066143, 11538.769224615293, 11618.825717614074, 10840.5121769519, 11074.667759967213, 11084.722881928017, 11560.94219528897, 11657.18399712012, 11055.834162487408, 11290.887091129312, 11543.018843398406, 11618.302901825917, 11749.353785541878, 7254.0, 7438.218987006503, 7494.475386723095, 7602.619869006417, 7636.809079772828, 9962.452065136487, 10878.619248326588, 11342.432878356214, 11524.94237528807, 11553.24903881245], "value": 9176.643617194504, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1352.114365090866, 1378.241966918195, 1513.7199618070654, 1711.2898147268884, 1917.5014496230986, 7815.843683126334, 8700.521471319084, 8919.108089191093, 8922.598483068261, 9052.140046695553, 8785.428947118433, 8866.202041816252, 8870.911290887105, 8885.378023538355, 8890.688825891095, 2007.8293345065688, 2407.181558270188, 2408.1932552594903, 2409.9397515062087, 2416.8429052111646, 2355.858648481088, 2394.0663141374876, 2405.6151015837468, 2408.9036438542526, 2410.2889647553925, 2365.9408514787096, 2387.1525608409024, 2397.4126339046957, 2399.9880000599974, 2408.554417432772, 2349.917752878641, 2373.1338061607476, 2375.5367703297866, 2376.9778995032043, 2391.844530105555, 2341.461463863304, 2362.4802543440414, 2366.3847399676038, 2373.133806160756, 2373.1456675596773, 7323.414126869838, 8728.912710872937, 8918.420302680304, 8929.598168082452, 8929.821403571892, 5095.668781529226, 5286.762095705664, 5287.312649355597, 5290.65610735301, 5300.549453296436, 3463.688268055887, 3504.56192975878, 3692.907677308062, 3698.001539584297, 3710.109573702319, 1040.4693606260785, 1080.0441609175857, 1081.7566047639298, 1094.7099018760034, 1098.2092893116942, 11678.299302041905, 11717.355545444998, 11803.111502159612, 11807.40962951849, 11811.94094029533, 4384.583464570861, 4476.127155204704, 4634.443866735945, 4654.930176047362, 4655.74393408359, 9125.904891413073, 9148.0, 9150.954245228731, 9153.725388238363, 9163.450192988443, 5244.0, 5249.265102885599, 5252.317198764213, 5254.133068043807, 5259.552938000236, 3898.863539776094, 4415.470143582726, 4781.760911954389, 4878.268259761065, 4897.632677549198, 3203.8878639247514, 3658.762180458262, 3773.471713960048, 3778.7165962552917, 3800.638939300762, 1033.994830025845, 1124.8638874736575, 1133.0708818768696, 1139.9601013964636, 1141.0814294492848, 800.203797221768, 856.5032281276845, 885.6493846883426, 933.4026223217146, 936.5317341329335, 4670.8365207217585, 5880.088586269135, 6022.096685497123, 6074.635521868702, 6233.844153896098, 932.4032619123766, 935.7426707655313, 945.645382981382, 959.0888655776901, 986.4278718343487, 3741.7380783344906, 3832.559255685576, 3869.6323849234277, 3878.1468077023574, 3994.8601798936897, 10551.103156231806, 10813.62152324665, 10927.508262128224, 10931.398773067558, 10939.07017903487, 10879.12966962641, 10923.836142457869, 10949.178811589163, 10955.616553420752, 10963.780724385468, 7755.526449974487, 8122.593870306343, 8129.79675508117, 8151.918480815235, 8292.917070829215, 5819.679917604572, 5883.058710606282, 5899.498542623754, 5965.463108320272, 6003.159557662018, 9850.753731156778, 9857.309988301036, 10096.899031009596, 10161.237907156845, 10161.79676406453, 10019.298649094493, 10291.382517049002, 10404.739881503023, 10450.42522661261, 10457.16342692598, 6166.876662466815, 7461.030066091374, 7568.4323675723435, 7572.621368931642, 7574.431917606092, 8154.959225203836, 8254.54599997006, 8255.298299644597, 8260.421770476009, 8268.545230012407, 7335.376492997942, 7429.628518573942, 7583.203763604742, 7627.313541781266, 7682.884756728766, 7230.530015548972, 7314.561126332437, 7320.597367144858, 7352.48532602734, 7362.7423040193335, 6843.315668433117, 6978.302169782981, 6993.2657071006815, 6996.650167491707, 6996.720131194696, 4254.0, 4995.900081998412, 5032.521910418504, 5056.191009438472, 5081.949180508148, 4897.314375987294, 5007.549320561167, 5014.373203349582, 5149.588032957429, 5156.510131537498], "value": 4546.740386327028, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.4559155238837, 291.96934321896214, 311.5995945210404, 314.7749359208166, 315.6322883840326, 5171.301874246976, 5270.341207349082, 5293.788248470057, 5334.386545547263, 5362.426035502961, 4464.508904020555, 4787.521247875211, 5223.164293713006, 5275.683458992463, 5324.0, 4041.919161616766, 4052.6018523609455, 4066.6949978751636, 4076.2255171517318, 4078.5105787305565, 3897.142628621699, 4079.3473044312914, 4079.4905884822656, 4110.321796903508, 4115.176964607075, 3303.7687361884678, 3700.777953322809, 3901.4189693801345, 4010.3382941814566, 4010.6390424861756, 3344.665533446648, 4011.7392369495888, 4017.8995525111814, 4031.21391328691, 4038.9192216155807, 3504.211552400715, 3509.543759311299, 3511.1397707561555, 3516.5252690886914, 3533.699635530983, 4113.382992551141, 4987.777994391397, 5010.448850626428, 5039.370078740158, 5104.4640312767, 4010.4786377770993, 4592.242280023792, 4596.126735920165, 4614.838480653195, 4629.166749985001, 4880.072786170616, 5307.2569840222395, 5521.4478552144865, 5565.2486914266865, 5587.413321601214, 3685.5761587417524, 3701.1857391373856, 3836.271108489378, 3866.1881004988986, 3889.591592882735, 4808.783604737795, 4877.488093631782, 5577.8605534861545, 5742.7415766290615, 5919.247549889279, 3817.9427308590393, 3917.9608203918165, 3931.1941052084253, 3944.2703099926607, 3978.5424676162033, 3518.5601799775027, 3824.7513911595656, 3964.5837187095517, 3970.7617542947514, 3997.6402123808416, 3710.9980305317413, 4152.4601801765575, 4567.269236922077, 4569.314602809539, 4588.105319462739, 4603.976980115078, 4834.057358814997, 5047.470015648378, 5484.753186106555, 5509.256250406224, 1183.810590305547, 1369.945202191921, 1372.9588112356646, 1383.5918404070824, 1394.8884089272833, 790.2808444315646, 832.6627715775121, 840.2521755637488, 846.196113691995, 849.0702680564772, 3874.85691720943, 4037.6567991720444, 4324.356744437094, 4356.194104090753, 4421.0, 4302.827886884552, 4557.994482427721, 4690.859274221779, 4737.573618374363, 4791.658335666019, 4016.136530645925, 4126.649234815014, 4305.612494875413, 4326.2528294533195, 4392.626626736763, 2054.719261619601, 2209.9668504972437, 2355.258945502, 2532.341591186268, 2532.3919311237496, 4914.483979182134, 4926.852194434173, 4937.506249375034, 4939.678920870132, 4967.205247160509, 4170.395292682544, 4875.731834749123, 4906.386701662293, 4914.771307173207, 4935.975320123376, 4477.6417886569, 4915.591360233531, 5199.0, 6090.482309003784, 6139.877202456015, 5833.82498525045, 5836.474717275466, 5867.882642347215, 5870.796486720126, 5874.618149820248, 5568.303962004749, 5772.422757724194, 5797.536197104306, 5798.59409841307, 5852.648841069549, 5728.0, 5880.647161170344, 5936.762529498772, 5995.310539288038, 6026.939730602813, 3833.6933045355745, 3870.283997460479, 3874.5156855393075, 3886.222755448865, 3895.357266051183, 5322.813701520428, 5382.407935127211, 5814.0, 5821.446962538552, 5852.356240813422, 3839.404892241707, 4779.378680771617, 5637.802676906289, 5646.2942132233475, 5796.507296879812, 5626.0, 5627.227423361628, 5639.746211420414, 5646.35066967304, 5671.0, 4651.906961860676, 5066.834628035499, 5483.024807014882, 5525.613207075467, 5634.380218176079, 5290.338707661543, 5576.800987787645, 5635.9436405635415, 5648.068068768772, 5674.375818660027, 4644.419447569054, 5504.559635229252, 5517.10352065169, 5518.91721624184, 5537.2524709163745], "value": 3946.878430108555, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.8301207710149, 724.5000949344951, 764.2548515197683, 799.5083023940279, 815.7634286057046, 15001.474948376808, 15943.840561594381, 15960.04239745616, 16055.91972040141, 16066.27701753421, 14370.281485925689, 14497.92751036246, 14535.273236338193, 14546.054506457072, 14663.706725865502, 685.1538350137577, 702.718912435025, 836.9288610468118, 847.5296210603112, 858.1975852577859, 828.3787159630277, 840.1304649687575, 841.3689732700475, 842.759813453166, 843.569779412501, 893.6559424621503, 894.1818236313765, 895.154079394972, 897.443584977315, 897.6992707442981, 724.4675163754636, 745.1691364129011, 746.8394295226552, 892.2683399612288, 892.5849479991803, 550.0291984647101, 655.4821690864212, 669.0065253099144, 673.6968364236113, 676.5061505101254, 14194.077384969944, 14212.71574568503, 14244.92877535616, 14253.0, 14267.714645707028, 13292.933535332262, 13442.663933401744, 13549.187048777105, 13728.0, 13970.580882573538, 11964.70088247792, 12347.567835125818, 12350.135490515668, 12376.319302438362, 12876.935615321863, 4448.612227481585, 4471.083427897306, 4584.426946631671, 4618.722876627413, 4635.675502714811, 106475.40286895703, 106693.0, 107666.46166769194, 108451.99999999921, 111813.44093279562, 10647.893521064845, 10734.78530429387, 10760.847830433788, 10784.406857622906, 11237.202047672497, 20996.790032099485, 21113.577728445343, 21122.0, 21171.15315387398, 21426.250081247083, 7787.0, 7868.291853733192, 7870.803229919184, 7882.842343153219, 7889.684412623431, 4266.573342665771, 4311.0, 4367.082912588328, 4367.759773212504, 4583.633309335245, 3475.4265546184597, 3628.8185590720886, 3629.6007439181667, 3631.5823680276576, 3640.7633503822167, 663.2704025571833, 672.4855485553555, 674.1539368093012, 682.4506272450725, 689.9034135221073, 507.1074908161662, 515.0882937201208, 605.5246631394423, 620.2494981072953, 634.9174607301113, 7754.457187996899, 8826.470411775314, 8944.552772361356, 9001.369904106781, 9198.796882200513, 504.4325134223998, 554.837426667986, 570.6148349863909, 620.3517324396017, 634.1027446163627, 4372.256716358227, 4379.255526560492, 4431.578999995059, 4444.933326000112, 4590.839320623762, 150258.24870875577, 150627.0, 151436.2428187852, 152250.47749522363, 154569.0, 106125.0, 106766.0, 109119.0, 129805.70194298125, 137927.0, 72217.0, 79978.0, 87355.0, 87412.0, 103984.0, 9429.764255893657, 9666.081722236377, 9742.853857192289, 9943.0, 9953.452560109263, 52166.21750673667, 53684.73157634187, 59398.0, 59760.70119649374, 60875.78248435095, 70566.0, 83101.0278766552, 90461.0, 90719.54640226757, 92664.07335926824, 10064.69805905824, 10251.48742562854, 12972.887063369519, 13311.33443327849, 13371.0, 14293.285335732964, 14346.426142954582, 14606.85393146055, 14802.0, 15306.540803775904, 13531.29260131367, 13532.86467135316, 13754.587362379145, 13851.307434628026, 13981.650458738613, 11942.641720748392, 12229.0, 12572.559960401708, 13818.792718109034, 14110.223937683526, 11714.414279285831, 13795.241261730702, 14067.648308792363, 14090.718185636435, 14254.073485223427, 11162.665120046411, 11394.601188958346, 11405.20163588574, 11518.0, 12028.09789265791, 9068.50123243228, 9832.459214743258, 11223.214374993999, 11585.768284634429, 11638.825417618573], "value": 7635.707794946274, "unit": "score"}], "name": "dromaeo_css", "value": 7180.7580226662185, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 117.0, 113.0, 114.0, 113.0, 112.0, 113.0, 112.0, 123.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 109.0, 133.0, 161.0, 107.0, 130.0, 127.0, 109.0, 159.0, 110.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 183.0, 182.0, 184.0, 183.0, 185.0, 185.0, 183.0, 183.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 98.0, 90.0, 57.0, 63.0, 62.0, 62.0, 160.0, 81.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 132.0, 68.0, 70.0, 102.0, 144.0, 99.0, 79.0, 111.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 86.0, 132.0, 84.0, 86.0, 84.0, 86.0, 84.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 94.0, 93.0, 95.0, 93.0, 94.0, 105.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 116.0, 86.0, 84.0, 84.0, 83.0, 86.0, 84.0, 83.0, 85.0], "value": 88.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 55.0, 54.0, 54.0, 55.0, 54.0, 53.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 77.0, 74.0, 75.0, 72.0, 69.0, 72.0, 69.0, 70.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 88.0, 84.0, 88.0, 82.0, 106.0, 84.0, 90.0, 86.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 137.0, 137.0, 139.0, 136.0, 136.0, 135.0, 177.0, 170.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 166.0, 169.0, 169.0, 168.0, 165.0, 221.0, 182.0, 169.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 83.0, 87.0, 68.0, 70.0, 67.0, 64.0, 66.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1485.1000000000001, "alertThreshold": 2.0}]} 10:34:22 INFO - Return code: 0 10:34:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:34:22 INFO - # TBPL SUCCESS # 10:34:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:34:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:34:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:34:22 INFO - 2016-09-07 10:34:22.800 screenresolution[1667:10193] starting screenresolution argv=screenresolution get 10:34:22 INFO - 2016-09-07 10:34:22.802 screenresolution[1667:10193] Display 0: 1600x1200x32@60 10:34:22 INFO - 2016-09-07 10:34:22.808 screenresolution[1668:10196] starting screenresolution argv=screenresolution list 10:34:22 INFO - Available Modes on Display 0 10:34:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:34:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:34:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:34:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:34:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:34:23 INFO - Intel Iris: 10:34:23 INFO - Chipset Model: Intel Iris 10:34:23 INFO - Type: GPU 10:34:23 INFO - Bus: Built-In 10:34:23 INFO - VRAM (Dynamic, Max): 1536 MB 10:34:23 INFO - Vendor: Intel (0x8086) 10:34:23 INFO - Device ID: 0x0a2e 10:34:23 INFO - Revision ID: 0x0009 10:34:23 INFO - Displays: 10:34:23 INFO - Display: 10:34:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:34:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:34:23 INFO - Display Serial Number: 1600x1200 60 10:34:23 INFO - Main Display: Yes 10:34:23 INFO - Mirror: Off 10:34:23 INFO - Online: Yes 10:34:23 INFO - Rotation: Supported 10:34:23 INFO - Return code: 0 10:34:23 INFO - Running post-action listener: _resource_record_post_action 10:34:23 INFO - [mozharness: 2016-09-07 17:34:23.046386Z] Finished run-tests step (success) 10:34:23 INFO - Running post-run listener: _resource_record_post_run 10:34:23 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 32906240; Write bytes: 704731136; Read time: 309; Write time: 2180 10:34:23 INFO - TinderboxPrint: CPU usage
53.5% 10:34:23 INFO - TinderboxPrint: I/O read bytes / time
32,906,240 / 309 10:34:23 INFO - TinderboxPrint: I/O write bytes / time
704,731,136 / 2,180 10:34:23 INFO - TinderboxPrint: CPU idle
2,254.0 (46.5%) 10:34:23 INFO - TinderboxPrint: CPU system
969.9 (20.0%) 10:34:23 INFO - TinderboxPrint: CPU user
1,625.7 (33.5%) 10:34:23 INFO - TinderboxPrint: Swap in / out
434,450,432 / 0 10:34:23 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 406792704; Write bytes: 409155584; Read time: 29746; Write time: 765 10:34:23 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29187072; Write bytes: 294260736; Read time: 277; Write time: 1410 10:34:23 INFO - Running post-run listener: _upload_blobber_files 10:34:23 INFO - Blob upload gear active. 10:34:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:34:23 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 10:34:23 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'] 10:34:23 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 10:34:23 INFO - (blobuploader) - INFO - Open directory for files ... 10:34:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:34:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:34:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:34:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:34:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:34:25 INFO - (blobuploader) - INFO - Done attempting. 10:34:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:34:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:34:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:34:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:34:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:34:26 INFO - (blobuploader) - INFO - Done attempting. 10:34:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:34:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:34:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:34:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:34:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:34:29 INFO - (blobuploader) - INFO - Done attempting. 10:34:29 INFO - (blobuploader) - INFO - Iteration through files over. 10:34:29 INFO - Return code: 0 10:34:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:34:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:34:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/705816a9018149c433153b649510fb7c326565e7ec7e0aa0892ac821cc85dbb6e4cf32b39ffd18a5272fd77768351edcfc6e468cffb0c1a0e19bbdf370e08d2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0d6a9b147148f63f566f3f2f5fbde47bb59ebafaf19470d5ffa3a5daa4c6810765831220404557abf858f2e7ebfaafa1791ea2f3f7a3e725b918534e95eed41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57874d307ba1c928b755357526ca01460569945340d5487a58c1775f2cfbaeee64ab1912beacc2306e66d5d862ee7cdf7dc0dc698dc9f72ddf26ce4efdc4215b"} 10:34:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:34:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:34:29 INFO - Contents: 10:34:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/705816a9018149c433153b649510fb7c326565e7ec7e0aa0892ac821cc85dbb6e4cf32b39ffd18a5272fd77768351edcfc6e468cffb0c1a0e19bbdf370e08d2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0d6a9b147148f63f566f3f2f5fbde47bb59ebafaf19470d5ffa3a5daa4c6810765831220404557abf858f2e7ebfaafa1791ea2f3f7a3e725b918534e95eed41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57874d307ba1c928b755357526ca01460569945340d5487a58c1775f2cfbaeee64ab1912beacc2306e66d5d862ee7cdf7dc0dc698dc9f72ddf26ce4efdc4215b"} 10:34:29 INFO - Running post-run listener: copy_logs_to_upload_dir 10:34:29 INFO - Copying logs to upload dir... 10:34:29 INFO - mkdir: /builds/slave/test/build/upload/logs 10:34:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.643288 ========= master_lag: 0.64 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-07 10:34:29.813312) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-07 10:34:29.818094) ========= 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.eogJCqZdsq/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.yDKgCDUvFh/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/705816a9018149c433153b649510fb7c326565e7ec7e0aa0892ac821cc85dbb6e4cf32b39ffd18a5272fd77768351edcfc6e468cffb0c1a0e19bbdf370e08d2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0d6a9b147148f63f566f3f2f5fbde47bb59ebafaf19470d5ffa3a5daa4c6810765831220404557abf858f2e7ebfaafa1791ea2f3f7a3e725b918534e95eed41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57874d307ba1c928b755357526ca01460569945340d5487a58c1775f2cfbaeee64ab1912beacc2306e66d5d862ee7cdf7dc0dc698dc9f72ddf26ce4efdc4215b"} build_url:https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011088 build_url: 'https://queue.taskcluster.net/v1/task/edKDonuHS5mRySn3jPIA-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/705816a9018149c433153b649510fb7c326565e7ec7e0aa0892ac821cc85dbb6e4cf32b39ffd18a5272fd77768351edcfc6e468cffb0c1a0e19bbdf370e08d2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0d6a9b147148f63f566f3f2f5fbde47bb59ebafaf19470d5ffa3a5daa4c6810765831220404557abf858f2e7ebfaafa1791ea2f3f7a3e725b918534e95eed41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57874d307ba1c928b755357526ca01460569945340d5487a58c1775f2cfbaeee64ab1912beacc2306e66d5d862ee7cdf7dc0dc698dc9f72ddf26ce4efdc4215b"}' ========= master_lag: 1.56 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-07 10:34:31.393550) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:34:31.393884) ========= 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.eogJCqZdsq/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.yDKgCDUvFh/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.005028 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:34:31.420044) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 10:34:31.420342) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 10:34:31.420674) ========= ========= Total master_lag: 2.55 =========