builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0170 starttime: 1473550927.36 results: success (0) buildid: 20160910133015 builduid: 21a1cf10879745a4b6f626adcba77980 revision: 8b57e28ff3423249a926dd86b43b0b3b9330784e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:07.362093) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:07.362567) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:07.362843) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:07.399728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:07.399987) ========= 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.LVdkzVcO90/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.iM4PxV2EAk/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-10 16:42: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% 70.2M=0s 2016-09-10 16:42:07 (70.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.508813 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:07.934994) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:07.935321) ========= 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.LVdkzVcO90/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.iM4PxV2EAk/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.027295 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:07.984383) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-10 16:42:07.984723) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8b57e28ff3423249a926dd86b43b0b3b9330784e --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 8b57e28ff3423249a926dd86b43b0b3b9330784e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LVdkzVcO90/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.iM4PxV2EAk/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-10 16:42:08,062 truncating revision to first 12 chars 2016-09-10 16:42:08,062 Setting DEBUG logging. 2016-09-10 16:42:08,062 attempt 1/10 2016-09-10 16:42:08,062 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8b57e28ff342?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-10 16:42:08,712 unpacking tar archive at: fx-team-8b57e28ff342/testing/mozharness/ program finished with exit code 0 elapsedTime=1.075479 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-10 16:42:09.083597) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:09.083915) ========= script_repo_revision: 8b57e28ff3423249a926dd86b43b0b3b9330784e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:09.084321) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:09.084623) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 16:42:09.104778) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2016-09-10 16:42:09.105175) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.LVdkzVcO90/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.iM4PxV2EAk/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 16:42:09 INFO - MultiFileLogger online at 20160910 16:42:09 in /builds/slave/test 16:42:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:42:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:42:09 INFO - {'append_to_log': False, 16:42:09 INFO - 'base_work_dir': '/builds/slave/test', 16:42:09 INFO - 'blob_upload_branch': 'Fx-Team', 16:42:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:42:09 INFO - 'branch': 'Fx-Team', 16:42:09 INFO - 'buildbot_json_path': 'buildprops.json', 16:42:09 INFO - 'config_files': ('talos/mac_config.py',), 16:42:09 INFO - 'default_actions': ('clobber', 16:42:09 INFO - 'read-buildbot-config', 16:42:09 INFO - 'download-and-extract', 16:42:09 INFO - 'populate-webroot', 16:42:09 INFO - 'create-virtualenv', 16:42:09 INFO - 'install', 16:42:09 INFO - 'run-tests'), 16:42:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:42:09 INFO - 'download_minidump_stackwalk': True, 16:42:09 INFO - 'download_symbols': 'ondemand', 16:42:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:42:09 INFO - 'tooltool.py': '/tools/tooltool.py', 16:42:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:42:09 INFO - '/tools/misc-python/virtualenv.py')}, 16:42:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:42:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:42:09 INFO - 'installer_path': 'installer.exe', 16:42:09 INFO - 'log_level': 'info', 16:42:09 INFO - 'log_name': 'talos', 16:42:09 INFO - 'log_to_console': True, 16:42:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:42:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:42:09 INFO - 'opt_config_files': (), 16:42:09 INFO - 'pip_index': False, 16:42:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:09 INFO - 'cmd': ('bash', 16:42:09 INFO - '-c', 16:42:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:09 INFO - 'enabled': True, 16:42:09 INFO - 'halt_on_failure': False, 16:42:09 INFO - 'name': 'check_screen_resolution'},), 16:42:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:09 INFO - 'cmd': ('bash', 16:42:09 INFO - '-c', 16:42:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:09 INFO - 'enabled': True, 16:42:09 INFO - 'halt_on_failure': False, 16:42:09 INFO - 'name': 'check_screen_resolution'},), 16:42:09 INFO - 'run_cmd_checks_enabled': True, 16:42:09 INFO - 'sps_profile': False, 16:42:09 INFO - 'sps_profile_interval': 0, 16:42:09 INFO - 'suite': 'dromaeojs-e10s', 16:42:09 INFO - 'system_bits': '32', 16:42:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:42:09 INFO - 'title': 't-yosemite-r7-0170', 16:42:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:42:09 INFO - 'use_talos_json': True, 16:42:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:42:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:42:09 INFO - 'work_dir': 'build'} 16:42:09 INFO - [mozharness: 2016-09-10 23:42:09.303034Z] Running clobber step. 16:42:09 INFO - Running pre-action listener: _resource_record_pre_action 16:42:09 INFO - Running main action method: clobber 16:42:09 INFO - rmtree: /builds/slave/test/build 16:42:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:42:11 INFO - Running post-action listener: _resource_record_post_action 16:42:11 INFO - [mozharness: 2016-09-10 23:42:11.456335Z] Finished clobber step (success) 16:42:11 INFO - [mozharness: 2016-09-10 23:42:11.456584Z] Running read-buildbot-config step. 16:42:11 INFO - Running pre-action listener: _resource_record_pre_action 16:42:11 INFO - Running main action method: read_buildbot_config 16:42:11 INFO - Using buildbot properties: 16:42:11 INFO - { 16:42:11 INFO - "project": "", 16:42:11 INFO - "product": "firefox", 16:42:11 INFO - "script_repo_revision": "production", 16:42:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 16:42:11 INFO - "repository": "", 16:42:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 16:42:11 INFO - "buildid": "20160910133015", 16:42:11 INFO - "pgo_build": "False", 16:42:11 INFO - "basedir": "/builds/slave/test", 16:42:11 INFO - "buildnumber": 0, 16:42:11 INFO - "slavename": "t-yosemite-r7-0170", 16:42:11 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:42:11 INFO - "platform": "yosemite_r7", 16:42:11 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 16:42:11 INFO - "branch": "fx-team", 16:42:11 INFO - "repo_path": "integration/fx-team", 16:42:11 INFO - "revision": "8b57e28ff3423249a926dd86b43b0b3b9330784e", 16:42:11 INFO - "stage_platform": "macosx64", 16:42:11 INFO - "builduid": "21a1cf10879745a4b6f626adcba77980", 16:42:11 INFO - "slavebuilddir": "test" 16:42:11 INFO - } 16:42:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:42:11 INFO - Running post-action listener: _resource_record_post_action 16:42:11 INFO - [mozharness: 2016-09-10 23:42:11.462522Z] Finished read-buildbot-config step (success) 16:42:11 INFO - [mozharness: 2016-09-10 23:42:11.462759Z] Running download-and-extract step. 16:42:11 INFO - Running pre-action listener: _resource_record_pre_action 16:42:11 INFO - Running main action method: download_and_extract 16:42:11 INFO - mkdir: /builds/slave/test/build/tests 16:42:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:11 INFO - trying https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:42:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/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 16:42:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/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 16:42:12 INFO - Downloaded 1199 bytes. 16:42:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:42:12 INFO - Using the following test package requirements: 16:42:12 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:42:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:42:12 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:42:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:42:12 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:42:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:42:12 INFO - u'jsshell-mac.zip'], 16:42:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:42:12 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:42:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:42:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:42:12 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:42:12 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:42:12 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:42:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:42:12 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:42:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:42:12 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:42:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:42:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:42:13 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 16:42:20 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 16:42:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:42:20 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 16:42:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:42:28 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 16:42:28 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')]} 16:42:28 INFO - trying https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:42:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:42:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/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 16:42:32 INFO - Downloaded 111647145 bytes. 16:42:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:42:32 INFO - mkdir: /builds/slave/test/properties 16:42:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:42:32 INFO - Writing to file /builds/slave/test/properties/build_url 16:42:32 INFO - Contents: 16:42:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:42:33 INFO - Running post-action listener: _resource_record_post_action 16:42:33 INFO - Running post-action listener: set_extra_try_arguments 16:42:33 INFO - [mozharness: 2016-09-10 23:42:33.376444Z] Finished download-and-extract step (success) 16:42:33 INFO - [mozharness: 2016-09-10 23:42:33.376563Z] Running populate-webroot step. 16:42:33 INFO - Running pre-action listener: _resource_record_pre_action 16:42:33 INFO - Running main action method: populate_webroot 16:42:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:42:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:42:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:42:33 INFO - '--tppagecycles', 16:42:33 INFO - '7'], 16:42:33 INFO - 'tests': ['tsvgm']}}, 16:42:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:42:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:42:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:42:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:42:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:42:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:42:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:33 INFO - 'talos_options': ['--disable-e10s'], 16:42:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:42:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:42:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:33 INFO - 'talos_options': ['--disable-e10s'], 16:42:33 INFO - 'tests': ['damp', 'tps']}, 16:42:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:33 INFO - 'tests': ['damp', 'tps']}, 16:42:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:42:33 INFO - 'tests': ['dromaeo_dom']}, 16:42:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:42:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:42:33 INFO - 'tests': ['basic_compositor_video']}, 16:42:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:42:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:42:33 INFO - 'tests': ['a11yr', 16:42:33 INFO - 'ts_paint', 16:42:33 INFO - 'tpaint', 16:42:33 INFO - 'sessionrestore', 16:42:33 INFO - 'sessionrestore_no_auto_restore', 16:42:33 INFO - 'tabpaint']}, 16:42:33 INFO - 'other-e10s': {'tests': ['a11yr', 16:42:33 INFO - 'ts_paint', 16:42:33 INFO - 'tpaint', 16:42:33 INFO - 'sessionrestore', 16:42:33 INFO - 'sessionrestore_no_auto_restore', 16:42:33 INFO - 'tabpaint']}, 16:42:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:42:33 INFO - 'ts_paint', 16:42:33 INFO - 'tpaint', 16:42:33 INFO - 'sessionrestore', 16:42:33 INFO - 'sessionrestore_no_auto_restore', 16:42:33 INFO - 'tabpaint']}, 16:42:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:42:33 INFO - 'ts_paint', 16:42:33 INFO - 'tpaint', 16:42:33 INFO - 'sessionrestore', 16:42:33 INFO - 'sessionrestore_no_auto_restore', 16:42:33 INFO - 'tabpaint']}, 16:42:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:42:33 INFO - 'tests': ['a11yr', 16:42:33 INFO - 'ts_paint', 16:42:33 INFO - 'tpaint', 16:42:33 INFO - 'sessionrestore', 16:42:33 INFO - 'sessionrestore_no_auto_restore', 16:42:33 INFO - 'tabpaint']}, 16:42:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:42:33 INFO - 'tests': ['a11yr', 16:42:33 INFO - 'ts_paint', 16:42:33 INFO - 'tpaint', 16:42:33 INFO - 'sessionrestore', 16:42:33 INFO - 'sessionrestore_no_auto_restore', 16:42:33 INFO - 'tabpaint']}, 16:42:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:42:33 INFO - 'tests': ['tsvgx', 16:42:33 INFO - 'tsvgr_opacity', 16:42:33 INFO - 'tart', 16:42:33 INFO - 'tscrollx', 16:42:33 INFO - 'cart']}, 16:42:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:42:33 INFO - 'tsvgr_opacity', 16:42:33 INFO - 'tart', 16:42:33 INFO - 'tscrollx', 16:42:33 INFO - 'cart']}, 16:42:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:33 INFO - 'talos_options': ['--disable-e10s'], 16:42:33 INFO - 'tests': ['tp5o']}, 16:42:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:33 INFO - 'tests': ['tp5o']}, 16:42:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:33 INFO - 'talos_options': ['--disable-e10s', 16:42:33 INFO - '--xperf_path', 16:42:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:42:33 INFO - 'tests': ['tp5n']}, 16:42:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:33 INFO - 'talos_options': ['--xperf_path', 16:42:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:42:33 INFO - 'tests': ['tp5n']}}, 16:42:33 INFO - 'talos.zip': {'path': '', 16:42:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:42:33 INFO - Running post-action listener: _resource_record_post_action 16:42:33 INFO - [mozharness: 2016-09-10 23:42:33.395291Z] Finished populate-webroot step (success) 16:42:33 INFO - [mozharness: 2016-09-10 23:42:33.395424Z] Running create-virtualenv step. 16:42:33 INFO - Running pre-action listener: _resource_record_pre_action 16:42:33 INFO - Running main action method: create_virtualenv 16:42:33 INFO - Creating virtualenv /builds/slave/test/build/venv 16:42: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 16:42:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:42:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:42:33 INFO - Using real prefix '/tools/python27' 16:42:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:42:34 INFO - Installing distribute.............................................................................................................................................................................................done. 16:42:37 INFO - Installing pip.................done. 16:42:37 INFO - Return code: 0 16:42:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:42: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')]} 16:42:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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')]} 16:42:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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 0x10b1b0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b126570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac2bd74e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afc4d30>, '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 0x10adeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afbcb20>, '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.LVdkzVcO90/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.iM4PxV2EAk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 16:42: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 16:42: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 16:42:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LVdkzVcO90/Render', 16:42:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:37 INFO - 'HOME': '/Users/cltbld', 16:42:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:37 INFO - 'LOGNAME': 'cltbld', 16:42:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:37 INFO - 'MOZ_NO_REMOTE': '1', 16:42:37 INFO - 'NO_EM_RESTART': '1', 16:42:37 INFO - 'PAGER': '/bin/cat', 16:42:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:37 INFO - 'PWD': '/builds/slave/test', 16:42:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:37 INFO - 'SHELL': '/bin/bash', 16:42:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM4PxV2EAk/Listeners', 16:42:37 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:42:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:37 INFO - 'USER': 'cltbld', 16:42:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:37 INFO - 'XPC_FLAGS': '0x0', 16:42:37 INFO - 'XPC_SERVICE_NAME': '0', 16:42:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42: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 16:42:37 INFO - Cleaning up... 16:42:37 INFO - Return code: 0 16:42:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:42: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')]} 16:42:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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')]} 16:42:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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 0x10b1b0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b126570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac2bd74e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afc4d30>, '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 0x10adeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afbcb20>, '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.LVdkzVcO90/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.iM4PxV2EAk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 16:42: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 16:42: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 16:42:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LVdkzVcO90/Render', 16:42:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:37 INFO - 'HOME': '/Users/cltbld', 16:42:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:37 INFO - 'LOGNAME': 'cltbld', 16:42:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:37 INFO - 'MOZ_NO_REMOTE': '1', 16:42:37 INFO - 'NO_EM_RESTART': '1', 16:42:37 INFO - 'PAGER': '/bin/cat', 16:42:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:37 INFO - 'PWD': '/builds/slave/test', 16:42:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:37 INFO - 'SHELL': '/bin/bash', 16:42:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM4PxV2EAk/Listeners', 16:42:37 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:42:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:37 INFO - 'USER': 'cltbld', 16:42:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:37 INFO - 'XPC_FLAGS': '0x0', 16:42:37 INFO - 'XPC_SERVICE_NAME': '0', 16:42:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:37 INFO - Downloading/unpacking psutil>=3.1.1 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:42:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:42:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:42:41 INFO - Installing collected packages: psutil 16:42:41 INFO - Running setup.py install for psutil 16:42:41 INFO - building 'psutil._psutil_osx' extension 16:42: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 16:42: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 16:42: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 16:42:42 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 16:42:42 INFO - building 'psutil._psutil_posix' extension 16:42:42 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 16:42:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:42:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:42:42 INFO - ^ 16:42:42 INFO - 1 warning generated. 16:42:42 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 16:42:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:42:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:42:42 INFO - Successfully installed psutil 16:42:42 INFO - Cleaning up... 16:42:42 INFO - Return code: 0 16:42:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:42: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')]} 16:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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')]} 16:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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 0x10b1b0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b126570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac2bd74e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afc4d30>, '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 0x10adeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afbcb20>, '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.LVdkzVcO90/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.iM4PxV2EAk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 16:42: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 16:42: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 16:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LVdkzVcO90/Render', 16:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:42 INFO - 'HOME': '/Users/cltbld', 16:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:42 INFO - 'LOGNAME': 'cltbld', 16:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:42 INFO - 'MOZ_NO_REMOTE': '1', 16:42:42 INFO - 'NO_EM_RESTART': '1', 16:42:42 INFO - 'PAGER': '/bin/cat', 16:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:42 INFO - 'PWD': '/builds/slave/test', 16:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:42 INFO - 'SHELL': '/bin/bash', 16:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM4PxV2EAk/Listeners', 16:42:42 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:42 INFO - 'USER': 'cltbld', 16:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:42 INFO - 'XPC_FLAGS': '0x0', 16:42:42 INFO - 'XPC_SERVICE_NAME': '0', 16:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:42:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:42:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:42:45 INFO - Installing collected packages: mozsystemmonitor 16:42:45 INFO - Running setup.py install for mozsystemmonitor 16:42:45 INFO - Successfully installed mozsystemmonitor 16:42:45 INFO - Cleaning up... 16:42:45 INFO - Return code: 0 16:42:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:42: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')]} 16:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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')]} 16:42:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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 0x10b1b0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b126570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac2bd74e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afc4d30>, '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 0x10adeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afbcb20>, '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.LVdkzVcO90/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.iM4PxV2EAk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 16:42: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 16:42: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 16:42:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LVdkzVcO90/Render', 16:42:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:45 INFO - 'HOME': '/Users/cltbld', 16:42:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:45 INFO - 'LOGNAME': 'cltbld', 16:42:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:45 INFO - 'MOZ_NO_REMOTE': '1', 16:42:45 INFO - 'NO_EM_RESTART': '1', 16:42:45 INFO - 'PAGER': '/bin/cat', 16:42:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:45 INFO - 'PWD': '/builds/slave/test', 16:42:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:45 INFO - 'SHELL': '/bin/bash', 16:42:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM4PxV2EAk/Listeners', 16:42:45 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:42:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:45 INFO - 'USER': 'cltbld', 16:42:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:45 INFO - 'XPC_FLAGS': '0x0', 16:42:45 INFO - 'XPC_SERVICE_NAME': '0', 16:42:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:45 INFO - Downloading/unpacking jsonschema==2.5.1 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:42:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:48 INFO - Downloading functools32-3.2.3-2.tar.gz 16:42:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:42:48 INFO - warning: no files found matching '*.txt' 16:42:48 INFO - no previously-included directories found matching 'build' 16:42:48 INFO - no previously-included directories found matching 'dist' 16:42:48 INFO - no previously-included directories found matching '.git*' 16:42:48 INFO - Installing collected packages: jsonschema, functools32 16:42:48 INFO - Running setup.py install for functools32 16:42:48 INFO - warning: no files found matching '*.txt' 16:42:48 INFO - no previously-included directories found matching 'build' 16:42:48 INFO - no previously-included directories found matching 'dist' 16:42:48 INFO - no previously-included directories found matching '.git*' 16:42:48 INFO - Successfully installed jsonschema functools32 16:42:48 INFO - Cleaning up... 16:42:49 INFO - Return code: 0 16:42:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:49 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 0x10b1b0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b126570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac2bd74e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afc4d30>, '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 0x10adeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afbcb20>, '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.LVdkzVcO90/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.iM4PxV2EAk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 16:42:49 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 16:42:49 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 16:42:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LVdkzVcO90/Render', 16:42:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:49 INFO - 'HOME': '/Users/cltbld', 16:42:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:49 INFO - 'LOGNAME': 'cltbld', 16:42:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:49 INFO - 'MOZ_NO_REMOTE': '1', 16:42:49 INFO - 'NO_EM_RESTART': '1', 16:42:49 INFO - 'PAGER': '/bin/cat', 16:42:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:49 INFO - 'PWD': '/builds/slave/test', 16:42:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:49 INFO - 'SHELL': '/bin/bash', 16:42:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM4PxV2EAk/Listeners', 16:42:49 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:42:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:49 INFO - 'USER': 'cltbld', 16:42:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:49 INFO - 'XPC_FLAGS': '0x0', 16:42:49 INFO - 'XPC_SERVICE_NAME': '0', 16:42:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:42:49 INFO - Cleaning up... 16:42:49 INFO - Return code: 0 16:42:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:49 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 0x10b1b0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b126570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac2bd74e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afc4d30>, '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 0x10adeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afbcb20>, '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.LVdkzVcO90/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.iM4PxV2EAk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 16:42:49 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 16:42:49 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 16:42:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LVdkzVcO90/Render', 16:42:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:49 INFO - 'HOME': '/Users/cltbld', 16:42:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:49 INFO - 'LOGNAME': 'cltbld', 16:42:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:49 INFO - 'MOZ_NO_REMOTE': '1', 16:42:49 INFO - 'NO_EM_RESTART': '1', 16:42:49 INFO - 'PAGER': '/bin/cat', 16:42:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:49 INFO - 'PWD': '/builds/slave/test', 16:42:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:49 INFO - 'SHELL': '/bin/bash', 16:42:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM4PxV2EAk/Listeners', 16:42:49 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:42:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:49 INFO - 'USER': 'cltbld', 16:42:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:49 INFO - 'XPC_FLAGS': '0x0', 16:42:49 INFO - 'XPC_SERVICE_NAME': '0', 16:42:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:49 INFO - Downloading/unpacking blobuploader==1.2.4 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42:51 INFO - Downloading blobuploader-1.2.4.tar.gz 16:42:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:42:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:42:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42:53 INFO - Downloading docopt-0.6.1.tar.gz 16:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:42:53 INFO - Installing collected packages: blobuploader, requests, docopt 16:42:53 INFO - Running setup.py install for blobuploader 16:42:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:42:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:42:53 INFO - Running setup.py install for requests 16:42:53 INFO - Running setup.py install for docopt 16:42:54 INFO - Successfully installed blobuploader requests docopt 16:42:54 INFO - Cleaning up... 16:42:54 INFO - Return code: 0 16:42:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:54 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 0x10b1b0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b126570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac2bd74e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afc4d30>, '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 0x10adeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afbcb20>, '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.LVdkzVcO90/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.iM4PxV2EAk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 16:42:54 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 16:42:54 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 16:42:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LVdkzVcO90/Render', 16:42:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:54 INFO - 'HOME': '/Users/cltbld', 16:42:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:54 INFO - 'LOGNAME': 'cltbld', 16:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:54 INFO - 'MOZ_NO_REMOTE': '1', 16:42:54 INFO - 'NO_EM_RESTART': '1', 16:42:54 INFO - 'PAGER': '/bin/cat', 16:42:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:54 INFO - 'PWD': '/builds/slave/test', 16:42:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:54 INFO - 'SHELL': '/bin/bash', 16:42:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM4PxV2EAk/Listeners', 16:42:54 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:42:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:54 INFO - 'USER': 'cltbld', 16:42:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:54 INFO - 'XPC_FLAGS': '0x0', 16:42:54 INFO - 'XPC_SERVICE_NAME': '0', 16:42:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:42:54 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-9MOifw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:42:54 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-InG0TU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:42:54 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-RYorHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:42:54 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Invwgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:42:54 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-9nJ26y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:42:54 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-YzeSSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:42:55 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-NkqlVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:42:55 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-7oLR5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:42:55 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ugrqI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:42:55 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-8WiE0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:42:55 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-RM28J5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:42:55 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-qWUhfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:42:55 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-3lekJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:42:55 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-OQxC6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:42:55 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-XIIM4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:42:56 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-JWes2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:42:56 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-gCj3mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:42:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:42:56 INFO - Running setup.py install for manifestparser 16:42:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:42:56 INFO - Running setup.py install for mozcrash 16:42:56 INFO - Running setup.py install for mozdebug 16:42:56 INFO - Running setup.py install for mozdevice 16:42:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:42:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:42:56 INFO - Running setup.py install for mozfile 16:42:56 INFO - Running setup.py install for mozhttpd 16:42:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:42:57 INFO - Running setup.py install for mozinfo 16:42:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:42:57 INFO - Running setup.py install for mozInstall 16:42:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:42:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:42:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:42:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:42:57 INFO - Running setup.py install for mozleak 16:42:57 INFO - Running setup.py install for mozlog 16:42:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:42:57 INFO - Running setup.py install for moznetwork 16:42:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:42:57 INFO - Running setup.py install for mozprocess 16:42:58 INFO - Running setup.py install for mozprofile 16:42:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:42:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:42:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:42:58 INFO - Running setup.py install for mozrunner 16:42:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:42:58 INFO - Running setup.py install for mozscreenshot 16:42:58 INFO - Running setup.py install for moztest 16:42:58 INFO - Running setup.py install for mozversion 16:42:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:42:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:42:58 INFO - Cleaning up... 16:42:58 INFO - Return code: 0 16:42:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:42: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')]} 16:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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')]} 16:42:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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 0x10b1b0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b126570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac2bd74e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afc4d30>, '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 0x10adeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afbcb20>, '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.LVdkzVcO90/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.iM4PxV2EAk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 16:42: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 16:42: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 16:42:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LVdkzVcO90/Render', 16:42:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:58 INFO - 'HOME': '/Users/cltbld', 16:42:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:58 INFO - 'LOGNAME': 'cltbld', 16:42:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:58 INFO - 'MOZ_NO_REMOTE': '1', 16:42:58 INFO - 'NO_EM_RESTART': '1', 16:42:58 INFO - 'PAGER': '/bin/cat', 16:42:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:58 INFO - 'PWD': '/builds/slave/test', 16:42:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:58 INFO - 'SHELL': '/bin/bash', 16:42:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM4PxV2EAk/Listeners', 16:42:58 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:42:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:58 INFO - 'USER': 'cltbld', 16:42:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:58 INFO - 'XPC_FLAGS': '0x0', 16:42:58 INFO - 'XPC_SERVICE_NAME': '0', 16:42:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:42:59 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-o7Esiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:42:59 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)) 16:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:42:59 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-scvLyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:42:59 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)) 16:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:42:59 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-CCijmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:42:59 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)) 16:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:42:59 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Vycx6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:42: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)) 16:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:42:59 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-hDVfxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:42: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)) 16:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:42:59 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-5oEWle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:42: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)) 16:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:42:59 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ukBhaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:42: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)) 16:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:42:59 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-UXttDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:42: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)) 16:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:42:59 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-yOc6MP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:43:00 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)) 16:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:43:00 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ztoa1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:43:00 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)) 16:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:43:00 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-zxETAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:43:00 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)) 16:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:43:00 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-muwMy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:43:00 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)) 16:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:43:00 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-LKGMcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:43: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)) 16:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:43:00 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-miJTkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:43: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)) 16:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:43:00 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-QRugpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:43: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)) 16:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:43:00 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ChlPDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:43: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)) 16:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:43:00 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-9v91rG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:43: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)) 16:43: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)) 16:43: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)) 16:43: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)) 16:43: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)) 16:43: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)) 16:43:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:43: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 16:43: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 16:43: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 16:43: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 16:43: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 16:43: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 16:43:03 INFO - Downloading blessings-1.6.tar.gz 16:43:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:43:03 INFO - Installing collected packages: blessings 16:43:03 INFO - Running setup.py install for blessings 16:43:03 INFO - Successfully installed blessings 16:43:03 INFO - Cleaning up... 16:43:03 INFO - Return code: 0 16:43:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:43:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:43:04 INFO - Reading from file tmpfile_stdout 16:43:04 INFO - Current package versions: 16:43:04 INFO - blessings == 1.6 16:43:04 INFO - blobuploader == 1.2.4 16:43:04 INFO - docopt == 0.6.1 16:43:04 INFO - functools32 == 3.2.3-2 16:43:04 INFO - jsonschema == 2.5.1 16:43:04 INFO - manifestparser == 1.1 16:43:04 INFO - mozInstall == 1.12 16:43:04 INFO - mozcrash == 0.17 16:43:04 INFO - mozdebug == 0.1 16:43:04 INFO - mozdevice == 0.48 16:43:04 INFO - mozfile == 1.2 16:43:04 INFO - mozhttpd == 0.7 16:43:04 INFO - mozinfo == 0.9 16:43:04 INFO - mozleak == 0.1 16:43:04 INFO - mozlog == 3.3 16:43:04 INFO - moznetwork == 0.27 16:43:04 INFO - mozprocess == 0.23 16:43:04 INFO - mozprofile == 0.28 16:43:04 INFO - mozrunner == 6.12 16:43:04 INFO - mozscreenshot == 0.1 16:43:04 INFO - mozsystemmonitor == 0.3 16:43:04 INFO - moztest == 0.7 16:43:04 INFO - mozversion == 1.4 16:43:04 INFO - psutil == 3.1.1 16:43:04 INFO - requests == 1.2.3 16:43:04 INFO - wsgiref == 0.1.2 16:43:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:43:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:04 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 0x10b1b0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b126570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac2bd74e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afc4d30>, '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 0x10adeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afbcb20>, '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.LVdkzVcO90/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.iM4PxV2EAk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 16:43:04 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 16:43:04 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 16:43:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LVdkzVcO90/Render', 16:43:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:04 INFO - 'HOME': '/Users/cltbld', 16:43:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:04 INFO - 'LOGNAME': 'cltbld', 16:43:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:04 INFO - 'MOZ_NO_REMOTE': '1', 16:43:04 INFO - 'NO_EM_RESTART': '1', 16:43:04 INFO - 'PAGER': '/bin/cat', 16:43:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:04 INFO - 'PWD': '/builds/slave/test', 16:43:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:04 INFO - 'SHELL': '/bin/bash', 16:43:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM4PxV2EAk/Listeners', 16:43:04 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:43:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:04 INFO - 'USER': 'cltbld', 16:43:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:04 INFO - 'XPC_FLAGS': '0x0', 16:43:04 INFO - 'XPC_SERVICE_NAME': '0', 16:43:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:04 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)) 16:43:04 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)) 16:43:04 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)) 16:43:04 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)) 16:43:04 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)) 16:43:04 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)) 16:43:04 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)) 16:43:04 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)) 16:43:04 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)) 16:43:04 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)) 16:43:04 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)) 16:43:04 INFO - Cleaning up... 16:43:04 INFO - Return code: 0 16:43:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:43:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:04 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 0x10b1b0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b126570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac2bd74e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afc4d30>, '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 0x10adeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afbcb20>, '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.LVdkzVcO90/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.iM4PxV2EAk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 16:43:04 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 16:43:04 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 16:43:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LVdkzVcO90/Render', 16:43:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:04 INFO - 'HOME': '/Users/cltbld', 16:43:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:04 INFO - 'LOGNAME': 'cltbld', 16:43:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:04 INFO - 'MOZ_NO_REMOTE': '1', 16:43:04 INFO - 'NO_EM_RESTART': '1', 16:43:04 INFO - 'PAGER': '/bin/cat', 16:43:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:04 INFO - 'PWD': '/builds/slave/test', 16:43:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:04 INFO - 'SHELL': '/bin/bash', 16:43:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM4PxV2EAk/Listeners', 16:43:04 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:43:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:04 INFO - 'USER': 'cltbld', 16:43:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:04 INFO - 'XPC_FLAGS': '0x0', 16:43:04 INFO - 'XPC_SERVICE_NAME': '0', 16:43:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:43:04 INFO - Cleaning up... 16:43:04 INFO - Return code: 0 16:43:04 INFO - Running post-action listener: _resource_record_post_action 16:43:04 INFO - Running post-action listener: _start_resource_monitoring 16:43:04 INFO - Starting resource monitoring. 16:43:04 INFO - [mozharness: 2016-09-10 23:43:04.622985Z] Finished create-virtualenv step (success) 16:43:04 INFO - [mozharness: 2016-09-10 23:43:04.623978Z] Running install step. 16:43:04 INFO - Running pre-action listener: _resource_record_pre_action 16:43:04 INFO - Running main action method: install 16:43:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:43:04 INFO - Reading from file tmpfile_stdout 16:43:04 INFO - Detecting whether we're running mozinstall >=1.0... 16:43:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:43:04 INFO - Reading from file tmpfile_stdout 16:43:04 INFO - Output received: 16:43:04 INFO - Usage: mozinstall [options] installer 16:43:04 INFO - Options: 16:43:04 INFO - -h, --help show this help message and exit 16:43:04 INFO - -d DEST, --destination=DEST 16:43:04 INFO - Directory to install application into. [default: 16:43:04 INFO - "/builds/slave/test"] 16:43:04 INFO - --app=APP Application being installed. [default: firefox] 16:43:04 INFO - mkdir: /builds/slave/test/build/application 16:43: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'] 16:43: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 16:43:38 INFO - Reading from file tmpfile_stdout 16:43:38 INFO - Output received: 16:43:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:43:38 INFO - Running post-action listener: _resource_record_post_action 16:43:38 INFO - [mozharness: 2016-09-10 23:43:38.699004Z] Finished install step (success) 16:43:38 INFO - [mozharness: 2016-09-10 23:43:38.699151Z] Running run-tests step. 16:43:38 INFO - Running pre-action listener: _resource_record_pre_action 16:43:38 INFO - Running main action method: run_tests 16:43:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:43:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:43:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:43:38 INFO - 2016-09-10 16:43:38.713 screenresolution[1650:9372] starting screenresolution argv=screenresolution get 16:43:38 INFO - 2016-09-10 16:43:38.716 screenresolution[1650:9372] Display 0: 1600x1200x32@60 16:43:38 INFO - 2016-09-10 16:43:38.721 screenresolution[1651:9374] starting screenresolution argv=screenresolution list 16:43:38 INFO - Available Modes on Display 0 16:43:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:43:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:43:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:43:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:43:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:43:38 INFO - Intel Iris: 16:43:38 INFO - Chipset Model: Intel Iris 16:43:38 INFO - Type: GPU 16:43:38 INFO - Bus: Built-In 16:43:38 INFO - VRAM (Dynamic, Max): 1536 MB 16:43:38 INFO - Vendor: Intel (0x8086) 16:43:38 INFO - Device ID: 0x0a2e 16:43:38 INFO - Revision ID: 0x0009 16:43:38 INFO - Displays: 16:43:38 INFO - Display: 16:43:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:43:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:43:38 INFO - Display Serial Number: 1600x1200 60 16:43:38 INFO - Main Display: Yes 16:43:38 INFO - Mirror: Off 16:43:38 INFO - Online: Yes 16:43:38 INFO - Rotation: Supported 16:43:38 INFO - Return code: 0 16:43:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:43:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:43:38 INFO - Python 2.7.3 16:43:38 INFO - Return code: 0 16:43:38 INFO - grabbing minidump binary from tooltool 16:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:38 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 0x10afc4d30>, '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 0x10adeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10afbcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:43:38 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 16:43:38 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 16:43:38 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 16:43:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:43:39 INFO - Return code: 0 16:43:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:43:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:43:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:43:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:43:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:43: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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:43: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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:43:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LVdkzVcO90/Render', 16:43:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:39 INFO - 'HOME': '/Users/cltbld', 16:43:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:39 INFO - 'LOGNAME': 'cltbld', 16:43:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:43:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:39 INFO - 'MOZ_NO_REMOTE': '1', 16:43:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:43:39 INFO - 'NO_EM_RESTART': '1', 16:43:39 INFO - 'PAGER': '/bin/cat', 16:43:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:39 INFO - 'PWD': '/builds/slave/test', 16:43:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:43:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:39 INFO - 'SHELL': '/bin/bash', 16:43:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iM4PxV2EAk/Listeners', 16:43:39 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 16:43:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:39 INFO - 'USER': 'cltbld', 16:43:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:39 INFO - 'XPC_FLAGS': '0x0', 16:43:39 INFO - 'XPC_SERVICE_NAME': '0', 16:43:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43: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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:43:39 INFO - mozversion application_buildid: 20160910133015 16:43:39 INFO - mozversion application_changeset: 8b57e28ff3423249a926dd86b43b0b3b9330784e 16:43:39 INFO - mozversion application_display_name: Nightly 16:43:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:43:39 INFO - mozversion application_name: Firefox 16:43:39 INFO - mozversion application_remotingname: firefox 16:43:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:43:39 INFO - mozversion application_vendor: Mozilla 16:43:39 INFO - mozversion application_version: 51.0a1 16:43:39 INFO - mozversion platform_buildid: 20160910133015 16:43:39 INFO - mozversion platform_changeset: 8b57e28ff3423249a926dd86b43b0b3b9330784e 16:43:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:43:39 INFO - mozversion platform_version: 51.0a1 16:43:39 INFO - using testdate: 1473551019 16:43:39 INFO - actual date: 1473551019 16:43:39 INFO - starting webserver on 'localhost:49266' 16:43:39 INFO - SUITE-START | Running 2 tests 16:43:39 INFO - TEST-START | dromaeo_css 16:43:39 INFO - operating with platform_type : mac_ 16:43:39 INFO - Initialising browser for dromaeo_css test... 16:43:39 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpJeltx5/profile http://localhost:49266/getInfo.html) 16:43:42 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 16:43:42 INFO - PROCESS | 1661 | colorDepth:24 16:43:42 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 16:43:42 INFO - PROCESS | 1661 | __metrics 16:43:42 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 16:43:43 INFO - TEST-INFO | 1661: exit 0 16:43:43 INFO - Browser initialized. 16:43:43 INFO - Running cycle 1/1 for dromaeo_css test... 16:43:43 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpJeltx5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:43:55 INFO - PROCESS | 1665 | RSS: Main: 244269056 16:43:55 INFO - PROCESS | 1665 | 16:44:44 INFO - PROCESS | 1665 | 1473551084823 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] 16:46:57 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 16:46:58 INFO - PROCESS | 1665 | RSS: Main: 266633216 16:46:58 INFO - PROCESS | 1665 | 16:50:00 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 16:50:01 INFO - PROCESS | 1665 | RSS: Main: 274472960 16:50:01 INFO - PROCESS | 1665 | 16:53:03 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 16:53:03 INFO - PROCESS | 1665 | RSS: Main: 272932864 16:53:03 INFO - PROCESS | 1665 | 16:56:05 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 16:56:06 INFO - PROCESS | 1665 | RSS: Main: 269242368 16:56:06 INFO - PROCESS | 1665 | 16:59:09 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 16:59:09 INFO - PROCESS | 1665 | RSS: Main: 274505728 16:59:09 INFO - PROCESS | 1665 | 17:02:12 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 17:02:13 INFO - PROCESS | 1665 | RSS: Main: 277217280 17:02:13 INFO - PROCESS | 1665 | 17:02:13 INFO - PROCESS | 1665 | __start_tp_report 17:02:13 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 17:02:13 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 17:02:13 INFO - PROCESS | 1665 | |i|pagename|runs| 17:02:13 INFO - PROCESS | 1665 | |0;dojo.html;3430.8456119474627;3433.296174284272;3469.16739982404;3472.184036751363;3475.95721283615;4777.856664300068;5670.319561652597;5812.709364531777;5829.475347218755;5889.52883769299;5734.971325143379;5765.578457048039;5818.505427038696;5857.853553661172;5913.881722365551;5222.477752224766;5256.342957130359;5322.4411436799155;5380.757865896044;5412.91880621791;5371.194320851864;5392.91910621341;5415.864603384907;5436.483534064278;5484.643498172606;5264.815731449419;5293.57651387888;5325.653832500894;5341.706206158659;5355.437679043722;5315.548178404841;5323.733813309358;5346.358436987586;5368.597355198376;5422.213779002022;5291.3650361956425;5314.176302673091;5321.361436627591;5340.172273297606;5360.437154098802;5712.571557133231;5733.713314334266;5789.565782566325;5796.188533605256;5813.244278243801;4707.246840505537;5206.869828254286;5365.570754339683;5989.831982763363;6296.6536840473755;5435.239066530688;6277.843053923642;6300.716467758962;6333.809985700436;6341.904871426932;5820.854478638068;5906.586538942232;5912.733926973297;6005.909911351289;6010.098485227207;6295.559310848196;6362.204724409449;6381.1066450696935;6387.936120638827;6387.968060159669;6060.2424696912885;6165.938340616626;6185.84535386619;6198.876022479525;6203.627782333075;5248.685078895279;6155.384461553809;6218.06728990655;6286;6308.3060863305;6232.781852635136;6271.749130034838;6298.02380631003;6304.085907543472;6311.873762524816;6285.9057114143325;6286.371362863769;6301.558890877594;6302.842428939313;6321.525885558602;4971.776270067856;4992.825251116194;5080.161773307437;5083.669561478566;5158.561522270649;4498.8425405110665;4546.340780586796;4573.771311434415;4611.146937816515;4628.166929952641;5784.913226301609;5939.613925094855;5959.165716799652;5959.344472108065;5981.581289309706;6193.4425901669065;6307.432331090224;6315.936840631535;6316.021016742412;6316.30520642729;5814.912776308359;5828.242328497268;5834.183214349994;5891.440313170242;5986.982213023709;5473.917891231635;5547.2511210986;5547.916781248285;5560.7219639017885;5563.471470210324;6337.176167098248;6354.205724284465;6369.299377068426;6375.744970201233;6390.808275751735;6232.002879539252;6250.281217660027;6264.937350626617;6280.560360774884;6290.46531044848;6354.745810167541;6388.680565971776;6394.1687580614225;6411.070394792822;6430.742770289136;5231.032259032091;6142.539309551891;6153.476954458921;6314.989601663712;6341.746330146927;6213.627182369072;6241.875162496815;6287.717052732546;6299.748010079728;6326.493880489457;6022.45797878193;6221.129041934223;6229.626222426669;6239.875202495834;6265.812025639056;6024.307204671343;6195.81412557624;6230.127782110418;6230.190075290183;6234.9712297470405;4627.884302892455;5693.032184528723;6047.123167140828;6077.361877002851;6155.230596175478;5151.484851514819;6214.751409943732;6237.96881015592;6242.781502647385;6272.655003974643;6116.633002019893;6124.5712800103565;6139.508839292936;6146.170267013896;6152.169457123231 17:02:13 INFO - PROCESS | 1665 | |1;ext.html;489.7135175922085;491.7295487481885;520.7005971566349;544.311446020784;550.8622844288927;38254.80872595637;39727.205455890864;39747.80126099373;42007.369889451606;42212.57787422125;32375;41042;41060.794696026554;41151;41185.58814411855;9515.952420237889;9691.95154024229;9717.805643887117;9733;9739.366941148803;9520.714378568655;9610.711678649652;9655.951720241388;9666.130048295652;9700.854487182698;8658.264047555935;9600.03999600038;9604.183644390236;9611;9613.567389467491;9615.375000624937;9643.469609171492;9648.614055437774;9656.903430965742;9674.70975870725;9561.235101191887;9642;9643.807123857554;9648.421094734338;9650.469224192666;37169.69906053302;39104.804475977726;39373.21253574941;39455.802720986496;39471.40792888109;3220.9355812883846;3642.5993140754763;3700.7972408966925;3723.6276372362818;3946.4277679736565;29771.553426698618;29932;30321.393572128654;31021.534676979863;31539.21151971196;3185.8566364513654;3244.4160051190775;3256.8697252109887;3261.7879837810465;3300.042987533612;479450.9999999965;481850.5907470475;483562;484756.15243847464;484901.99999999645;55544.16683749747;55720;55724.44275557274;55791.721041394536;55854.44145558492;33355;34492.31015379678;57896.8420631585;58234.70882645559;58267.417325827046;15782.92108539473;16160.595985100232;16181.4336498222;16192;16198.271077801528;12516.249025058525;12613.873861261454;12629.368531573384;12652.620421387373;12690.111692181643;18077.276908923497;18126.63746725058;18146.364877229233;18226;18263.45209643713;6625.4037136657935;6808.978653202059;6932.133483314586;7950.342712932493;7967.203279672102;891.7012800711705;947.3889341374775;973.1387721866058;975.6682727872558;999.2805180270194;25700.742992569834;26169.476610467686;26212.344691382867;27781.16656500308;29212.123636290944;1042.259995403257;1052.4527245832123;1071.244772435431;1080.357187473452;1136.363636363639;15558.84441155574;15999.920000399923;16300.42948496797;16323.755143672854;16630.50108496747;660399;706003;706577;708604;712423;54508.45491545113;54600;55131.44868551343;55239.72380138154;55513;1346.2191928681536;1388.8125103110951;1393.2754967416886;1393.4495874129661;1393.5749596373;22940.885295573415;28949.7105028947;29192.416151677273;30305.848470757504;30513.694863051085;45997;47753.28370074521;48306;48413.757931210115;48835;603670.6696397346;607446;608173.9591302015;609100;609607;23928.40178995539;24426.75573244245;24472.87763561242;24655.013799448538;24655.630165547173;43005.35491967561;43672.781636092885;44295.01344878977;44321.77839110784;44723.77638111789;30708.53937190899;36223.63776362203;36307;37302.44046339253;37518.24963500769;36723;36873.63126368702;37627.623723762415;38035.04912377213;38237.617623823404;28663.42673146567;34830;36027.45958810672;36033.639663603026;36040.098997525274;31275.84362078175;32447.188820279684;32669;32737.017889463354;32908.67091329152;23253.224090443757;24098.15656452016;24454.755452445246;24754.257372278862;24825.62761558614 17:02:13 INFO - PROCESS | 1665 | |2;jquery.html;282.66441065963323;369.1251733391861;375.3431494883953;380.1996796742858;394.9861754838581;19748;20088.799112008877;20303.187872485083;20744.89627551862;20933.47666308343;20086.196552137935;20341.982900854935;20574.177032918666;20624.587508249864;20729.585408291827;5915.378885217055;5925.992581261196;6080.543959203055;6082.330943596201;6180.412860778241;3503.8248087595643;5924.200232968558;6009.248843894513;6034.155218269446;6036.8188954331445;4310.633596144332;6363.554551181419;6627.403533681968;6720.025596288535;6783.118194634691;6480.8703825923685;6701.899471507932;6713.765018224339;6715.059891615129;6729.8317542061595;5769.653820770767;5774.335951365562;5786.68173250471;5798.710064496759;5800.564957628152;25552;25775.613365799527;25898.705064746686;25901.22296331113;25977;2199.7910198531113;2306.988465057681;2400.327908185711;2861.241770930705;3036.5141577347517;9668.95165524175;15642.843571564252;15684.372625094982;16030.198490075449;16272.860899737023;1881.2569035231145;1892.8391086757642;1922.144645632694;1950.6488832010232;1980.5345743750206;78729;121843;122871;124098;129102.41795164044;9925.709657744592;11067.833982490269;11227.21409501327;11285.774284514264;11519.769604607862;14336;14354.425822967174;14365.856341436453;14417.13497190172;14419.927900360432;7035.92964070363;8286.337093032544;8483.533405662625;8506.31949444043;8669.21977022071;8406.621702023423;8431.283340915968;8464.40749147566;8581.528015959057;8613.956930215309;3769.246150769857;3965.4844870166694;4424.800883960165;4578.885527861764;4611.1930412177735;1562.10959752942;1574.8818838587151;1925.990370048141;1945.2219112354887;1962.1464662871697;736.3005145112165;800.9599520023976;842.5408152556748;861.7328628125301;1016.8169729448622;17168.656626867643;19895.801041989685;20864.852433116324;20987.05558249852;21095.472613184487;818.9549574773325;856.6787454704486;921.313621352097;923.8357581121563;931.4271722890373;10296.845547316796;10398;10993.560257589606;11036.282641628268;11057.336559806437;526228;540416;541697.2915135478;551887;573954.1302293462;131515;132954;133860;134928;138319;944.8866136063579;1003.1924300937815;1003.4947578632052;1027.4554486122368;1028.2391030637275;18998.43004709861;19132.713009304593;19468.123934422703;19579.412617621496;19654.803451965297;51867.96264074622;53196.54222475681;54310;54988;55718.44281557295;80635.13013568385;80970.5951470239;81159.59420202862;81262;81364;6840.589564626139;7151.964240178766;7238.963805180941;7359.77920662381;7832.608369581612;11131.499082541142;12330.50677972871;12660.87339126597;12824.422900969292;12972.675683107998;10674.946625266824;10931.617393391192;11105.777884442428;11253.437328133397;11320.26418282809;10653.360798352123;10896.945515272373;10958.506867191156;11048.281861678965;11097.722556936142;10899.891001090205;11203.775924481628;11425.143114266297;11469.942650286695;11535.942320288681;6033.276006879202;7169.139703235645;7270.382017528358;7428.665710042953;7454.739084132029;9222.492762897837;9962.452065136196;9966.903640599383;11030.724231894266;11380.829287560859 17:02:13 INFO - PROCESS | 1665 | |3;mootools.html;1509.8339182689906;1550.620098075971;1734.9479515614537;1845.8154184581535;1853.6756067688152;8124.187581241873;8376.120507346732;8556.657733690652;9129.497877616728;9176.816463670722;8551;8850.203481686647;8917.91082089179;8959.865602015974;8970.237529809974;2384.129792625692;2421.624648179535;2421.6730741349957;2425.8059355251626;2430.744771798962;2372.098602531036;2397.5444665513583;2412.8311018228733;2421.6004359280696;2427.0413186791206;2259.5932732108213;2272.8863556822093;2315.7336906255828;2366.7869891709743;2375.9762402375927;2360.9527809443716;2372.5729368713623;2377.4888398994212;2379.2267513058237;2411.168146989286;2361.5276944610973;2379.6668466414717;2396.892139853711;2406.795422389099;2407.253751337091;8933.821323573493;9010.27917766577;9053.954730226307;9060.818783624356;9074.274058075338;5289.153735402318;5403.864903377408;5457.426970168154;5464;5469.917951230735;3634.345817752803;3670.8715194968313;3706.7405281630295;3725.0873535983587;3741.401375779862;1077.6659235636978;1080.4273734920425;1084.2572837606265;1092.5902786455079;1102.4212288548497;10740.892591074145;11333.376664283462;11783.941080294544;11893.108016898766;11895.04839612829;4032.0927791246736;4706.91740899595;4731.384919960383;4781.713097214179;4908.779104940286;8409.873851892227;10019.398836069713;10066;10083.344582602254;10103.64637237708;5344.35867695879;5353.170258609844;5392.352917649829;5426.9185962210595;5434.402215756264;4449.377087207728;4626.028534007829;4750.881227969259;4910.288008238786;4941.629377796679;3952.9209415812097;3960.247552964985;4044.211378781168;4089.6114869087387;4125.278076336629;1297.5199176304827;1311.9803202951964;1314.8553659097493;1317.6903427694483;1329.820474235985;814.5519964019884;917.3165991336497;920.4799288402031;930.3766476460787;953.1564565359567;5954.583179177503;6004.669743164168;6085.6348619083;6098.72555734993;6161.983272760034;425.4277996095247;427.260838748966;437.3898411715639;437.46410646957224;441.4966937691035;3976.7613943162924;3981.944784632075;3987.960120398817;4118.423420721101;4127.772972486482;10561.471926403648;10794.78410431787;10894.67315980522;10912.890871091346;10927.781444371069;10772;10841.783164336826;10879;10881.945590271998;10905.236633435583;8082.797930051795;8152.673893044331;8171.387145964077;8194.877076843852;8270.710525131592;5767.394423585463;5924.052151655715;5947.524198064231;5949.167116603766;5974.551908606959;10162.441065741456;10165.542550585093;10171.694849154537;10214.336068155546;10249.898770752126;8718.825623487368;9960.103590676874;10243.873173950711;10572.36565806054;10578.41818699948;6540.149780528501;6572.309907459648;7408.703651853865;7449.329560339595;7630.542167469969;8412.537310447984;8439.535825529409;8568.100349463217;8588.484690918565;8596.441231319945;7260.709571617076;7333.816654583465;7389.039424874732;7406.629668516661;7479.6634151464405;7425.591592462466;7458.142313634002;7476.514026588472;7564.810879728051;7623.618819059136;6933.5493192942295;6980.755673551402;7057.117860267466;7086.291370862872;7089.64551772399;4741.312509686008;4978.4274808397495;5067.113255180402;5140.254663073908;5171.456997015259;4291.377750226262;5129.948700512947;5230.189320655152;5296.549793267615;5319.893602127859 17:02:13 INFO - PROCESS | 1665 | |4;prototype.html;250.86704046855166;281.7773958572728;310.8507916200224;316.67698947073984;318.63038874905084;4956.537821342704;5183.2484289778;5534.280543529346;5772.220750198727;5779.133130030497;5062.949370506294;5441.700706461143;5559.694216818073;5597.732918168148;5662.37713851476;3931.3906344516645;3986.66113380363;3990;4174.937375939348;4181.435506206669;3505;4170.332746760519;4186.434831297766;4193.287141185991;4243.681723870706;3702.4446333050123;3908.17928235071;3998.900027499307;4148.502179738421;4195.328747400416;4051.837926482952;4099.733517321394;4182.344211425736;4228.957710422918;4252.681048921343;3599.7120230381506;3617.5839778425557;3621.7645853019735;3629.891103266906;3633.8364773585254;4986.725730084844;5413.26920865682;5417.295751552312;5452.182172674091;5517.544692611789;4401.889952751175;4500.302453119739;4738.455077666078;4852.636052296057;4888.486708895551;5383.138697808332;5521.889562208733;5828.795992140297;5878.706064696791;5971.924458903624;3483.7038851697635;3845.903852403684;3920.5687374388795;3956.742811717229;3957.703172262092;5354.866128346784;6032.491877030742;6136.662483563402;6230.408111229426;6269.0909818076125;3509.5613048368955;3984.426151670132;4021.557628660845;4037.394390841397;4056.350983842571;3235.6117265928237;3384.8322328796526;3537.451694987281;4024.577419370924;4312.094460163338;4214.936775948363;4545.499995000613;4756.762161891892;4796.928046079312;4800.687955282895;5247.868803279948;5876.7355469004;5968.701564921824;5992.700364981733;6009.789657361971;1175.6531823112202;1326.3898606641053;1350.540816122523;1383.4881093995084;1390.2353705462158;795.0459448661629;827.8261565071282;846.5767116441779;847;847.5507980770202;4882.145624515695;5184.766685499161;5261.894762104736;5415.241866138744;5416.756245968941;6129.2032035835055;6133.693315334304;6165.352637973038;6192;6232.3201023559395;5285.3351194373645;5300.833816560351;5327.334083239595;5574.358948720868;5597.916031259534;2859.742623163925;3123.87504499822;3173.8571764270664;3176.110689007082;3186.9362612747614;5642.802501912413;5891.169241222156;6021.789237376671;6263.467605253513;6311.147995020705;5330.7068111253475;6255.624662520263;6275.184226050585;6283.59156654816;6298.464630506367;5557.416471270459;6247.406496382836;6326.49388048955;6353.491720662336;6354.23749150105;5070.847874563769;5580.358258800127;6037.667928263988;6056.0007598745715;6060.939390606038;5107.87230319245;6030.276366836008;6044.939550604614;6098;6125.540584456095;5895.498882594856;6258.748250349857;6339.619622822645;6357.96821015892;6404.039394090923;3352.7150192233935;3549.183687751883;3689.2805902848686;3967.325554655773;3984.601539845992;5160.142348754521;6064.179566872842;6085.421884920926;6099.725512352044;6112.8910525868505;4534.387857639176;5298.761555730079;5554.666719996813;5771.422857714195;5827.9679648240935;5657.8684263146715;5681.863627274479;5831.521603318657;5923.437273459014;5943.167956486009;5775.112443778111;5802;5817.2146760188525;5824.883502329845;5852.800175964002;5168.8449346519665;5558.6937069787755;5695.914561281667;5844.240248767633;5853.473187413153;5740.708340623326;5747.5401967841635;5749.8275051748515;5752.568557358131;5802.970985145047 17:02:13 INFO - PROCESS | 1665 | |5;yui.html;683.753848614499;700.1842853076166;712.9714811407543;733.8862476316173;763.3015790551646;14920.701585968287;15685.294161762706;15687.215639218051;15748.76376854348;15774.290156942936;13622.523211687592;13640.454381824715;13646.658833529167;13653.931730341334;13742.519011834587;677.156939610186;698.6087790837147;840.6553313141613;846.124261389462;866.4411454611771;861.0356400831078;871.525018864717;873.5544872115204;874.0865795243953;874.9518776467291;914.4833169259381;927.939683920543;930.06993006993;930.3208657679897;931.12009151352;921.4655499810094;922.202295014814;922.8477301245287;923.7136487688805;925.9814803703922;650.3009265040101;684.3635419060272;686.2828344380134;688.6006116452446;689.3554526517672;14611.269436528131;14689.265536723122;14738.410463581444;14762.335694893756;14775.852241477554;12716.872831271661;12741.235525868478;12786.104972651918;12810.871891281062;13211.05675488328;11501.252418592849;11775.470103845262;11798;11816.527338906433;12258.75482490354;3813.389857622795;3820.7707537547835;3827.119762454623;3863.864764733249;3900.1614652849776;98299;103889;103906.48046759794;116772.41613791962;129731;9079.863802042975;9350.95324523373;9590.856137157949;10796.67609971702;11214.887851121384;21949.451263718536;21991.010404531833;21995.450113746963;22003.889980549993;22011.889940550194;8041.718539851077;8148;8174.427790054639;8179.304759095425;8331.8333633327;4442.888927776831;4473.217186992263;4536;4585.426821647294;4592.03567250881;3051.5727798108283;3181.8886338978486;3562.5190599268767;3699.167687270343;3700.5559332880225;544.5297696220142;547.9232907392968;611.260374946311;669.8827705151579;676.9559978601375;607.5018484842475;610.6305685060563;614.4193736918659;668.8394785251602;676.6379986707186;8252.555802734498;9372.297077719199;9562.473160160213;9627.711168664951;9795.522589480632;501.5185421994853;532.7762339817209;621.0342916764474;624.5908929651085;668.9765858195037;4490.418248677085;4623.722576645412;4692.5307469253485;4777.235642297216;4795.345605766005;95342.04657953331;134872;153024;155414;160272;81480.59259703782;130583;134567.32716336354;143949;148465.2576737131;121382;122821.38589307175;124391;125354;127010.72989270174;8756.4308319959;8993.7751556209;9046.728598142066;9077.45535267886;9192.678256260999;62049.689751550955;65582.01626975504;65809;66884.3278918031;68641.65679171772;94043.52978235339;102493.48753256186;103111;104856;106100;11771.352575608424;11889.28664280146;11892;11959.76080478403;12031.819522706992;12971.286579237854;13010.804837927628;13314.667133321744;13431.395587198404;13755.17468951866;11147.442627868737;11740.823887641522;12243.571474998333;12316.507339706312;12776.169548979287;12103.455344509342;12163.695907602381;12225.493773776177;12491;12775.936120319338;12074.094442916641;12247.816282755588;12480.188787728768;12580;12688.302143381834;8500.44747091437;9927.801443970935;10329.018743219684;10356.948215258875;10458.63394781179;8988.820223595621;10253.58985640566;10306.969303069933;10543.472826358498;10570.841437378293 17:02:13 INFO - PROCESS | 1665 | __end_tp_report 17:02:13 INFO - PROCESS | 1665 | __start_cc_report 17:02:13 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,390 17:02:13 INFO - PROCESS | 1665 | __end_cc_report 17:02:13 INFO - PROCESS | 1665 | __startTimestamp1473552133002__endTimestamp 17:02:13 INFO - PROCESS | 1665 | 17:02:13 INFO - PROCESS | 1665 | ------- Summary: start ------- 17:02:13 INFO - PROCESS | 1665 | Number of tests: 6 17:02:13 INFO - PROCESS | 1665 | 17:02:13 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5786.62 Median:5989.83 stddev:637.35 (10.6%) stddev-sans-first:611.93 17:02:13 INFO - PROCESS | 1665 | Values: 3430.8 3433.3 3469.2 3472.2 3476.0 4777.9 5670.3 5812.7 5829.5 5889.5 5735.0 5765.6 5818.5 5857.9 5913.9 5222.5 5256.3 5322.4 5380.8 5412.9 5371.2 5392.9 5415.9 5436.5 5484.6 5264.8 5293.6 5325.7 5341.7 5355.4 5315.5 5323.7 5346.4 5368.6 5422.2 5291.4 5314.2 5321.4 5340.2 5360.4 5712.6 5733.7 5789.6 5796.2 5813.2 4707.2 5206.9 5365.6 5989.8 6296.7 5435.2 6277.8 6300.7 6333.8 6341.9 5820.9 5906.6 5912.7 6005.9 6010.1 6295.6 6362.2 6381.1 6387.9 6388.0 6060.2 6165.9 6185.8 6198.9 6203.6 5248.7 6155.4 6218.1 6286.0 6308.3 6232.8 6271.7 6298.0 6304.1 6311.9 6285.9 6286.4 6301.6 6302.8 6321.5 4971.8 4992.8 5080.2 5083.7 5158.6 4498.8 4546.3 4573.8 4611.1 4628.2 5784.9 5939.6 5959.2 5959.3 5981.6 6193.4 6307.4 6315.9 6316.0 6316.3 5814.9 5828.2 5834.2 5891.4 5987.0 5473.9 5547.3 5547.9 5560.7 5563.5 6337.2 6354.2 6369.3 6375.7 6390.8 6232.0 6250.3 6264.9 6280.6 6290.5 6354.7 6388.7 6394.2 6411.1 6430.7 5231.0 6142.5 6153.5 6315.0 6341.7 6213.6 6241.9 6287.7 6299.7 6326.5 6022.5 6221.1 6229.6 6239.9 6265.8 6024.3 6195.8 6230.1 6230.2 6235.0 4627.9 5693.0 6047.1 6077.4 6155.2 5151.5 6214.8 6238.0 6242.8 6272.7 6116.6 6124.6 6139.5 6146.2 6152.2 17:02:13 INFO - PROCESS | 1665 | 17:02:13 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:71183.80 Median:39414.51 stddev:161725.40 (410.3%) stddev-sans-first:162091.98 17:02:13 INFO - PROCESS | 1665 | Values: 489.7 491.7 520.7 544.3 550.9 38254.8 39727.2 39747.8 42007.4 42212.6 32375.0 41042.0 41060.8 41151.0 41185.6 9516.0 9692.0 9717.8 9733.0 9739.4 9520.7 9610.7 9656.0 9666.1 9700.9 8658.3 9600.0 9604.2 9611.0 9613.6 9615.4 9643.5 9648.6 9656.9 9674.7 9561.2 9642.0 9643.8 9648.4 9650.5 37169.7 39104.8 39373.2 39455.8 39471.4 3220.9 3642.6 3700.8 3723.6 3946.4 29771.6 29932.0 30321.4 31021.5 31539.2 3185.9 3244.4 3256.9 3261.8 3300.0 479451.0 481850.6 483562.0 484756.2 484902.0 55544.2 55720.0 55724.4 55791.7 55854.4 33355.0 34492.3 57896.8 58234.7 58267.4 15782.9 16160.6 16181.4 16192.0 16198.3 12516.2 12613.9 12629.4 12652.6 12690.1 18077.3 18126.6 18146.4 18226.0 18263.5 6625.4 6809.0 6932.1 7950.3 7967.2 891.7 947.4 973.1 975.7 999.3 25700.7 26169.5 26212.3 27781.2 29212.1 1042.3 1052.5 1071.2 1080.4 1136.4 15558.8 15999.9 16300.4 16323.8 16630.5 660399.0 706003.0 706577.0 708604.0 712423.0 54508.5 54600.0 55131.4 55239.7 55513.0 1346.2 1388.8 1393.3 1393.4 1393.6 22940.9 28949.7 29192.4 30305.8 30513.7 45997.0 47753.3 48306.0 48413.8 48835.0 603670.7 607446.0 608174.0 609100.0 609607.0 23928.4 24426.8 24472.9 24655.0 24655.6 43005.4 43672.8 44295.0 44321.8 44723.8 30708.5 36223.6 36307.0 37302.4 37518.2 36723.0 36873.6 37627.6 38035.0 38237.6 28663.4 34830.0 36027.5 36033.6 36040.1 31275.8 32447.2 32669.0 32737.0 32908.7 23253.2 24098.2 24454.8 24754.3 24825.6 17:02:13 INFO - PROCESS | 1665 | 17:02:13 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:33868.11 Median:25939.11 stddev:92029.30 (354.8%) stddev-sans-first:92252.91 17:02:13 INFO - PROCESS | 1665 | Values: 282.7 369.1 375.3 380.2 395.0 19748.0 20088.8 20303.2 20744.9 20933.5 20086.2 20342.0 20574.2 20624.6 20729.6 5915.4 5926.0 6080.5 6082.3 6180.4 3503.8 5924.2 6009.2 6034.2 6036.8 4310.6 6363.6 6627.4 6720.0 6783.1 6480.9 6701.9 6713.8 6715.1 6729.8 5769.7 5774.3 5786.7 5798.7 5800.6 25552.0 25775.6 25898.7 25901.2 25977.0 2199.8 2307.0 2400.3 2861.2 3036.5 9669.0 15642.8 15684.4 16030.2 16272.9 1881.3 1892.8 1922.1 1950.6 1980.5 78729.0 121843.0 122871.0 124098.0 129102.4 9925.7 11067.8 11227.2 11285.8 11519.8 14336.0 14354.4 14365.9 14417.1 14419.9 7035.9 8286.3 8483.5 8506.3 8669.2 8406.6 8431.3 8464.4 8581.5 8614.0 3769.2 3965.5 4424.8 4578.9 4611.2 1562.1 1574.9 1926.0 1945.2 1962.1 736.3 801.0 842.5 861.7 1016.8 17168.7 19895.8 20864.9 20987.1 21095.5 819.0 856.7 921.3 923.8 931.4 10296.8 10398.0 10993.6 11036.3 11057.3 526228.0 540416.0 541697.3 551887.0 573954.1 131515.0 132954.0 133860.0 134928.0 138319.0 944.9 1003.2 1003.5 1027.5 1028.2 18998.4 19132.7 19468.1 19579.4 19654.8 51868.0 53196.5 54310.0 54988.0 55718.4 80635.1 80970.6 81159.6 81262.0 81364.0 6840.6 7152.0 7239.0 7359.8 7832.6 11131.5 12330.5 12660.9 12824.4 12972.7 10674.9 10931.6 11105.8 11253.4 11320.3 10653.4 10896.9 10958.5 11048.3 11097.7 10899.9 11203.8 11425.1 11469.9 11535.9 6033.3 7169.1 7270.4 7428.7 7454.7 9222.5 9962.5 9966.9 11030.7 11380.8 17:02:13 INFO - PROCESS | 1665 | 17:02:13 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5743.61 Median:4666.47 stddev:3226.13 (69.1%) stddev-sans-first:3219.49 17:02:13 INFO - PROCESS | 1665 | Values: 1509.8 1550.6 1734.9 1845.8 1853.7 8124.2 8376.1 8556.7 9129.5 9176.8 8551.0 8850.2 8917.9 8959.9 8970.2 2384.1 2421.6 2421.7 2425.8 2430.7 2372.1 2397.5 2412.8 2421.6 2427.0 2259.6 2272.9 2315.7 2366.8 2376.0 2361.0 2372.6 2377.5 2379.2 2411.2 2361.5 2379.7 2396.9 2406.8 2407.3 8933.8 9010.3 9054.0 9060.8 9074.3 5289.2 5403.9 5457.4 5464.0 5469.9 3634.3 3670.9 3706.7 3725.1 3741.4 1077.7 1080.4 1084.3 1092.6 1102.4 10740.9 11333.4 11783.9 11893.1 11895.0 4032.1 4706.9 4731.4 4781.7 4908.8 8409.9 10019.4 10066.0 10083.3 10103.6 5344.4 5353.2 5392.4 5426.9 5434.4 4449.4 4626.0 4750.9 4910.3 4941.6 3952.9 3960.2 4044.2 4089.6 4125.3 1297.5 1312.0 1314.9 1317.7 1329.8 814.6 917.3 920.5 930.4 953.2 5954.6 6004.7 6085.6 6098.7 6162.0 425.4 427.3 437.4 437.5 441.5 3976.8 3981.9 3988.0 4118.4 4127.8 10561.5 10794.8 10894.7 10912.9 10927.8 10772.0 10841.8 10879.0 10881.9 10905.2 8082.8 8152.7 8171.4 8194.9 8270.7 5767.4 5924.1 5947.5 5949.2 5974.6 10162.4 10165.5 10171.7 10214.3 10249.9 8718.8 9960.1 10243.9 10572.4 10578.4 6540.1 6572.3 7408.7 7449.3 7630.5 8412.5 8439.5 8568.1 8588.5 8596.4 7260.7 7333.8 7389.0 7406.6 7479.7 7425.6 7458.1 7476.5 7564.8 7623.6 6933.5 6980.8 7057.1 7086.3 7089.6 4741.3 4978.4 5067.1 5140.3 5171.5 4291.4 5129.9 5230.2 5296.5 5319.9 17:02:13 INFO - PROCESS | 1665 | 17:02:13 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4750.49 Median:5414.26 stddev:1514.41 (28.0%) stddev-sans-first:1480.52 17:02:13 INFO - PROCESS | 1665 | Values: 250.9 281.8 310.9 316.7 318.6 4956.5 5183.2 5534.3 5772.2 5779.1 5062.9 5441.7 5559.7 5597.7 5662.4 3931.4 3986.7 3990.0 4174.9 4181.4 3505.0 4170.3 4186.4 4193.3 4243.7 3702.4 3908.2 3998.9 4148.5 4195.3 4051.8 4099.7 4182.3 4229.0 4252.7 3599.7 3617.6 3621.8 3629.9 3633.8 4986.7 5413.3 5417.3 5452.2 5517.5 4401.9 4500.3 4738.5 4852.6 4888.5 5383.1 5521.9 5828.8 5878.7 5971.9 3483.7 3845.9 3920.6 3956.7 3957.7 5354.9 6032.5 6136.7 6230.4 6269.1 3509.6 3984.4 4021.6 4037.4 4056.4 3235.6 3384.8 3537.5 4024.6 4312.1 4214.9 4545.5 4756.8 4796.9 4800.7 5247.9 5876.7 5968.7 5992.7 6009.8 1175.7 1326.4 1350.5 1383.5 1390.2 795.0 827.8 846.6 847.0 847.6 4882.1 5184.8 5261.9 5415.2 5416.8 6129.2 6133.7 6165.4 6192.0 6232.3 5285.3 5300.8 5327.3 5574.4 5597.9 2859.7 3123.9 3173.9 3176.1 3186.9 5642.8 5891.2 6021.8 6263.5 6311.1 5330.7 6255.6 6275.2 6283.6 6298.5 5557.4 6247.4 6326.5 6353.5 6354.2 5070.8 5580.4 6037.7 6056.0 6060.9 5107.9 6030.3 6044.9 6098.0 6125.5 5895.5 6258.7 6339.6 6358.0 6404.0 3352.7 3549.2 3689.3 3967.3 3984.6 5160.1 6064.2 6085.4 6099.7 6112.9 4534.4 5298.8 5554.7 5771.4 5828.0 5657.9 5681.9 5831.5 5923.4 5943.2 5775.1 5802.0 5817.2 5824.9 5852.8 5168.8 5558.7 5695.9 5844.2 5853.5 5740.7 5747.5 5749.8 5752.6 5803.0 17:02:13 INFO - PROCESS | 1665 | 17:02:13 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:25073.35 Median:3817.08 stddev:40855.50 (1070.3%) stddev-sans-first:40929.07 17:02:13 INFO - PROCESS | 1665 | Values: 683.8 700.2 713.0 733.9 763.3 14920.7 15685.3 15687.2 15748.8 15774.3 13622.5 13640.5 13646.7 13653.9 13742.5 677.2 698.6 840.7 846.1 866.4 861.0 871.5 873.6 874.1 875.0 914.5 927.9 930.1 930.3 931.1 921.5 922.2 922.8 923.7 926.0 650.3 684.4 686.3 688.6 689.4 14611.3 14689.3 14738.4 14762.3 14775.9 12716.9 12741.2 12786.1 12810.9 13211.1 11501.3 11775.5 11798.0 11816.5 12258.8 3813.4 3820.8 3827.1 3863.9 3900.2 98299.0 103889.0 103906.5 116772.4 129731.0 9079.9 9351.0 9590.9 10796.7 11214.9 21949.5 21991.0 21995.5 22003.9 22011.9 8041.7 8148.0 8174.4 8179.3 8331.8 4442.9 4473.2 4536.0 4585.4 4592.0 3051.6 3181.9 3562.5 3699.2 3700.6 544.5 547.9 611.3 669.9 677.0 607.5 610.6 614.4 668.8 676.6 8252.6 9372.3 9562.5 9627.7 9795.5 501.5 532.8 621.0 624.6 669.0 4490.4 4623.7 4692.5 4777.2 4795.3 95342.0 134872.0 153024.0 155414.0 160272.0 81480.6 130583.0 134567.3 143949.0 148465.3 121382.0 122821.4 124391.0 125354.0 127010.7 8756.4 8993.8 9046.7 9077.5 9192.7 62049.7 65582.0 65809.0 66884.3 68641.7 94043.5 102493.5 103111.0 104856.0 106100.0 11771.4 11889.3 11892.0 11959.8 12031.8 12971.3 13010.8 13314.7 13431.4 13755.2 11147.4 11740.8 12243.6 12316.5 12776.2 12103.5 12163.7 12225.5 12491.0 12775.9 12074.1 12247.8 12480.2 12580.0 12688.3 8500.4 9927.8 10329.0 10356.9 10458.6 8988.8 10253.6 10307.0 10543.5 10570.8 17:02:13 INFO - PROCESS | 1665 | -------- Summary: end -------- 17:02:13 INFO - PROCESS | 1665 | 17:02:13 INFO - TEST-INFO | 1665: exit 0 17:02:13 INFO - TEST-OK | dromaeo_css | took 1114383ms 17:02:13 INFO - TEST-START | kraken 17:02:13 INFO - operating with platform_type : mac_ 17:02:13 INFO - Initialising browser for kraken test... 17:02:13 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpbekwqC/profile http://localhost:49266/getInfo.html) 17:02:16 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 17:02:16 INFO - PROCESS | 1673 | colorDepth:24 17:02:16 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 17:02:16 INFO - PROCESS | 1673 | __metrics 17:02:16 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 17:02:17 INFO - TEST-INFO | 1673: exit 0 17:02:17 INFO - Browser initialized. 17:02:17 INFO - Running cycle 1/1 for kraken test... 17:02:17 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpbekwqC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:02:29 INFO - PROCESS | 1675 | RSS: Main: 244244480 17:02:29 INFO - PROCESS | 1675 | 17:02:32 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 17:02:33 INFO - PROCESS | 1675 | RSS: Main: 256798720 17:02:33 INFO - PROCESS | 1675 | 17:02:35 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 17:02:36 INFO - PROCESS | 1675 | RSS: Main: 265424896 17:02:36 INFO - PROCESS | 1675 | 17:02:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 17:02:40 INFO - PROCESS | 1675 | RSS: Main: 262008832 17:02:40 INFO - PROCESS | 1675 | 17:02:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 17:02:42 INFO - PROCESS | 1675 | RSS: Main: 263335936 17:02:42 INFO - PROCESS | 1675 | 17:02:44 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:02:45 INFO - PROCESS | 1675 | RSS: Main: 265003008 17:02:45 INFO - PROCESS | 1675 | 17:02:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 17:02:48 INFO - PROCESS | 1675 | RSS: Main: 265764864 17:02:48 INFO - PROCESS | 1675 | 17:02:52 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 17:02:52 INFO - PROCESS | 1675 | RSS: Main: 266022912 17:02:52 INFO - PROCESS | 1675 | 17:02:55 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 17:02:56 INFO - PROCESS | 1675 | RSS: Main: 266407936 17:02:56 INFO - PROCESS | 1675 | 17:02:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:02:58 INFO - PROCESS | 1675 | RSS: Main: 265306112 17:02:58 INFO - PROCESS | 1675 | 17:03:00 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:03:00 INFO - PROCESS | 1675 | RSS: Main: 265531392 17:03:00 INFO - PROCESS | 1675 | 17:03:03 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:03:03 INFO - PROCESS | 1675 | RSS: Main: 265740288 17:03:03 INFO - PROCESS | 1675 | 17:03:06 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:03:07 INFO - PROCESS | 1675 | RSS: Main: 266264576 17:03:07 INFO - PROCESS | 1675 | 17:03:10 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:03:11 INFO - PROCESS | 1675 | RSS: Main: 266137600 17:03:11 INFO - PROCESS | 1675 | 17:03:12 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 17:03:13 INFO - PROCESS | 1675 | RSS: Main: 268206080 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | __start_tp_report 17:03:13 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 17:03:13 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 17:03:13 INFO - PROCESS | 1675 | |i|pagename|runs| 17:03:13 INFO - PROCESS | 1675 | |0;ai-astar;106;117;118;114;117;117;118;117;116;134 17:03:13 INFO - PROCESS | 1675 | |1;audio-beat-detection;139;110;132;156;110;133;129;111;158;110 17:03:13 INFO - PROCESS | 1675 | |2;audio-dft;199;229;268;199;196;206;195;199;231;278 17:03:13 INFO - PROCESS | 1675 | |3;audio-fft;69;81;74;143;58;58;59;60;138;62 17:03:13 INFO - PROCESS | 1675 | |4;audio-oscillator;69;70;115;72;78;114;144;96;72;113 17:03:13 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;88;86;89;86;88;85;88;86;88;87 17:03:13 INFO - PROCESS | 1675 | |6;imaging-darkroom;111;151;114;95;95;94;94;97;151;151 17:03:13 INFO - PROCESS | 1675 | |7;imaging-desaturate;102;140;92;91;90;94;93;91;92;91 17:03:13 INFO - PROCESS | 1675 | |8;json-parse-financial;55;57;57;56;56;57;57;59;56;57 17:03:13 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;65;67;92;102;105;99;72;68;68;82 17:03:13 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;77;77;79;100;100;80;108;106;106;99 17:03:13 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;158;146;153;158;148;143;152;162;186;139 17:03:13 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;170;176;232;192;169;173;168;171;168;168 17:03:13 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;69;68;73;88;88;91;82;68;69;70 17:03:13 INFO - PROCESS | 1675 | __end_tp_report 17:03:13 INFO - PROCESS | 1675 | __start_cc_report 17:03:13 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,946 17:03:13 INFO - PROCESS | 1675 | __end_cc_report 17:03:13 INFO - PROCESS | 1675 | __startTimestamp1473552193122__endTimestamp 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | ------- Summary: start ------- 17:03:13 INFO - PROCESS | 1675 | Number of tests: 14 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:117.40 Median:117.00 stddev:6.83 (5.8%) stddev-sans-first:5.87 17:03:13 INFO - PROCESS | 1675 | Values: 106.0 117.0 118.0 114.0 117.0 117.0 118.0 117.0 116.0 134.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:132.50 stddev:18.56 (14.0%) stddev-sans-first:19.32 17:03:13 INFO - PROCESS | 1675 | Values: 139.0 110.0 132.0 156.0 110.0 133.0 129.0 111.0 158.0 110.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:220.00 Median:217.50 stddev:30.89 (14.2%) stddev-sans-first:31.82 17:03:13 INFO - PROCESS | 1675 | Values: 199.0 229.0 268.0 199.0 196.0 206.0 195.0 199.0 231.0 278.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:80.20 Median:61.00 stddev:32.70 (53.6%) stddev-sans-first:34.43 17:03:13 INFO - PROCESS | 1675 | Values: 69.0 81.0 74.0 143.0 58.0 58.0 59.0 60.0 138.0 62.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:71.00 stddev:26.10 (36.8%) stddev-sans-first:26.03 17:03:13 INFO - PROCESS | 1675 | Values: 69.0 70.0 115.0 72.0 78.0 114.0 144.0 96.0 72.0 113.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:88.00 stddev:1.29 (1.5%) stddev-sans-first:1.32 17:03:13 INFO - PROCESS | 1675 | Values: 88.0 86.0 89.0 86.0 88.0 85.0 88.0 86.0 88.0 87.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:115.30 Median:94.00 stddev:25.63 (27.3%) stddev-sans-first:27.13 17:03:13 INFO - PROCESS | 1675 | Values: 111.0 151.0 114.0 95.0 95.0 94.0 94.0 97.0 151.0 151.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:91.50 stddev:15.28 (16.7%) stddev-sans-first:16.13 17:03:13 INFO - PROCESS | 1675 | Values: 102.0 140.0 92.0 91.0 90.0 94.0 93.0 91.0 92.0 91.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:1.06 (1.9%) stddev-sans-first:0.93 17:03:13 INFO - PROCESS | 1675 | Values: 55.0 57.0 57.0 56.0 56.0 57.0 57.0 59.0 56.0 57.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:82.00 Median:70.00 stddev:16.07 (23.0%) stddev-sans-first:15.82 17:03:13 INFO - PROCESS | 1675 | Values: 65.0 67.0 92.0 102.0 105.0 99.0 72.0 68.0 68.0 82.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:77.00 stddev:13.22 (17.2%) stddev-sans-first:12.66 17:03:13 INFO - PROCESS | 1675 | Values: 77.0 77.0 79.0 100.0 100.0 80.0 108.0 106.0 106.0 99.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:154.50 Median:155.50 stddev:13.20 (8.5%) stddev-sans-first:13.94 17:03:13 INFO - PROCESS | 1675 | Values: 158.0 146.0 153.0 158.0 148.0 143.0 152.0 162.0 186.0 139.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:172.00 stddev:20.08 (11.7%) stddev-sans-first:21.05 17:03:13 INFO - PROCESS | 1675 | Values: 170.0 176.0 232.0 192.0 169.0 173.0 168.0 171.0 168.0 168.0 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.60 Median:77.50 stddev:9.52 (12.3%) stddev-sans-first:9.70 17:03:13 INFO - PROCESS | 1675 | Values: 69.0 68.0 73.0 88.0 88.0 91.0 82.0 68.0 69.0 70.0 17:03:13 INFO - PROCESS | 1675 | -------- Summary: end -------- 17:03:13 INFO - PROCESS | 1675 | 17:03:13 INFO - TEST-INFO | 1675: exit 0 17:03:13 INFO - TEST-OK | kraken | took 60274ms 17:03:13 INFO - SUITE-END | took 1174s 17:03:14 INFO - Completed test suite (00:19:34) 17:03:14 INFO - javascript benchmark 17:03:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7352.474730189965, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3430.8456119474627, 3433.296174284272, 3469.16739982404, 3472.184036751363, 3475.95721283615, 4777.856664300068, 5670.319561652597, 5812.709364531777, 5829.475347218755, 5889.52883769299, 5734.971325143379, 5765.578457048039, 5818.505427038696, 5857.853553661172, 5913.881722365551, 5222.477752224766, 5256.342957130359, 5322.4411436799155, 5380.757865896044, 5412.91880621791, 5371.194320851864, 5392.91910621341, 5415.864603384907, 5436.483534064278, 5484.643498172606, 5264.815731449419, 5293.57651387888, 5325.653832500894, 5341.706206158659, 5355.437679043722, 5315.548178404841, 5323.733813309358, 5346.358436987586, 5368.597355198376, 5422.213779002022, 5291.3650361956425, 5314.176302673091, 5321.361436627591, 5340.172273297606, 5360.437154098802, 5712.571557133231, 5733.713314334266, 5789.565782566325, 5796.188533605256, 5813.244278243801, 4707.246840505537, 5206.869828254286, 5365.570754339683, 5989.831982763363, 6296.6536840473755, 5435.239066530688, 6277.843053923642, 6300.716467758962, 6333.809985700436, 6341.904871426932, 5820.854478638068, 5906.586538942232, 5912.733926973297, 6005.909911351289, 6010.098485227207, 6295.559310848196, 6362.204724409449, 6381.1066450696935, 6387.936120638827, 6387.968060159669, 6060.2424696912885, 6165.938340616626, 6185.84535386619, 6198.876022479525, 6203.627782333075, 5248.685078895279, 6155.384461553809, 6218.06728990655, 6286.0, 6308.3060863305, 6232.781852635136, 6271.749130034838, 6298.02380631003, 6304.085907543472, 6311.873762524816, 6285.9057114143325, 6286.371362863769, 6301.558890877594, 6302.842428939313, 6321.525885558602, 4971.776270067856, 4992.825251116194, 5080.161773307437, 5083.669561478566, 5158.561522270649, 4498.8425405110665, 4546.340780586796, 4573.771311434415, 4611.146937816515, 4628.166929952641, 5784.913226301609, 5939.613925094855, 5959.165716799652, 5959.344472108065, 5981.581289309706, 6193.4425901669065, 6307.432331090224, 6315.936840631535, 6316.021016742412, 6316.30520642729, 5814.912776308359, 5828.242328497268, 5834.183214349994, 5891.440313170242, 5986.982213023709, 5473.917891231635, 5547.2511210986, 5547.916781248285, 5560.7219639017885, 5563.471470210324, 6337.176167098248, 6354.205724284465, 6369.299377068426, 6375.744970201233, 6390.808275751735, 6232.002879539252, 6250.281217660027, 6264.937350626617, 6280.560360774884, 6290.46531044848, 6354.745810167541, 6388.680565971776, 6394.1687580614225, 6411.070394792822, 6430.742770289136, 5231.032259032091, 6142.539309551891, 6153.476954458921, 6314.989601663712, 6341.746330146927, 6213.627182369072, 6241.875162496815, 6287.717052732546, 6299.748010079728, 6326.493880489457, 6022.45797878193, 6221.129041934223, 6229.626222426669, 6239.875202495834, 6265.812025639056, 6024.307204671343, 6195.81412557624, 6230.127782110418, 6230.190075290183, 6234.9712297470405, 4627.884302892455, 5693.032184528723, 6047.123167140828, 6077.361877002851, 6155.230596175478, 5151.484851514819, 6214.751409943732, 6237.96881015592, 6242.781502647385, 6272.655003974643, 6116.633002019893, 6124.5712800103565, 6139.508839292936, 6146.170267013896, 6152.169457123231], "value": 5749.830553764915, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.7135175922085, 491.7295487481885, 520.7005971566349, 544.311446020784, 550.8622844288927, 38254.80872595637, 39727.205455890864, 39747.80126099373, 42007.369889451606, 42212.57787422125, 32375.0, 41042.0, 41060.794696026554, 41151.0, 41185.58814411855, 9515.952420237889, 9691.95154024229, 9717.805643887117, 9733.0, 9739.366941148803, 9520.714378568655, 9610.711678649652, 9655.951720241388, 9666.130048295652, 9700.854487182698, 8658.264047555935, 9600.03999600038, 9604.183644390236, 9611.0, 9613.567389467491, 9615.375000624937, 9643.469609171492, 9648.614055437774, 9656.903430965742, 9674.70975870725, 9561.235101191887, 9642.0, 9643.807123857554, 9648.421094734338, 9650.469224192666, 37169.69906053302, 39104.804475977726, 39373.21253574941, 39455.802720986496, 39471.40792888109, 3220.9355812883846, 3642.5993140754763, 3700.7972408966925, 3723.6276372362818, 3946.4277679736565, 29771.553426698618, 29932.0, 30321.393572128654, 31021.534676979863, 31539.21151971196, 3185.8566364513654, 3244.4160051190775, 3256.8697252109887, 3261.7879837810465, 3300.042987533612, 479450.9999999965, 481850.5907470475, 483562.0, 484756.15243847464, 484901.99999999645, 55544.16683749747, 55720.0, 55724.44275557274, 55791.721041394536, 55854.44145558492, 33355.0, 34492.31015379678, 57896.8420631585, 58234.70882645559, 58267.417325827046, 15782.92108539473, 16160.595985100232, 16181.4336498222, 16192.0, 16198.271077801528, 12516.249025058525, 12613.873861261454, 12629.368531573384, 12652.620421387373, 12690.111692181643, 18077.276908923497, 18126.63746725058, 18146.364877229233, 18226.0, 18263.45209643713, 6625.4037136657935, 6808.978653202059, 6932.133483314586, 7950.342712932493, 7967.203279672102, 891.7012800711705, 947.3889341374775, 973.1387721866058, 975.6682727872558, 999.2805180270194, 25700.742992569834, 26169.476610467686, 26212.344691382867, 27781.16656500308, 29212.123636290944, 1042.259995403257, 1052.4527245832123, 1071.244772435431, 1080.357187473452, 1136.363636363639, 15558.84441155574, 15999.920000399923, 16300.42948496797, 16323.755143672854, 16630.50108496747, 660399.0, 706003.0, 706577.0, 708604.0, 712423.0, 54508.45491545113, 54600.0, 55131.44868551343, 55239.72380138154, 55513.0, 1346.2191928681536, 1388.8125103110951, 1393.2754967416886, 1393.4495874129661, 1393.5749596373, 22940.885295573415, 28949.7105028947, 29192.416151677273, 30305.848470757504, 30513.694863051085, 45997.0, 47753.28370074521, 48306.0, 48413.757931210115, 48835.0, 603670.6696397346, 607446.0, 608173.9591302015, 609100.0, 609607.0, 23928.40178995539, 24426.75573244245, 24472.87763561242, 24655.013799448538, 24655.630165547173, 43005.35491967561, 43672.781636092885, 44295.01344878977, 44321.77839110784, 44723.77638111789, 30708.53937190899, 36223.63776362203, 36307.0, 37302.44046339253, 37518.24963500769, 36723.0, 36873.63126368702, 37627.623723762415, 38035.04912377213, 38237.617623823404, 28663.42673146567, 34830.0, 36027.45958810672, 36033.639663603026, 36040.098997525274, 31275.84362078175, 32447.188820279684, 32669.0, 32737.017889463354, 32908.67091329152, 23253.224090443757, 24098.15656452016, 24454.755452445246, 24754.257372278862, 24825.62761558614], "value": 19456.00933603336, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.66441065963323, 369.1251733391861, 375.3431494883953, 380.1996796742858, 394.9861754838581, 19748.0, 20088.799112008877, 20303.187872485083, 20744.89627551862, 20933.47666308343, 20086.196552137935, 20341.982900854935, 20574.177032918666, 20624.587508249864, 20729.585408291827, 5915.378885217055, 5925.992581261196, 6080.543959203055, 6082.330943596201, 6180.412860778241, 3503.8248087595643, 5924.200232968558, 6009.248843894513, 6034.155218269446, 6036.8188954331445, 4310.633596144332, 6363.554551181419, 6627.403533681968, 6720.025596288535, 6783.118194634691, 6480.8703825923685, 6701.899471507932, 6713.765018224339, 6715.059891615129, 6729.8317542061595, 5769.653820770767, 5774.335951365562, 5786.68173250471, 5798.710064496759, 5800.564957628152, 25552.0, 25775.613365799527, 25898.705064746686, 25901.22296331113, 25977.0, 2199.7910198531113, 2306.988465057681, 2400.327908185711, 2861.241770930705, 3036.5141577347517, 9668.95165524175, 15642.843571564252, 15684.372625094982, 16030.198490075449, 16272.860899737023, 1881.2569035231145, 1892.8391086757642, 1922.144645632694, 1950.6488832010232, 1980.5345743750206, 78729.0, 121843.0, 122871.0, 124098.0, 129102.41795164044, 9925.709657744592, 11067.833982490269, 11227.21409501327, 11285.774284514264, 11519.769604607862, 14336.0, 14354.425822967174, 14365.856341436453, 14417.13497190172, 14419.927900360432, 7035.92964070363, 8286.337093032544, 8483.533405662625, 8506.31949444043, 8669.21977022071, 8406.621702023423, 8431.283340915968, 8464.40749147566, 8581.528015959057, 8613.956930215309, 3769.246150769857, 3965.4844870166694, 4424.800883960165, 4578.885527861764, 4611.1930412177735, 1562.10959752942, 1574.8818838587151, 1925.990370048141, 1945.2219112354887, 1962.1464662871697, 736.3005145112165, 800.9599520023976, 842.5408152556748, 861.7328628125301, 1016.8169729448622, 17168.656626867643, 19895.801041989685, 20864.852433116324, 20987.05558249852, 21095.472613184487, 818.9549574773325, 856.6787454704486, 921.313621352097, 923.8357581121563, 931.4271722890373, 10296.845547316796, 10398.0, 10993.560257589606, 11036.282641628268, 11057.336559806437, 526228.0, 540416.0, 541697.2915135478, 551887.0, 573954.1302293462, 131515.0, 132954.0, 133860.0, 134928.0, 138319.0, 944.8866136063579, 1003.1924300937815, 1003.4947578632052, 1027.4554486122368, 1028.2391030637275, 18998.43004709861, 19132.713009304593, 19468.123934422703, 19579.412617621496, 19654.803451965297, 51867.96264074622, 53196.54222475681, 54310.0, 54988.0, 55718.44281557295, 80635.13013568385, 80970.5951470239, 81159.59420202862, 81262.0, 81364.0, 6840.589564626139, 7151.964240178766, 7238.963805180941, 7359.77920662381, 7832.608369581612, 11131.499082541142, 12330.50677972871, 12660.87339126597, 12824.422900969292, 12972.675683107998, 10674.946625266824, 10931.617393391192, 11105.777884442428, 11253.437328133397, 11320.26418282809, 10653.360798352123, 10896.945515272373, 10958.506867191156, 11048.281861678965, 11097.722556936142, 10899.891001090205, 11203.775924481628, 11425.143114266297, 11469.942650286695, 11535.942320288681, 6033.276006879202, 7169.139703235645, 7270.382017528358, 7428.665710042953, 7454.739084132029, 9222.492762897837, 9962.452065136196, 9966.903640599383, 11030.724231894266, 11380.829287560859], "value": 9560.979101393787, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1509.8339182689906, 1550.620098075971, 1734.9479515614537, 1845.8154184581535, 1853.6756067688152, 8124.187581241873, 8376.120507346732, 8556.657733690652, 9129.497877616728, 9176.816463670722, 8551.0, 8850.203481686647, 8917.91082089179, 8959.865602015974, 8970.237529809974, 2384.129792625692, 2421.624648179535, 2421.6730741349957, 2425.8059355251626, 2430.744771798962, 2372.098602531036, 2397.5444665513583, 2412.8311018228733, 2421.6004359280696, 2427.0413186791206, 2259.5932732108213, 2272.8863556822093, 2315.7336906255828, 2366.7869891709743, 2375.9762402375927, 2360.9527809443716, 2372.5729368713623, 2377.4888398994212, 2379.2267513058237, 2411.168146989286, 2361.5276944610973, 2379.6668466414717, 2396.892139853711, 2406.795422389099, 2407.253751337091, 8933.821323573493, 9010.27917766577, 9053.954730226307, 9060.818783624356, 9074.274058075338, 5289.153735402318, 5403.864903377408, 5457.426970168154, 5464.0, 5469.917951230735, 3634.345817752803, 3670.8715194968313, 3706.7405281630295, 3725.0873535983587, 3741.401375779862, 1077.6659235636978, 1080.4273734920425, 1084.2572837606265, 1092.5902786455079, 1102.4212288548497, 10740.892591074145, 11333.376664283462, 11783.941080294544, 11893.108016898766, 11895.04839612829, 4032.0927791246736, 4706.91740899595, 4731.384919960383, 4781.713097214179, 4908.779104940286, 8409.873851892227, 10019.398836069713, 10066.0, 10083.344582602254, 10103.64637237708, 5344.35867695879, 5353.170258609844, 5392.352917649829, 5426.9185962210595, 5434.402215756264, 4449.377087207728, 4626.028534007829, 4750.881227969259, 4910.288008238786, 4941.629377796679, 3952.9209415812097, 3960.247552964985, 4044.211378781168, 4089.6114869087387, 4125.278076336629, 1297.5199176304827, 1311.9803202951964, 1314.8553659097493, 1317.6903427694483, 1329.820474235985, 814.5519964019884, 917.3165991336497, 920.4799288402031, 930.3766476460787, 953.1564565359567, 5954.583179177503, 6004.669743164168, 6085.6348619083, 6098.72555734993, 6161.983272760034, 425.4277996095247, 427.260838748966, 437.3898411715639, 437.46410646957224, 441.4966937691035, 3976.7613943162924, 3981.944784632075, 3987.960120398817, 4118.423420721101, 4127.772972486482, 10561.471926403648, 10794.78410431787, 10894.67315980522, 10912.890871091346, 10927.781444371069, 10772.0, 10841.783164336826, 10879.0, 10881.945590271998, 10905.236633435583, 8082.797930051795, 8152.673893044331, 8171.387145964077, 8194.877076843852, 8270.710525131592, 5767.394423585463, 5924.052151655715, 5947.524198064231, 5949.167116603766, 5974.551908606959, 10162.441065741456, 10165.542550585093, 10171.694849154537, 10214.336068155546, 10249.898770752126, 8718.825623487368, 9960.103590676874, 10243.873173950711, 10572.36565806054, 10578.41818699948, 6540.149780528501, 6572.309907459648, 7408.703651853865, 7449.329560339595, 7630.542167469969, 8412.537310447984, 8439.535825529409, 8568.100349463217, 8588.484690918565, 8596.441231319945, 7260.709571617076, 7333.816654583465, 7389.039424874732, 7406.629668516661, 7479.6634151464405, 7425.591592462466, 7458.142313634002, 7476.514026588472, 7564.810879728051, 7623.618819059136, 6933.5493192942295, 6980.755673551402, 7057.117860267466, 7086.291370862872, 7089.64551772399, 4741.312509686008, 4978.4274808397495, 5067.113255180402, 5140.254663073908, 5171.456997015259, 4291.377750226262, 5129.948700512947, 5230.189320655152, 5296.549793267615, 5319.893602127859], "value": 4543.749855704, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.86704046855166, 281.7773958572728, 310.8507916200224, 316.67698947073984, 318.63038874905084, 4956.537821342704, 5183.2484289778, 5534.280543529346, 5772.220750198727, 5779.133130030497, 5062.949370506294, 5441.700706461143, 5559.694216818073, 5597.732918168148, 5662.37713851476, 3931.3906344516645, 3986.66113380363, 3990.0, 4174.937375939348, 4181.435506206669, 3505.0, 4170.332746760519, 4186.434831297766, 4193.287141185991, 4243.681723870706, 3702.4446333050123, 3908.17928235071, 3998.900027499307, 4148.502179738421, 4195.328747400416, 4051.837926482952, 4099.733517321394, 4182.344211425736, 4228.957710422918, 4252.681048921343, 3599.7120230381506, 3617.5839778425557, 3621.7645853019735, 3629.891103266906, 3633.8364773585254, 4986.725730084844, 5413.26920865682, 5417.295751552312, 5452.182172674091, 5517.544692611789, 4401.889952751175, 4500.302453119739, 4738.455077666078, 4852.636052296057, 4888.486708895551, 5383.138697808332, 5521.889562208733, 5828.795992140297, 5878.706064696791, 5971.924458903624, 3483.7038851697635, 3845.903852403684, 3920.5687374388795, 3956.742811717229, 3957.703172262092, 5354.866128346784, 6032.491877030742, 6136.662483563402, 6230.408111229426, 6269.0909818076125, 3509.5613048368955, 3984.426151670132, 4021.557628660845, 4037.394390841397, 4056.350983842571, 3235.6117265928237, 3384.8322328796526, 3537.451694987281, 4024.577419370924, 4312.094460163338, 4214.936775948363, 4545.499995000613, 4756.762161891892, 4796.928046079312, 4800.687955282895, 5247.868803279948, 5876.7355469004, 5968.701564921824, 5992.700364981733, 6009.789657361971, 1175.6531823112202, 1326.3898606641053, 1350.540816122523, 1383.4881093995084, 1390.2353705462158, 795.0459448661629, 827.8261565071282, 846.5767116441779, 847.0, 847.5507980770202, 4882.145624515695, 5184.766685499161, 5261.894762104736, 5415.241866138744, 5416.756245968941, 6129.2032035835055, 6133.693315334304, 6165.352637973038, 6192.0, 6232.3201023559395, 5285.3351194373645, 5300.833816560351, 5327.334083239595, 5574.358948720868, 5597.916031259534, 2859.742623163925, 3123.87504499822, 3173.8571764270664, 3176.110689007082, 3186.9362612747614, 5642.802501912413, 5891.169241222156, 6021.789237376671, 6263.467605253513, 6311.147995020705, 5330.7068111253475, 6255.624662520263, 6275.184226050585, 6283.59156654816, 6298.464630506367, 5557.416471270459, 6247.406496382836, 6326.49388048955, 6353.491720662336, 6354.23749150105, 5070.847874563769, 5580.358258800127, 6037.667928263988, 6056.0007598745715, 6060.939390606038, 5107.87230319245, 6030.276366836008, 6044.939550604614, 6098.0, 6125.540584456095, 5895.498882594856, 6258.748250349857, 6339.619622822645, 6357.96821015892, 6404.039394090923, 3352.7150192233935, 3549.183687751883, 3689.2805902848686, 3967.325554655773, 3984.601539845992, 5160.142348754521, 6064.179566872842, 6085.421884920926, 6099.725512352044, 6112.8910525868505, 4534.387857639176, 5298.761555730079, 5554.666719996813, 5771.422857714195, 5827.9679648240935, 5657.8684263146715, 5681.863627274479, 5831.521603318657, 5923.437273459014, 5943.167956486009, 5775.112443778111, 5802.0, 5817.2146760188525, 5824.883502329845, 5852.800175964002, 5168.8449346519665, 5558.6937069787755, 5695.914561281667, 5844.240248767633, 5853.473187413153, 5740.708340623326, 5747.5401967841635, 5749.8275051748515, 5752.568557358131, 5802.970985145047], "value": 4249.628268349019, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.753848614499, 700.1842853076166, 712.9714811407543, 733.8862476316173, 763.3015790551646, 14920.701585968287, 15685.294161762706, 15687.215639218051, 15748.76376854348, 15774.290156942936, 13622.523211687592, 13640.454381824715, 13646.658833529167, 13653.931730341334, 13742.519011834587, 677.156939610186, 698.6087790837147, 840.6553313141613, 846.124261389462, 866.4411454611771, 861.0356400831078, 871.525018864717, 873.5544872115204, 874.0865795243953, 874.9518776467291, 914.4833169259381, 927.939683920543, 930.06993006993, 930.3208657679897, 931.12009151352, 921.4655499810094, 922.202295014814, 922.8477301245287, 923.7136487688805, 925.9814803703922, 650.3009265040101, 684.3635419060272, 686.2828344380134, 688.6006116452446, 689.3554526517672, 14611.269436528131, 14689.265536723122, 14738.410463581444, 14762.335694893756, 14775.852241477554, 12716.872831271661, 12741.235525868478, 12786.104972651918, 12810.871891281062, 13211.05675488328, 11501.252418592849, 11775.470103845262, 11798.0, 11816.527338906433, 12258.75482490354, 3813.389857622795, 3820.7707537547835, 3827.119762454623, 3863.864764733249, 3900.1614652849776, 98299.0, 103889.0, 103906.48046759794, 116772.41613791962, 129731.0, 9079.863802042975, 9350.95324523373, 9590.856137157949, 10796.67609971702, 11214.887851121384, 21949.451263718536, 21991.010404531833, 21995.450113746963, 22003.889980549993, 22011.889940550194, 8041.718539851077, 8148.0, 8174.427790054639, 8179.304759095425, 8331.8333633327, 4442.888927776831, 4473.217186992263, 4536.0, 4585.426821647294, 4592.03567250881, 3051.5727798108283, 3181.8886338978486, 3562.5190599268767, 3699.167687270343, 3700.5559332880225, 544.5297696220142, 547.9232907392968, 611.260374946311, 669.8827705151579, 676.9559978601375, 607.5018484842475, 610.6305685060563, 614.4193736918659, 668.8394785251602, 676.6379986707186, 8252.555802734498, 9372.297077719199, 9562.473160160213, 9627.711168664951, 9795.522589480632, 501.5185421994853, 532.7762339817209, 621.0342916764474, 624.5908929651085, 668.9765858195037, 4490.418248677085, 4623.722576645412, 4692.5307469253485, 4777.235642297216, 4795.345605766005, 95342.04657953331, 134872.0, 153024.0, 155414.0, 160272.0, 81480.59259703782, 130583.0, 134567.32716336354, 143949.0, 148465.2576737131, 121382.0, 122821.38589307175, 124391.0, 125354.0, 127010.72989270174, 8756.4308319959, 8993.7751556209, 9046.728598142066, 9077.45535267886, 9192.678256260999, 62049.689751550955, 65582.01626975504, 65809.0, 66884.3278918031, 68641.65679171772, 94043.52978235339, 102493.48753256186, 103111.0, 104856.0, 106100.0, 11771.352575608424, 11889.28664280146, 11892.0, 11959.76080478403, 12031.819522706992, 12971.286579237854, 13010.804837927628, 13314.667133321744, 13431.395587198404, 13755.17468951866, 11147.442627868737, 11740.823887641522, 12243.571474998333, 12316.507339706312, 12776.169548979287, 12103.455344509342, 12163.695907602381, 12225.493773776177, 12491.0, 12775.936120319338, 12074.094442916641, 12247.816282755588, 12480.188787728768, 12580.0, 12688.302143381834, 8500.44747091437, 9927.801443970935, 10329.018743219684, 10356.948215258875, 10458.63394781179, 8988.820223595621, 10253.58985640566, 10306.969303069933, 10543.472826358498, 10570.841437378293], "value": 7648.588376144255, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1582.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 118.0, 114.0, 117.0, 117.0, 118.0, 117.0, 116.0, 134.0], "value": 117.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 110.0, 132.0, 156.0, 110.0, 133.0, 129.0, 111.0, 158.0, 110.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 229.0, 268.0, 199.0, 196.0, 206.0, 195.0, 199.0, 231.0, 278.0], "value": 220.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 81.0, 74.0, 143.0, 58.0, 58.0, 59.0, 60.0, 138.0, 62.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 115.0, 72.0, 78.0, 114.0, 144.0, 96.0, 72.0, 113.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 88.0, 85.0, 88.0, 86.0, 88.0, 87.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 151.0, 114.0, 95.0, 95.0, 94.0, 94.0, 97.0, 151.0, 151.0], "value": 115.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 140.0, 92.0, 91.0, 90.0, 94.0, 93.0, 91.0, 92.0, 91.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 57.0, 56.0, 56.0, 57.0, 57.0, 59.0, 56.0, 57.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 92.0, 102.0, 105.0, 99.0, 72.0, 68.0, 68.0, 82.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 79.0, 100.0, 100.0, 80.0, 108.0, 106.0, 106.0, 99.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 146.0, 153.0, 158.0, 148.0, 143.0, 152.0, 162.0, 186.0, 139.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 176.0, 232.0, 192.0, 169.0, 173.0, 168.0, 171.0, 168.0, 168.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 73.0, 88.0, 88.0, 91.0, 82.0, 68.0, 69.0, 70.0], "value": 76.6, "unit": "score"}]}]} 17:03:14 INFO - Return code: 0 17:03:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:03:14 ERROR - # TBPL SUCCESS # 17:03:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:03:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:03:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:03:14 INFO - 2016-09-10 17:03:14.435 screenresolution[1680:12291] starting screenresolution argv=screenresolution get 17:03:14 INFO - 2016-09-10 17:03:14.438 screenresolution[1680:12291] Display 0: 1600x1200x32@60 17:03:14 INFO - 2016-09-10 17:03:14.444 screenresolution[1681:12294] starting screenresolution argv=screenresolution list 17:03:14 INFO - Available Modes on Display 0 17:03:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:03:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:03:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:03:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:03:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:03:14 INFO - Intel Iris: 17:03:14 INFO - Chipset Model: Intel Iris 17:03:14 INFO - Type: GPU 17:03:14 INFO - Bus: Built-In 17:03:14 INFO - VRAM (Dynamic, Max): 1536 MB 17:03:14 INFO - Vendor: Intel (0x8086) 17:03:14 INFO - Device ID: 0x0a2e 17:03:14 INFO - Revision ID: 0x0009 17:03:14 INFO - Displays: 17:03:14 INFO - Display: 17:03:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:03:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:03:14 INFO - Display Serial Number: 1600x1200 60 17:03:14 INFO - Main Display: Yes 17:03:14 INFO - Mirror: Off 17:03:14 INFO - Online: Yes 17:03:14 INFO - Rotation: Supported 17:03:14 INFO - Return code: 0 17:03:14 INFO - Running post-action listener: _resource_record_post_action 17:03:14 INFO - [mozharness: 2016-09-11 00:03:14.679367Z] Finished run-tests step (success) 17:03:14 INFO - Running post-run listener: _resource_record_post_run 17:03:14 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 32627712; Write bytes: 707881984; Read time: 316; Write time: 2240 17:03:14 INFO - TinderboxPrint: CPU usage
54.7% 17:03:14 INFO - TinderboxPrint: I/O read bytes / time
32,627,712 / 316 17:03:14 INFO - TinderboxPrint: I/O write bytes / time
707,881,984 / 2,240 17:03:14 INFO - TinderboxPrint: CPU idle
2,192.4 (45.3%) 17:03:14 INFO - TinderboxPrint: CPU system
982.5 (20.3%) 17:03:14 INFO - TinderboxPrint: CPU user
1,662.9 (34.4%) 17:03:14 INFO - TinderboxPrint: Swap in / out
434,716,672 / 0 17:03:14 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 406968832; Write bytes: 408152064; Read time: 29898; Write time: 799 17:03:14 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 29125632; Write bytes: 298161152; Read time: 286; Write time: 1436 17:03:15 INFO - Running post-run listener: _upload_blobber_files 17:03:15 INFO - Blob upload gear active. 17:03:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:03:15 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:03:15 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'] 17:03:15 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 17:03:15 INFO - (blobuploader) - INFO - Open directory for files ... 17:03:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:03:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:03:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:16 INFO - (blobuploader) - INFO - Done attempting. 17:03:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:03:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:03:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:17 INFO - (blobuploader) - INFO - Done attempting. 17:03:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:03:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:03:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:19 INFO - (blobuploader) - INFO - Done attempting. 17:03:19 INFO - (blobuploader) - INFO - Iteration through files over. 17:03:19 INFO - Return code: 0 17:03:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:03:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:03:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b7235e2120486c8fcc61b99abf728452b401431f5524ee8a455496aa6fed77f40fe7258ecf13fd6ed940a2e588a01c18fb5d820b005e5791359cee6422556b14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c3998c0fa166b7324efb58bef14cb87252cc4db0344a75995aa5a19d40ac674068456bf60220e5df43c95cf821e3e982442ef51237f71540600d4e442267043", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6880181927f5bc493b357e02dfa6a853c35909451be3450bd0fbcd6a684a0417b055d267617abb844c224cb6346279f7bb5cbffc6744e2d747977120bb47301"} 17:03:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:03:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:03:19 INFO - Contents: 17:03:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b7235e2120486c8fcc61b99abf728452b401431f5524ee8a455496aa6fed77f40fe7258ecf13fd6ed940a2e588a01c18fb5d820b005e5791359cee6422556b14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c3998c0fa166b7324efb58bef14cb87252cc4db0344a75995aa5a19d40ac674068456bf60220e5df43c95cf821e3e982442ef51237f71540600d4e442267043", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6880181927f5bc493b357e02dfa6a853c35909451be3450bd0fbcd6a684a0417b055d267617abb844c224cb6346279f7bb5cbffc6744e2d747977120bb47301"} 17:03:19 INFO - Running post-run listener: copy_logs_to_upload_dir 17:03:19 INFO - Copying logs to upload dir... 17:03:19 INFO - mkdir: /builds/slave/test/build/upload/logs 17:03:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.714178 ========= master_lag: 57.39 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2016-09-10 17:04:17.204392) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 17:04:17.207651) ========= 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.LVdkzVcO90/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.iM4PxV2EAk/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b7235e2120486c8fcc61b99abf728452b401431f5524ee8a455496aa6fed77f40fe7258ecf13fd6ed940a2e588a01c18fb5d820b005e5791359cee6422556b14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c3998c0fa166b7324efb58bef14cb87252cc4db0344a75995aa5a19d40ac674068456bf60220e5df43c95cf821e3e982442ef51237f71540600d4e442267043", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6880181927f5bc493b357e02dfa6a853c35909451be3450bd0fbcd6a684a0417b055d267617abb844c224cb6346279f7bb5cbffc6744e2d747977120bb47301"} build_url:https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010759 build_url: 'https://queue.taskcluster.net/v1/task/Sm5uRIVaSDKVSBZMfZQHyA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b7235e2120486c8fcc61b99abf728452b401431f5524ee8a455496aa6fed77f40fe7258ecf13fd6ed940a2e588a01c18fb5d820b005e5791359cee6422556b14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c3998c0fa166b7324efb58bef14cb87252cc4db0344a75995aa5a19d40ac674068456bf60220e5df43c95cf821e3e982442ef51237f71540600d4e442267043", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6880181927f5bc493b357e02dfa6a853c35909451be3450bd0fbcd6a684a0417b055d267617abb844c224cb6346279f7bb5cbffc6744e2d747977120bb47301"}' ========= master_lag: 0.40 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 17:04:17.622442) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 17:04:17.622760) ========= 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.LVdkzVcO90/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.iM4PxV2EAk/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.005165 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 17:04:17.734899) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 17:04:17.735204) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 17:04:17.812681) ========= ========= Total master_lag: 57.97 =========