builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0314 starttime: 1473283567.52 results: success (0) buildid: 20160907121415 builduid: 3b3a269c0c764f0a843a40aff91769cd revision: fb737d495a7912d8030f7ffc5e277ed496e1a7a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:07.519807) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:07.520290) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:07.520566) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:07.705860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:07.706131) ========= 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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:26:07-- 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% 26.2M=0s 2016-09-07 14:26:08 (26.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.454027 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:08.184247) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:08.184616) ========= 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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.026734 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:08.233507) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 14:26:08.233856) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fb737d495a7912d8030f7ffc5e277ed496e1a7a4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev fb737d495a7912d8030f7ffc5e277ed496e1a7a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8l2PpZgxa7/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.cqgSbIwF2a/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:26:08,293 truncating revision to first 12 chars 2016-09-07 14:26:08,293 Setting DEBUG logging. 2016-09-07 14:26:08,293 attempt 1/10 2016-09-07 14:26:08,293 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fb737d495a79?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 14:26:09,607 unpacking tar archive at: fx-team-fb737d495a79/testing/mozharness/ program finished with exit code 0 elapsedTime=1.573791 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 14:26:09.830027) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:09.830344) ========= script_repo_revision: fb737d495a7912d8030f7ffc5e277ed496e1a7a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:09.830717) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:09.830979) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 14:26:09.851344) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-07 14:26:09.851626) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8l2PpZgxa7/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.cqgSbIwF2a/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:26:10 INFO - MultiFileLogger online at 20160907 14:26:10 in /builds/slave/test 14:26:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:26:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:26:10 INFO - {'append_to_log': False, 14:26:10 INFO - 'base_work_dir': '/builds/slave/test', 14:26:10 INFO - 'blob_upload_branch': 'Fx-Team', 14:26:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:26:10 INFO - 'branch': 'Fx-Team', 14:26:10 INFO - 'buildbot_json_path': 'buildprops.json', 14:26:10 INFO - 'config_files': ('talos/mac_config.py',), 14:26:10 INFO - 'default_actions': ('clobber', 14:26:10 INFO - 'read-buildbot-config', 14:26:10 INFO - 'download-and-extract', 14:26:10 INFO - 'populate-webroot', 14:26:10 INFO - 'create-virtualenv', 14:26:10 INFO - 'install', 14:26:10 INFO - 'run-tests'), 14:26:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:26:10 INFO - 'download_minidump_stackwalk': True, 14:26:10 INFO - 'download_symbols': 'ondemand', 14:26:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:26:10 INFO - 'tooltool.py': '/tools/tooltool.py', 14:26:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:26:10 INFO - '/tools/misc-python/virtualenv.py')}, 14:26:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:26:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:26:10 INFO - 'installer_path': 'installer.exe', 14:26:10 INFO - 'log_level': 'info', 14:26:10 INFO - 'log_name': 'talos', 14:26:10 INFO - 'log_to_console': True, 14:26:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:26:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:26:10 INFO - 'opt_config_files': (), 14:26:10 INFO - 'pip_index': False, 14:26:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:26:10 INFO - 'cmd': ('bash', 14:26:10 INFO - '-c', 14:26:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:26:10 INFO - 'enabled': True, 14:26:10 INFO - 'halt_on_failure': False, 14:26:10 INFO - 'name': 'check_screen_resolution'},), 14:26:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:26:10 INFO - 'cmd': ('bash', 14:26:10 INFO - '-c', 14:26:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:26:10 INFO - 'enabled': True, 14:26:10 INFO - 'halt_on_failure': False, 14:26:10 INFO - 'name': 'check_screen_resolution'},), 14:26:10 INFO - 'run_cmd_checks_enabled': True, 14:26:10 INFO - 'sps_profile': False, 14:26:10 INFO - 'sps_profile_interval': 0, 14:26:10 INFO - 'suite': 'dromaeojs', 14:26:10 INFO - 'system_bits': '32', 14:26:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:26:10 INFO - 'title': 't-yosemite-r7-0314', 14:26:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:26:10 INFO - 'use_talos_json': True, 14:26:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:26:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:26:10 INFO - 'work_dir': 'build'} 14:26:10 INFO - [mozharness: 2016-09-07 21:26:10.027225Z] Running clobber step. 14:26:10 INFO - Running pre-action listener: _resource_record_pre_action 14:26:10 INFO - Running main action method: clobber 14:26:10 INFO - rmtree: /builds/slave/test/build 14:26:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:26:12 INFO - Running post-action listener: _resource_record_post_action 14:26:12 INFO - [mozharness: 2016-09-07 21:26:12.186841Z] Finished clobber step (success) 14:26:12 INFO - [mozharness: 2016-09-07 21:26:12.187154Z] Running read-buildbot-config step. 14:26:12 INFO - Running pre-action listener: _resource_record_pre_action 14:26:12 INFO - Running main action method: read_buildbot_config 14:26:12 INFO - Using buildbot properties: 14:26:12 INFO - { 14:26:12 INFO - "project": "", 14:26:12 INFO - "product": "firefox", 14:26:12 INFO - "script_repo_revision": "production", 14:26:12 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 14:26:12 INFO - "repository": "", 14:26:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 14:26:12 INFO - "buildid": "20160907121415", 14:26:12 INFO - "pgo_build": "False", 14:26:12 INFO - "basedir": "/builds/slave/test", 14:26:12 INFO - "buildnumber": 133, 14:26:12 INFO - "slavename": "t-yosemite-r7-0314", 14:26:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:26:12 INFO - "platform": "yosemite_r7", 14:26:12 INFO - "scheduler": "tests-fx-team-macosx64-talos", 14:26:12 INFO - "branch": "fx-team", 14:26:12 INFO - "repo_path": "integration/fx-team", 14:26:12 INFO - "revision": "fb737d495a7912d8030f7ffc5e277ed496e1a7a4", 14:26:12 INFO - "stage_platform": "macosx64", 14:26:12 INFO - "builduid": "3b3a269c0c764f0a843a40aff91769cd", 14:26:12 INFO - "slavebuilddir": "test" 14:26:12 INFO - } 14:26:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 14:26:12 INFO - Running post-action listener: _resource_record_post_action 14:26:12 INFO - [mozharness: 2016-09-07 21:26:12.192733Z] Finished read-buildbot-config step (success) 14:26:12 INFO - [mozharness: 2016-09-07 21:26:12.192990Z] Running download-and-extract step. 14:26:12 INFO - Running pre-action listener: _resource_record_pre_action 14:26:12 INFO - Running main action method: download_and_extract 14:26:12 INFO - mkdir: /builds/slave/test/build/tests 14:26:12 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')]} 14:26:12 INFO - trying https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 14:26:12 INFO - Downloading https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/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 14:26:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/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 14:26:13 INFO - Downloaded 1199 bytes. 14:26:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 14:26:13 INFO - Using the following test package requirements: 14:26:13 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 14:26:13 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:26:13 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 14:26:13 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:26:13 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 14:26:13 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:26:13 INFO - u'jsshell-mac.zip'], 14:26:13 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:26:13 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 14:26:13 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 14:26:13 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:26:13 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 14:26:13 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:26:13 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 14:26:13 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:26:13 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 14:26:13 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:26:13 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 14:26:13 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:26:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:26:14 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 14:26:21 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 14:26:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:26:21 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 14:26:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 14:26:29 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 14:26:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:29 INFO - trying https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:26:29 INFO - Downloading https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 14:26:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/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 14:26:32 INFO - Downloaded 111548105 bytes. 14:26:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:26:32 INFO - mkdir: /builds/slave/test/properties 14:26:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:26:32 INFO - Writing to file /builds/slave/test/properties/build_url 14:26:32 INFO - Contents: 14:26:32 INFO - build_url:https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:26:33 INFO - Running post-action listener: _resource_record_post_action 14:26:33 INFO - Running post-action listener: set_extra_try_arguments 14:26:33 INFO - [mozharness: 2016-09-07 21:26:33.296231Z] Finished download-and-extract step (success) 14:26:33 INFO - [mozharness: 2016-09-07 21:26:33.296352Z] Running populate-webroot step. 14:26:33 INFO - Running pre-action listener: _resource_record_pre_action 14:26:33 INFO - Running main action method: populate_webroot 14:26:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:26:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:26:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:26:33 INFO - '--tppagecycles', 14:26:33 INFO - '7'], 14:26:33 INFO - 'tests': ['tsvgm']}}, 14:26:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:26:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:26:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:26:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:26:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:26:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:26:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:33 INFO - 'talos_options': ['--disable-e10s'], 14:26:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:26:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:26:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:33 INFO - 'talos_options': ['--disable-e10s'], 14:26:33 INFO - 'tests': ['damp', 'tps']}, 14:26:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:33 INFO - 'tests': ['damp', 'tps']}, 14:26:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:26:33 INFO - 'tests': ['dromaeo_dom']}, 14:26:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:26:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:26:33 INFO - 'tests': ['basic_compositor_video']}, 14:26:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:26:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:26:33 INFO - 'tests': ['a11yr', 14:26:33 INFO - 'ts_paint', 14:26:33 INFO - 'tpaint', 14:26:33 INFO - 'sessionrestore', 14:26:33 INFO - 'sessionrestore_no_auto_restore', 14:26:33 INFO - 'tabpaint']}, 14:26:33 INFO - 'other-e10s': {'tests': ['a11yr', 14:26:33 INFO - 'ts_paint', 14:26:33 INFO - 'tpaint', 14:26:33 INFO - 'sessionrestore', 14:26:33 INFO - 'sessionrestore_no_auto_restore', 14:26:33 INFO - 'tabpaint']}, 14:26:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:26:33 INFO - 'ts_paint', 14:26:33 INFO - 'tpaint', 14:26:33 INFO - 'sessionrestore', 14:26:33 INFO - 'sessionrestore_no_auto_restore', 14:26:33 INFO - 'tabpaint']}, 14:26:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:26:33 INFO - 'ts_paint', 14:26:33 INFO - 'tpaint', 14:26:33 INFO - 'sessionrestore', 14:26:33 INFO - 'sessionrestore_no_auto_restore', 14:26:33 INFO - 'tabpaint']}, 14:26:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:26:33 INFO - 'tests': ['a11yr', 14:26:33 INFO - 'ts_paint', 14:26:33 INFO - 'tpaint', 14:26:33 INFO - 'sessionrestore', 14:26:33 INFO - 'sessionrestore_no_auto_restore', 14:26:33 INFO - 'tabpaint']}, 14:26:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:26:33 INFO - 'tests': ['a11yr', 14:26:33 INFO - 'ts_paint', 14:26:33 INFO - 'tpaint', 14:26:33 INFO - 'sessionrestore', 14:26:33 INFO - 'sessionrestore_no_auto_restore', 14:26:33 INFO - 'tabpaint']}, 14:26:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:26:33 INFO - 'tests': ['tsvgx', 14:26:33 INFO - 'tsvgr_opacity', 14:26:33 INFO - 'tart', 14:26:33 INFO - 'tscrollx', 14:26:33 INFO - 'cart']}, 14:26:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:26:33 INFO - 'tsvgr_opacity', 14:26:33 INFO - 'tart', 14:26:33 INFO - 'tscrollx', 14:26:33 INFO - 'cart']}, 14:26:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:33 INFO - 'talos_options': ['--disable-e10s'], 14:26:33 INFO - 'tests': ['tp5o']}, 14:26:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:33 INFO - 'tests': ['tp5o']}, 14:26:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:33 INFO - 'talos_options': ['--disable-e10s', 14:26:33 INFO - '--xperf_path', 14:26:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:26:33 INFO - 'tests': ['tp5n']}, 14:26:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:26:33 INFO - 'talos_options': ['--xperf_path', 14:26:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:26:33 INFO - 'tests': ['tp5n']}}, 14:26:33 INFO - 'talos.zip': {'path': '', 14:26:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:26:33 INFO - Running post-action listener: _resource_record_post_action 14:26:33 INFO - [mozharness: 2016-09-07 21:26:33.315510Z] Finished populate-webroot step (success) 14:26:33 INFO - [mozharness: 2016-09-07 21:26:33.315626Z] Running create-virtualenv step. 14:26:33 INFO - Running pre-action listener: _resource_record_pre_action 14:26:33 INFO - Running main action method: create_virtualenv 14:26:33 INFO - Creating virtualenv /builds/slave/test/build/venv 14:26:33 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 14:26:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:26:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:26:33 INFO - Using real prefix '/tools/python27' 14:26:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:26:34 INFO - Installing distribute.............................................................................................................................................................................................done. 14:26:37 INFO - Installing pip.................done. 14:26:37 INFO - Return code: 0 14:26:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:26:37 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')]} 14:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:37 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')]} 14:26:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:37 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 0x10778ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107821618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe729cd2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f0d30>, '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 0x107455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e8b20>, '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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:26:37 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 14:26:37 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 14:26:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8l2PpZgxa7/Render', 14:26:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:37 INFO - 'HOME': '/Users/cltbld', 14:26:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:37 INFO - 'LOGNAME': 'cltbld', 14:26:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:37 INFO - 'MOZ_NO_REMOTE': '1', 14:26:37 INFO - 'NO_EM_RESTART': '1', 14:26:37 INFO - 'PAGER': '/bin/cat', 14:26:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:37 INFO - 'PWD': '/builds/slave/test', 14:26:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:37 INFO - 'SHELL': '/bin/bash', 14:26:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqgSbIwF2a/Listeners', 14:26:37 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:26:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:37 INFO - 'USER': 'cltbld', 14:26:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:37 INFO - 'XPC_FLAGS': '0x0', 14:26:37 INFO - 'XPC_SERVICE_NAME': '0', 14:26:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:37 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 14:26:37 INFO - Cleaning up... 14:26:37 INFO - Return code: 0 14:26:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:26:37 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')]} 14:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:37 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')]} 14:26:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:37 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 0x10778ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107821618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe729cd2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f0d30>, '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 0x107455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e8b20>, '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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:26:37 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 14:26:37 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 14:26:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8l2PpZgxa7/Render', 14:26:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:37 INFO - 'HOME': '/Users/cltbld', 14:26:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:37 INFO - 'LOGNAME': 'cltbld', 14:26:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:37 INFO - 'MOZ_NO_REMOTE': '1', 14:26:37 INFO - 'NO_EM_RESTART': '1', 14:26:37 INFO - 'PAGER': '/bin/cat', 14:26:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:37 INFO - 'PWD': '/builds/slave/test', 14:26:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:37 INFO - 'SHELL': '/bin/bash', 14:26:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqgSbIwF2a/Listeners', 14:26:37 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:26:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:37 INFO - 'USER': 'cltbld', 14:26:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:37 INFO - 'XPC_FLAGS': '0x0', 14:26:37 INFO - 'XPC_SERVICE_NAME': '0', 14:26:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:37 INFO - Downloading/unpacking psutil>=3.1.1 14:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:26:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:26:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:26:40 INFO - Installing collected packages: psutil 14:26:40 INFO - Running setup.py install for psutil 14:26:41 INFO - building 'psutil._psutil_osx' extension 14:26:41 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 14:26:41 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 14:26:41 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 14:26:41 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 14:26:41 INFO - building 'psutil._psutil_posix' extension 14:26:41 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 14:26:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:26:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:26:41 INFO - ^ 14:26:41 INFO - 1 warning generated. 14:26:41 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 14:26:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:26:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:26:42 INFO - Successfully installed psutil 14:26:42 INFO - Cleaning up... 14:26:42 INFO - Return code: 0 14:26:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:26:42 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')]} 14:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:42 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')]} 14:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:42 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 0x10778ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107821618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe729cd2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f0d30>, '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 0x107455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e8b20>, '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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:26:42 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 14:26:42 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 14:26:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8l2PpZgxa7/Render', 14:26:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:42 INFO - 'HOME': '/Users/cltbld', 14:26:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:42 INFO - 'LOGNAME': 'cltbld', 14:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:42 INFO - 'MOZ_NO_REMOTE': '1', 14:26:42 INFO - 'NO_EM_RESTART': '1', 14:26:42 INFO - 'PAGER': '/bin/cat', 14:26:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:42 INFO - 'PWD': '/builds/slave/test', 14:26:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:42 INFO - 'SHELL': '/bin/bash', 14:26:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqgSbIwF2a/Listeners', 14:26:42 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:26:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:42 INFO - 'USER': 'cltbld', 14:26:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:42 INFO - 'XPC_FLAGS': '0x0', 14:26:42 INFO - 'XPC_SERVICE_NAME': '0', 14:26:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:26:42 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 14:26:42 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 14:26:42 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 14:26:42 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 14:26:42 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 14:26:42 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 14:26:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:26:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:26:44 INFO - Installing collected packages: mozsystemmonitor 14:26:44 INFO - Running setup.py install for mozsystemmonitor 14:26:45 INFO - Successfully installed mozsystemmonitor 14:26:45 INFO - Cleaning up... 14:26:45 INFO - Return code: 0 14:26:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:26:45 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')]} 14:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:45 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')]} 14:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:45 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 0x10778ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107821618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe729cd2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f0d30>, '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 0x107455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e8b20>, '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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:26:45 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 14:26:45 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 14:26:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8l2PpZgxa7/Render', 14:26:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:45 INFO - 'HOME': '/Users/cltbld', 14:26:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:45 INFO - 'LOGNAME': 'cltbld', 14:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:45 INFO - 'MOZ_NO_REMOTE': '1', 14:26:45 INFO - 'NO_EM_RESTART': '1', 14:26:45 INFO - 'PAGER': '/bin/cat', 14:26:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:45 INFO - 'PWD': '/builds/slave/test', 14:26:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:45 INFO - 'SHELL': '/bin/bash', 14:26:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqgSbIwF2a/Listeners', 14:26:45 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:26:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:45 INFO - 'USER': 'cltbld', 14:26:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:45 INFO - 'XPC_FLAGS': '0x0', 14:26:45 INFO - 'XPC_SERVICE_NAME': '0', 14:26:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:45 INFO - Downloading/unpacking jsonschema==2.5.1 14:26:45 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 14:26:45 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 14:26:45 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 14:26:45 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 14:26:45 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 14:26:45 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 14:26:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:26:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:48 INFO - Downloading functools32-3.2.3-2.tar.gz 14:26:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:26:48 INFO - warning: no files found matching '*.txt' 14:26:48 INFO - no previously-included directories found matching 'build' 14:26:48 INFO - no previously-included directories found matching 'dist' 14:26:48 INFO - no previously-included directories found matching '.git*' 14:26:48 INFO - Installing collected packages: jsonschema, functools32 14:26:48 INFO - Running setup.py install for functools32 14:26:48 INFO - warning: no files found matching '*.txt' 14:26:48 INFO - no previously-included directories found matching 'build' 14:26:48 INFO - no previously-included directories found matching 'dist' 14:26:48 INFO - no previously-included directories found matching '.git*' 14:26:48 INFO - Successfully installed jsonschema functools32 14:26:48 INFO - Cleaning up... 14:26:48 INFO - Return code: 0 14:26:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:26: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')]} 14:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26: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')]} 14:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26: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', '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 0x10778ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107821618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe729cd2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f0d30>, '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 0x107455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e8b20>, '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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:26: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:26: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 functools32==3.2.3-2 14:26:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8l2PpZgxa7/Render', 14:26:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:48 INFO - 'HOME': '/Users/cltbld', 14:26:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:48 INFO - 'LOGNAME': 'cltbld', 14:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:48 INFO - 'MOZ_NO_REMOTE': '1', 14:26:48 INFO - 'NO_EM_RESTART': '1', 14:26:48 INFO - 'PAGER': '/bin/cat', 14:26:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:48 INFO - 'PWD': '/builds/slave/test', 14:26:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:48 INFO - 'SHELL': '/bin/bash', 14:26:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqgSbIwF2a/Listeners', 14:26:48 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:26:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:48 INFO - 'USER': 'cltbld', 14:26:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:48 INFO - 'XPC_FLAGS': '0x0', 14:26:48 INFO - 'XPC_SERVICE_NAME': '0', 14:26:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:26:48 INFO - Cleaning up... 14:26:48 INFO - Return code: 0 14:26:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:26: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')]} 14:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26: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')]} 14:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26: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', '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 0x10778ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107821618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe729cd2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f0d30>, '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 0x107455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e8b20>, '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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:26: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:26: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 blobuploader==1.2.4 14:26:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8l2PpZgxa7/Render', 14:26:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:48 INFO - 'HOME': '/Users/cltbld', 14:26:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:48 INFO - 'LOGNAME': 'cltbld', 14:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:48 INFO - 'MOZ_NO_REMOTE': '1', 14:26:48 INFO - 'NO_EM_RESTART': '1', 14:26:48 INFO - 'PAGER': '/bin/cat', 14:26:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:48 INFO - 'PWD': '/builds/slave/test', 14:26:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:48 INFO - 'SHELL': '/bin/bash', 14:26:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqgSbIwF2a/Listeners', 14:26:48 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:26:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:48 INFO - 'USER': 'cltbld', 14:26:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:48 INFO - 'XPC_FLAGS': '0x0', 14:26:48 INFO - 'XPC_SERVICE_NAME': '0', 14:26:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:49 INFO - Downloading/unpacking blobuploader==1.2.4 14:26:49 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 14:26:49 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 14:26:49 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 14:26:49 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 14:26:49 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 14:26:49 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 14:26:51 INFO - Downloading blobuploader-1.2.4.tar.gz 14:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:26:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:26: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 14:26: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 14:26: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 14:26: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 14:26: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 14:26: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 14:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:26:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:52 INFO - Downloading docopt-0.6.1.tar.gz 14:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:26:52 INFO - Installing collected packages: blobuploader, requests, docopt 14:26:52 INFO - Running setup.py install for blobuploader 14:26:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:26:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:26:52 INFO - Running setup.py install for requests 14:26:53 INFO - Running setup.py install for docopt 14:26:53 INFO - Successfully installed blobuploader requests docopt 14:26:53 INFO - Cleaning up... 14:26:53 INFO - Return code: 0 14:26:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26:53 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')]} 14:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:53 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')]} 14:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:53 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 0x10778ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107821618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe729cd2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f0d30>, '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 0x107455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e8b20>, '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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:26:53 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 14:26:53 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 14:26:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8l2PpZgxa7/Render', 14:26:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:53 INFO - 'HOME': '/Users/cltbld', 14:26:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:53 INFO - 'LOGNAME': 'cltbld', 14:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:53 INFO - 'MOZ_NO_REMOTE': '1', 14:26:53 INFO - 'NO_EM_RESTART': '1', 14:26:53 INFO - 'PAGER': '/bin/cat', 14:26:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:53 INFO - 'PWD': '/builds/slave/test', 14:26:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:53 INFO - 'SHELL': '/bin/bash', 14:26:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqgSbIwF2a/Listeners', 14:26:53 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:26:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:53 INFO - 'USER': 'cltbld', 14:26:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:53 INFO - 'XPC_FLAGS': '0x0', 14:26:53 INFO - 'XPC_SERVICE_NAME': '0', 14:26:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:26:54 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-C4XZXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:26:54 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-8vJk2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:26:54 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-koVmKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:26:54 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-kiSSeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:26:54 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ZK7JbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:26:54 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-tZG7BF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:26:54 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-6LZlsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:26:54 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-K65DJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:26:54 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Zyyplg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:26:54 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ziTpgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:26:55 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-766uOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:26:55 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-N_XrFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:26:55 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-rU_TD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:26:55 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-gciDBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:55 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-wgDUvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:26:55 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-0NbHxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:26:55 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-uqnJt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:26:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:26:55 INFO - Running setup.py install for manifestparser 14:26:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:26:55 INFO - Running setup.py install for mozcrash 14:26:56 INFO - Running setup.py install for mozdebug 14:26:56 INFO - Running setup.py install for mozdevice 14:26:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:26:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:26:56 INFO - Running setup.py install for mozfile 14:26:56 INFO - Running setup.py install for mozhttpd 14:26:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:26:56 INFO - Running setup.py install for mozinfo 14:26:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:26:56 INFO - Running setup.py install for mozInstall 14:26:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:26:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:26:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:26:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:26:56 INFO - Running setup.py install for mozleak 14:26:57 INFO - Running setup.py install for mozlog 14:26:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:26:57 INFO - Running setup.py install for moznetwork 14:26:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:26:57 INFO - Running setup.py install for mozprocess 14:26:57 INFO - Running setup.py install for mozprofile 14:26:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:26:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:26:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:26:57 INFO - Running setup.py install for mozrunner 14:26:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:26:57 INFO - Running setup.py install for mozscreenshot 14:26:58 INFO - Running setup.py install for moztest 14:26:58 INFO - Running setup.py install for mozversion 14:26:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:26:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:26:58 INFO - Cleaning up... 14:26:58 INFO - Return code: 0 14:26:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:58 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 0x10778ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107821618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe729cd2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f0d30>, '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 0x107455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e8b20>, '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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:26:58 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 14:26:58 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 14:26:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8l2PpZgxa7/Render', 14:26:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:58 INFO - 'HOME': '/Users/cltbld', 14:26:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:58 INFO - 'LOGNAME': 'cltbld', 14:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:58 INFO - 'MOZ_NO_REMOTE': '1', 14:26:58 INFO - 'NO_EM_RESTART': '1', 14:26:58 INFO - 'PAGER': '/bin/cat', 14:26:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:58 INFO - 'PWD': '/builds/slave/test', 14:26:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:58 INFO - 'SHELL': '/bin/bash', 14:26:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqgSbIwF2a/Listeners', 14:26:58 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:26:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:58 INFO - 'USER': 'cltbld', 14:26:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:58 INFO - 'XPC_FLAGS': '0x0', 14:26:58 INFO - 'XPC_SERVICE_NAME': '0', 14:26:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:26:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-I5Pz7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:26:58 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)) 14:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:26:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-6XrRYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:26:58 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)) 14:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:26:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ZAE54c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:26:58 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)) 14:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:26:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Qjgeny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:26:59 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)) 14:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:26:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-_Fy1Zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:26:59 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)) 14:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:26:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-LsUMoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:26:59 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)) 14:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:26:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-7GynsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:26:59 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)) 14:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:26:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-UATqta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:26:59 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)) 14:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:26:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-FDpCBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:26:59 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)) 14:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:26:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-oiS4zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:26:59 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)) 14:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:26:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-I1CmmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:26:59 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)) 14:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:26:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Ib4SIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:26:59 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)) 14:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:26:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-eGVjjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:27:00 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)) 14:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:27:00 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-PmeafB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:27:00 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)) 14:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:27:00 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-CAV1bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:27:00 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)) 14:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:27:00 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-MP1kPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:27:00 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)) 14:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:27:00 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-V8_P8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:27:00 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)) 14:27:00 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)) 14:27:00 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)) 14:27:00 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)) 14:27:00 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)) 14:27:00 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)) 14:27:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:27:00 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 14:27:00 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 14:27:00 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 14:27:00 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 14:27:00 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 14:27:00 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 14:27:02 INFO - Downloading blessings-1.6.tar.gz 14:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:27:03 INFO - Installing collected packages: blessings 14:27:03 INFO - Running setup.py install for blessings 14:27:03 INFO - Successfully installed blessings 14:27:03 INFO - Cleaning up... 14:27:03 INFO - Return code: 0 14:27:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:27:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:27:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:27:03 INFO - Reading from file tmpfile_stdout 14:27:03 INFO - Current package versions: 14:27:03 INFO - blessings == 1.6 14:27:03 INFO - blobuploader == 1.2.4 14:27:03 INFO - docopt == 0.6.1 14:27:03 INFO - functools32 == 3.2.3-2 14:27:03 INFO - jsonschema == 2.5.1 14:27:03 INFO - manifestparser == 1.1 14:27:03 INFO - mozInstall == 1.12 14:27:03 INFO - mozcrash == 0.17 14:27:03 INFO - mozdebug == 0.1 14:27:03 INFO - mozdevice == 0.48 14:27:03 INFO - mozfile == 1.2 14:27:03 INFO - mozhttpd == 0.7 14:27:03 INFO - mozinfo == 0.9 14:27:03 INFO - mozleak == 0.1 14:27:03 INFO - mozlog == 3.3 14:27:03 INFO - moznetwork == 0.27 14:27:03 INFO - mozprocess == 0.23 14:27:03 INFO - mozprofile == 0.28 14:27:03 INFO - mozrunner == 6.12 14:27:03 INFO - mozscreenshot == 0.1 14:27:03 INFO - mozsystemmonitor == 0.3 14:27:03 INFO - moztest == 0.7 14:27:03 INFO - mozversion == 1.4 14:27:03 INFO - psutil == 3.1.1 14:27:03 INFO - requests == 1.2.3 14:27:03 INFO - wsgiref == 0.1.2 14:27:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:03 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 0x10778ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107821618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe729cd2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f0d30>, '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 0x107455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e8b20>, '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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:27:03 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 14:27:03 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 14:27:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8l2PpZgxa7/Render', 14:27:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:03 INFO - 'HOME': '/Users/cltbld', 14:27:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:03 INFO - 'LOGNAME': 'cltbld', 14:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:03 INFO - 'MOZ_NO_REMOTE': '1', 14:27:03 INFO - 'NO_EM_RESTART': '1', 14:27:03 INFO - 'PAGER': '/bin/cat', 14:27:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:03 INFO - 'PWD': '/builds/slave/test', 14:27:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:03 INFO - 'SHELL': '/bin/bash', 14:27:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqgSbIwF2a/Listeners', 14:27:03 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:27:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:03 INFO - 'USER': 'cltbld', 14:27:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:03 INFO - 'XPC_FLAGS': '0x0', 14:27:03 INFO - 'XPC_SERVICE_NAME': '0', 14:27:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:03 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)) 14:27:03 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)) 14:27:03 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)) 14:27:03 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)) 14:27:03 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)) 14:27:03 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)) 14:27:03 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)) 14:27:03 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)) 14:27:03 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)) 14:27:03 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)) 14:27:03 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)) 14:27:03 INFO - Cleaning up... 14:27:03 INFO - Return code: 0 14:27:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:03 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 0x10778ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107821618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe729cd2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076f0d30>, '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 0x107455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e8b20>, '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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 14:27:03 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 14:27:03 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 14:27:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8l2PpZgxa7/Render', 14:27:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:03 INFO - 'HOME': '/Users/cltbld', 14:27:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:03 INFO - 'LOGNAME': 'cltbld', 14:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:03 INFO - 'MOZ_NO_REMOTE': '1', 14:27:03 INFO - 'NO_EM_RESTART': '1', 14:27:03 INFO - 'PAGER': '/bin/cat', 14:27:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:03 INFO - 'PWD': '/builds/slave/test', 14:27:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:03 INFO - 'SHELL': '/bin/bash', 14:27:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqgSbIwF2a/Listeners', 14:27:03 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:27:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:03 INFO - 'USER': 'cltbld', 14:27:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:03 INFO - 'XPC_FLAGS': '0x0', 14:27:03 INFO - 'XPC_SERVICE_NAME': '0', 14:27:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:27:04 INFO - Cleaning up... 14:27:04 INFO - Return code: 0 14:27:04 INFO - Running post-action listener: _resource_record_post_action 14:27:04 INFO - Running post-action listener: _start_resource_monitoring 14:27:04 INFO - Starting resource monitoring. 14:27:04 INFO - [mozharness: 2016-09-07 21:27:04.123034Z] Finished create-virtualenv step (success) 14:27:04 INFO - [mozharness: 2016-09-07 21:27:04.123997Z] Running install step. 14:27:04 INFO - Running pre-action listener: _resource_record_pre_action 14:27:04 INFO - Running main action method: install 14:27:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:27:04 INFO - Reading from file tmpfile_stdout 14:27:04 INFO - Detecting whether we're running mozinstall >=1.0... 14:27:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:27:04 INFO - Reading from file tmpfile_stdout 14:27:04 INFO - Output received: 14:27:04 INFO - Usage: mozinstall [options] installer 14:27:04 INFO - Options: 14:27:04 INFO - -h, --help show this help message and exit 14:27:04 INFO - -d DEST, --destination=DEST 14:27:04 INFO - Directory to install application into. [default: 14:27:04 INFO - "/builds/slave/test"] 14:27:04 INFO - --app=APP Application being installed. [default: firefox] 14:27:04 INFO - mkdir: /builds/slave/test/build/application 14:27:04 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'] 14:27:04 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 14:27:38 INFO - Reading from file tmpfile_stdout 14:27:38 INFO - Output received: 14:27:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:27:38 INFO - Running post-action listener: _resource_record_post_action 14:27:38 INFO - [mozharness: 2016-09-07 21:27:38.768979Z] Finished install step (success) 14:27:38 INFO - [mozharness: 2016-09-07 21:27:38.769137Z] Running run-tests step. 14:27:38 INFO - Running pre-action listener: _resource_record_pre_action 14:27:38 INFO - Running main action method: run_tests 14:27:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:27:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:27:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:27:38 INFO - 2016-09-07 14:27:38.783 screenresolution[1738:8534] starting screenresolution argv=screenresolution get 14:27:38 INFO - 2016-09-07 14:27:38.786 screenresolution[1738:8534] Display 0: 1600x1200x32@60 14:27:38 INFO - 2016-09-07 14:27:38.791 screenresolution[1739:8536] starting screenresolution argv=screenresolution list 14:27:38 INFO - Available Modes on Display 0 14:27:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:27:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:27:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:27:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:27:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:27:39 INFO - Intel Iris: 14:27:39 INFO - Chipset Model: Intel Iris 14:27:39 INFO - Type: GPU 14:27:39 INFO - Bus: Built-In 14:27:39 INFO - VRAM (Dynamic, Max): 1536 MB 14:27:39 INFO - Vendor: Intel (0x8086) 14:27:39 INFO - Device ID: 0x0a2e 14:27:39 INFO - Revision ID: 0x0009 14:27:39 INFO - Displays: 14:27:39 INFO - Display: 14:27:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:27:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:27:39 INFO - Display Serial Number: 1600x1200 60 14:27:39 INFO - Main Display: Yes 14:27:39 INFO - Mirror: Off 14:27:39 INFO - Online: Yes 14:27:39 INFO - Rotation: Supported 14:27:39 INFO - Return code: 0 14:27:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:27:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:27:39 INFO - Python 2.7.3 14:27:39 INFO - Return code: 0 14:27:39 INFO - grabbing minidump binary from tooltool 14:27:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:39 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 0x1076f0d30>, '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 0x107455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076e8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:27:39 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 14:27:39 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 14:27:39 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 14:27:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:27:39 INFO - Return code: 0 14:27:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:27:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:27:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:27:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:27:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:27:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 14:27:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0314 --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 14:27:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8l2PpZgxa7/Render', 14:27:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:39 INFO - 'HOME': '/Users/cltbld', 14:27:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:39 INFO - 'LOGNAME': 'cltbld', 14:27:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:27:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:27:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:39 INFO - 'MOZ_NO_REMOTE': '1', 14:27:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:27:39 INFO - 'NO_EM_RESTART': '1', 14:27:39 INFO - 'PAGER': '/bin/cat', 14:27:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:39 INFO - 'PWD': '/builds/slave/test', 14:27:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:27:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:39 INFO - 'SHELL': '/bin/bash', 14:27:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqgSbIwF2a/Listeners', 14:27:39 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:27:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:39 INFO - 'USER': 'cltbld', 14:27:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:39 INFO - 'XPC_FLAGS': '0x0', 14:27:39 INFO - 'XPC_SERVICE_NAME': '0', 14:27:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 14:27:39 INFO - mozversion application_buildid: 20160907121415 14:27:39 INFO - mozversion application_changeset: fb737d495a7912d8030f7ffc5e277ed496e1a7a4 14:27:39 INFO - mozversion application_display_name: Nightly 14:27:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:27:39 INFO - mozversion application_name: Firefox 14:27:39 INFO - mozversion application_remotingname: firefox 14:27:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:27:39 INFO - mozversion application_vendor: Mozilla 14:27:39 INFO - mozversion application_version: 51.0a1 14:27:39 INFO - mozversion platform_buildid: 20160907121415 14:27:39 INFO - mozversion platform_changeset: fb737d495a7912d8030f7ffc5e277ed496e1a7a4 14:27:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:27:39 INFO - mozversion platform_version: 51.0a1 14:27:39 INFO - using testdate: 1473283659 14:27:39 INFO - actual date: 1473283659 14:27:39 INFO - starting webserver on 'localhost:49284' 14:27:39 INFO - SUITE-START | Running 2 tests 14:27:39 INFO - TEST-START | dromaeo_css 14:27:39 INFO - operating with platform_type : mac_ 14:27:39 INFO - Initialising browser for dromaeo_css test... 14:27:39 INFO - TEST-INFO | started process 1749 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpeFmX4z/profile http://localhost:49284/getInfo.html) 14:27:41 INFO - PROCESS | 1749 | __metrics Screen width/height:1600/1200 14:27:41 INFO - PROCESS | 1749 | colorDepth:24 14:27:41 INFO - PROCESS | 1749 | Browser inner width/height: 1024/689 14:27:41 INFO - PROCESS | 1749 | __metrics 14:27:41 INFO - PROCESS | 1749 | 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" 14:27:41 INFO - PROCESS | 1749 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:27:42 INFO - TEST-INFO | 1749: exit 0 14:27:42 INFO - Browser initialized. 14:27:42 INFO - Running cycle 1/1 for dromaeo_css test... 14:27:42 INFO - TEST-INFO | started process 1752 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpeFmX4z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:27:54 INFO - PROCESS | 1752 | RSS: Main: 232370176 14:27:54 INFO - PROCESS | 1752 | 14:28:46 INFO - PROCESS | 1752 | 1473283726297 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] 14:30:58 INFO - PROCESS | 1752 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49284/tests/dromaeo/cssquery-ext.html) 14:30:59 INFO - PROCESS | 1752 | RSS: Main: 386912256 14:30:59 INFO - PROCESS | 1752 | 14:34:02 INFO - PROCESS | 1752 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-ext.html (next: http://localhost:49284/tests/dromaeo/cssquery-jquery.html) 14:34:03 INFO - PROCESS | 1752 | RSS: Main: 370196480 14:34:03 INFO - PROCESS | 1752 | 14:37:05 INFO - PROCESS | 1752 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49284/tests/dromaeo/cssquery-mootools.html) 14:37:06 INFO - PROCESS | 1752 | RSS: Main: 349806592 14:37:06 INFO - PROCESS | 1752 | 14:40:08 INFO - PROCESS | 1752 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49284/tests/dromaeo/cssquery-prototype.html) 14:40:09 INFO - PROCESS | 1752 | RSS: Main: 336179200 14:40:09 INFO - PROCESS | 1752 | 14:43:12 INFO - PROCESS | 1752 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49284/tests/dromaeo/cssquery-yui.html) 14:43:13 INFO - PROCESS | 1752 | RSS: Main: 323289088 14:43:13 INFO - PROCESS | 1752 | 14:46:16 INFO - PROCESS | 1752 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-yui.html (next: http://localhost:49284/tests/dromaeo/cssquery-dojo.html) 14:46:17 INFO - PROCESS | 1752 | RSS: Main: 474841088 14:46:17 INFO - PROCESS | 1752 | 14:46:17 INFO - PROCESS | 1752 | __start_tp_report 14:46:17 INFO - PROCESS | 1752 | _x_x_mozilla_page_load 14:46:17 INFO - PROCESS | 1752 | _x_x_mozilla_page_load_details 14:46:17 INFO - PROCESS | 1752 | |i|pagename|runs| 14:46:17 INFO - PROCESS | 1752 | |0;dojo.html;2821.562657788043;3327.5674162358896;3361.5293858859764;3386.5597472328595;3430.142464383904;4510.706804057742;5522.171674248854;5628.802991895284;5691.117876729103;5731.45551172639;5658;5676.99999999999;5740.024195886699;5750.367459579443;5823.039198532248;4791.904161916759;5120.845622439096;5195;5223.373195216579;5231.189165679307;5258.374512676608;5320.9467905320835;5326.627136100475;5333.599980001496;5402.78388864445;5138.049460849755;5167.896642067138;5258.0535503609335;5269.683818970874;5286.286351342558;5205.9219111713355;5215.530602245778;5223.869403264949;5237.188235823453;5238.449962753894;4320.546342634009;5154.433012368637;5173.560247378978;5203.427622961472;5217.582593392557;5617.129344951539;5660.915086273668;5685.118806584986;5685.147227915804;5708.229389032511;6008.429199226067;6011.849703757397;6030.577859549833;6101.481374083208;6243.750249989995;6089.786857460012;6190.792795404898;6208.96895515524;6225.128482012522;6249.2813326467585;5586.608937374426;5680.488756011979;5694.202811606378;5712.457316554921;5756.251687280626;6288;6299.433051025429;6326.46225070865;6344.143540622049;6350.364963503613;6149.846253843601;6164.845878853064;6169.043798211284;6177;6180.6291622502795;6098.734805259241;6150.831342044997;6212.968935155295;6249.218847644044;6276.435120839055;6195.721192546346;6210.31686514483;6218.9378106219265;6220.875582488416;6229.1590635264565;6244.469220116341;6249.25008998923;6255.655938923312;6256.186695729526;6261.968690156611;4868.659193856467;4873.439554451213;4908.067467181296;4972.627052971042;5024.648274620815;4462.732236065846;4486;4491.663125265618;4509.616682582017;4523.932141017887;5588;5595.048841696921;5621.353544342372;5625.409332020161;5721.39925307845;5469.371022332403;6176.66028368444;6289;6306.590071645328;6318.842028949313;5598.356189038232;5627.774889004476;5633.464820842013;5647.322321321468;5650.66096034231;5327.9733601331745;5411.621186516984;5421.539169170585;5440.401555828856;5447.4552544746;6303.180586523723;6337.6197428154455;6338.334474880071;6341.841453963595;6348.047792831112;6252.781152659635;6290.654014029089;6301.117843501822;6326.2092238470195;6331.651759153291;6228.408301211378;6271.122042913905;6272.5922815016875;6327.11420401135;6344.460720838596;6189.535784816247;6221.3156552780065;6221.96889015552;6222.657753823583;6230.657313847782;6268.780592679417;6279.968600157153;6286.622802631857;6289.591176573508;6306.527010474141;5947.91078133837;5988.221531200916;6003.369646187262;6124.0201567748945;6142.969285153546;6108.159949115189;6116.554003752164;6129.295131059986;6143.327652471189;6145.72344244519;5923.289205295392;5956.195913551615;5966.880662386641;5982.102684597346;6025.578209525291;5924.940750592439;5975.850603734942;5981.910271345846;6142.662153581596;6146.0473626587955;5865.442782935615;5893.528517718658;5893.793717219876;5931.228940237742;5942.286925568959 14:46:17 INFO - PROCESS | 1752 | |1;ext.html;503.78840886827527;527.2407732864674;528.709947727545;530.1570502900388;538.6014349381456;27534.311642208955;31205.219869503246;31942.881999130033;31985.840070799673;32101.19747006326;32882.342353152926;42353.7882310588;43566.78216608921;43624.34563481542;45520.772396137974;9913.702588922344;10056.849147262761;10068.395896246213;10077.14344280737;10093.848592271122;9813.067758562926;9932.106110450059;9934.056264654882;9936.453495057769;9942.751431214241;9905.950470247639;9964.601415943354;9970.90029099707;9976.102150806426;9996.250281228937;9877.259205559576;9907.504624768804;9941.552630131662;9943.254255930762;9967.601295948154;9829.459379734111;9956.601735930553;9960.80078398428;9964.601415943354;9972.202223822149;40914.386284205466;41182.38226426606;41316.79341603273;41321.58678413238;41344;3385.2044769479153;3426.674465925733;3458.654134586521;3548.7870727756417;3893.2602805467154;24061;29708.55437168444;30317.848410758026;30318.84840575805;30356.54465183024;3163.1933856866353;3220.436423625856;3282.573265475473;3361.697447229761;3366.107981384935;492091;493047;493591;493996;495984.9999999964;58028.12957805636;58384;58457.12314315289;58786.11820822691;58906.11640825391;60701;60746.69626651838;60753.69623151856;60894.69552652208;60972;16035.919820401057;16254.18729063542;16262.51212463628;16262.51212463628;16264.430744923871;12652.240865548096;12706.174098683556;12715.364231788373;12716.936415317865;12725.745485090247;15346;15937.20313984296;15947.521574352788;15951.208538505714;16002;6090.33006369299;6447.806565803034;7186.4549121939035;7202.351788339075;7207.063081799332;897.7979954510314;908.4594666173616;908.7682640926521;994.080475560104;1017.6489111256604;26255;29907.551386729217;30220;30819.229519262197;30941.845290773403;1033.8914413986574;1084.4740300954031;1092.257265059751;1120.456578559398;1137.3801278303179;15654.608634783994;15655.452059177875;15815.367385304458;15863.444779432895;15921.128325167338;659390;662839;663757;664709.6764516146;666637;58051;58145.418545814;58250.8349833001;58438.415615843296;58439.70780146157;1056.8309070548676;1368.8699573540498;1382.6266907935003;1390.0964373157124;1414.9575512734634;30169.28476437131;31095.22261943379;31244.531332029583;31988.68011319857;32235.35529289448;48771.75614122049;49449.75275123601;49517.50482495129;49549.75225123851;49572.50427495679;559346;563233;563381;564703;564749;31833;33849.49225761566;34786.478202826474;38620;39564.010899726585;46660.300095497914;47211.05577888492;47321.76339118282;47535.286970694775;47844.521554784005;37585.81207093947;38259;38410.80794596009;39517.60482395139;39833.60166398299;34617.30765384628;34692;38618.62792639183;39440.01399965024;39442;37184.6281537192;37585.624143758214;37585.897225984925;38089.23821523499;38193.4270985941;31814.681853181173;32412.513812292364;33097.50353744648;34155.82922085374;34247;25070.49859002773;25097.87451062733;25349.619755703312;25350.49299014046;25740.227793166236 14:46:17 INFO - PROCESS | 1752 | |2;jquery.html;348.5590727729421;370.7664171571909;372.5994555852458;379.9468074469574;400.4813766172806;14606;15135.318910649019;20066.799332006674;20609.896950515227;20684.68972965405;19842.007899605032;20279.797202027974;20357.898210508927;20397.59204815903;20418.979051047463;4145.689073319498;5869.266341707297;6138.539609529304;6149.200603921484;6149.2313460817395;5608.327000759894;6741.494387920926;6835.872081106611;6907.309269073103;6908.654567271641;5835.970820145893;6132.908006379907;6211.9068213976825;6809.114815074058;6829.795106146798;5987.251593550806;6157.938420615804;6200.503959683215;6201.193844800192;6210.254769427653;4971.701697898102;5989.520838332923;5992.8501787455225;6000.33996260411;6003.8198854034445;12572.622821315284;12801.359932003363;17668.734968975477;24118.517629647486;24470.755292447204;1780.3501721871423;2067.989660051705;2124.5007423255524;2180.4221881201493;2334.4280651240383;9611.567479463441;9713.61145554177;9800;10026;10295.176385889112;1822.334847780564;1883.6138591588692;1890.5840715042668;1912.8660993730443;1915.9137838797287;115739;129293.35353323267;129421.35289323587;131679;132470.38942468708;9506.95246523763;10652.786944261072;11183.161262905314;11195.328280303207;11348.716282092848;14229.928850355682;14236.644083897985;14240.43038278478;14250.786238206436;14252.358643860844;8120.512769233865;8414.705485308108;8420.8315833683;8532.189442003;8537.499625153781;7548.169701332849;8352.415330926777;8364.832703345899;8506.404551681446;8598.990353376394;3967.9603203968168;4151.484708081529;4278.850240241577;4448.532904045029;4466.843660471868;1843.2811203630595;1889.1782074797231;1889.2254175787862;1904.1050706168078;1928.0745242283501;366.98165091745307;401.39589917174465;425.07334011854454;819.19309480162;900.8694088918365;11263.66209013731;11508.309501429942;11806.40967951599;11875.346855922835;11957.043436525231;413.92342416653014;421.93040641972453;429.84740417152085;433.8416477985576;446.60475479200494;5674.2339784129435;5885.411458854081;6043.7280322385595;6055.6063855849225;6108.297545782292;515468;584778;595529;601650;607814;121998;137137;138325;139520;141122;436.2627160099422;445.36757803918516;446.02988500012344;962.3119469579284;1038.3043360948184;16587.2631015881;18794.718079229096;19621.215151393786;19685.901570492628;19719;46494;49936.014076903026;50476.74761626168;57548.84902302014;59076.40923590709;77542.44915101779;78179.69661610223;83470.58264708638;85286.72069918831;86454;6708.396244338033;7763.611819408894;7777.727824484125;7972.720954766555;8159;8933.096743558499;11186;11983.64049078529;12327.876721232673;12342.691432714253;9199.678011269574;10620.787584248426;10962.287451315638;11050.613228536962;11108.166887483632;10241.74395640115;10610;10667.786644267226;10957.013868751532;10971.451427428437;10779.568817247537;10888.346699198073;10941.507632156732;11167.664970050912;11204.43977801123;7847.215278472336;7978.481398709066;7996.6401511930935;8162.102168761313;8271.17288271168;10667;10771.19216058783;11004.504797284302;11253.831192532283;11347.546098156312 14:46:17 INFO - PROCESS | 1752 | |3;mootools.html;1378.6691194113403;1532.908025518469;1569.9215039248036;1789.1412122181353;1941.6505029094758;6246.906296405557;8600.74197774067;8933.330000249991;8935.329850261232;9043.095690430953;8810.823783524325;8903.599338029788;8920.197182253596;8928.866067008983;8936.776580585489;2130.371540395587;2328.4178955261186;2393.198278576679;2419.52819200256;2426.33275849142;2354.75275096115;2388.6894703688495;2410.783029527342;2414.6498757680124;2415.577273977047;2359.315798418457;2361.327021798788;2363.5627408929317;2408.2654790289043;2408.6989126359204;2349.4596242864236;2375.2992867104244;2381.7975472084895;2388.749181335952;2392.6052201386924;2331.6269396896587;2365.8935347909387;2370.2178281167176;2370.4192472844093;2371.9169829056073;8719.171678690578;8891.377603567753;8915.3313501487;8975.294694008118;9003.774905627346;5288.2596436498925;5507.614466987313;5511.944880551183;5524.696141712204;5526.751296191641;3692.8892133236045;3702.9629703702885;3754.887353379403;3766.5103536540073;3773.471713960048;1080.7946490166921;1095.4741723972459;1096.7587130831205;1103.0679076180616;1103.349024075791;10095.495225238783;11859.169858109935;11968.880311196863;11973.401329933555;11980.880191198064;4426.424564806619;4505.864824055284;4673.579377856009;4687.601553867891;4702.482726900038;9255.305852060988;9257.259419246579;9257.67598134062;9271.629134834666;9302.720918372324;4579.862604121881;5309.681419114865;5323.9884421959705;5327.680339179662;5343.0649636314265;4298.398224248608;4420.513743488214;4624.1676498230645;4973.42805577356;4998.625103117281;3733.7199710021855;3771.321162190777;3878.204967981613;3886.84452621892;3887.2419878123496;1024.99487502562;1166.5742004168417;1192.5229908036854;1194.3550482739397;1202.0804084875078;891.4695756025154;905.6920646979927;916.1617120334819;937.8265020971143;943.4480828715278;5766.423357664284;5957.4042595740075;6004.249468816398;6119.204503414528;6182.350853160443;432.7295440349781;447.98432054877924;448.1015563794571;448.4125795208288;456.8560903315512;3592.8203589820405;3820.255050265227;3893.065664240562;3903.6681882039775;3934.6852251819787;10040.748981275527;10511.421871797125;10517.684469465928;10539.57841686324;10556.208284378703;10475.319104258198;10480.68557943263;10483.266171367932;10490.21323400748;10494.527746251437;7981.720639777696;7999.600019998977;8065.354771618257;8093.109757926623;8123.350131989545;4724.338592596971;5641.774329026793;5802.984477716298;5809.215755872903;5830.53355731549;9644.710658680251;9750.80498390014;9769.876464206707;9812.313138080266;9828.164606008284;9655.420674759536;9786.217102631916;9870.407775533491;9897.132634299118;9985;6091.634501929899;6514.57655252407;6701.128853249046;6846.144231971004;6905.551139175937;7976.082750483537;8031.437799353989;8038.598070096588;8040.798980025546;8183.95908020456;7376.483646144933;7581.924180758342;7615.162332143571;7648.34989025939;7750.651220694969;7229.819254518679;7390.076240469942;7457.552546847207;7461.030066091374;7606.733764318223;5625.296837895263;7057.012018317542;7066;7072.646367681698;7074.3633073023675;4869.902601947871;5022.397312322545;5056.64603477753;5101.7194054327365;5197.272381866466;5001.674891132065;5014.323066386137;5166.509181627739;5200.089984252816;5285.260063591178 14:46:17 INFO - PROCESS | 1752 | |4;prototype.html;298.4523399561804;298.6490873223962;307.7169004515844;312.31135346560836;312.5951892299472;4214.7471151730915;5060.41805192403;5080.491950804918;5131.690739167376;5191.610629202815;4435.556444355563;4791.897863491399;4803.687760295578;4947.901041979158;5031.949680503194;3615.566132064156;3626.383514802477;3872.3610604250334;3915.588863169369;3924.548676902164;3368.0401085690582;3737.7570457920283;3853.9614603854025;3912.76523408595;3913.4912461380122;3303.5209894565273;3722.236941427001;3758.9624103759024;3909.706771992098;3911.2177564487215;2977.478941185284;3594.370985077601;3799.639034291738;3905.785181814999;3944;3417.5215469834243;3432.2449061206744;3434.244466217428;3444.431668774649;3466.254755227613;4456.598906098467;4734.928976065327;4916.336294600254;4979.900401991976;5034.270030845543;3641.3236271340543;4407.449068866392;4466.481396325233;4482.439695038121;4488.910221795579;5228.163493840967;5264.842054738364;5306.734663266821;5310.548603368718;5323.600729945231;3468.6357932417236;3762.398016317376;3802;3806.657400833938;3810.2950954073585;4835.564799168056;4916.287138364917;5265.657732247391;5577.721113944286;5615.922108819681;3224.806511609264;3808.5429748430365;3816.790076545761;3820.7707537547835;3833.025991614321;3423.6747508986605;3729.6456836601023;3926.862559810393;3953.327934251184;3959.544652365015;4143.0678097427835;4272.124214536013;4494.056248187917;4520.886977825515;4529.886752831206;4221.6200541951375;4532.66005049616;4876.634252431082;5418.566514678816;5513.7794488220825;1199.650393307529;1281.3721276574597;1297.2605614799663;1301.1607513154152;1304.386938139092;722.407625746893;782.9725959591387;783.2338077634897;809.7125520440277;835.3484282259847;4010.7794071325775;4926.014797040606;4939.863831318817;4952.752362381866;5026.874328141826;4487.618552423015;5029.924551131736;5398.757055932492;5405.621606487508;5442.034091420185;3957.960420395817;4332.653387728974;4692.859214223578;4910.680805747615;4927.846178445798;2361.480474295652;2488.191337815217;2742.9588556171675;2808.0312292259136;2837.3474100956896;4848.393950756155;4932.435133730512;5160.354955630546;5571.804986825442;5642.492175704207;4880.341153944172;5038.496150384932;5190.480951904779;5571.554275657938;5572.804951826748;5581.916271255935;5614.466625670555;5629.324481062299;5633.493205435717;5644.842807224509;4412.874716947158;4573.7713114344815;5074.7716352764955;5447.754851031792;5470.030788916067;4269.423627810206;5068.670536415121;5363.490468405495;5363.812266570651;5419.945800541944;4711.246200607886;5121.948780512147;5596.216529685766;5597.888042239214;5622.493975542221;3643.6154261380507;3706.870259540903;3717.7955212463576;3719.3119272934596;3725.6274372562525;4482.843100491467;5137.5889928806355;5306.126527634771;5440.945590544044;5444.047291724011;4652.744099074584;5248.212768084665;5302.761375738178;5312.203169524602;5348.839534813962;5233.476652334888;5251.109600543878;5261.500157485033;5262.947370526245;5269.288646032736;4283.507396649301;4653.627709783277;5124.256982737556;5158.761897144734;5183.663061900965;4456.910861782681;5025;5144.176931690911;5221.1283793912435;5282.260483532232;4848.224284114524;4959.504049595012;5153.252778347193;5276.546405921265;5278.5249327560705 14:46:17 INFO - PROCESS | 1752 | |5;yui.html;706.3819158236543;740.2375553180226;761.3908872901677;786.5595266650674;817.7955511122219;13387.59837204883;16181.91909040456;16186.595335116628;16229.350825966962;16248.999999999985;14586;14742.189179595118;14764.926175369135;14770.113793172417;14773.965822392436;662.6951602262941;680.9012693159514;831.5509624802614;839.1734141870253;858.8196478739472;841.032812265893;842.2083241752763;845.1970627903517;849.8087930215714;864.6800683747023;885.7387070814125;885.7608445719652;886.6320477002037;886.8226354729047;887.1749273175943;691.9238883722786;713.636045616734;718.2673672853712;881.894172699277;887.0198430734043;536.372444240236;590.3329237961076;652.9771457998947;656.8226578823738;679.1476696745602;15032.624184395478;15054.698906021928;15065.548033559011;15079.924600377037;15086.849131508654;13818.792718109236;14161.716765664733;14244.857551424457;14272.714545709028;14273.286335683175;12910.87089129106;13017.86982130176;13073.934630326883;13209.603711888658;13586.25275609841;4594.402727645384;4761.2143996240575;4765.571098601143;4793.664443488957;4863.902721945541;120995.79004209934;123903;127141.72858271391;129808;129910;10979.890201098047;11165.553377864795;11311.434428278553;11377.886221137682;11590.76818463626;22080.668789968164;22273.220437284617;22434.10263589471;22631.88684056602;22663.660045099336;7938.801529961682;7952.3638108951145;7965.800854978672;7978.561179135203;8206.8358632827;4166.645835103989;4472.1502914446155;4481.305397663367;4502.482214545304;4581.175388430048;3455.9654403455643;3564.3584154851856;3567.9108022299133;3568.8215589220436;3575.213453040327;558.6368860240794;628.6008384675764;628.8616504369123;633.5533448918501;639.0222958872932;588.3702145058165;607.5746977115948;613.929398119222;615.9384061593894;626.2297374229723;8491.150884911583;8724.258438032712;8814.691485797966;8884.200421962054;8940.898888761136;539.9136138217866;605.0086784031738;607.271274470637;625.3871206217932;639.1435476461564;3590.784552926833;4132;4404.889877753082;4408.162449134654;4496.168208881368;126899;127832.90757025173;128435;141061.29469352792;169355;85045.57477212575;119060;125251;128859;129203.35398323137;81580.18419815727;82325.58837205895;91299.54350228206;111205;115728.05631662127;8856.468611883307;9372.484513351832;9586.520673966133;9752.268579856398;9833.360831545926;50539.4838154856;52523;55559;59477.7026114884;61905.69047154736;91127.54436227777;93553.5322323384;93743;94409.75156118129;98194.01805981848;11410.315381077162;14338.56984290473;14506.1296322221;14520.128792272497;14842.257887105818;14107.858921410654;14831.913873469477;15154.461814645998;15344.462943796914;15495.612609684847;11525.193236473366;13828.86171138316;14002.369893355028;14435;14762.188079655722;13927.930360348133;14190.361433735301;14202.573922782332;14276.500322488662;14283.143011419348;13565.728685426433;13675.863241367459;13847.79228311556;14399.42402303938;14635.34140963638;9776.902230977883;11560.768784624428;11679.883201168219;11821.586244481401;12089.637310880687;11466;11637.709057273298;11852.881471185177;11899.286042837457;11913.52345906154 14:46:17 INFO - PROCESS | 1752 | __end_tp_report 14:46:17 INFO - PROCESS | 1752 | __start_cc_report 14:46:17 INFO - PROCESS | 1752 | _x_x_mozilla_cycle_collect,1375 14:46:17 INFO - PROCESS | 1752 | __end_cc_report 14:46:17 INFO - PROCESS | 1752 | __startTimestamp1473284777204__endTimestamp 14:46:17 INFO - PROCESS | 1752 | 14:46:17 INFO - PROCESS | 1752 | ------- Summary: start ------- 14:46:17 INFO - PROCESS | 1752 | Number of tests: 6 14:46:17 INFO - PROCESS | 1752 | 14:46:17 INFO - PROCESS | 1752 | [#0] dojo.html Cycles:165 Average:5721.67 Median:5942.29 stddev:661.24 (11.1%) stddev-sans-first:622.90 14:46:17 INFO - PROCESS | 1752 | Values: 2821.6 3327.6 3361.5 3386.6 3430.1 4510.7 5522.2 5628.8 5691.1 5731.5 5658.0 5677.0 5740.0 5750.4 5823.0 4791.9 5120.8 5195.0 5223.4 5231.2 5258.4 5320.9 5326.6 5333.6 5402.8 5138.0 5167.9 5258.1 5269.7 5286.3 5205.9 5215.5 5223.9 5237.2 5238.4 4320.5 5154.4 5173.6 5203.4 5217.6 5617.1 5660.9 5685.1 5685.1 5708.2 6008.4 6011.8 6030.6 6101.5 6243.8 6089.8 6190.8 6209.0 6225.1 6249.3 5586.6 5680.5 5694.2 5712.5 5756.3 6288.0 6299.4 6326.5 6344.1 6350.4 6149.8 6164.8 6169.0 6177.0 6180.6 6098.7 6150.8 6213.0 6249.2 6276.4 6195.7 6210.3 6218.9 6220.9 6229.2 6244.5 6249.3 6255.7 6256.2 6262.0 4868.7 4873.4 4908.1 4972.6 5024.6 4462.7 4486.0 4491.7 4509.6 4523.9 5588.0 5595.0 5621.4 5625.4 5721.4 5469.4 6176.7 6289.0 6306.6 6318.8 5598.4 5627.8 5633.5 5647.3 5650.7 5328.0 5411.6 5421.5 5440.4 5447.5 6303.2 6337.6 6338.3 6341.8 6348.0 6252.8 6290.7 6301.1 6326.2 6331.7 6228.4 6271.1 6272.6 6327.1 6344.5 6189.5 6221.3 6222.0 6222.7 6230.7 6268.8 6280.0 6286.6 6289.6 6306.5 5947.9 5988.2 6003.4 6124.0 6143.0 6108.2 6116.6 6129.3 6143.3 6145.7 5923.3 5956.2 5966.9 5982.1 6025.6 5924.9 5975.9 5981.9 6142.7 6146.0 5865.4 5893.5 5893.8 5931.2 5942.3 14:46:17 INFO - PROCESS | 1752 | 14:46:17 INFO - PROCESS | 1752 | [#1] ext.html Cycles:180 Average:70392.20 Median:21666.64 stddev:154433.18 (712.8%) stddev-sans-first:154777.26 14:46:17 INFO - PROCESS | 1752 | Values: 503.8 527.2 528.7 530.2 538.6 27534.3 31205.2 31942.9 31985.8 32101.2 32882.3 42353.8 43566.8 43624.3 45520.8 9913.7 10056.8 10068.4 10077.1 10093.8 9813.1 9932.1 9934.1 9936.5 9942.8 9906.0 9964.6 9970.9 9976.1 9996.3 9877.3 9907.5 9941.6 9943.3 9967.6 9829.5 9956.6 9960.8 9964.6 9972.2 40914.4 41182.4 41316.8 41321.6 41344.0 3385.2 3426.7 3458.7 3548.8 3893.3 24061.0 29708.6 30317.8 30318.8 30356.5 3163.2 3220.4 3282.6 3361.7 3366.1 492091.0 493047.0 493591.0 493996.0 495985.0 58028.1 58384.0 58457.1 58786.1 58906.1 60701.0 60746.7 60753.7 60894.7 60972.0 16035.9 16254.2 16262.5 16262.5 16264.4 12652.2 12706.2 12715.4 12716.9 12725.7 15346.0 15937.2 15947.5 15951.2 16002.0 6090.3 6447.8 7186.5 7202.4 7207.1 897.8 908.5 908.8 994.1 1017.6 26255.0 29907.6 30220.0 30819.2 30941.8 1033.9 1084.5 1092.3 1120.5 1137.4 15654.6 15655.5 15815.4 15863.4 15921.1 659390.0 662839.0 663757.0 664709.7 666637.0 58051.0 58145.4 58250.8 58438.4 58439.7 1056.8 1368.9 1382.6 1390.1 1415.0 30169.3 31095.2 31244.5 31988.7 32235.4 48771.8 49449.8 49517.5 49549.8 49572.5 559346.0 563233.0 563381.0 564703.0 564749.0 31833.0 33849.5 34786.5 38620.0 39564.0 46660.3 47211.1 47321.8 47535.3 47844.5 37585.8 38259.0 38410.8 39517.6 39833.6 34617.3 34692.0 38618.6 39440.0 39442.0 37184.6 37585.6 37585.9 38089.2 38193.4 31814.7 32412.5 33097.5 34155.8 34247.0 25070.5 25097.9 25349.6 25350.5 25740.2 14:46:17 INFO - PROCESS | 1752 | 14:46:17 INFO - PROCESS | 1752 | [#2] jquery.html Cycles:180 Average:34370.68 Median:2184.22 stddev:97985.95 (4486.1%) stddev-sans-first:98227.52 14:46:17 INFO - PROCESS | 1752 | Values: 348.6 370.8 372.6 379.9 400.5 14606.0 15135.3 20066.8 20609.9 20684.7 19842.0 20279.8 20357.9 20397.6 20419.0 4145.7 5869.3 6138.5 6149.2 6149.2 5608.3 6741.5 6835.9 6907.3 6908.7 5836.0 6132.9 6211.9 6809.1 6829.8 5987.3 6157.9 6200.5 6201.2 6210.3 4971.7 5989.5 5992.9 6000.3 6003.8 12572.6 12801.4 17668.7 24118.5 24470.8 1780.4 2068.0 2124.5 2180.4 2334.4 9611.6 9713.6 9800.0 10026.0 10295.2 1822.3 1883.6 1890.6 1912.9 1915.9 115739.0 129293.4 129421.4 131679.0 132470.4 9507.0 10652.8 11183.2 11195.3 11348.7 14229.9 14236.6 14240.4 14250.8 14252.4 8120.5 8414.7 8420.8 8532.2 8537.5 7548.2 8352.4 8364.8 8506.4 8599.0 3968.0 4151.5 4278.9 4448.5 4466.8 1843.3 1889.2 1889.2 1904.1 1928.1 367.0 401.4 425.1 819.2 900.9 11263.7 11508.3 11806.4 11875.3 11957.0 413.9 421.9 429.8 433.8 446.6 5674.2 5885.4 6043.7 6055.6 6108.3 515468.0 584778.0 595529.0 601650.0 607814.0 121998.0 137137.0 138325.0 139520.0 141122.0 436.3 445.4 446.0 962.3 1038.3 16587.3 18794.7 19621.2 19685.9 19719.0 46494.0 49936.0 50476.7 57548.8 59076.4 77542.4 78179.7 83470.6 85286.7 86454.0 6708.4 7763.6 7777.7 7972.7 8159.0 8933.1 11186.0 11983.6 12327.9 12342.7 9199.7 10620.8 10962.3 11050.6 11108.2 10241.7 10610.0 10667.8 10957.0 10971.5 10779.6 10888.3 10941.5 11167.7 11204.4 7847.2 7978.5 7996.6 8162.1 8271.2 10667.0 10771.2 11004.5 11253.8 11347.5 14:46:17 INFO - PROCESS | 1752 | 14:46:17 INFO - PROCESS | 1752 | [#3] mootools.html Cycles:180 Average:5617.04 Median:5039.52 stddev:3145.80 (62.4%) stddev-sans-first:3138.49 14:46:17 INFO - PROCESS | 1752 | Values: 1378.7 1532.9 1569.9 1789.1 1941.7 6246.9 8600.7 8933.3 8935.3 9043.1 8810.8 8903.6 8920.2 8928.9 8936.8 2130.4 2328.4 2393.2 2419.5 2426.3 2354.8 2388.7 2410.8 2414.6 2415.6 2359.3 2361.3 2363.6 2408.3 2408.7 2349.5 2375.3 2381.8 2388.7 2392.6 2331.6 2365.9 2370.2 2370.4 2371.9 8719.2 8891.4 8915.3 8975.3 9003.8 5288.3 5507.6 5511.9 5524.7 5526.8 3692.9 3703.0 3754.9 3766.5 3773.5 1080.8 1095.5 1096.8 1103.1 1103.3 10095.5 11859.2 11968.9 11973.4 11980.9 4426.4 4505.9 4673.6 4687.6 4702.5 9255.3 9257.3 9257.7 9271.6 9302.7 4579.9 5309.7 5324.0 5327.7 5343.1 4298.4 4420.5 4624.2 4973.4 4998.6 3733.7 3771.3 3878.2 3886.8 3887.2 1025.0 1166.6 1192.5 1194.4 1202.1 891.5 905.7 916.2 937.8 943.4 5766.4 5957.4 6004.2 6119.2 6182.4 432.7 448.0 448.1 448.4 456.9 3592.8 3820.3 3893.1 3903.7 3934.7 10040.7 10511.4 10517.7 10539.6 10556.2 10475.3 10480.7 10483.3 10490.2 10494.5 7981.7 7999.6 8065.4 8093.1 8123.4 4724.3 5641.8 5803.0 5809.2 5830.5 9644.7 9750.8 9769.9 9812.3 9828.2 9655.4 9786.2 9870.4 9897.1 9985.0 6091.6 6514.6 6701.1 6846.1 6905.6 7976.1 8031.4 8038.6 8040.8 8184.0 7376.5 7581.9 7615.2 7648.3 7750.7 7229.8 7390.1 7457.6 7461.0 7606.7 5625.3 7057.0 7066.0 7072.6 7074.4 4869.9 5022.4 5056.6 5101.7 5197.3 5001.7 5014.3 5166.5 5200.1 5285.3 14:46:17 INFO - PROCESS | 1752 | 14:46:17 INFO - PROCESS | 1752 | [#4] prototype.html Cycles:180 Average:4266.93 Median:4797.79 stddev:1294.04 (27.0%) stddev-sans-first:1262.92 14:46:17 INFO - PROCESS | 1752 | Values: 298.5 298.6 307.7 312.3 312.6 4214.7 5060.4 5080.5 5131.7 5191.6 4435.6 4791.9 4803.7 4947.9 5031.9 3615.6 3626.4 3872.4 3915.6 3924.5 3368.0 3737.8 3854.0 3912.8 3913.5 3303.5 3722.2 3759.0 3909.7 3911.2 2977.5 3594.4 3799.6 3905.8 3944.0 3417.5 3432.2 3434.2 3444.4 3466.3 4456.6 4734.9 4916.3 4979.9 5034.3 3641.3 4407.4 4466.5 4482.4 4488.9 5228.2 5264.8 5306.7 5310.5 5323.6 3468.6 3762.4 3802.0 3806.7 3810.3 4835.6 4916.3 5265.7 5577.7 5615.9 3224.8 3808.5 3816.8 3820.8 3833.0 3423.7 3729.6 3926.9 3953.3 3959.5 4143.1 4272.1 4494.1 4520.9 4529.9 4221.6 4532.7 4876.6 5418.6 5513.8 1199.7 1281.4 1297.3 1301.2 1304.4 722.4 783.0 783.2 809.7 835.3 4010.8 4926.0 4939.9 4952.8 5026.9 4487.6 5029.9 5398.8 5405.6 5442.0 3958.0 4332.7 4692.9 4910.7 4927.8 2361.5 2488.2 2743.0 2808.0 2837.3 4848.4 4932.4 5160.4 5571.8 5642.5 4880.3 5038.5 5190.5 5571.6 5572.8 5581.9 5614.5 5629.3 5633.5 5644.8 4412.9 4573.8 5074.8 5447.8 5470.0 4269.4 5068.7 5363.5 5363.8 5419.9 4711.2 5121.9 5596.2 5597.9 5622.5 3643.6 3706.9 3717.8 3719.3 3725.6 4482.8 5137.6 5306.1 5440.9 5444.0 4652.7 5248.2 5302.8 5312.2 5348.8 5233.5 5251.1 5261.5 5262.9 5269.3 4283.5 4653.6 5124.3 5158.8 5183.7 4456.9 5025.0 5144.2 5221.1 5282.3 4848.2 4959.5 5153.3 5276.5 5278.5 14:46:17 INFO - PROCESS | 1752 | 14:46:17 INFO - PROCESS | 1752 | [#5] yui.html Cycles:180 Average:24425.46 Median:3861.39 stddev:38439.99 (995.5%) stddev-sans-first:38506.57 14:46:17 INFO - PROCESS | 1752 | Values: 706.4 740.2 761.4 786.6 817.8 13387.6 16181.9 16186.6 16229.4 16249.0 14586.0 14742.2 14764.9 14770.1 14774.0 662.7 680.9 831.6 839.2 858.8 841.0 842.2 845.2 849.8 864.7 885.7 885.8 886.6 886.8 887.2 691.9 713.6 718.3 881.9 887.0 536.4 590.3 653.0 656.8 679.1 15032.6 15054.7 15065.5 15079.9 15086.8 13818.8 14161.7 14244.9 14272.7 14273.3 12910.9 13017.9 13073.9 13209.6 13586.3 4594.4 4761.2 4765.6 4793.7 4863.9 120995.8 123903.0 127141.7 129808.0 129910.0 10979.9 11165.6 11311.4 11377.9 11590.8 22080.7 22273.2 22434.1 22631.9 22663.7 7938.8 7952.4 7965.8 7978.6 8206.8 4166.6 4472.2 4481.3 4502.5 4581.2 3456.0 3564.4 3567.9 3568.8 3575.2 558.6 628.6 628.9 633.6 639.0 588.4 607.6 613.9 615.9 626.2 8491.2 8724.3 8814.7 8884.2 8940.9 539.9 605.0 607.3 625.4 639.1 3590.8 4132.0 4404.9 4408.2 4496.2 126899.0 127832.9 128435.0 141061.3 169355.0 85045.6 119060.0 125251.0 128859.0 129203.4 81580.2 82325.6 91299.5 111205.0 115728.1 8856.5 9372.5 9586.5 9752.3 9833.4 50539.5 52523.0 55559.0 59477.7 61905.7 91127.5 93553.5 93743.0 94409.8 98194.0 11410.3 14338.6 14506.1 14520.1 14842.3 14107.9 14831.9 15154.5 15344.5 15495.6 11525.2 13828.9 14002.4 14435.0 14762.2 13927.9 14190.4 14202.6 14276.5 14283.1 13565.7 13675.9 13847.8 14399.4 14635.3 9776.9 11560.8 11679.9 11821.6 12089.6 11466.0 11637.7 11852.9 11899.3 11913.5 14:46:17 INFO - PROCESS | 1752 | -------- Summary: end -------- 14:46:17 INFO - PROCESS | 1752 | 14:46:17 INFO - TEST-INFO | 1752: exit 0 14:46:17 INFO - TEST-OK | dromaeo_css | took 1118501ms 14:46:17 INFO - TEST-START | kraken 14:46:17 INFO - operating with platform_type : mac_ 14:46:17 INFO - Initialising browser for kraken test... 14:46:17 INFO - TEST-INFO | started process 1873 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpeX3i_R/profile http://localhost:49284/getInfo.html) 14:46:20 INFO - PROCESS | 1873 | __metrics Screen width/height:1600/1200 14:46:20 INFO - PROCESS | 1873 | colorDepth:24 14:46:20 INFO - PROCESS | 1873 | Browser inner width/height: 1024/689 14:46:20 INFO - PROCESS | 1873 | __metrics 14:46:20 INFO - PROCESS | 1873 | 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" 14:46:20 INFO - PROCESS | 1873 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:46:21 INFO - TEST-INFO | 1873: exit 0 14:46:21 INFO - Browser initialized. 14:46:21 INFO - Running cycle 1/1 for kraken test... 14:46:21 INFO - TEST-INFO | started process 1874 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpeX3i_R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:46:33 INFO - PROCESS | 1874 | RSS: Main: 232419328 14:46:33 INFO - PROCESS | 1874 | 14:46:35 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection) 14:46:36 INFO - PROCESS | 1874 | RSS: Main: 291958784 14:46:36 INFO - PROCESS | 1874 | 14:46:39 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-dft) 14:46:40 INFO - PROCESS | 1874 | RSS: Main: 287621120 14:46:40 INFO - PROCESS | 1874 | 14:46:43 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-fft) 14:46:44 INFO - PROCESS | 1874 | RSS: Main: 376762368 14:46:44 INFO - PROCESS | 1874 | 14:46:46 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator) 14:46:46 INFO - PROCESS | 1874 | RSS: Main: 278806528 14:46:46 INFO - PROCESS | 1874 | 14:46:48 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:46:49 INFO - PROCESS | 1874 | RSS: Main: 284176384 14:46:49 INFO - PROCESS | 1874 | 14:46:52 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom) 14:46:52 INFO - PROCESS | 1874 | RSS: Main: 320856064 14:46:52 INFO - PROCESS | 1874 | 14:46:55 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate) 14:46:56 INFO - PROCESS | 1874 | RSS: Main: 322412544 14:46:56 INFO - PROCESS | 1874 | 14:46:59 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial) 14:46:59 INFO - PROCESS | 1874 | RSS: Main: 320880640 14:46:59 INFO - PROCESS | 1874 | 14:47:01 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:47:02 INFO - PROCESS | 1874 | RSS: Main: 298225664 14:47:02 INFO - PROCESS | 1874 | 14:47:03 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:47:04 INFO - PROCESS | 1874 | RSS: Main: 304402432 14:47:04 INFO - PROCESS | 1874 | 14:47:06 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:47:07 INFO - PROCESS | 1874 | RSS: Main: 306667520 14:47:07 INFO - PROCESS | 1874 | 14:47:10 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:47:11 INFO - PROCESS | 1874 | RSS: Main: 321101824 14:47:11 INFO - PROCESS | 1874 | 14:47:13 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:47:14 INFO - PROCESS | 1874 | RSS: Main: 317206528 14:47:14 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49284/tests/kraken/driver.html?testName=ai-astar) 14:47:16 INFO - PROCESS | 1874 | RSS: Main: 360857600 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | __start_tp_report 14:47:16 INFO - PROCESS | 1874 | _x_x_mozilla_page_load 14:47:16 INFO - PROCESS | 1874 | _x_x_mozilla_page_load_details 14:47:16 INFO - PROCESS | 1874 | |i|pagename|runs| 14:47:16 INFO - PROCESS | 1874 | |0;ai-astar;111;112;117;113;114;112;118;161;112;121 14:47:16 INFO - PROCESS | 1874 | |1;audio-beat-detection;145;108;133;162;108;128;140;135;198;123 14:47:16 INFO - PROCESS | 1874 | |2;audio-dft;184;185;183;184;182;183;181;185;183;183 14:47:16 INFO - PROCESS | 1874 | |3;audio-fft;79;75;78;153;59;59;57;70;146;58 14:47:16 INFO - PROCESS | 1874 | |4;audio-oscillator;82;81;131;70;79;89;114;68;69;116 14:47:16 INFO - PROCESS | 1874 | |5;imaging-gaussian-blur;88;92;88;86;87;84;94;86;87;84 14:47:16 INFO - PROCESS | 1874 | |6;imaging-darkroom;93;93;94;94;92;94;93;93;93;93 14:47:16 INFO - PROCESS | 1874 | |7;imaging-desaturate;126;131;87;89;84;85;86;85;85;87 14:47:16 INFO - PROCESS | 1874 | |8;json-parse-financial;53;53;54;53;53;55;54;53;54;54 14:47:16 INFO - PROCESS | 1874 | |9;json-stringify-tinderbox;65;72;67;73;72;74;70;71;70;70 14:47:16 INFO - PROCESS | 1874 | |10;stanford-crypto-aes;89;88;83;86;84;86;90;89;87;89 14:47:16 INFO - PROCESS | 1874 | |11;stanford-crypto-ccm;139;138;136;138;139;136;136;136;188;157 14:47:16 INFO - PROCESS | 1874 | |12;stanford-crypto-pbkdf2;167;169;169;167;168;169;172;170;166;168 14:47:16 INFO - PROCESS | 1874 | |13;stanford-crypto-sha256-iterative;67;65;69;68;67;67;66;68;66;65 14:47:16 INFO - PROCESS | 1874 | __end_tp_report 14:47:16 INFO - PROCESS | 1874 | __start_cc_report 14:47:16 INFO - PROCESS | 1874 | _x_x_mozilla_cycle_collect,2069 14:47:16 INFO - PROCESS | 1874 | __end_cc_report 14:47:16 INFO - PROCESS | 1874 | __startTimestamp1473284836485__endTimestamp 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | ------- Summary: start ------- 14:47:16 INFO - PROCESS | 1874 | Number of tests: 14 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#0] ai-astar Cycles:10 Average:119.10 Median:115.50 stddev:15.07 (13.1%) stddev-sans-first:15.70 14:47:16 INFO - PROCESS | 1874 | Values: 111.0 112.0 117.0 113.0 114.0 112.0 118.0 161.0 112.0 121.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:137.50 stddev:26.64 (19.4%) stddev-sans-first:28.14 14:47:16 INFO - PROCESS | 1874 | Values: 145.0 108.0 133.0 162.0 108.0 128.0 140.0 135.0 198.0 123.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.25 (0.7%) stddev-sans-first:1.30 14:47:16 INFO - PROCESS | 1874 | Values: 184.0 185.0 183.0 184.0 182.0 183.0 181.0 185.0 183.0 183.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#3] audio-fft Cycles:10 Average:83.40 Median:64.50 stddev:35.89 (55.6%) stddev-sans-first:38.03 14:47:16 INFO - PROCESS | 1874 | Values: 79.0 75.0 78.0 153.0 59.0 59.0 57.0 70.0 146.0 58.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:74.50 stddev:22.42 (30.1%) stddev-sans-first:23.60 14:47:16 INFO - PROCESS | 1874 | Values: 82.0 81.0 131.0 70.0 79.0 89.0 114.0 68.0 69.0 116.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:87.50 stddev:3.20 (3.7%) stddev-sans-first:3.40 14:47:16 INFO - PROCESS | 1874 | Values: 88.0 92.0 88.0 86.0 87.0 84.0 94.0 86.0 87.0 84.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 14:47:16 INFO - PROCESS | 1874 | Values: 93.0 93.0 94.0 94.0 92.0 94.0 93.0 93.0 93.0 93.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:85.50 stddev:18.01 (21.1%) stddev-sans-first:15.07 14:47:16 INFO - PROCESS | 1874 | Values: 126.0 131.0 87.0 89.0 84.0 85.0 86.0 85.0 85.0 87.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.70 (1.3%) stddev-sans-first:0.71 14:47:16 INFO - PROCESS | 1874 | Values: 53.0 53.0 54.0 53.0 53.0 55.0 54.0 53.0 54.0 54.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.50 stddev:2.72 (3.8%) stddev-sans-first:2.06 14:47:16 INFO - PROCESS | 1874 | Values: 65.0 72.0 67.0 73.0 72.0 74.0 70.0 71.0 70.0 70.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:88.50 stddev:2.33 (2.6%) stddev-sans-first:2.37 14:47:16 INFO - PROCESS | 1874 | Values: 89.0 88.0 83.0 86.0 84.0 86.0 90.0 89.0 87.0 89.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:138.50 stddev:16.61 (12.0%) stddev-sans-first:17.50 14:47:16 INFO - PROCESS | 1874 | Values: 139.0 138.0 136.0 138.0 139.0 136.0 136.0 136.0 188.0 157.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.50 Median:169.00 stddev:1.72 (1.0%) stddev-sans-first:1.73 14:47:16 INFO - PROCESS | 1874 | Values: 167.0 169.0 169.0 167.0 168.0 169.0 172.0 170.0 166.0 168.0 14:47:16 INFO - PROCESS | 1874 | 14:47:16 INFO - PROCESS | 1874 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.32 (2.0%) stddev-sans-first:1.39 14:47:16 INFO - PROCESS | 1874 | Values: 67.0 65.0 69.0 68.0 67.0 67.0 66.0 68.0 66.0 65.0 14:47:16 INFO - PROCESS | 1874 | -------- Summary: end -------- 14:47:16 INFO - PROCESS | 1874 | 14:47:17 INFO - TEST-INFO | 1874: exit 0 14:47:17 INFO - TEST-OK | kraken | took 59517ms 14:47:17 INFO - SUITE-END | took 1178s 14:47:17 INFO - Completed test suite (00:19:38) 14:47:17 INFO - javascript benchmark 14:47:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2821.562657788043, 3327.5674162358896, 3361.5293858859764, 3386.5597472328595, 3430.142464383904, 4510.706804057742, 5522.171674248854, 5628.802991895284, 5691.117876729103, 5731.45551172639, 5658.0, 5676.99999999999, 5740.024195886699, 5750.367459579443, 5823.039198532248, 4791.904161916759, 5120.845622439096, 5195.0, 5223.373195216579, 5231.189165679307, 5258.374512676608, 5320.9467905320835, 5326.627136100475, 5333.599980001496, 5402.78388864445, 5138.049460849755, 5167.896642067138, 5258.0535503609335, 5269.683818970874, 5286.286351342558, 5205.9219111713355, 5215.530602245778, 5223.869403264949, 5237.188235823453, 5238.449962753894, 4320.546342634009, 5154.433012368637, 5173.560247378978, 5203.427622961472, 5217.582593392557, 5617.129344951539, 5660.915086273668, 5685.118806584986, 5685.147227915804, 5708.229389032511, 6008.429199226067, 6011.849703757397, 6030.577859549833, 6101.481374083208, 6243.750249989995, 6089.786857460012, 6190.792795404898, 6208.96895515524, 6225.128482012522, 6249.2813326467585, 5586.608937374426, 5680.488756011979, 5694.202811606378, 5712.457316554921, 5756.251687280626, 6288.0, 6299.433051025429, 6326.46225070865, 6344.143540622049, 6350.364963503613, 6149.846253843601, 6164.845878853064, 6169.043798211284, 6177.0, 6180.6291622502795, 6098.734805259241, 6150.831342044997, 6212.968935155295, 6249.218847644044, 6276.435120839055, 6195.721192546346, 6210.31686514483, 6218.9378106219265, 6220.875582488416, 6229.1590635264565, 6244.469220116341, 6249.25008998923, 6255.655938923312, 6256.186695729526, 6261.968690156611, 4868.659193856467, 4873.439554451213, 4908.067467181296, 4972.627052971042, 5024.648274620815, 4462.732236065846, 4486.0, 4491.663125265618, 4509.616682582017, 4523.932141017887, 5588.0, 5595.048841696921, 5621.353544342372, 5625.409332020161, 5721.39925307845, 5469.371022332403, 6176.66028368444, 6289.0, 6306.590071645328, 6318.842028949313, 5598.356189038232, 5627.774889004476, 5633.464820842013, 5647.322321321468, 5650.66096034231, 5327.9733601331745, 5411.621186516984, 5421.539169170585, 5440.401555828856, 5447.4552544746, 6303.180586523723, 6337.6197428154455, 6338.334474880071, 6341.841453963595, 6348.047792831112, 6252.781152659635, 6290.654014029089, 6301.117843501822, 6326.2092238470195, 6331.651759153291, 6228.408301211378, 6271.122042913905, 6272.5922815016875, 6327.11420401135, 6344.460720838596, 6189.535784816247, 6221.3156552780065, 6221.96889015552, 6222.657753823583, 6230.657313847782, 6268.780592679417, 6279.968600157153, 6286.622802631857, 6289.591176573508, 6306.527010474141, 5947.91078133837, 5988.221531200916, 6003.369646187262, 6124.0201567748945, 6142.969285153546, 6108.159949115189, 6116.554003752164, 6129.295131059986, 6143.327652471189, 6145.72344244519, 5923.289205295392, 5956.195913551615, 5966.880662386641, 5982.102684597346, 6025.578209525291, 5924.940750592439, 5975.850603734942, 5981.910271345846, 6142.662153581596, 6146.0473626587955, 5865.442782935615, 5893.528517718658, 5893.793717219876, 5931.228940237742, 5942.286925568959], "value": 5677.944749264961, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.78840886827527, 527.2407732864674, 528.709947727545, 530.1570502900388, 538.6014349381456, 27534.311642208955, 31205.219869503246, 31942.881999130033, 31985.840070799673, 32101.19747006326, 32882.342353152926, 42353.7882310588, 43566.78216608921, 43624.34563481542, 45520.772396137974, 9913.702588922344, 10056.849147262761, 10068.395896246213, 10077.14344280737, 10093.848592271122, 9813.067758562926, 9932.106110450059, 9934.056264654882, 9936.453495057769, 9942.751431214241, 9905.950470247639, 9964.601415943354, 9970.90029099707, 9976.102150806426, 9996.250281228937, 9877.259205559576, 9907.504624768804, 9941.552630131662, 9943.254255930762, 9967.601295948154, 9829.459379734111, 9956.601735930553, 9960.80078398428, 9964.601415943354, 9972.202223822149, 40914.386284205466, 41182.38226426606, 41316.79341603273, 41321.58678413238, 41344.0, 3385.2044769479153, 3426.674465925733, 3458.654134586521, 3548.7870727756417, 3893.2602805467154, 24061.0, 29708.55437168444, 30317.848410758026, 30318.84840575805, 30356.54465183024, 3163.1933856866353, 3220.436423625856, 3282.573265475473, 3361.697447229761, 3366.107981384935, 492091.0, 493047.0, 493591.0, 493996.0, 495984.9999999964, 58028.12957805636, 58384.0, 58457.12314315289, 58786.11820822691, 58906.11640825391, 60701.0, 60746.69626651838, 60753.69623151856, 60894.69552652208, 60972.0, 16035.919820401057, 16254.18729063542, 16262.51212463628, 16262.51212463628, 16264.430744923871, 12652.240865548096, 12706.174098683556, 12715.364231788373, 12716.936415317865, 12725.745485090247, 15346.0, 15937.20313984296, 15947.521574352788, 15951.208538505714, 16002.0, 6090.33006369299, 6447.806565803034, 7186.4549121939035, 7202.351788339075, 7207.063081799332, 897.7979954510314, 908.4594666173616, 908.7682640926521, 994.080475560104, 1017.6489111256604, 26255.0, 29907.551386729217, 30220.0, 30819.229519262197, 30941.845290773403, 1033.8914413986574, 1084.4740300954031, 1092.257265059751, 1120.456578559398, 1137.3801278303179, 15654.608634783994, 15655.452059177875, 15815.367385304458, 15863.444779432895, 15921.128325167338, 659390.0, 662839.0, 663757.0, 664709.6764516146, 666637.0, 58051.0, 58145.418545814, 58250.8349833001, 58438.415615843296, 58439.70780146157, 1056.8309070548676, 1368.8699573540498, 1382.6266907935003, 1390.0964373157124, 1414.9575512734634, 30169.28476437131, 31095.22261943379, 31244.531332029583, 31988.68011319857, 32235.35529289448, 48771.75614122049, 49449.75275123601, 49517.50482495129, 49549.75225123851, 49572.50427495679, 559346.0, 563233.0, 563381.0, 564703.0, 564749.0, 31833.0, 33849.49225761566, 34786.478202826474, 38620.0, 39564.010899726585, 46660.300095497914, 47211.05577888492, 47321.76339118282, 47535.286970694775, 47844.521554784005, 37585.81207093947, 38259.0, 38410.80794596009, 39517.60482395139, 39833.60166398299, 34617.30765384628, 34692.0, 38618.62792639183, 39440.01399965024, 39442.0, 37184.6281537192, 37585.624143758214, 37585.897225984925, 38089.23821523499, 38193.4270985941, 31814.681853181173, 32412.513812292364, 33097.50353744648, 34155.82922085374, 34247.0, 25070.49859002773, 25097.87451062733, 25349.619755703312, 25350.49299014046, 25740.227793166236], "value": 19926.470101210827, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.5590727729421, 370.7664171571909, 372.5994555852458, 379.9468074469574, 400.4813766172806, 14606.0, 15135.318910649019, 20066.799332006674, 20609.896950515227, 20684.68972965405, 19842.007899605032, 20279.797202027974, 20357.898210508927, 20397.59204815903, 20418.979051047463, 4145.689073319498, 5869.266341707297, 6138.539609529304, 6149.200603921484, 6149.2313460817395, 5608.327000759894, 6741.494387920926, 6835.872081106611, 6907.309269073103, 6908.654567271641, 5835.970820145893, 6132.908006379907, 6211.9068213976825, 6809.114815074058, 6829.795106146798, 5987.251593550806, 6157.938420615804, 6200.503959683215, 6201.193844800192, 6210.254769427653, 4971.701697898102, 5989.520838332923, 5992.8501787455225, 6000.33996260411, 6003.8198854034445, 12572.622821315284, 12801.359932003363, 17668.734968975477, 24118.517629647486, 24470.755292447204, 1780.3501721871423, 2067.989660051705, 2124.5007423255524, 2180.4221881201493, 2334.4280651240383, 9611.567479463441, 9713.61145554177, 9800.0, 10026.0, 10295.176385889112, 1822.334847780564, 1883.6138591588692, 1890.5840715042668, 1912.8660993730443, 1915.9137838797287, 115739.0, 129293.35353323267, 129421.35289323587, 131679.0, 132470.38942468708, 9506.95246523763, 10652.786944261072, 11183.161262905314, 11195.328280303207, 11348.716282092848, 14229.928850355682, 14236.644083897985, 14240.43038278478, 14250.786238206436, 14252.358643860844, 8120.512769233865, 8414.705485308108, 8420.8315833683, 8532.189442003, 8537.499625153781, 7548.169701332849, 8352.415330926777, 8364.832703345899, 8506.404551681446, 8598.990353376394, 3967.9603203968168, 4151.484708081529, 4278.850240241577, 4448.532904045029, 4466.843660471868, 1843.2811203630595, 1889.1782074797231, 1889.2254175787862, 1904.1050706168078, 1928.0745242283501, 366.98165091745307, 401.39589917174465, 425.07334011854454, 819.19309480162, 900.8694088918365, 11263.66209013731, 11508.309501429942, 11806.40967951599, 11875.346855922835, 11957.043436525231, 413.92342416653014, 421.93040641972453, 429.84740417152085, 433.8416477985576, 446.60475479200494, 5674.2339784129435, 5885.411458854081, 6043.7280322385595, 6055.6063855849225, 6108.297545782292, 515468.0, 584778.0, 595529.0, 601650.0, 607814.0, 121998.0, 137137.0, 138325.0, 139520.0, 141122.0, 436.2627160099422, 445.36757803918516, 446.02988500012344, 962.3119469579284, 1038.3043360948184, 16587.2631015881, 18794.718079229096, 19621.215151393786, 19685.901570492628, 19719.0, 46494.0, 49936.014076903026, 50476.74761626168, 57548.84902302014, 59076.40923590709, 77542.44915101779, 78179.69661610223, 83470.58264708638, 85286.72069918831, 86454.0, 6708.396244338033, 7763.611819408894, 7777.727824484125, 7972.720954766555, 8159.0, 8933.096743558499, 11186.0, 11983.64049078529, 12327.876721232673, 12342.691432714253, 9199.678011269574, 10620.787584248426, 10962.287451315638, 11050.613228536962, 11108.166887483632, 10241.74395640115, 10610.0, 10667.786644267226, 10957.013868751532, 10971.451427428437, 10779.568817247537, 10888.346699198073, 10941.507632156732, 11167.664970050912, 11204.43977801123, 7847.215278472336, 7978.481398709066, 7996.6401511930935, 8162.102168761313, 8271.17288271168, 10667.0, 10771.19216058783, 11004.504797284302, 11253.831192532283, 11347.546098156312], "value": 8702.149642794026, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.6691194113403, 1532.908025518469, 1569.9215039248036, 1789.1412122181353, 1941.6505029094758, 6246.906296405557, 8600.74197774067, 8933.330000249991, 8935.329850261232, 9043.095690430953, 8810.823783524325, 8903.599338029788, 8920.197182253596, 8928.866067008983, 8936.776580585489, 2130.371540395587, 2328.4178955261186, 2393.198278576679, 2419.52819200256, 2426.33275849142, 2354.75275096115, 2388.6894703688495, 2410.783029527342, 2414.6498757680124, 2415.577273977047, 2359.315798418457, 2361.327021798788, 2363.5627408929317, 2408.2654790289043, 2408.6989126359204, 2349.4596242864236, 2375.2992867104244, 2381.7975472084895, 2388.749181335952, 2392.6052201386924, 2331.6269396896587, 2365.8935347909387, 2370.2178281167176, 2370.4192472844093, 2371.9169829056073, 8719.171678690578, 8891.377603567753, 8915.3313501487, 8975.294694008118, 9003.774905627346, 5288.2596436498925, 5507.614466987313, 5511.944880551183, 5524.696141712204, 5526.751296191641, 3692.8892133236045, 3702.9629703702885, 3754.887353379403, 3766.5103536540073, 3773.471713960048, 1080.7946490166921, 1095.4741723972459, 1096.7587130831205, 1103.0679076180616, 1103.349024075791, 10095.495225238783, 11859.169858109935, 11968.880311196863, 11973.401329933555, 11980.880191198064, 4426.424564806619, 4505.864824055284, 4673.579377856009, 4687.601553867891, 4702.482726900038, 9255.305852060988, 9257.259419246579, 9257.67598134062, 9271.629134834666, 9302.720918372324, 4579.862604121881, 5309.681419114865, 5323.9884421959705, 5327.680339179662, 5343.0649636314265, 4298.398224248608, 4420.513743488214, 4624.1676498230645, 4973.42805577356, 4998.625103117281, 3733.7199710021855, 3771.321162190777, 3878.204967981613, 3886.84452621892, 3887.2419878123496, 1024.99487502562, 1166.5742004168417, 1192.5229908036854, 1194.3550482739397, 1202.0804084875078, 891.4695756025154, 905.6920646979927, 916.1617120334819, 937.8265020971143, 943.4480828715278, 5766.423357664284, 5957.4042595740075, 6004.249468816398, 6119.204503414528, 6182.350853160443, 432.7295440349781, 447.98432054877924, 448.1015563794571, 448.4125795208288, 456.8560903315512, 3592.8203589820405, 3820.255050265227, 3893.065664240562, 3903.6681882039775, 3934.6852251819787, 10040.748981275527, 10511.421871797125, 10517.684469465928, 10539.57841686324, 10556.208284378703, 10475.319104258198, 10480.68557943263, 10483.266171367932, 10490.21323400748, 10494.527746251437, 7981.720639777696, 7999.600019998977, 8065.354771618257, 8093.109757926623, 8123.350131989545, 4724.338592596971, 5641.774329026793, 5802.984477716298, 5809.215755872903, 5830.53355731549, 9644.710658680251, 9750.80498390014, 9769.876464206707, 9812.313138080266, 9828.164606008284, 9655.420674759536, 9786.217102631916, 9870.407775533491, 9897.132634299118, 9985.0, 6091.634501929899, 6514.57655252407, 6701.128853249046, 6846.144231971004, 6905.551139175937, 7976.082750483537, 8031.437799353989, 8038.598070096588, 8040.798980025546, 8183.95908020456, 7376.483646144933, 7581.924180758342, 7615.162332143571, 7648.34989025939, 7750.651220694969, 7229.819254518679, 7390.076240469942, 7457.552546847207, 7461.030066091374, 7606.733764318223, 5625.296837895263, 7057.012018317542, 7066.0, 7072.646367681698, 7074.3633073023675, 4869.902601947871, 5022.397312322545, 5056.64603477753, 5101.7194054327365, 5197.272381866466, 5001.674891132065, 5014.323066386137, 5166.509181627739, 5200.089984252816, 5285.260063591178], "value": 4454.210808826609, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.4523399561804, 298.6490873223962, 307.7169004515844, 312.31135346560836, 312.5951892299472, 4214.7471151730915, 5060.41805192403, 5080.491950804918, 5131.690739167376, 5191.610629202815, 4435.556444355563, 4791.897863491399, 4803.687760295578, 4947.901041979158, 5031.949680503194, 3615.566132064156, 3626.383514802477, 3872.3610604250334, 3915.588863169369, 3924.548676902164, 3368.0401085690582, 3737.7570457920283, 3853.9614603854025, 3912.76523408595, 3913.4912461380122, 3303.5209894565273, 3722.236941427001, 3758.9624103759024, 3909.706771992098, 3911.2177564487215, 2977.478941185284, 3594.370985077601, 3799.639034291738, 3905.785181814999, 3944.0, 3417.5215469834243, 3432.2449061206744, 3434.244466217428, 3444.431668774649, 3466.254755227613, 4456.598906098467, 4734.928976065327, 4916.336294600254, 4979.900401991976, 5034.270030845543, 3641.3236271340543, 4407.449068866392, 4466.481396325233, 4482.439695038121, 4488.910221795579, 5228.163493840967, 5264.842054738364, 5306.734663266821, 5310.548603368718, 5323.600729945231, 3468.6357932417236, 3762.398016317376, 3802.0, 3806.657400833938, 3810.2950954073585, 4835.564799168056, 4916.287138364917, 5265.657732247391, 5577.721113944286, 5615.922108819681, 3224.806511609264, 3808.5429748430365, 3816.790076545761, 3820.7707537547835, 3833.025991614321, 3423.6747508986605, 3729.6456836601023, 3926.862559810393, 3953.327934251184, 3959.544652365015, 4143.0678097427835, 4272.124214536013, 4494.056248187917, 4520.886977825515, 4529.886752831206, 4221.6200541951375, 4532.66005049616, 4876.634252431082, 5418.566514678816, 5513.7794488220825, 1199.650393307529, 1281.3721276574597, 1297.2605614799663, 1301.1607513154152, 1304.386938139092, 722.407625746893, 782.9725959591387, 783.2338077634897, 809.7125520440277, 835.3484282259847, 4010.7794071325775, 4926.014797040606, 4939.863831318817, 4952.752362381866, 5026.874328141826, 4487.618552423015, 5029.924551131736, 5398.757055932492, 5405.621606487508, 5442.034091420185, 3957.960420395817, 4332.653387728974, 4692.859214223578, 4910.680805747615, 4927.846178445798, 2361.480474295652, 2488.191337815217, 2742.9588556171675, 2808.0312292259136, 2837.3474100956896, 4848.393950756155, 4932.435133730512, 5160.354955630546, 5571.804986825442, 5642.492175704207, 4880.341153944172, 5038.496150384932, 5190.480951904779, 5571.554275657938, 5572.804951826748, 5581.916271255935, 5614.466625670555, 5629.324481062299, 5633.493205435717, 5644.842807224509, 4412.874716947158, 4573.7713114344815, 5074.7716352764955, 5447.754851031792, 5470.030788916067, 4269.423627810206, 5068.670536415121, 5363.490468405495, 5363.812266570651, 5419.945800541944, 4711.246200607886, 5121.948780512147, 5596.216529685766, 5597.888042239214, 5622.493975542221, 3643.6154261380507, 3706.870259540903, 3717.7955212463576, 3719.3119272934596, 3725.6274372562525, 4482.843100491467, 5137.5889928806355, 5306.126527634771, 5440.945590544044, 5444.047291724011, 4652.744099074584, 5248.212768084665, 5302.761375738178, 5312.203169524602, 5348.839534813962, 5233.476652334888, 5251.109600543878, 5261.500157485033, 5262.947370526245, 5269.288646032736, 4283.507396649301, 4653.627709783277, 5124.256982737556, 5158.761897144734, 5183.663061900965, 4456.910861782681, 5025.0, 5144.176931690911, 5221.1283793912435, 5282.260483532232, 4848.224284114524, 4959.504049595012, 5153.252778347193, 5276.546405921265, 5278.5249327560705], "value": 3857.3905082769847, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.3819158236543, 740.2375553180226, 761.3908872901677, 786.5595266650674, 817.7955511122219, 13387.59837204883, 16181.91909040456, 16186.595335116628, 16229.350825966962, 16248.999999999985, 14586.0, 14742.189179595118, 14764.926175369135, 14770.113793172417, 14773.965822392436, 662.6951602262941, 680.9012693159514, 831.5509624802614, 839.1734141870253, 858.8196478739472, 841.032812265893, 842.2083241752763, 845.1970627903517, 849.8087930215714, 864.6800683747023, 885.7387070814125, 885.7608445719652, 886.6320477002037, 886.8226354729047, 887.1749273175943, 691.9238883722786, 713.636045616734, 718.2673672853712, 881.894172699277, 887.0198430734043, 536.372444240236, 590.3329237961076, 652.9771457998947, 656.8226578823738, 679.1476696745602, 15032.624184395478, 15054.698906021928, 15065.548033559011, 15079.924600377037, 15086.849131508654, 13818.792718109236, 14161.716765664733, 14244.857551424457, 14272.714545709028, 14273.286335683175, 12910.87089129106, 13017.86982130176, 13073.934630326883, 13209.603711888658, 13586.25275609841, 4594.402727645384, 4761.2143996240575, 4765.571098601143, 4793.664443488957, 4863.902721945541, 120995.79004209934, 123903.0, 127141.72858271391, 129808.0, 129910.0, 10979.890201098047, 11165.553377864795, 11311.434428278553, 11377.886221137682, 11590.76818463626, 22080.668789968164, 22273.220437284617, 22434.10263589471, 22631.88684056602, 22663.660045099336, 7938.801529961682, 7952.3638108951145, 7965.800854978672, 7978.561179135203, 8206.8358632827, 4166.645835103989, 4472.1502914446155, 4481.305397663367, 4502.482214545304, 4581.175388430048, 3455.9654403455643, 3564.3584154851856, 3567.9108022299133, 3568.8215589220436, 3575.213453040327, 558.6368860240794, 628.6008384675764, 628.8616504369123, 633.5533448918501, 639.0222958872932, 588.3702145058165, 607.5746977115948, 613.929398119222, 615.9384061593894, 626.2297374229723, 8491.150884911583, 8724.258438032712, 8814.691485797966, 8884.200421962054, 8940.898888761136, 539.9136138217866, 605.0086784031738, 607.271274470637, 625.3871206217932, 639.1435476461564, 3590.784552926833, 4132.0, 4404.889877753082, 4408.162449134654, 4496.168208881368, 126899.0, 127832.90757025173, 128435.0, 141061.29469352792, 169355.0, 85045.57477212575, 119060.0, 125251.0, 128859.0, 129203.35398323137, 81580.18419815727, 82325.58837205895, 91299.54350228206, 111205.0, 115728.05631662127, 8856.468611883307, 9372.484513351832, 9586.520673966133, 9752.268579856398, 9833.360831545926, 50539.4838154856, 52523.0, 55559.0, 59477.7026114884, 61905.69047154736, 91127.54436227777, 93553.5322323384, 93743.0, 94409.75156118129, 98194.01805981848, 11410.315381077162, 14338.56984290473, 14506.1296322221, 14520.128792272497, 14842.257887105818, 14107.858921410654, 14831.913873469477, 15154.461814645998, 15344.462943796914, 15495.612609684847, 11525.193236473366, 13828.86171138316, 14002.369893355028, 14435.0, 14762.188079655722, 13927.930360348133, 14190.361433735301, 14202.573922782332, 14276.500322488662, 14283.143011419348, 13565.728685426433, 13675.863241367459, 13847.79228311556, 14399.42402303938, 14635.34140963638, 9776.902230977883, 11560.768784624428, 11679.883201168219, 11821.586244481401, 12089.637310880687, 11466.0, 11637.709057273298, 11852.881471185177, 11899.286042837457, 11913.52345906154], "value": 7808.399963923354, "unit": "score"}], "name": "dromaeo_css", "value": 7136.531878345442, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 117.0, 113.0, 114.0, 112.0, 118.0, 161.0, 112.0, 121.0], "value": 119.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 133.0, 162.0, 108.0, 128.0, 140.0, 135.0, 198.0, 123.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 183.0, 184.0, 182.0, 183.0, 181.0, 185.0, 183.0, 183.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 78.0, 153.0, 59.0, 59.0, 57.0, 70.0, 146.0, 58.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 131.0, 70.0, 79.0, 89.0, 114.0, 68.0, 69.0, 116.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 88.0, 86.0, 87.0, 84.0, 94.0, 86.0, 87.0, 84.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 92.0, 94.0, 93.0, 93.0, 93.0, 93.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 131.0, 87.0, 89.0, 84.0, 85.0, 86.0, 85.0, 85.0, 87.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 53.0, 53.0, 55.0, 54.0, 53.0, 54.0, 54.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 67.0, 73.0, 72.0, 74.0, 70.0, 71.0, 70.0, 70.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 83.0, 86.0, 84.0, 86.0, 90.0, 89.0, 87.0, 89.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 136.0, 138.0, 139.0, 136.0, 136.0, 136.0, 188.0, 157.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 169.0, 167.0, 168.0, 169.0, 172.0, 170.0, 166.0, 168.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 69.0, 68.0, 67.0, 67.0, 66.0, 68.0, 66.0, 65.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1479.7, "alertThreshold": 2.0}]} 14:47:17 INFO - Return code: 0 14:47:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:47:17 INFO - # TBPL SUCCESS # 14:47:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:47:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:47:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:47:17 INFO - 2016-09-07 14:47:17.796 screenresolution[1883:10917] starting screenresolution argv=screenresolution get 14:47:17 INFO - 2016-09-07 14:47:17.801 screenresolution[1883:10917] Display 0: 1600x1200x32@60 14:47:17 INFO - 2016-09-07 14:47:17.808 screenresolution[1885:10922] starting screenresolution argv=screenresolution list 14:47:17 INFO - Available Modes on Display 0 14:47:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:47:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:47:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:47:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:47:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:47:18 INFO - Intel Iris: 14:47:18 INFO - Chipset Model: Intel Iris 14:47:18 INFO - Type: GPU 14:47:18 INFO - Bus: Built-In 14:47:18 INFO - VRAM (Dynamic, Max): 1536 MB 14:47:18 INFO - Vendor: Intel (0x8086) 14:47:18 INFO - Device ID: 0x0a2e 14:47:18 INFO - Revision ID: 0x0009 14:47:18 INFO - Displays: 14:47:18 INFO - Display: 14:47:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:47:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:47:18 INFO - Display Serial Number: 1600x1200 60 14:47:18 INFO - Main Display: Yes 14:47:18 INFO - Mirror: Off 14:47:18 INFO - Online: Yes 14:47:18 INFO - Rotation: Supported 14:47:18 INFO - Return code: 0 14:47:18 INFO - Running post-action listener: _resource_record_post_action 14:47:18 INFO - [mozharness: 2016-09-07 21:47:18.047713Z] Finished run-tests step (success) 14:47:18 INFO - Running post-run listener: _resource_record_post_run 14:47:18 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 32111616; Write bytes: 708817408; Read time: 196; Write time: 2055 14:47:18 INFO - TinderboxPrint: CPU usage
53.3% 14:47:18 INFO - TinderboxPrint: I/O read bytes / time
32,111,616 / 196 14:47:18 INFO - TinderboxPrint: I/O write bytes / time
708,817,408 / 2,055 14:47:18 INFO - TinderboxPrint: CPU idle
2,267.3 (46.7%) 14:47:18 INFO - TinderboxPrint: CPU system
968.9 (20.0%) 14:47:18 INFO - TinderboxPrint: CPU user
1,617.3 (33.3%) 14:47:18 INFO - TinderboxPrint: Swap in / out
434,565,120 / 0 14:47:18 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392153600; Write bytes: 385930752; Read time: 29335; Write time: 828 14:47:18 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30346240; Write bytes: 299973632; Read time: 181; Write time: 1173 14:47:18 INFO - Running post-run listener: _upload_blobber_files 14:47:18 INFO - Blob upload gear active. 14:47:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:47:18 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 14:47:18 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:47:18 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:47:18 INFO - (blobuploader) - INFO - Open directory for files ... 14:47:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:47:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:47:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:47:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:47:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:47:20 INFO - (blobuploader) - INFO - Done attempting. 14:47:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:47:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:47:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:47:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:47:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:47:21 INFO - (blobuploader) - INFO - Done attempting. 14:47:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:47:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:47:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:47:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:47:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:47:23 INFO - (blobuploader) - INFO - Done attempting. 14:47:23 INFO - (blobuploader) - INFO - Iteration through files over. 14:47:23 INFO - Return code: 0 14:47:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:47:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:47:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40567f90f27800bbfab05da1b4dc7c1b621c0ae4735b163f35f3b86b8595ae0752fc86988d83e70e641d94b6ebbf989b3e1979be233b26c3bf5edb40000cc7f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e99d818c69d97bb53a6bafb867affbc0303be32943b5e831ebd364ca01cb3a39905f0e33475c09663540a4371e2400837b6cbf5a059a236599164efd13b3d87e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9ddafd7d4e8d5d304dee3d5f90e70213df8400619f590fbf4227c379a2223ed22939b76374960cdad68b653f8b00d15177abe290bca64737cf15fb9252401757"} 14:47:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:47:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:47:23 INFO - Contents: 14:47:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40567f90f27800bbfab05da1b4dc7c1b621c0ae4735b163f35f3b86b8595ae0752fc86988d83e70e641d94b6ebbf989b3e1979be233b26c3bf5edb40000cc7f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e99d818c69d97bb53a6bafb867affbc0303be32943b5e831ebd364ca01cb3a39905f0e33475c09663540a4371e2400837b6cbf5a059a236599164efd13b3d87e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9ddafd7d4e8d5d304dee3d5f90e70213df8400619f590fbf4227c379a2223ed22939b76374960cdad68b653f8b00d15177abe290bca64737cf15fb9252401757"} 14:47:23 INFO - Running post-run listener: copy_logs_to_upload_dir 14:47:23 INFO - Copying logs to upload dir... 14:47:23 INFO - mkdir: /builds/slave/test/build/upload/logs 14:47:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.783110 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-07 14:47:23.665710) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 14:47:23.669595) ========= 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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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/Fx-Team/sha512/40567f90f27800bbfab05da1b4dc7c1b621c0ae4735b163f35f3b86b8595ae0752fc86988d83e70e641d94b6ebbf989b3e1979be233b26c3bf5edb40000cc7f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e99d818c69d97bb53a6bafb867affbc0303be32943b5e831ebd364ca01cb3a39905f0e33475c09663540a4371e2400837b6cbf5a059a236599164efd13b3d87e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9ddafd7d4e8d5d304dee3d5f90e70213df8400619f590fbf4227c379a2223ed22939b76374960cdad68b653f8b00d15177abe290bca64737cf15fb9252401757"} build_url:https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011290 build_url: 'https://queue.taskcluster.net/v1/task/UHEEinpuSE6ckNnwtukyAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40567f90f27800bbfab05da1b4dc7c1b621c0ae4735b163f35f3b86b8595ae0752fc86988d83e70e641d94b6ebbf989b3e1979be233b26c3bf5edb40000cc7f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e99d818c69d97bb53a6bafb867affbc0303be32943b5e831ebd364ca01cb3a39905f0e33475c09663540a4371e2400837b6cbf5a059a236599164efd13b3d87e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9ddafd7d4e8d5d304dee3d5f90e70213df8400619f590fbf4227c379a2223ed22939b76374960cdad68b653f8b00d15177abe290bca64737cf15fb9252401757"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 14:47:23.703096) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 14:47:23.703438) ========= 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.8l2PpZgxa7/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.cqgSbIwF2a/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.005224 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 14:47:23.751842) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 14:47:23.752132) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 14:47:23.752484) ========= ========= Total master_lag: 0.16 =========