builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0025 starttime: 1473211074.64 results: success (0) buildid: 20160906170916 builduid: e2ccd903a6c8490f9409482bafa34ca8 revision: a093339f5b6181440201bcf747c9013da11c4bda ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:54.638153) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:54.639045) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:54.639513) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:54.665074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:54.665418) ========= 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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-06 18:17:54-- 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% 25.5M=0s 2016-09-06 18:17:54 (25.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126522 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:54.815486) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:54.815842) ========= 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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.028349 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:54.865915) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 18:17:54.866337) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a093339f5b6181440201bcf747c9013da11c4bda --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a093339f5b6181440201bcf747c9013da11c4bda --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-06 18:17:54,944 truncating revision to first 12 chars 2016-09-06 18:17:54,944 Setting DEBUG logging. 2016-09-06 18:17:54,944 attempt 1/10 2016-09-06 18:17:54,944 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a093339f5b61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 18:17:55,982 unpacking tar archive at: autoland-a093339f5b61/testing/mozharness/ program finished with exit code 0 elapsedTime=1.291117 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 18:17:56.180526) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:56.180980) ========= script_repo_revision: a093339f5b6181440201bcf747c9013da11c4bda ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:56.181850) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:56.182483) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 18:17:56.202034) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-06 18:17:56.202363) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 18:17:56 INFO - MultiFileLogger online at 20160906 18:17:56 in /builds/slave/test 18:17:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:17:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:17:56 INFO - {'append_to_log': False, 18:17:56 INFO - 'base_work_dir': '/builds/slave/test', 18:17:56 INFO - 'blob_upload_branch': 'Autoland', 18:17:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:17:56 INFO - 'branch': 'Autoland', 18:17:56 INFO - 'buildbot_json_path': 'buildprops.json', 18:17:56 INFO - 'config_files': ('talos/mac_config.py',), 18:17:56 INFO - 'default_actions': ('clobber', 18:17:56 INFO - 'read-buildbot-config', 18:17:56 INFO - 'download-and-extract', 18:17:56 INFO - 'populate-webroot', 18:17:56 INFO - 'create-virtualenv', 18:17:56 INFO - 'install', 18:17:56 INFO - 'run-tests'), 18:17:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:17:56 INFO - 'download_minidump_stackwalk': True, 18:17:56 INFO - 'download_symbols': 'ondemand', 18:17:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:17:56 INFO - 'tooltool.py': '/tools/tooltool.py', 18:17:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:17:56 INFO - '/tools/misc-python/virtualenv.py')}, 18:17:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:17:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:17:56 INFO - 'installer_path': 'installer.exe', 18:17:56 INFO - 'log_level': 'info', 18:17:56 INFO - 'log_name': 'talos', 18:17:56 INFO - 'log_to_console': True, 18:17:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:17:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:17:56 INFO - 'opt_config_files': (), 18:17:56 INFO - 'pip_index': False, 18:17:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:17:56 INFO - 'cmd': ('bash', 18:17:56 INFO - '-c', 18:17:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:17:56 INFO - 'enabled': True, 18:17:56 INFO - 'halt_on_failure': False, 18:17:56 INFO - 'name': 'check_screen_resolution'},), 18:17:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:17:56 INFO - 'cmd': ('bash', 18:17:56 INFO - '-c', 18:17:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:17:56 INFO - 'enabled': True, 18:17:56 INFO - 'halt_on_failure': False, 18:17:56 INFO - 'name': 'check_screen_resolution'},), 18:17:56 INFO - 'run_cmd_checks_enabled': True, 18:17:56 INFO - 'sps_profile': False, 18:17:56 INFO - 'sps_profile_interval': 0, 18:17:56 INFO - 'suite': 'dromaeojs', 18:17:56 INFO - 'system_bits': '32', 18:17:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:17:56 INFO - 'title': 't-yosemite-r7-0025', 18:17:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:17:56 INFO - 'use_talos_json': True, 18:17:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:17:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:17:56 INFO - 'work_dir': 'build'} 18:17:56 INFO - [mozharness: 2016-09-07 01:17:56.402358Z] Running clobber step. 18:17:56 INFO - Running pre-action listener: _resource_record_pre_action 18:17:56 INFO - Running main action method: clobber 18:17:56 INFO - rmtree: /builds/slave/test/build 18:17:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:17:58 INFO - Running post-action listener: _resource_record_post_action 18:17:58 INFO - [mozharness: 2016-09-07 01:17:58.568793Z] Finished clobber step (success) 18:17:58 INFO - [mozharness: 2016-09-07 01:17:58.568985Z] Running read-buildbot-config step. 18:17:58 INFO - Running pre-action listener: _resource_record_pre_action 18:17:58 INFO - Running main action method: read_buildbot_config 18:17:58 INFO - Using buildbot properties: 18:17:58 INFO - { 18:17:58 INFO - "project": "", 18:17:58 INFO - "product": "firefox", 18:17:58 INFO - "script_repo_revision": "production", 18:17:58 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 18:17:58 INFO - "repository": "", 18:17:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 18:17:58 INFO - "buildid": "20160906170916", 18:17:58 INFO - "pgo_build": "False", 18:17:58 INFO - "basedir": "/builds/slave/test", 18:17:58 INFO - "buildnumber": 231, 18:17:58 INFO - "slavename": "t-yosemite-r7-0025", 18:17:58 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:17:58 INFO - "platform": "yosemite_r7", 18:17:58 INFO - "scheduler": "tests-autoland-macosx64-talos", 18:17:58 INFO - "branch": "autoland", 18:17:58 INFO - "repo_path": "integration/autoland", 18:17:58 INFO - "revision": "a093339f5b6181440201bcf747c9013da11c4bda", 18:17:58 INFO - "stage_platform": "macosx64", 18:17:58 INFO - "builduid": "e2ccd903a6c8490f9409482bafa34ca8", 18:17:58 INFO - "slavebuilddir": "test" 18:17:58 INFO - } 18:17:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:17:58 INFO - Running post-action listener: _resource_record_post_action 18:17:58 INFO - [mozharness: 2016-09-07 01:17:58.572966Z] Finished read-buildbot-config step (success) 18:17:58 INFO - [mozharness: 2016-09-07 01:17:58.573118Z] Running download-and-extract step. 18:17:58 INFO - Running pre-action listener: _resource_record_pre_action 18:17:58 INFO - Running main action method: download_and_extract 18:17:58 INFO - mkdir: /builds/slave/test/build/tests 18:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:58 INFO - trying https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:17:58 INFO - Downloading https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/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 18:17:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/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 18:17:59 INFO - Downloaded 1199 bytes. 18:17:59 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:17:59 INFO - Using the following test package requirements: 18:17:59 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:17:59 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:17:59 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:17:59 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:17:59 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:17:59 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:17:59 INFO - u'jsshell-mac.zip'], 18:17:59 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:17:59 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:17:59 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:17:59 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:17:59 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:17:59 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:17:59 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:17:59 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:17:59 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:17:59 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:17:59 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:17:59 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:17:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:17:59 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:18:06 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 18:18:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:18:07 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:18:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:18:14 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:18:15 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')]} 18:18:15 INFO - trying https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:18:15 INFO - Downloading https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/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 18:18:19 INFO - Downloaded 111524313 bytes. 18:18:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:18:19 INFO - mkdir: /builds/slave/test/properties 18:18:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:18:19 INFO - Writing to file /builds/slave/test/properties/build_url 18:18:19 INFO - Contents: 18:18:19 INFO - build_url:https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:18:19 INFO - Running post-action listener: _resource_record_post_action 18:18:19 INFO - Running post-action listener: set_extra_try_arguments 18:18:19 INFO - [mozharness: 2016-09-07 01:18:19.815428Z] Finished download-and-extract step (success) 18:18:19 INFO - [mozharness: 2016-09-07 01:18:19.815547Z] Running populate-webroot step. 18:18:19 INFO - Running pre-action listener: _resource_record_pre_action 18:18:19 INFO - Running main action method: populate_webroot 18:18:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:18:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:18:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:18:19 INFO - '--tppagecycles', 18:18:19 INFO - '7'], 18:18:19 INFO - 'tests': ['tsvgm']}}, 18:18:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:18:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:18:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:18:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:18:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:18:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:18:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:19 INFO - 'talos_options': ['--disable-e10s'], 18:18:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:19 INFO - 'talos_options': ['--disable-e10s'], 18:18:19 INFO - 'tests': ['damp', 'tps']}, 18:18:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:19 INFO - 'tests': ['damp', 'tps']}, 18:18:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:18:19 INFO - 'tests': ['dromaeo_dom']}, 18:18:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:18:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:18:19 INFO - 'tests': ['basic_compositor_video']}, 18:18:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:18:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:18:19 INFO - 'tests': ['a11yr', 18:18:19 INFO - 'ts_paint', 18:18:19 INFO - 'tpaint', 18:18:19 INFO - 'sessionrestore', 18:18:19 INFO - 'sessionrestore_no_auto_restore', 18:18:19 INFO - 'tabpaint']}, 18:18:19 INFO - 'other-e10s': {'tests': ['a11yr', 18:18:19 INFO - 'ts_paint', 18:18:19 INFO - 'tpaint', 18:18:19 INFO - 'sessionrestore', 18:18:19 INFO - 'sessionrestore_no_auto_restore', 18:18:19 INFO - 'tabpaint']}, 18:18:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:18:19 INFO - 'ts_paint', 18:18:19 INFO - 'tpaint', 18:18:19 INFO - 'sessionrestore', 18:18:19 INFO - 'sessionrestore_no_auto_restore', 18:18:19 INFO - 'tabpaint']}, 18:18:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:18:19 INFO - 'ts_paint', 18:18:19 INFO - 'tpaint', 18:18:19 INFO - 'sessionrestore', 18:18:19 INFO - 'sessionrestore_no_auto_restore', 18:18:19 INFO - 'tabpaint']}, 18:18:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:18:19 INFO - 'tests': ['a11yr', 18:18:19 INFO - 'ts_paint', 18:18:19 INFO - 'tpaint', 18:18:19 INFO - 'sessionrestore', 18:18:19 INFO - 'sessionrestore_no_auto_restore', 18:18:19 INFO - 'tabpaint']}, 18:18:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:18:19 INFO - 'tests': ['a11yr', 18:18:19 INFO - 'ts_paint', 18:18:19 INFO - 'tpaint', 18:18:19 INFO - 'sessionrestore', 18:18:19 INFO - 'sessionrestore_no_auto_restore', 18:18:19 INFO - 'tabpaint']}, 18:18:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:18:19 INFO - 'tests': ['tsvgx', 18:18:19 INFO - 'tsvgr_opacity', 18:18:19 INFO - 'tart', 18:18:19 INFO - 'tscrollx', 18:18:19 INFO - 'cart']}, 18:18:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:18:19 INFO - 'tsvgr_opacity', 18:18:19 INFO - 'tart', 18:18:19 INFO - 'tscrollx', 18:18:19 INFO - 'cart']}, 18:18:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:19 INFO - 'talos_options': ['--disable-e10s'], 18:18:19 INFO - 'tests': ['tp5o']}, 18:18:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:19 INFO - 'tests': ['tp5o']}, 18:18:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:19 INFO - 'talos_options': ['--disable-e10s', 18:18:19 INFO - '--xperf_path', 18:18:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:19 INFO - 'tests': ['tp5n']}, 18:18:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:19 INFO - 'talos_options': ['--xperf_path', 18:18:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:19 INFO - 'tests': ['tp5n']}}, 18:18:19 INFO - 'talos.zip': {'path': '', 18:18:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:18:19 INFO - Running post-action listener: _resource_record_post_action 18:18:19 INFO - [mozharness: 2016-09-07 01:18:19.834193Z] Finished populate-webroot step (success) 18:18:19 INFO - [mozharness: 2016-09-07 01:18:19.834328Z] Running create-virtualenv step. 18:18:19 INFO - Running pre-action listener: _resource_record_pre_action 18:18:19 INFO - Running main action method: create_virtualenv 18:18:19 INFO - Creating virtualenv /builds/slave/test/build/venv 18:18:19 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 18:18:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:18:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:18:19 INFO - Using real prefix '/tools/python27' 18:18:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:18:20 INFO - Installing distribute.............................................................................................................................................................................................done. 18:18:23 INFO - Installing pip.................done. 18:18:23 INFO - Return code: 0 18:18:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:18:23 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')]} 18:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:23 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')]} 18:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:23 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 0x108c96a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d2b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d946afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bfad30>, '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 0x10895fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf2b20>, '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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 18:18:23 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 18:18:23 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 18:18:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3gxWcRbhu/Render', 18:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:23 INFO - 'HOME': '/Users/cltbld', 18:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:23 INFO - 'LOGNAME': 'cltbld', 18:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:23 INFO - 'MOZ_NO_REMOTE': '1', 18:18:23 INFO - 'NO_EM_RESTART': '1', 18:18:23 INFO - 'PAGER': '/bin/cat', 18:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:23 INFO - 'PWD': '/builds/slave/test', 18:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:23 INFO - 'SHELL': '/bin/bash', 18:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UZ2bRKUnEt/Listeners', 18:18:23 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:23 INFO - 'USER': 'cltbld', 18:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:23 INFO - 'XPC_FLAGS': '0x0', 18:18:23 INFO - 'XPC_SERVICE_NAME': '0', 18:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:23 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 18:18:23 INFO - Cleaning up... 18:18:24 INFO - Return code: 0 18:18:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:18:24 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')]} 18:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:24 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')]} 18:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:24 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 0x108c96a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d2b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d946afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bfad30>, '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 0x10895fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf2b20>, '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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 18:18:24 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 18:18:24 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 18:18:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3gxWcRbhu/Render', 18:18:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:24 INFO - 'HOME': '/Users/cltbld', 18:18:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:24 INFO - 'LOGNAME': 'cltbld', 18:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:24 INFO - 'MOZ_NO_REMOTE': '1', 18:18:24 INFO - 'NO_EM_RESTART': '1', 18:18:24 INFO - 'PAGER': '/bin/cat', 18:18:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:24 INFO - 'PWD': '/builds/slave/test', 18:18:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:24 INFO - 'SHELL': '/bin/bash', 18:18:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UZ2bRKUnEt/Listeners', 18:18:24 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:18:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:24 INFO - 'USER': 'cltbld', 18:18:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:24 INFO - 'XPC_FLAGS': '0x0', 18:18:24 INFO - 'XPC_SERVICE_NAME': '0', 18:18:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:24 INFO - Downloading/unpacking psutil>=3.1.1 18:18:24 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 18:18:24 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 18:18:24 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 18:18:24 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 18:18:24 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 18:18:24 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 18:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:18:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:27 INFO - Installing collected packages: psutil 18:18:27 INFO - Running setup.py install for psutil 18:18:27 INFO - building 'psutil._psutil_osx' extension 18:18:27 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 18:18:28 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 18:18:28 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 18:18:28 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 18:18:28 INFO - building 'psutil._psutil_posix' extension 18:18:28 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 18:18:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:18:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:18:28 INFO - ^ 18:18:28 INFO - 1 warning generated. 18:18:28 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 18:18:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:28 INFO - Successfully installed psutil 18:18:28 INFO - Cleaning up... 18:18:28 INFO - Return code: 0 18:18:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:28 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 0x108c96a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d2b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d946afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bfad30>, '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 0x10895fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf2b20>, '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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 18:18:28 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 18:18:28 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 18:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3gxWcRbhu/Render', 18:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:28 INFO - 'HOME': '/Users/cltbld', 18:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:28 INFO - 'LOGNAME': 'cltbld', 18:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:28 INFO - 'MOZ_NO_REMOTE': '1', 18:18:28 INFO - 'NO_EM_RESTART': '1', 18:18:28 INFO - 'PAGER': '/bin/cat', 18:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:28 INFO - 'PWD': '/builds/slave/test', 18:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:28 INFO - 'SHELL': '/bin/bash', 18:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UZ2bRKUnEt/Listeners', 18:18:28 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:28 INFO - 'USER': 'cltbld', 18:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:28 INFO - 'XPC_FLAGS': '0x0', 18:18:28 INFO - 'XPC_SERVICE_NAME': '0', 18:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:18:29 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 18:18:29 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 18:18:29 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 18:18:29 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 18:18:29 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 18:18:29 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 18:18:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:18:31 INFO - Installing collected packages: mozsystemmonitor 18:18:31 INFO - Running setup.py install for mozsystemmonitor 18:18:31 INFO - Successfully installed mozsystemmonitor 18:18:31 INFO - Cleaning up... 18:18:31 INFO - Return code: 0 18:18:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:18:31 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')]} 18:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:31 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')]} 18:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:31 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 0x108c96a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d2b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d946afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bfad30>, '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 0x10895fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf2b20>, '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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 18:18:31 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 18:18:31 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 18:18:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3gxWcRbhu/Render', 18:18:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:31 INFO - 'HOME': '/Users/cltbld', 18:18:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:31 INFO - 'LOGNAME': 'cltbld', 18:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:31 INFO - 'MOZ_NO_REMOTE': '1', 18:18:31 INFO - 'NO_EM_RESTART': '1', 18:18:31 INFO - 'PAGER': '/bin/cat', 18:18:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:31 INFO - 'PWD': '/builds/slave/test', 18:18:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:31 INFO - 'SHELL': '/bin/bash', 18:18:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UZ2bRKUnEt/Listeners', 18:18:31 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:18:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:31 INFO - 'USER': 'cltbld', 18:18:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:31 INFO - 'XPC_FLAGS': '0x0', 18:18:31 INFO - 'XPC_SERVICE_NAME': '0', 18:18:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:32 INFO - Downloading/unpacking jsonschema==2.5.1 18:18:32 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 18:18:32 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 18:18:32 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 18:18:32 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 18:18:32 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 18:18:32 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 18:18:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:18:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:18:34 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 18:18:34 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 18:18:34 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 18:18:34 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 18:18:34 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 18:18:34 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 18:18:35 INFO - Downloading functools32-3.2.3-2.tar.gz 18:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:18:35 INFO - warning: no files found matching '*.txt' 18:18:35 INFO - no previously-included directories found matching 'build' 18:18:35 INFO - no previously-included directories found matching 'dist' 18:18:35 INFO - no previously-included directories found matching '.git*' 18:18:35 INFO - Installing collected packages: jsonschema, functools32 18:18:35 INFO - Running setup.py install for functools32 18:18:35 INFO - warning: no files found matching '*.txt' 18:18:35 INFO - no previously-included directories found matching 'build' 18:18:35 INFO - no previously-included directories found matching 'dist' 18:18:35 INFO - no previously-included directories found matching '.git*' 18:18:35 INFO - Successfully installed jsonschema functools32 18:18:35 INFO - Cleaning up... 18:18:35 INFO - Return code: 0 18:18:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:35 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 0x108c96a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d2b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d946afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bfad30>, '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 0x10895fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf2b20>, '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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 18:18:35 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 18:18:35 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 18:18:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3gxWcRbhu/Render', 18:18:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:35 INFO - 'HOME': '/Users/cltbld', 18:18:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:35 INFO - 'LOGNAME': 'cltbld', 18:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:35 INFO - 'MOZ_NO_REMOTE': '1', 18:18:35 INFO - 'NO_EM_RESTART': '1', 18:18:35 INFO - 'PAGER': '/bin/cat', 18:18:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:35 INFO - 'PWD': '/builds/slave/test', 18:18:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:35 INFO - 'SHELL': '/bin/bash', 18:18:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UZ2bRKUnEt/Listeners', 18:18:35 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:18:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:35 INFO - 'USER': 'cltbld', 18:18:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:35 INFO - 'XPC_FLAGS': '0x0', 18:18:35 INFO - 'XPC_SERVICE_NAME': '0', 18:18:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:18:35 INFO - Cleaning up... 18:18:35 INFO - Return code: 0 18:18:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:35 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 0x108c96a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d2b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d946afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bfad30>, '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 0x10895fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf2b20>, '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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 18:18:35 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 18:18:35 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 18:18:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3gxWcRbhu/Render', 18:18:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:35 INFO - 'HOME': '/Users/cltbld', 18:18:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:35 INFO - 'LOGNAME': 'cltbld', 18:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:35 INFO - 'MOZ_NO_REMOTE': '1', 18:18:35 INFO - 'NO_EM_RESTART': '1', 18:18:35 INFO - 'PAGER': '/bin/cat', 18:18:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:35 INFO - 'PWD': '/builds/slave/test', 18:18:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:35 INFO - 'SHELL': '/bin/bash', 18:18:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UZ2bRKUnEt/Listeners', 18:18:35 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:18:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:35 INFO - 'USER': 'cltbld', 18:18:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:35 INFO - 'XPC_FLAGS': '0x0', 18:18:35 INFO - 'XPC_SERVICE_NAME': '0', 18:18:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:35 INFO - Downloading/unpacking blobuploader==1.2.4 18:18:35 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 18:18:35 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 18:18:35 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 18:18:35 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 18:18:35 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 18:18:35 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 18:18:38 INFO - Downloading blobuploader-1.2.4.tar.gz 18:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:18:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:18:38 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 18:18:38 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 18:18:38 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 18:18:38 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 18:18:38 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 18:18:38 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 18:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:18:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:18:39 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 18:18:39 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 18:18:39 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 18:18:39 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 18:18:39 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 18:18:39 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 18:18:39 INFO - Downloading docopt-0.6.1.tar.gz 18:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:18:39 INFO - Installing collected packages: blobuploader, requests, docopt 18:18:39 INFO - Running setup.py install for blobuploader 18:18:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:18:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:18:39 INFO - Running setup.py install for requests 18:18:40 INFO - Running setup.py install for docopt 18:18:40 INFO - Successfully installed blobuploader requests docopt 18:18:40 INFO - Cleaning up... 18:18:40 INFO - Return code: 0 18:18:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:40 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')]} 18:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:40 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')]} 18:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:40 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 0x108c96a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d2b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d946afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bfad30>, '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 0x10895fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf2b20>, '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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 18:18:40 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 18:18:40 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 18:18:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3gxWcRbhu/Render', 18:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:40 INFO - 'HOME': '/Users/cltbld', 18:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:40 INFO - 'LOGNAME': 'cltbld', 18:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:40 INFO - 'MOZ_NO_REMOTE': '1', 18:18:40 INFO - 'NO_EM_RESTART': '1', 18:18:40 INFO - 'PAGER': '/bin/cat', 18:18:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:40 INFO - 'PWD': '/builds/slave/test', 18:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:40 INFO - 'SHELL': '/bin/bash', 18:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UZ2bRKUnEt/Listeners', 18:18:40 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:40 INFO - 'USER': 'cltbld', 18:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:40 INFO - 'XPC_FLAGS': '0x0', 18:18:40 INFO - 'XPC_SERVICE_NAME': '0', 18:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:40 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-msP4zJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:40 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-DA5ey6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:41 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-mAvEPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:41 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-SgXOsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:41 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-aVc_8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:41 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-MTQHL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:41 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-SDwMG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:41 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-RoiAt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:41 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-_U2ICW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:41 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Q_9kHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:41 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-av7ygR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:41 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-mAtABp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:42 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-7Ltb2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:42 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-1yyRL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:42 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-LPZkgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:42 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-jy2Vyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:42 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-3mHzm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:18:42 INFO - Running setup.py install for manifestparser 18:18:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:18:42 INFO - Running setup.py install for mozcrash 18:18:42 INFO - Running setup.py install for mozdebug 18:18:43 INFO - Running setup.py install for mozdevice 18:18:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:18:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:18:43 INFO - Running setup.py install for mozfile 18:18:43 INFO - Running setup.py install for mozhttpd 18:18:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:18:43 INFO - Running setup.py install for mozinfo 18:18:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:18:43 INFO - Running setup.py install for mozInstall 18:18:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:18:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:18:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:18:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:18:43 INFO - Running setup.py install for mozleak 18:18:43 INFO - Running setup.py install for mozlog 18:18:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:18:44 INFO - Running setup.py install for moznetwork 18:18:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:18:44 INFO - Running setup.py install for mozprocess 18:18:44 INFO - Running setup.py install for mozprofile 18:18:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:18:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:18:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:18:44 INFO - Running setup.py install for mozrunner 18:18:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:18:44 INFO - Running setup.py install for mozscreenshot 18:18:44 INFO - Running setup.py install for moztest 18:18:45 INFO - Running setup.py install for mozversion 18:18:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:18:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:18:45 INFO - Cleaning up... 18:18:45 INFO - Return code: 0 18:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:45 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 0x108c96a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d2b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d946afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bfad30>, '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 0x10895fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf2b20>, '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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 18:18:45 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 18:18:45 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 18:18:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3gxWcRbhu/Render', 18:18:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:45 INFO - 'HOME': '/Users/cltbld', 18:18:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:45 INFO - 'LOGNAME': 'cltbld', 18:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:45 INFO - 'MOZ_NO_REMOTE': '1', 18:18:45 INFO - 'NO_EM_RESTART': '1', 18:18:45 INFO - 'PAGER': '/bin/cat', 18:18:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:45 INFO - 'PWD': '/builds/slave/test', 18:18:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:45 INFO - 'SHELL': '/bin/bash', 18:18:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UZ2bRKUnEt/Listeners', 18:18:45 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:18:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:45 INFO - 'USER': 'cltbld', 18:18:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:45 INFO - 'XPC_FLAGS': '0x0', 18:18:45 INFO - 'XPC_SERVICE_NAME': '0', 18:18:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-tnwLv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:45 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)) 18:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-y2UIjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:45 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)) 18:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-CuvAiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:45 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)) 18:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-8yJ7Lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:45 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)) 18:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-I5gPAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:45 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)) 18:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:45 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-0MRT4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:46 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)) 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-76SWNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:46 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)) 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-t2UM46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:46 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)) 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-H72D7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:46 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)) 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-6YeyMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:46 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)) 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-25magl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:46 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)) 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-rlmvo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:46 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)) 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-jwqomN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:46 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)) 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-yBFnNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:46 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)) 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-425Eaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:47 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)) 18:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-owj3r2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:47 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)) 18:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-lHKxsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:47 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)) 18:18:47 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)) 18:18:47 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)) 18:18:47 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)) 18:18:47 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)) 18:18:47 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)) 18:18:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:49 INFO - Downloading blessings-1.6.tar.gz 18:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:18:49 INFO - Installing collected packages: blessings 18:18:49 INFO - Running setup.py install for blessings 18:18:50 INFO - Successfully installed blessings 18:18:50 INFO - Cleaning up... 18:18:50 INFO - Return code: 0 18:18:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:18:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:50 INFO - Reading from file tmpfile_stdout 18:18:50 INFO - Current package versions: 18:18:50 INFO - blessings == 1.6 18:18:50 INFO - blobuploader == 1.2.4 18:18:50 INFO - docopt == 0.6.1 18:18:50 INFO - functools32 == 3.2.3-2 18:18:50 INFO - jsonschema == 2.5.1 18:18:50 INFO - manifestparser == 1.1 18:18:50 INFO - mozInstall == 1.12 18:18:50 INFO - mozcrash == 0.17 18:18:50 INFO - mozdebug == 0.1 18:18:50 INFO - mozdevice == 0.48 18:18:50 INFO - mozfile == 1.2 18:18:50 INFO - mozhttpd == 0.7 18:18:50 INFO - mozinfo == 0.9 18:18:50 INFO - mozleak == 0.1 18:18:50 INFO - mozlog == 3.3 18:18:50 INFO - moznetwork == 0.27 18:18:50 INFO - mozprocess == 0.23 18:18:50 INFO - mozprofile == 0.28 18:18:50 INFO - mozrunner == 6.12 18:18:50 INFO - mozscreenshot == 0.1 18:18:50 INFO - mozsystemmonitor == 0.3 18:18:50 INFO - moztest == 0.7 18:18:50 INFO - mozversion == 1.4 18:18:50 INFO - psutil == 3.1.1 18:18:50 INFO - requests == 1.2.3 18:18:50 INFO - wsgiref == 0.1.2 18:18:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:50 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 0x108c96a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d2b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d946afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bfad30>, '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 0x10895fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf2b20>, '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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 18:18:50 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 18:18:50 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 18:18:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3gxWcRbhu/Render', 18:18:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:50 INFO - 'HOME': '/Users/cltbld', 18:18:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:50 INFO - 'LOGNAME': 'cltbld', 18:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:50 INFO - 'MOZ_NO_REMOTE': '1', 18:18:50 INFO - 'NO_EM_RESTART': '1', 18:18:50 INFO - 'PAGER': '/bin/cat', 18:18:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:50 INFO - 'PWD': '/builds/slave/test', 18:18:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:50 INFO - 'SHELL': '/bin/bash', 18:18:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UZ2bRKUnEt/Listeners', 18:18:50 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:18:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:50 INFO - 'USER': 'cltbld', 18:18:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:50 INFO - 'XPC_FLAGS': '0x0', 18:18:50 INFO - 'XPC_SERVICE_NAME': '0', 18:18:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 INFO - Cleaning up... 18:18:50 INFO - Return code: 0 18:18:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108c96a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d2b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2d946afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bfad30>, '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 0x10895fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf2b20>, '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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 18:18:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:18:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3gxWcRbhu/Render', 18:18:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:50 INFO - 'HOME': '/Users/cltbld', 18:18:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:50 INFO - 'LOGNAME': 'cltbld', 18:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:50 INFO - 'MOZ_NO_REMOTE': '1', 18:18:50 INFO - 'NO_EM_RESTART': '1', 18:18:50 INFO - 'PAGER': '/bin/cat', 18:18:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:50 INFO - 'PWD': '/builds/slave/test', 18:18:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:50 INFO - 'SHELL': '/bin/bash', 18:18:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UZ2bRKUnEt/Listeners', 18:18:50 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:18:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:50 INFO - 'USER': 'cltbld', 18:18:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:50 INFO - 'XPC_FLAGS': '0x0', 18:18:50 INFO - 'XPC_SERVICE_NAME': '0', 18:18:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:18:50 INFO - Cleaning up... 18:18:50 INFO - Return code: 0 18:18:50 INFO - Running post-action listener: _resource_record_post_action 18:18:50 INFO - Running post-action listener: _start_resource_monitoring 18:18:50 INFO - Starting resource monitoring. 18:18:50 INFO - [mozharness: 2016-09-07 01:18:50.959362Z] Finished create-virtualenv step (success) 18:18:50 INFO - [mozharness: 2016-09-07 01:18:50.960332Z] Running install step. 18:18:50 INFO - Running pre-action listener: _resource_record_pre_action 18:18:50 INFO - Running main action method: install 18:18:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:51 INFO - Reading from file tmpfile_stdout 18:18:51 INFO - Detecting whether we're running mozinstall >=1.0... 18:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:18:51 INFO - Reading from file tmpfile_stdout 18:18:51 INFO - Output received: 18:18:51 INFO - Usage: mozinstall [options] installer 18:18:51 INFO - Options: 18:18:51 INFO - -h, --help show this help message and exit 18:18:51 INFO - -d DEST, --destination=DEST 18:18:51 INFO - Directory to install application into. [default: 18:18:51 INFO - "/builds/slave/test"] 18:18:51 INFO - --app=APP Application being installed. [default: firefox] 18:18:51 INFO - mkdir: /builds/slave/test/build/application 18:18:51 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'] 18:18:51 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 18:19:25 INFO - Reading from file tmpfile_stdout 18:19:25 INFO - Output received: 18:19:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:19:25 INFO - Running post-action listener: _resource_record_post_action 18:19:25 INFO - [mozharness: 2016-09-07 01:19:25.500938Z] Finished install step (success) 18:19:25 INFO - [mozharness: 2016-09-07 01:19:25.501104Z] Running run-tests step. 18:19:25 INFO - Running pre-action listener: _resource_record_pre_action 18:19:25 INFO - Running main action method: run_tests 18:19:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:19:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:19:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:19:25 INFO - 2016-09-06 18:19:25.513 screenresolution[1632:7506] starting screenresolution argv=screenresolution get 18:19:25 INFO - 2016-09-06 18:19:25.515 screenresolution[1632:7506] Display 0: 1600x1200x32@60 18:19:25 INFO - 2016-09-06 18:19:25.520 screenresolution[1633:7508] starting screenresolution argv=screenresolution list 18:19:25 INFO - Available Modes on Display 0 18:19:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:19:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:19:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:19:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:19:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:19:25 INFO - Intel Iris: 18:19:25 INFO - Chipset Model: Intel Iris 18:19:25 INFO - Type: GPU 18:19:25 INFO - Bus: Built-In 18:19:25 INFO - VRAM (Dynamic, Max): 1536 MB 18:19:25 INFO - Vendor: Intel (0x8086) 18:19:25 INFO - Device ID: 0x0a2e 18:19:25 INFO - Revision ID: 0x0009 18:19:25 INFO - Displays: 18:19:25 INFO - Display: 18:19:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:19:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:19:25 INFO - Display Serial Number: 1600x1200 60 18:19:25 INFO - Main Display: Yes 18:19:25 INFO - Mirror: Off 18:19:25 INFO - Online: Yes 18:19:25 INFO - Rotation: Supported 18:19:25 INFO - Return code: 0 18:19:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:19:25 INFO - Python 2.7.3 18:19:25 INFO - Return code: 0 18:19:25 INFO - grabbing minidump binary from tooltool 18:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:25 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 0x108bfad30>, '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 0x10895fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bf2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:19:25 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 18:19:25 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 18:19:25 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 18:19:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:19:25 INFO - Return code: 0 18:19:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:19:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:19:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:19:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:19:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:19:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:19:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t3gxWcRbhu/Render', 18:19:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:25 INFO - 'HOME': '/Users/cltbld', 18:19:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:25 INFO - 'LOGNAME': 'cltbld', 18:19:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:19:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:19:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:25 INFO - 'MOZ_NO_REMOTE': '1', 18:19:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:19:25 INFO - 'NO_EM_RESTART': '1', 18:19:25 INFO - 'PAGER': '/bin/cat', 18:19:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:25 INFO - 'PWD': '/builds/slave/test', 18:19:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:19:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:25 INFO - 'SHELL': '/bin/bash', 18:19:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UZ2bRKUnEt/Listeners', 18:19:25 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:19:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:25 INFO - 'USER': 'cltbld', 18:19:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:25 INFO - 'XPC_FLAGS': '0x0', 18:19:25 INFO - 'XPC_SERVICE_NAME': '0', 18:19:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:19:26 INFO - mozversion application_buildid: 20160906170916 18:19:26 INFO - mozversion application_changeset: a093339f5b6181440201bcf747c9013da11c4bda 18:19:26 INFO - mozversion application_display_name: Nightly 18:19:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:19:26 INFO - mozversion application_name: Firefox 18:19:26 INFO - mozversion application_remotingname: firefox 18:19:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:19:26 INFO - mozversion application_vendor: Mozilla 18:19:26 INFO - mozversion application_version: 51.0a1 18:19:26 INFO - mozversion platform_buildid: 20160906170916 18:19:26 INFO - mozversion platform_changeset: a093339f5b6181440201bcf747c9013da11c4bda 18:19:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:19:26 INFO - mozversion platform_version: 51.0a1 18:19:26 INFO - using testdate: 1473211166 18:19:26 INFO - actual date: 1473211166 18:19:26 INFO - starting webserver on 'localhost:49267' 18:19:26 INFO - SUITE-START | Running 2 tests 18:19:26 INFO - TEST-START | dromaeo_css 18:19:26 INFO - operating with platform_type : mac_ 18:19:26 INFO - Initialising browser for dromaeo_css test... 18:19:26 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpSNiZcL/profile http://localhost:49267/getInfo.html) 18:19:28 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 18:19:28 INFO - PROCESS | 1643 | colorDepth:24 18:19:28 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 18:19:28 INFO - PROCESS | 1643 | __metrics 18:19:28 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:19:28 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:19:29 INFO - TEST-INFO | 1643: exit 0 18:19:29 INFO - Browser initialized. 18:19:29 INFO - Running cycle 1/1 for dromaeo_css test... 18:19:29 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpSNiZcL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:19:41 INFO - PROCESS | 1645 | RSS: Main: 232169472 18:19:41 INFO - PROCESS | 1645 | 18:20:33 INFO - PROCESS | 1645 | 1473211233118 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] 18:22:44 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 18:22:46 INFO - PROCESS | 1645 | RSS: Main: 358027264 18:22:46 INFO - PROCESS | 1645 | 18:25:49 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 18:25:49 INFO - PROCESS | 1645 | RSS: Main: 367357952 18:25:49 INFO - PROCESS | 1645 | 18:28:52 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 18:28:52 INFO - PROCESS | 1645 | RSS: Main: 342327296 18:28:52 INFO - PROCESS | 1645 | 18:31:55 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 18:31:55 INFO - PROCESS | 1645 | RSS: Main: 336269312 18:31:55 INFO - PROCESS | 1645 | 18:34:59 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 18:34:59 INFO - PROCESS | 1645 | RSS: Main: 324595712 18:34:59 INFO - PROCESS | 1645 | 18:38:03 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 18:38:03 INFO - PROCESS | 1645 | RSS: Main: 372805632 18:38:03 INFO - PROCESS | 1645 | 18:38:03 INFO - PROCESS | 1645 | __start_tp_report 18:38:03 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 18:38:03 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 18:38:03 INFO - PROCESS | 1645 | |i|pagename|runs| 18:38:03 INFO - PROCESS | 1645 | |0;dojo.html;3286.5070239464076;3311.7516186286025;3320.850561724722;3372.966270337296;3387.4749413840864;5506.201600767887;5576.302962129734;5619.016672082379;5628.605997580171;5631.493165615099;5507.311586051744;5596;5618.2415373924505;5643.322801263844;5683.5169010634045;5081.415637201713;5112.079825631385;5152;5156.097682905495;5212.374515058198;5194.2208668699805;5215.113430716788;5230.293910322115;5239.10935141025;5284.788608455658;5087.847364579068;5106.974465127688;5119.667221630582;5151.356080489939;5167.302414174076;5160.380754309469;5162.716050617215;5167.715775632339;5178.14560597501;5185.714785686786;5069.645124841262;5116.206987916841;5148.716820574867;5149.2791009258735;5150.484951504857;4656.301554766778;5547.278853749027;5599.63602365849;5625.071863142576;5634.549236061105;6050.299829947196;6061.572977689144;6086.482648974842;6126.908096378558;6163.137160797492;6040.063790112539;6177.876442471171;6182.721777520022;6186.907196392058;6194.4115309045565;5598.804041858515;5614.157876318503;5653.57598180134;5698.914516282259;5741.741621627037;6281.748730050838;6312.747490100436;6314.810555683337;6319.146915166486;6351.809445716636;6094.847628809227;6122.846928826815;6128.4790792783115;6132.846678833065;6134.969325153345;6127.632342059491;6163.3528479509905;6189.6286222826775;6237.313895471494;6261;6199.845003874939;6200.3179650238435;6201.844953876189;6206.410391012847;6209.348018457997;6201.875962480815;6205.782797602063;6207.34822843604;6217.285012223652;6229.159063526366;4810.254410566367;4844.418669759581;4850;4868.610511159099;4945.901081978341;4356.106998065389;4368.344748287782;4391.121775644884;4458.152950939376;4486.977565112154;5696.458836410535;5721.914171287434;5737.340205876294;5738.77044918207;5770.711464426762;6122.66325352101;6164.352742962014;6238.875222495615;6262.874742505215;6272.435480806658;5669.546436285087;5699.487046165782;5742.368339482654;5771.94228057714;5794.420557944172;4486.573775491323;5394.460553944574;5398.055340315429;5404.945950540523;5420.07858664028;6141.148492690724;6183.84540386476;6266;6290.2451705793765;6293.559450838397;6137.836677219377;6224.408681175281;6269.62382257066;6269.874602507833;6291.182146320949;6280.120783090462;6281.623102613858;6284.905726414199;6284.994400895835;6292.622442653455;5980.8803823924145;6055.455009049207;6101.481374083075;6102.755889764359;6119.296280927572;6045.425684559959;6131.448169664752;6172.2901866284155;6181.505479561534;6190.380961903954;5296.920546191887;5968.134620479919;5987.401259873977;5993.850153746192;5998.1002849572915;5885;5912.674802885883;5957.106434034929;5982.940170598413;6016.458518733335;5858.297004359504;5956.176582884057;5960.493358064613;6000.849978750566;6012.8797424052145;6081.209442772588;6106.053561697944;6106.938930610637;6116.938830611637;6136.509079273558;5688.8577785554035;5736.4550367715;5885.529157667462;5891.3224979127945;5906.232189815297 18:38:03 INFO - PROCESS | 1645 | |1;ext.html;467.42740143324426;487.648892797186;492.16086572394073;500.69457630845153;526.9868253293668;30081.699183008164;30257.697423025762;31672.20819479514;39222.21555568887;40427.19145617086;42066;42423.36364954528;42462.78768606153;42496.78751606237;42670.35994460086;9542.33203675743;9736.221102311834;9740.172085372717;9744.658936937209;9745.074217949319;8727.60725767342;9591.472469014201;11744.706382340468;11907.583234586747;11925.522979080826;11982;12056.819147712791;12061.698457538589;12093.274403535772;12095.334756588383;11809.11431642626;11903.702407439796;11908.285502869856;11908.46411911466;11910.761784764256;8588.957055214747;11470.885291147066;11723.413829308585;11731.1201659875;11968.34174120423;39794;40146.19707605831;40149.799251003555;40217.79891100526;40247.19505609901;3368.1747971746836;3624.1845584743223;3930.4693866327966;4006.358982562805;4062.2281766464275;29769.553456698166;30534.389312213854;30792.692073079208;31038.534421983688;31246.53130203026;3148.480500717402;3265.9183520411943;3277.2789986202997;3300.339932013607;3330.58367704037;499580;501534.86186243047;503308;504097;504860;52839.943201135764;52989;53098.73450632722;53138;53158.46841531613;59244;59351;59407.702961484916;59449.40550594525;59494;15588.376464941275;16014.919925400298;16033.518994430186;16050.598735031717;16058.43795467153;11393.601223957121;12547.498100076078;12620.684482888002;12624.747505049847;12637.747245055047;13317.267550284634;13724.65688357799;13727.382267797973;13742.381592828347;13742.93128534371;5513.724313784375;6521.152250207443;6522.706478208492;6569.967150164219;6572.934270657328;785.6660919109356;862.9007664118582;973.6932866147141;1000.2947921715173;1026.9691909242736;27529.44941101167;27840;29054.983075592674;29092.418151636848;29712;983.6163896080534;1009.8889122196553;1090.7327704712238;1093.8906109388997;1144.9255798373079;14493.710125797425;15080.245987700571;15141.091534507965;15408.922955385375;15428.768568471482;622495;661857;662056;663807;664121;51694;51719.48280517222;51722.224166637534;51813.740931295106;51986.740066299426;1308.1366298243304;1343.8656134386483;1365.49476693622;1368.1380730139947;1371.1841454334456;26077.348066297734;28000.87996480118;28819;29117.12648620545;29687.85156074206;47583.048339032335;47804.7609761949;47927.76036119797;47945.5205447955;47962.04075918532;592581;596612;597971;598008;598111;37602;38809.2238155241;38891.80554097211;38905.80547097247;38974;44564.33153502635;46120;46584;47253.76373118113;48723;37371;37460.43809342916;37666.623333767406;37873.81063094667;39092.804535977135;30617.693823062375;37402;37719;38394.42408363933;38414.423783643826;36059.63940360668;36070.8196459016;36568.63431365652;36872;37497.81251093727;32102.678973210903;33561.49657755184;33582.49626255559;33666.49500257547;33869.83065084659;22680.546389072457;23566.35035547495;24106.915188816194;25048.62427063632;25197.496050079262 18:38:03 INFO - PROCESS | 1645 | |2;jquery.html;310.6970703564025;333.8615321752823;334.7271973341725;376.07298010404355;404.9028233224025;11877.28736275823;14717.852821471768;15599.454019109333;20260.493487662814;20556.177752889867;14732.926335368307;18835.811641883578;20535.897320513377;20566.58866822663;20628.587428251427;5731.770729170838;6068.605540639845;6075.878482430348;6085.604435711664;6085.908711369333;5575.829075894047;5933.5549833762425;5974.850628734294;6017.338092809788;6028.879422411549;5089.2620570017325;5970.0746384310505;5976.252968378953;5983.132445795357;5995.250593675791;5581.386047534768;5663.915041274384;5877.52979761618;5902.763889444417;5903.085021821625;4035.59644035597;5030.666873278584;5114.641975061747;5119.206522988943;5125.871853203663;24457.26628201157;24860;24985.750142498524;25207.49585008308;25330.49339013228;2360.209329874494;2388.558116748407;2481.6351006946106;2676.304160918156;2777.777777777773;14439;14647.780283295759;15321;15871;15992.920035399864;1841.1806745998035;1860.646477169347;1885.7265696473935;1897.5540747924226;1924.201456395594;123161.38419307936;123242.21093468751;123351;123886;126305.36847315704;7006;9556;9783.65170595616;9945.900540994642;10342.534585943651;12240.204386714835;12304.876951230552;12308;12320.383980800923;12337.938310308391;7794.986651735238;7892.210778922062;8002.719904803421;8024.959875200587;8115.715949941842;7158.498905076699;8366.958165209135;8381.706640267561;8537.871931921027;8566.18621230982;3439.5381962665765;3749.3063783200196;3836.3861782114727;3867.439221312894;3903.9219215616095;1768.6462707458559;1889.80942006535;1893.5266183454137;1894.3369820562968;1897.6204759048246;830.6054624053647;838.6477679374677;854.5513605357237;878.2008372381102;909.5452273863068;20063.899680501505;20623.175072996914;20742;20782.1687132513;20920.790792091884;909.9408538445113;920.8112336970905;927.2628260532892;937.5031233446284;981.2101258486844;9141.725748227564;9253.629854805868;10884.72788180286;10939.56241750337;10959.945200274107;562859;571082;581932;586327;587326.1901071487;94522.52738736263;100407.49388759174;100793;103272.45091323636;119757;917.6925729880564;955.9952200238954;991.1476130527741;1012.2357619996856;1012.3875055591409;18497.445076647724;19099.713504297168;19130.61738765189;19136.234550618377;19264.518387040436;55897.16154257608;56401.15398269111;57059;57127.71436142793;57858.421415785306;78134.60932695528;82810;84825;86342.56828715817;87304;7671.731489397845;7837.137914829478;8095.473794203594;8133.877991830009;8428.199321064489;11258.774824503627;12410.627681169579;12763.106582539132;12806.61580152597;12922.612321630366;11128.332300061698;11274.492647830702;11292.435378230892;11434.94282528582;11624.360660163773;10093.889672136205;10137.087662110445;11164.497597607964;11344.03575696042;11653.53385864556;8426.620802063799;8924.598393072198;9124.49815260167;10966.561337546409;11347.943260283975;6727.293634168342;7945.8410831781885;8051.83896322082;8054.83890322202;8143.063547692092;8189.877151842847;9572.611971263988;10261.281710280507;10730;10958.123350131811 18:38:03 INFO - PROCESS | 1645 | |3;mootools.html;1330.5675655411994;1403.571910567277;1464.8461911499292;1482.2959094430148;1518.9316480758364;6467.482601391889;7850.764477065697;8084.029916410039;8798.252148567371;8822.382433229677;8373.748787536384;8536.78658033548;8626.482411055342;8738.432001919886;8747.12528747125;2376.9168079117235;2408.795252403539;2411.2645643078854;2413.746556611557;2415.3478560788662;2100.4433825036303;2193.2323686709606;2362.692849929507;2392.4856155926473;2418.8064954803663;1906.7425897503867;2157.2880949286778;2178.9128434862587;2393.102586530051;2394.162043284854;2383.7020372453444;2390.5696974544576;2391.5216956608574;2393.4323018422874;2395.8921848516857;2346.3312955807514;2386.2364043506086;2388.152205966874;2389.7371289158177;2396.7842894139435;8721.520316382597;8794.20852123312;8805.823883522358;8806.427582207136;8845.027047024822;5140.948590514085;5375.354957405137;5385.7307134643115;5389.595780316492;5390.434004429557;3479.913002174966;3487.424574945131;3690.335739566877;3698.8705395311035;3708.091517578184;1074.355386767941;1086.8261078227524;1089.455272363818;1105.1159072741775;1110.3726394587034;11381.374024428653;11515.30908145514;11527.538898444052;11534.826977595343;11550.884491155064;4592.70147440422;4659.114768194032;4843.24929635907;4855.247436647325;4875.317455556225;8289.212524810133;9805.068518490874;9812.411255324703;9818;9836.917939026702;5081.720505372166;5362.329708786402;5362.731863406814;5367.543758780469;5369.543588794918;4696.2486002239475;4855.028994201174;4895.657303988686;4914.631402644816;4916.115099282092;3747;3784.375578029649;3802.752821066622;3844.1927195289304;3870.4774855394726;1168.7545615420686;1173.9823902641467;1183.2545496337252;1186.3652945674023;1189.934553599543;626.5707990026846;752.0438130010332;877.3156937588691;901.756525738047;946.0539460539461;4693.967327188013;5606.6636001840025;5854.7072646367515;5940.554458415636;5958.940410595926;453.90921815637006;467.99766001169775;479.03952575086726;485.6697445736862;612.4212619074931;3804.2771873343977;3852.5954774748807;3901.551321597996;3975.662068724133;3981.442598036277;9159.954200228956;10191.980801919748;10639;10713.678589642323;10721.249512534048;10703.785924281627;10713.92860713938;10721.35671859691;10731.08785753204;10734.838977415346;7793.532388056735;7941.014746313422;8113.18868113184;8133.959330203311;8137.715179968554;5646.520045795989;5677.31872175325;5697.601167918206;5728.885422291614;5769.711514424178;8348;9782.41305521671;9949.850752238866;9986.300958933096;10049.447280399649;8856.690015849414;9965.053319934595;9982.700518984442;10109.292349535463;10127.341722787995;7382.187959324363;7577.431692622965;7587.886181707389;7596.81007974805;7600.049993750781;8151.470154439942;8230.09468958426;8232.50604963704;8232.876506852283;8238.546879921421;6792.60751545922;7332.193458719643;7402.222766609429;7525.774226773206;7558.3953283738265;6936.410405115622;7337.633118343954;7345;7367.815804604714;7401.296876796911;6789.694463749044;6840.589564626139;6917.619530925847;6927.411170050602;6932.653367331714;4884.9511504884495;4896.41243050822;4938.555530002173;4965.304857319906;5068.619853511075;4962.082014827269;4976.776045077908;5001.0748011616515;5065.341505604395;5073.8731531712 18:38:03 INFO - PROCESS | 1645 | |4;prototype.html;295.7796441650972;300.5131686667599;307.8645396025749;310.14245610885905;310.3978282132664;4490.41624588803;5315.229291752694;5391.541718953888;5400.405955344909;5414.864628384287;5077.771500282487;5224.712640804754;5239.401982395368;5254.159334506479;5261.2371206175085;3342.8662853485953;3493.545839040934;4030.8387664493384;4051.817668204938;4054.067564460176;3399.9320013599713;3408.113890388492;3705.184859330943;3829.636184562462;3839.4240863870364;3783.3000894834404;3989.803059082278;4015.8795236142964;4042.3532234842432;4042.5148982122187;4004.8398064077546;4006.3990401439873;4017.35722284436;4020.8994775130564;4024.215278020787;3476.036039637622;3479.5733749162973;3484.4773284007347;3487.5640544931885;3496.842642081087;5064.594832413398;5089.496019638426;5140.3060586820675;5182.274481572583;5185.455527169631;4507.954920450787;4573.931391029138;4581.977090114528;4603.838865639686;4607;5073.594112470993;5562.3881373048935;5573.27547418837;5577.462254674114;5578.693171875545;3460.5501284832812;3551.2897420516;3589.4974703541525;3664.670179683841;3667.8716244931566;5883.676172861072;5886.234789477383;5886.293644762613;5892.558058145656;5904.498117660047;3260.6739326067204;3876.5154355705536;3880.1075752576503;3897.5322961244265;3907.531096268466;3759.812009399519;3911.706622003361;3950.367941129463;3962.920741585152;3963.623455771697;3782.3759079752085;4560.62847319392;4560.885977850514;4562;4600.700954437951;4990.600751939836;5397.082495975694;5643.548516118701;5649.802256921071;5731.312242530839;1193.1826698711236;1252.4802207083958;1283.3893463702993;1295.1257900916844;1346.7104572516955;769.6190385758973;881.5283823154582;902.8465160922527;904.5884122724276;905.3752910491739;4541.84103556374;5111.514406131411;5168.8449346519665;5201.815237600217;5226.555742761831;4731.810727570927;4948.604111671058;5223.947760522422;5745.971270143623;5771.538276937835;4873.634477414208;5180.912008478262;5196.45437229093;5201.791928322825;5240.580753539708;2094.4449720823995;2300.6549017647485;2354.446705024352;2376.8234723811825;2537.0928769694465;4071.714979951375;4416.955830441719;4932.284818701268;4956.578690811321;4990.6257030722845;4607.456502071844;4859.028194361142;4872.9512704873205;4908.239222920453;4960.677555958851;4399.824007039682;4621.560951709582;4972.950270497394;4984.601231901512;4985.3020577118505;3976.321902240358;4096.446979657708;4529.590997917763;4613.861584152481;4726.1729197391005;4397.515403796844;4708.655675807914;4730.550597693214;4789.425268967746;4797.888928871285;4479.7984090715345;4553.931691024571;4756.928646070242;4832.734199618914;4998.001199280403;2855.471737728527;2891.4506243813607;3348.916277093092;3350.2142578885705;3376.57792775903;4427.446569178853;5130.7178105204575;5279.920801187908;5726.570507212059;5795.217645617956;4915.476202377275;5623.09195109395;5633.2395126657375;5648.604597678123;5664.801731939528;4514.68397212192;5276.261323414802;5300.814471493479;5648.519875810603;5659.518940890071;4474.843380481668;5457.536109430743;5501.944980550144;5547.805826796042;5548.556115510669;5642.633228840113;5647.519960803378;5649.135785190795;5654.40628733977;5666.829995100154;4581.2669972805525;5324.148136298174;5431.511163995259;5452.454754524516;5515.806946756844 18:38:03 INFO - PROCESS | 1645 | |5;yui.html;771.783900507858;775.1666958020127;793.5595744361879;839.7564706235194;848.8726690996353;14787.63030924226;15130.394784208645;15488.767668484954;15863.682726345467;16047.67904641908;14297.785533216984;14612.561623151296;14628;14636.853631463682;14666.340014699337;682.689376333767;856.1353033436212;869.1265278395203;871.4509858788954;873.5326600268858;700.8002719225021;847.9703210387606;868.9913100868973;871.9564021798916;877.7191298784392;881.3610132653806;881.9647214111428;881.9867701984475;882.9161229683202;885.8006948436615;720.2545365546634;873.366809063424;875.5184648443362;876.3865294293988;877.5612193903048;667.5127157175241;687.2577616174519;689.2383915773099;692.5152393324643;692.8891377379646;15160.241987900672;15219.543413697607;15315.770263446057;15329;15386.461473848361;13862.306884655827;14131.858681413158;14191.929040354835;14290.285485725672;14435.71128577433;12851.935740321238;12908.483660653563;12981.675458113528;13098.934505327508;13188.868111318956;3628.4194528875255;4526.4794548627;4577.725336479822;4706.411698537683;4837.975810120962;88009;105583;112274.87725122726;116127;117623.64752704983;10812.94593527043;11053.115750739922;11120.277181983145;11159.274647148071;11369.602063927723;18319.358822441416;21846;22267.443313916956;22322.10711571519;22338.106475740788;7566.470347075652;7666.731664391719;7764;7826.4130190235965;7855.253306713252;4256.735608428024;4287.228298906164;4295.694396274055;4346.413234213404;4458.9331160032625;3429.2112814052907;3553.573571171476;3553.911152221215;3555.484454754046;3556.0220939241917;634.1660716158237;641.6535071061575;657.3196741372752;658.3613894522306;671.9193696756421;522.1984254169831;579.9710014499258;609.9207103076571;617.718937883262;641.6214433484173;8639.568021599021;8794.736157915273;8864.42381245217;8948.239399651102;8992.717658054893;593.7387549478216;618.7927044440073;629.7071861584294;635.9491240700733;645.1999520594522;3938.369860822255;4056.7971601419813;4263.232618128704;4315.8489452869;4336.956630433655;144512;147255;152586;155096;158067;132236;132578;136968;138870.61129388577;140115.29942350223;66081.99999999904;90592;91836.6224506633;99599.00801984065;116040.41979790047;8475.643896976455;9468.763280918032;9528.802084893598;9730.495604713662;9850.950745246228;47511.762441188956;57533.71233143807;59453.40546594652;61845.38154618396;62590.374096258456;92177;94807.52596236975;95031;96441;98468.50765746126;12826.679333016376;12939.676508086997;12965.481380744664;13080.869191307966;13315.933420332836;14422.711545768816;14829.555113346616;15047;15064.623384415478;15329.770053448985;10193.694189174337;13621.110281304456;13712.725745484835;13772.24252666113;14233.074850134708;13464.663383415493;13773.104748191336;14066.718665626835;14191.006629535834;14379.424823006962;13313.800292995806;13536.187828730308;13660.590182294547;13883.298799000308;14039.859601404263;10936.289141205796;11302.773944521228;11350.54597816078;11627.593034244099;11628.94185529067;9330.440173589606;10897.291676041032;11368.488418021374;11607.709807254549;11628.883711163118 18:38:03 INFO - PROCESS | 1645 | __end_tp_report 18:38:03 INFO - PROCESS | 1645 | __start_cc_report 18:38:03 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1143 18:38:03 INFO - PROCESS | 1645 | __end_cc_report 18:38:03 INFO - PROCESS | 1645 | __startTimestamp1473212283401__endTimestamp 18:38:03 INFO - PROCESS | 1645 | 18:38:03 INFO - PROCESS | 1645 | ------- Summary: start ------- 18:38:03 INFO - PROCESS | 1645 | Number of tests: 6 18:38:03 INFO - PROCESS | 1645 | 18:38:03 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5683.55 Median:5906.23 stddev:646.08 (10.9%) stddev-sans-first:620.09 18:38:03 INFO - PROCESS | 1645 | Values: 3286.5 3311.8 3320.9 3373.0 3387.5 5506.2 5576.3 5619.0 5628.6 5631.5 5507.3 5596.0 5618.2 5643.3 5683.5 5081.4 5112.1 5152.0 5156.1 5212.4 5194.2 5215.1 5230.3 5239.1 5284.8 5087.8 5107.0 5119.7 5151.4 5167.3 5160.4 5162.7 5167.7 5178.1 5185.7 5069.6 5116.2 5148.7 5149.3 5150.5 4656.3 5547.3 5599.6 5625.1 5634.5 6050.3 6061.6 6086.5 6126.9 6163.1 6040.1 6177.9 6182.7 6186.9 6194.4 5598.8 5614.2 5653.6 5698.9 5741.7 6281.7 6312.7 6314.8 6319.1 6351.8 6094.8 6122.8 6128.5 6132.8 6135.0 6127.6 6163.4 6189.6 6237.3 6261.0 6199.8 6200.3 6201.8 6206.4 6209.3 6201.9 6205.8 6207.3 6217.3 6229.2 4810.3 4844.4 4850.0 4868.6 4945.9 4356.1 4368.3 4391.1 4458.2 4487.0 5696.5 5721.9 5737.3 5738.8 5770.7 6122.7 6164.4 6238.9 6262.9 6272.4 5669.5 5699.5 5742.4 5771.9 5794.4 4486.6 5394.5 5398.1 5404.9 5420.1 6141.1 6183.8 6266.0 6290.2 6293.6 6137.8 6224.4 6269.6 6269.9 6291.2 6280.1 6281.6 6284.9 6285.0 6292.6 5980.9 6055.5 6101.5 6102.8 6119.3 6045.4 6131.4 6172.3 6181.5 6190.4 5296.9 5968.1 5987.4 5993.9 5998.1 5885.0 5912.7 5957.1 5982.9 6016.5 5858.3 5956.2 5960.5 6000.8 6012.9 6081.2 6106.1 6106.9 6116.9 6136.5 5688.9 5736.5 5885.5 5891.3 5906.2 18:38:03 INFO - PROCESS | 1645 | 18:38:03 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:70956.01 Median:37479.13 stddev:157320.57 (419.8%) stddev-sans-first:157672.87 18:38:03 INFO - PROCESS | 1645 | Values: 467.4 487.6 492.2 500.7 527.0 30081.7 30257.7 31672.2 39222.2 40427.2 42066.0 42423.4 42462.8 42496.8 42670.4 9542.3 9736.2 9740.2 9744.7 9745.1 8727.6 9591.5 11744.7 11907.6 11925.5 11982.0 12056.8 12061.7 12093.3 12095.3 11809.1 11903.7 11908.3 11908.5 11910.8 8589.0 11470.9 11723.4 11731.1 11968.3 39794.0 40146.2 40149.8 40217.8 40247.2 3368.2 3624.2 3930.5 4006.4 4062.2 29769.6 30534.4 30792.7 31038.5 31246.5 3148.5 3265.9 3277.3 3300.3 3330.6 499580.0 501534.9 503308.0 504097.0 504860.0 52839.9 52989.0 53098.7 53138.0 53158.5 59244.0 59351.0 59407.7 59449.4 59494.0 15588.4 16014.9 16033.5 16050.6 16058.4 11393.6 12547.5 12620.7 12624.7 12637.7 13317.3 13724.7 13727.4 13742.4 13742.9 5513.7 6521.2 6522.7 6570.0 6572.9 785.7 862.9 973.7 1000.3 1027.0 27529.4 27840.0 29055.0 29092.4 29712.0 983.6 1009.9 1090.7 1093.9 1144.9 14493.7 15080.2 15141.1 15408.9 15428.8 622495.0 661857.0 662056.0 663807.0 664121.0 51694.0 51719.5 51722.2 51813.7 51986.7 1308.1 1343.9 1365.5 1368.1 1371.2 26077.3 28000.9 28819.0 29117.1 29687.9 47583.0 47804.8 47927.8 47945.5 47962.0 592581.0 596612.0 597971.0 598008.0 598111.0 37602.0 38809.2 38891.8 38905.8 38974.0 44564.3 46120.0 46584.0 47253.8 48723.0 37371.0 37460.4 37666.6 37873.8 39092.8 30617.7 37402.0 37719.0 38394.4 38414.4 36059.6 36070.8 36568.6 36872.0 37497.8 32102.7 33561.5 33582.5 33666.5 33869.8 22680.5 23566.4 24106.9 25048.6 25197.5 18:38:03 INFO - PROCESS | 1645 | 18:38:03 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:33936.42 Median:2106.23 stddev:96414.68 (4577.6%) stddev-sans-first:96652.09 18:38:03 INFO - PROCESS | 1645 | Values: 310.7 333.9 334.7 376.1 404.9 11877.3 14717.9 15599.5 20260.5 20556.2 14732.9 18835.8 20535.9 20566.6 20628.6 5731.8 6068.6 6075.9 6085.6 6085.9 5575.8 5933.6 5974.9 6017.3 6028.9 5089.3 5970.1 5976.3 5983.1 5995.3 5581.4 5663.9 5877.5 5902.8 5903.1 4035.6 5030.7 5114.6 5119.2 5125.9 24457.3 24860.0 24985.8 25207.5 25330.5 2360.2 2388.6 2481.6 2676.3 2777.8 14439.0 14647.8 15321.0 15871.0 15992.9 1841.2 1860.6 1885.7 1897.6 1924.2 123161.4 123242.2 123351.0 123886.0 126305.4 7006.0 9556.0 9783.7 9945.9 10342.5 12240.2 12304.9 12308.0 12320.4 12337.9 7795.0 7892.2 8002.7 8025.0 8115.7 7158.5 8367.0 8381.7 8537.9 8566.2 3439.5 3749.3 3836.4 3867.4 3903.9 1768.6 1889.8 1893.5 1894.3 1897.6 830.6 838.6 854.6 878.2 909.5 20063.9 20623.2 20742.0 20782.2 20920.8 909.9 920.8 927.3 937.5 981.2 9141.7 9253.6 10884.7 10939.6 10959.9 562859.0 571082.0 581932.0 586327.0 587326.2 94522.5 100407.5 100793.0 103272.5 119757.0 917.7 956.0 991.1 1012.2 1012.4 18497.4 19099.7 19130.6 19136.2 19264.5 55897.2 56401.2 57059.0 57127.7 57858.4 78134.6 82810.0 84825.0 86342.6 87304.0 7671.7 7837.1 8095.5 8133.9 8428.2 11258.8 12410.6 12763.1 12806.6 12922.6 11128.3 11274.5 11292.4 11434.9 11624.4 10093.9 10137.1 11164.5 11344.0 11653.5 8426.6 8924.6 9124.5 10966.6 11347.9 6727.3 7945.8 8051.8 8054.8 8143.1 8189.9 9572.6 10261.3 10730.0 10958.1 18:38:03 INFO - PROCESS | 1645 | 18:38:03 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5603.10 Median:4963.69 stddev:3150.50 (63.5%) stddev-sans-first:3142.97 18:38:03 INFO - PROCESS | 1645 | Values: 1330.6 1403.6 1464.8 1482.3 1518.9 6467.5 7850.8 8084.0 8798.3 8822.4 8373.7 8536.8 8626.5 8738.4 8747.1 2376.9 2408.8 2411.3 2413.7 2415.3 2100.4 2193.2 2362.7 2392.5 2418.8 1906.7 2157.3 2178.9 2393.1 2394.2 2383.7 2390.6 2391.5 2393.4 2395.9 2346.3 2386.2 2388.2 2389.7 2396.8 8721.5 8794.2 8805.8 8806.4 8845.0 5140.9 5375.4 5385.7 5389.6 5390.4 3479.9 3487.4 3690.3 3698.9 3708.1 1074.4 1086.8 1089.5 1105.1 1110.4 11381.4 11515.3 11527.5 11534.8 11550.9 4592.7 4659.1 4843.2 4855.2 4875.3 8289.2 9805.1 9812.4 9818.0 9836.9 5081.7 5362.3 5362.7 5367.5 5369.5 4696.2 4855.0 4895.7 4914.6 4916.1 3747.0 3784.4 3802.8 3844.2 3870.5 1168.8 1174.0 1183.3 1186.4 1189.9 626.6 752.0 877.3 901.8 946.1 4694.0 5606.7 5854.7 5940.6 5958.9 453.9 468.0 479.0 485.7 612.4 3804.3 3852.6 3901.6 3975.7 3981.4 9160.0 10192.0 10639.0 10713.7 10721.2 10703.8 10713.9 10721.4 10731.1 10734.8 7793.5 7941.0 8113.2 8134.0 8137.7 5646.5 5677.3 5697.6 5728.9 5769.7 8348.0 9782.4 9949.9 9986.3 10049.4 8856.7 9965.1 9982.7 10109.3 10127.3 7382.2 7577.4 7587.9 7596.8 7600.0 8151.5 8230.1 8232.5 8232.9 8238.5 6792.6 7332.2 7402.2 7525.8 7558.4 6936.4 7337.6 7345.0 7367.8 7401.3 6789.7 6840.6 6917.6 6927.4 6932.7 4885.0 4896.4 4938.6 4965.3 5068.6 4962.1 4976.8 5001.1 5065.3 5073.9 18:38:03 INFO - PROCESS | 1645 | 18:38:03 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4315.10 Median:4731.18 stddev:1343.78 (28.4%) stddev-sans-first:1313.25 18:38:03 INFO - PROCESS | 1645 | Values: 295.8 300.5 307.9 310.1 310.4 4490.4 5315.2 5391.5 5400.4 5414.9 5077.8 5224.7 5239.4 5254.2 5261.2 3342.9 3493.5 4030.8 4051.8 4054.1 3399.9 3408.1 3705.2 3829.6 3839.4 3783.3 3989.8 4015.9 4042.4 4042.5 4004.8 4006.4 4017.4 4020.9 4024.2 3476.0 3479.6 3484.5 3487.6 3496.8 5064.6 5089.5 5140.3 5182.3 5185.5 4508.0 4573.9 4582.0 4603.8 4607.0 5073.6 5562.4 5573.3 5577.5 5578.7 3460.6 3551.3 3589.5 3664.7 3667.9 5883.7 5886.2 5886.3 5892.6 5904.5 3260.7 3876.5 3880.1 3897.5 3907.5 3759.8 3911.7 3950.4 3962.9 3963.6 3782.4 4560.6 4560.9 4562.0 4600.7 4990.6 5397.1 5643.5 5649.8 5731.3 1193.2 1252.5 1283.4 1295.1 1346.7 769.6 881.5 902.8 904.6 905.4 4541.8 5111.5 5168.8 5201.8 5226.6 4731.8 4948.6 5223.9 5746.0 5771.5 4873.6 5180.9 5196.5 5201.8 5240.6 2094.4 2300.7 2354.4 2376.8 2537.1 4071.7 4417.0 4932.3 4956.6 4990.6 4607.5 4859.0 4873.0 4908.2 4960.7 4399.8 4621.6 4973.0 4984.6 4985.3 3976.3 4096.4 4529.6 4613.9 4726.2 4397.5 4708.7 4730.6 4789.4 4797.9 4479.8 4553.9 4756.9 4832.7 4998.0 2855.5 2891.5 3348.9 3350.2 3376.6 4427.4 5130.7 5279.9 5726.6 5795.2 4915.5 5623.1 5633.2 5648.6 5664.8 4514.7 5276.3 5300.8 5648.5 5659.5 4474.8 5457.5 5501.9 5547.8 5548.6 5642.6 5647.5 5649.1 5654.4 5666.8 4581.3 5324.1 5431.5 5452.5 5515.8 18:38:03 INFO - PROCESS | 1645 | 18:38:03 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:24636.69 Median:3997.58 stddev:39525.96 (988.7%) stddev-sans-first:39596.23 18:38:03 INFO - PROCESS | 1645 | Values: 771.8 775.2 793.6 839.8 848.9 14787.6 15130.4 15488.8 15863.7 16047.7 14297.8 14612.6 14628.0 14636.9 14666.3 682.7 856.1 869.1 871.5 873.5 700.8 848.0 869.0 872.0 877.7 881.4 882.0 882.0 882.9 885.8 720.3 873.4 875.5 876.4 877.6 667.5 687.3 689.2 692.5 692.9 15160.2 15219.5 15315.8 15329.0 15386.5 13862.3 14131.9 14191.9 14290.3 14435.7 12851.9 12908.5 12981.7 13098.9 13188.9 3628.4 4526.5 4577.7 4706.4 4838.0 88009.0 105583.0 112274.9 116127.0 117623.6 10812.9 11053.1 11120.3 11159.3 11369.6 18319.4 21846.0 22267.4 22322.1 22338.1 7566.5 7666.7 7764.0 7826.4 7855.3 4256.7 4287.2 4295.7 4346.4 4458.9 3429.2 3553.6 3553.9 3555.5 3556.0 634.2 641.7 657.3 658.4 671.9 522.2 580.0 609.9 617.7 641.6 8639.6 8794.7 8864.4 8948.2 8992.7 593.7 618.8 629.7 635.9 645.2 3938.4 4056.8 4263.2 4315.8 4337.0 144512.0 147255.0 152586.0 155096.0 158067.0 132236.0 132578.0 136968.0 138870.6 140115.3 66082.0 90592.0 91836.6 99599.0 116040.4 8475.6 9468.8 9528.8 9730.5 9851.0 47511.8 57533.7 59453.4 61845.4 62590.4 92177.0 94807.5 95031.0 96441.0 98468.5 12826.7 12939.7 12965.5 13080.9 13315.9 14422.7 14829.6 15047.0 15064.6 15329.8 10193.7 13621.1 13712.7 13772.2 14233.1 13464.7 13773.1 14066.7 14191.0 14379.4 13313.8 13536.2 13660.6 13883.3 14039.9 10936.3 11302.8 11350.5 11627.6 11628.9 9330.4 10897.3 11368.5 11607.7 11628.9 18:38:03 INFO - PROCESS | 1645 | -------- Summary: end -------- 18:38:03 INFO - PROCESS | 1645 | 18:38:03 INFO - TEST-INFO | 1645: exit 0 18:38:03 INFO - TEST-OK | dromaeo_css | took 1117895ms 18:38:03 INFO - TEST-START | kraken 18:38:03 INFO - operating with platform_type : mac_ 18:38:03 INFO - Initialising browser for kraken test... 18:38:04 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp0ZGzo7/profile http://localhost:49267/getInfo.html) 18:38:06 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 18:38:06 INFO - PROCESS | 1652 | colorDepth:24 18:38:06 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 18:38:06 INFO - PROCESS | 1652 | __metrics 18:38:06 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:38:06 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:38:07 INFO - TEST-INFO | 1652: exit 0 18:38:07 INFO - Browser initialized. 18:38:07 INFO - Running cycle 1/1 for kraken test... 18:38:07 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp0ZGzo7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:38:18 INFO - PROCESS | 1653 | RSS: Main: 232325120 18:38:18 INFO - PROCESS | 1653 | 18:38:21 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 18:38:22 INFO - PROCESS | 1653 | RSS: Main: 290848768 18:38:22 INFO - PROCESS | 1653 | 18:38:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 18:38:25 INFO - PROCESS | 1653 | RSS: Main: 286846976 18:38:25 INFO - PROCESS | 1653 | 18:38:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 18:38:30 INFO - PROCESS | 1653 | RSS: Main: 375353344 18:38:30 INFO - PROCESS | 1653 | 18:38:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 18:38:32 INFO - PROCESS | 1653 | RSS: Main: 278429696 18:38:32 INFO - PROCESS | 1653 | 18:38:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:38:35 INFO - PROCESS | 1653 | RSS: Main: 282292224 18:38:35 INFO - PROCESS | 1653 | 18:38:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 18:38:38 INFO - PROCESS | 1653 | RSS: Main: 321478656 18:38:38 INFO - PROCESS | 1653 | 18:38:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 18:38:42 INFO - PROCESS | 1653 | RSS: Main: 321781760 18:38:42 INFO - PROCESS | 1653 | 18:38:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 18:38:46 INFO - PROCESS | 1653 | RSS: Main: 321224704 18:38:46 INFO - PROCESS | 1653 | 18:38:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:38:48 INFO - PROCESS | 1653 | RSS: Main: 297418752 18:38:48 INFO - PROCESS | 1653 | 18:38:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:38:50 INFO - PROCESS | 1653 | RSS: Main: 303710208 18:38:50 INFO - PROCESS | 1653 | 18:38:52 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:38:53 INFO - PROCESS | 1653 | RSS: Main: 306204672 18:38:53 INFO - PROCESS | 1653 | 18:38:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:38:57 INFO - PROCESS | 1653 | RSS: Main: 318664704 18:38:57 INFO - PROCESS | 1653 | 18:39:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:39:00 INFO - PROCESS | 1653 | RSS: Main: 314654720 18:39:00 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 18:39:02 INFO - PROCESS | 1653 | RSS: Main: 358637568 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | __start_tp_report 18:39:02 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 18:39:02 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 18:39:02 INFO - PROCESS | 1653 | |i|pagename|runs| 18:39:02 INFO - PROCESS | 1653 | |0;ai-astar;115;113;118;114;112;115;114;111;112;122 18:39:02 INFO - PROCESS | 1653 | |1;audio-beat-detection;151;114;132;161;106;132;130;108;157;105 18:39:02 INFO - PROCESS | 1653 | |2;audio-dft;185;186;186;185;183;182;187;185;182;184 18:39:02 INFO - PROCESS | 1653 | |3;audio-fft;77;77;78;157;59;72;82;86;148;57 18:39:02 INFO - PROCESS | 1653 | |4;audio-oscillator;82;83;141;70;72;76;131;99;111;131 18:39:02 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;91;85;89;85;87;85;88;86;87;85 18:39:02 INFO - PROCESS | 1653 | |6;imaging-darkroom;95;97;96;116;162;161;97;96;97;97 18:39:02 INFO - PROCESS | 1653 | |7;imaging-desaturate;85;84;86;83;84;83;84;84;84;84 18:39:02 INFO - PROCESS | 1653 | |8;json-parse-financial;53;53;54;53;54;53;53;53;53;54 18:39:02 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;67;65;71;72;72;75;73;70;70;70 18:39:02 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;86;87;89;84;84;87;89;89;89;88 18:39:02 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;152;158;140;140;152;150;152;148;187;142 18:39:02 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;172;173;172;173;172;173;170;182;175;172 18:39:02 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;68;68;70;68;69;68;68;66;68;68 18:39:02 INFO - PROCESS | 1653 | __end_tp_report 18:39:02 INFO - PROCESS | 1653 | __start_cc_report 18:39:02 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,2071 18:39:02 INFO - PROCESS | 1653 | __end_cc_report 18:39:02 INFO - PROCESS | 1653 | __startTimestamp1473212342875__endTimestamp 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | ------- Summary: start ------- 18:39:02 INFO - PROCESS | 1653 | Number of tests: 14 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:114.60 Median:114.50 stddev:3.27 (2.9%) stddev-sans-first:3.47 18:39:02 INFO - PROCESS | 1653 | Values: 115.0 113.0 118.0 114.0 112.0 115.0 114.0 111.0 112.0 122.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:132.00 stddev:21.29 (16.1%) stddev-sans-first:21.12 18:39:02 INFO - PROCESS | 1653 | Values: 151.0 114.0 132.0 161.0 106.0 132.0 130.0 108.0 157.0 105.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.72 (0.9%) stddev-sans-first:1.81 18:39:02 INFO - PROCESS | 1653 | Values: 185.0 186.0 186.0 185.0 183.0 182.0 187.0 185.0 182.0 184.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:89.30 Median:77.00 stddev:34.62 (45.0%) stddev-sans-first:36.43 18:39:02 INFO - PROCESS | 1653 | Values: 77.0 77.0 78.0 157.0 59.0 72.0 82.0 86.0 148.0 57.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:99.60 Median:74.00 stddev:27.07 (36.6%) stddev-sans-first:27.96 18:39:02 INFO - PROCESS | 1653 | Values: 82.0 83.0 141.0 70.0 72.0 76.0 131.0 99.0 111.0 131.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.00 stddev:2.04 (2.3%) stddev-sans-first:1.50 18:39:02 INFO - PROCESS | 1653 | Values: 91.0 85.0 89.0 85.0 87.0 85.0 88.0 86.0 87.0 85.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:111.40 Median:96.50 stddev:27.11 (28.1%) stddev-sans-first:28.10 18:39:02 INFO - PROCESS | 1653 | Values: 95.0 97.0 96.0 116.0 162.0 161.0 97.0 96.0 97.0 97.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.00 stddev:0.88 (1.0%) stddev-sans-first:0.87 18:39:02 INFO - PROCESS | 1653 | Values: 85.0 84.0 86.0 83.0 84.0 83.0 84.0 84.0 84.0 84.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 18:39:02 INFO - PROCESS | 1653 | Values: 53.0 53.0 54.0 53.0 54.0 53.0 53.0 53.0 53.0 54.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.50 stddev:2.88 (4.0%) stddev-sans-first:2.76 18:39:02 INFO - PROCESS | 1653 | Values: 67.0 65.0 71.0 72.0 72.0 75.0 73.0 70.0 70.0 70.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:88.50 stddev:1.99 (2.2%) stddev-sans-first:2.06 18:39:02 INFO - PROCESS | 1653 | Values: 86.0 87.0 89.0 84.0 84.0 87.0 89.0 89.0 89.0 88.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:152.00 stddev:13.62 (9.0%) stddev-sans-first:14.44 18:39:02 INFO - PROCESS | 1653 | Values: 152.0 158.0 140.0 140.0 152.0 150.0 152.0 148.0 187.0 142.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:173.00 stddev:3.27 (1.9%) stddev-sans-first:3.43 18:39:02 INFO - PROCESS | 1653 | Values: 172.0 173.0 172.0 173.0 172.0 173.0 170.0 182.0 175.0 172.0 18:39:02 INFO - PROCESS | 1653 | 18:39:02 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 18:39:02 INFO - PROCESS | 1653 | Values: 68.0 68.0 70.0 68.0 69.0 68.0 68.0 66.0 68.0 68.0 18:39:02 INFO - PROCESS | 1653 | -------- Summary: end -------- 18:39:02 INFO - PROCESS | 1653 | 18:39:03 INFO - TEST-INFO | 1653: exit 0 18:39:03 INFO - TEST-OK | kraken | took 59769ms 18:39:03 INFO - SUITE-END | took 1177s 18:39:04 INFO - Completed test suite (00:19:37) 18:39:04 INFO - javascript benchmark 18:39:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3286.5070239464076, 3311.7516186286025, 3320.850561724722, 3372.966270337296, 3387.4749413840864, 5506.201600767887, 5576.302962129734, 5619.016672082379, 5628.605997580171, 5631.493165615099, 5507.311586051744, 5596.0, 5618.2415373924505, 5643.322801263844, 5683.5169010634045, 5081.415637201713, 5112.079825631385, 5152.0, 5156.097682905495, 5212.374515058198, 5194.2208668699805, 5215.113430716788, 5230.293910322115, 5239.10935141025, 5284.788608455658, 5087.847364579068, 5106.974465127688, 5119.667221630582, 5151.356080489939, 5167.302414174076, 5160.380754309469, 5162.716050617215, 5167.715775632339, 5178.14560597501, 5185.714785686786, 5069.645124841262, 5116.206987916841, 5148.716820574867, 5149.2791009258735, 5150.484951504857, 4656.301554766778, 5547.278853749027, 5599.63602365849, 5625.071863142576, 5634.549236061105, 6050.299829947196, 6061.572977689144, 6086.482648974842, 6126.908096378558, 6163.137160797492, 6040.063790112539, 6177.876442471171, 6182.721777520022, 6186.907196392058, 6194.4115309045565, 5598.804041858515, 5614.157876318503, 5653.57598180134, 5698.914516282259, 5741.741621627037, 6281.748730050838, 6312.747490100436, 6314.810555683337, 6319.146915166486, 6351.809445716636, 6094.847628809227, 6122.846928826815, 6128.4790792783115, 6132.846678833065, 6134.969325153345, 6127.632342059491, 6163.3528479509905, 6189.6286222826775, 6237.313895471494, 6261.0, 6199.845003874939, 6200.3179650238435, 6201.844953876189, 6206.410391012847, 6209.348018457997, 6201.875962480815, 6205.782797602063, 6207.34822843604, 6217.285012223652, 6229.159063526366, 4810.254410566367, 4844.418669759581, 4850.0, 4868.610511159099, 4945.901081978341, 4356.106998065389, 4368.344748287782, 4391.121775644884, 4458.152950939376, 4486.977565112154, 5696.458836410535, 5721.914171287434, 5737.340205876294, 5738.77044918207, 5770.711464426762, 6122.66325352101, 6164.352742962014, 6238.875222495615, 6262.874742505215, 6272.435480806658, 5669.546436285087, 5699.487046165782, 5742.368339482654, 5771.94228057714, 5794.420557944172, 4486.573775491323, 5394.460553944574, 5398.055340315429, 5404.945950540523, 5420.07858664028, 6141.148492690724, 6183.84540386476, 6266.0, 6290.2451705793765, 6293.559450838397, 6137.836677219377, 6224.408681175281, 6269.62382257066, 6269.874602507833, 6291.182146320949, 6280.120783090462, 6281.623102613858, 6284.905726414199, 6284.994400895835, 6292.622442653455, 5980.8803823924145, 6055.455009049207, 6101.481374083075, 6102.755889764359, 6119.296280927572, 6045.425684559959, 6131.448169664752, 6172.2901866284155, 6181.505479561534, 6190.380961903954, 5296.920546191887, 5968.134620479919, 5987.401259873977, 5993.850153746192, 5998.1002849572915, 5885.0, 5912.674802885883, 5957.106434034929, 5982.940170598413, 6016.458518733335, 5858.297004359504, 5956.176582884057, 5960.493358064613, 6000.849978750566, 6012.8797424052145, 6081.209442772588, 6106.053561697944, 6106.938930610637, 6116.938830611637, 6136.509079273558, 5688.8577785554035, 5736.4550367715, 5885.529157667462, 5891.3224979127945, 5906.232189815297], "value": 5641.6987736014935, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.42740143324426, 487.648892797186, 492.16086572394073, 500.69457630845153, 526.9868253293668, 30081.699183008164, 30257.697423025762, 31672.20819479514, 39222.21555568887, 40427.19145617086, 42066.0, 42423.36364954528, 42462.78768606153, 42496.78751606237, 42670.35994460086, 9542.33203675743, 9736.221102311834, 9740.172085372717, 9744.658936937209, 9745.074217949319, 8727.60725767342, 9591.472469014201, 11744.706382340468, 11907.583234586747, 11925.522979080826, 11982.0, 12056.819147712791, 12061.698457538589, 12093.274403535772, 12095.334756588383, 11809.11431642626, 11903.702407439796, 11908.285502869856, 11908.46411911466, 11910.761784764256, 8588.957055214747, 11470.885291147066, 11723.413829308585, 11731.1201659875, 11968.34174120423, 39794.0, 40146.19707605831, 40149.799251003555, 40217.79891100526, 40247.19505609901, 3368.1747971746836, 3624.1845584743223, 3930.4693866327966, 4006.358982562805, 4062.2281766464275, 29769.553456698166, 30534.389312213854, 30792.692073079208, 31038.534421983688, 31246.53130203026, 3148.480500717402, 3265.9183520411943, 3277.2789986202997, 3300.339932013607, 3330.58367704037, 499580.0, 501534.86186243047, 503308.0, 504097.0, 504860.0, 52839.943201135764, 52989.0, 53098.73450632722, 53138.0, 53158.46841531613, 59244.0, 59351.0, 59407.702961484916, 59449.40550594525, 59494.0, 15588.376464941275, 16014.919925400298, 16033.518994430186, 16050.598735031717, 16058.43795467153, 11393.601223957121, 12547.498100076078, 12620.684482888002, 12624.747505049847, 12637.747245055047, 13317.267550284634, 13724.65688357799, 13727.382267797973, 13742.381592828347, 13742.93128534371, 5513.724313784375, 6521.152250207443, 6522.706478208492, 6569.967150164219, 6572.934270657328, 785.6660919109356, 862.9007664118582, 973.6932866147141, 1000.2947921715173, 1026.9691909242736, 27529.44941101167, 27840.0, 29054.983075592674, 29092.418151636848, 29712.0, 983.6163896080534, 1009.8889122196553, 1090.7327704712238, 1093.8906109388997, 1144.9255798373079, 14493.710125797425, 15080.245987700571, 15141.091534507965, 15408.922955385375, 15428.768568471482, 622495.0, 661857.0, 662056.0, 663807.0, 664121.0, 51694.0, 51719.48280517222, 51722.224166637534, 51813.740931295106, 51986.740066299426, 1308.1366298243304, 1343.8656134386483, 1365.49476693622, 1368.1380730139947, 1371.1841454334456, 26077.348066297734, 28000.87996480118, 28819.0, 29117.12648620545, 29687.85156074206, 47583.048339032335, 47804.7609761949, 47927.76036119797, 47945.5205447955, 47962.04075918532, 592581.0, 596612.0, 597971.0, 598008.0, 598111.0, 37602.0, 38809.2238155241, 38891.80554097211, 38905.80547097247, 38974.0, 44564.33153502635, 46120.0, 46584.0, 47253.76373118113, 48723.0, 37371.0, 37460.43809342916, 37666.623333767406, 37873.81063094667, 39092.804535977135, 30617.693823062375, 37402.0, 37719.0, 38394.42408363933, 38414.423783643826, 36059.63940360668, 36070.8196459016, 36568.63431365652, 36872.0, 37497.81251093727, 32102.678973210903, 33561.49657755184, 33582.49626255559, 33666.49500257547, 33869.83065084659, 22680.546389072457, 23566.35035547495, 24106.915188816194, 25048.62427063632, 25197.496050079262], "value": 19905.999374840874, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.6970703564025, 333.8615321752823, 334.7271973341725, 376.07298010404355, 404.9028233224025, 11877.28736275823, 14717.852821471768, 15599.454019109333, 20260.493487662814, 20556.177752889867, 14732.926335368307, 18835.811641883578, 20535.897320513377, 20566.58866822663, 20628.587428251427, 5731.770729170838, 6068.605540639845, 6075.878482430348, 6085.604435711664, 6085.908711369333, 5575.829075894047, 5933.5549833762425, 5974.850628734294, 6017.338092809788, 6028.879422411549, 5089.2620570017325, 5970.0746384310505, 5976.252968378953, 5983.132445795357, 5995.250593675791, 5581.386047534768, 5663.915041274384, 5877.52979761618, 5902.763889444417, 5903.085021821625, 4035.59644035597, 5030.666873278584, 5114.641975061747, 5119.206522988943, 5125.871853203663, 24457.26628201157, 24860.0, 24985.750142498524, 25207.49585008308, 25330.49339013228, 2360.209329874494, 2388.558116748407, 2481.6351006946106, 2676.304160918156, 2777.777777777773, 14439.0, 14647.780283295759, 15321.0, 15871.0, 15992.920035399864, 1841.1806745998035, 1860.646477169347, 1885.7265696473935, 1897.5540747924226, 1924.201456395594, 123161.38419307936, 123242.21093468751, 123351.0, 123886.0, 126305.36847315704, 7006.0, 9556.0, 9783.65170595616, 9945.900540994642, 10342.534585943651, 12240.204386714835, 12304.876951230552, 12308.0, 12320.383980800923, 12337.938310308391, 7794.986651735238, 7892.210778922062, 8002.719904803421, 8024.959875200587, 8115.715949941842, 7158.498905076699, 8366.958165209135, 8381.706640267561, 8537.871931921027, 8566.18621230982, 3439.5381962665765, 3749.3063783200196, 3836.3861782114727, 3867.439221312894, 3903.9219215616095, 1768.6462707458559, 1889.80942006535, 1893.5266183454137, 1894.3369820562968, 1897.6204759048246, 830.6054624053647, 838.6477679374677, 854.5513605357237, 878.2008372381102, 909.5452273863068, 20063.899680501505, 20623.175072996914, 20742.0, 20782.1687132513, 20920.790792091884, 909.9408538445113, 920.8112336970905, 927.2628260532892, 937.5031233446284, 981.2101258486844, 9141.725748227564, 9253.629854805868, 10884.72788180286, 10939.56241750337, 10959.945200274107, 562859.0, 571082.0, 581932.0, 586327.0, 587326.1901071487, 94522.52738736263, 100407.49388759174, 100793.0, 103272.45091323636, 119757.0, 917.6925729880564, 955.9952200238954, 991.1476130527741, 1012.2357619996856, 1012.3875055591409, 18497.445076647724, 19099.713504297168, 19130.61738765189, 19136.234550618377, 19264.518387040436, 55897.16154257608, 56401.15398269111, 57059.0, 57127.71436142793, 57858.421415785306, 78134.60932695528, 82810.0, 84825.0, 86342.56828715817, 87304.0, 7671.731489397845, 7837.137914829478, 8095.473794203594, 8133.877991830009, 8428.199321064489, 11258.774824503627, 12410.627681169579, 12763.106582539132, 12806.61580152597, 12922.612321630366, 11128.332300061698, 11274.492647830702, 11292.435378230892, 11434.94282528582, 11624.360660163773, 10093.889672136205, 10137.087662110445, 11164.497597607964, 11344.03575696042, 11653.53385864556, 8426.620802063799, 8924.598393072198, 9124.49815260167, 10966.561337546409, 11347.943260283975, 6727.293634168342, 7945.8410831781885, 8051.83896322082, 8054.83890322202, 8143.063547692092, 8189.877151842847, 9572.611971263988, 10261.281710280507, 10730.0, 10958.123350131811], "value": 9270.482560041923, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.5675655411994, 1403.571910567277, 1464.8461911499292, 1482.2959094430148, 1518.9316480758364, 6467.482601391889, 7850.764477065697, 8084.029916410039, 8798.252148567371, 8822.382433229677, 8373.748787536384, 8536.78658033548, 8626.482411055342, 8738.432001919886, 8747.12528747125, 2376.9168079117235, 2408.795252403539, 2411.2645643078854, 2413.746556611557, 2415.3478560788662, 2100.4433825036303, 2193.2323686709606, 2362.692849929507, 2392.4856155926473, 2418.8064954803663, 1906.7425897503867, 2157.2880949286778, 2178.9128434862587, 2393.102586530051, 2394.162043284854, 2383.7020372453444, 2390.5696974544576, 2391.5216956608574, 2393.4323018422874, 2395.8921848516857, 2346.3312955807514, 2386.2364043506086, 2388.152205966874, 2389.7371289158177, 2396.7842894139435, 8721.520316382597, 8794.20852123312, 8805.823883522358, 8806.427582207136, 8845.027047024822, 5140.948590514085, 5375.354957405137, 5385.7307134643115, 5389.595780316492, 5390.434004429557, 3479.913002174966, 3487.424574945131, 3690.335739566877, 3698.8705395311035, 3708.091517578184, 1074.355386767941, 1086.8261078227524, 1089.455272363818, 1105.1159072741775, 1110.3726394587034, 11381.374024428653, 11515.30908145514, 11527.538898444052, 11534.826977595343, 11550.884491155064, 4592.70147440422, 4659.114768194032, 4843.24929635907, 4855.247436647325, 4875.317455556225, 8289.212524810133, 9805.068518490874, 9812.411255324703, 9818.0, 9836.917939026702, 5081.720505372166, 5362.329708786402, 5362.731863406814, 5367.543758780469, 5369.543588794918, 4696.2486002239475, 4855.028994201174, 4895.657303988686, 4914.631402644816, 4916.115099282092, 3747.0, 3784.375578029649, 3802.752821066622, 3844.1927195289304, 3870.4774855394726, 1168.7545615420686, 1173.9823902641467, 1183.2545496337252, 1186.3652945674023, 1189.934553599543, 626.5707990026846, 752.0438130010332, 877.3156937588691, 901.756525738047, 946.0539460539461, 4693.967327188013, 5606.6636001840025, 5854.7072646367515, 5940.554458415636, 5958.940410595926, 453.90921815637006, 467.99766001169775, 479.03952575086726, 485.6697445736862, 612.4212619074931, 3804.2771873343977, 3852.5954774748807, 3901.551321597996, 3975.662068724133, 3981.442598036277, 9159.954200228956, 10191.980801919748, 10639.0, 10713.678589642323, 10721.249512534048, 10703.785924281627, 10713.92860713938, 10721.35671859691, 10731.08785753204, 10734.838977415346, 7793.532388056735, 7941.014746313422, 8113.18868113184, 8133.959330203311, 8137.715179968554, 5646.520045795989, 5677.31872175325, 5697.601167918206, 5728.885422291614, 5769.711514424178, 8348.0, 9782.41305521671, 9949.850752238866, 9986.300958933096, 10049.447280399649, 8856.690015849414, 9965.053319934595, 9982.700518984442, 10109.292349535463, 10127.341722787995, 7382.187959324363, 7577.431692622965, 7587.886181707389, 7596.81007974805, 7600.049993750781, 8151.470154439942, 8230.09468958426, 8232.50604963704, 8232.876506852283, 8238.546879921421, 6792.60751545922, 7332.193458719643, 7402.222766609429, 7525.774226773206, 7558.3953283738265, 6936.410405115622, 7337.633118343954, 7345.0, 7367.815804604714, 7401.296876796911, 6789.694463749044, 6840.589564626139, 6917.619530925847, 6927.411170050602, 6932.653367331714, 4884.9511504884495, 4896.41243050822, 4938.555530002173, 4965.304857319906, 5068.619853511075, 4962.082014827269, 4976.776045077908, 5001.0748011616515, 5065.341505604395, 5073.8731531712], "value": 4430.257994362565, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.7796441650972, 300.5131686667599, 307.8645396025749, 310.14245610885905, 310.3978282132664, 4490.41624588803, 5315.229291752694, 5391.541718953888, 5400.405955344909, 5414.864628384287, 5077.771500282487, 5224.712640804754, 5239.401982395368, 5254.159334506479, 5261.2371206175085, 3342.8662853485953, 3493.545839040934, 4030.8387664493384, 4051.817668204938, 4054.067564460176, 3399.9320013599713, 3408.113890388492, 3705.184859330943, 3829.636184562462, 3839.4240863870364, 3783.3000894834404, 3989.803059082278, 4015.8795236142964, 4042.3532234842432, 4042.5148982122187, 4004.8398064077546, 4006.3990401439873, 4017.35722284436, 4020.8994775130564, 4024.215278020787, 3476.036039637622, 3479.5733749162973, 3484.4773284007347, 3487.5640544931885, 3496.842642081087, 5064.594832413398, 5089.496019638426, 5140.3060586820675, 5182.274481572583, 5185.455527169631, 4507.954920450787, 4573.931391029138, 4581.977090114528, 4603.838865639686, 4607.0, 5073.594112470993, 5562.3881373048935, 5573.27547418837, 5577.462254674114, 5578.693171875545, 3460.5501284832812, 3551.2897420516, 3589.4974703541525, 3664.670179683841, 3667.8716244931566, 5883.676172861072, 5886.234789477383, 5886.293644762613, 5892.558058145656, 5904.498117660047, 3260.6739326067204, 3876.5154355705536, 3880.1075752576503, 3897.5322961244265, 3907.531096268466, 3759.812009399519, 3911.706622003361, 3950.367941129463, 3962.920741585152, 3963.623455771697, 3782.3759079752085, 4560.62847319392, 4560.885977850514, 4562.0, 4600.700954437951, 4990.600751939836, 5397.082495975694, 5643.548516118701, 5649.802256921071, 5731.312242530839, 1193.1826698711236, 1252.4802207083958, 1283.3893463702993, 1295.1257900916844, 1346.7104572516955, 769.6190385758973, 881.5283823154582, 902.8465160922527, 904.5884122724276, 905.3752910491739, 4541.84103556374, 5111.514406131411, 5168.8449346519665, 5201.815237600217, 5226.555742761831, 4731.810727570927, 4948.604111671058, 5223.947760522422, 5745.971270143623, 5771.538276937835, 4873.634477414208, 5180.912008478262, 5196.45437229093, 5201.791928322825, 5240.580753539708, 2094.4449720823995, 2300.6549017647485, 2354.446705024352, 2376.8234723811825, 2537.0928769694465, 4071.714979951375, 4416.955830441719, 4932.284818701268, 4956.578690811321, 4990.6257030722845, 4607.456502071844, 4859.028194361142, 4872.9512704873205, 4908.239222920453, 4960.677555958851, 4399.824007039682, 4621.560951709582, 4972.950270497394, 4984.601231901512, 4985.3020577118505, 3976.321902240358, 4096.446979657708, 4529.590997917763, 4613.861584152481, 4726.1729197391005, 4397.515403796844, 4708.655675807914, 4730.550597693214, 4789.425268967746, 4797.888928871285, 4479.7984090715345, 4553.931691024571, 4756.928646070242, 4832.734199618914, 4998.001199280403, 2855.471737728527, 2891.4506243813607, 3348.916277093092, 3350.2142578885705, 3376.57792775903, 4427.446569178853, 5130.7178105204575, 5279.920801187908, 5726.570507212059, 5795.217645617956, 4915.476202377275, 5623.09195109395, 5633.2395126657375, 5648.604597678123, 5664.801731939528, 4514.68397212192, 5276.261323414802, 5300.814471493479, 5648.519875810603, 5659.518940890071, 4474.843380481668, 5457.536109430743, 5501.944980550144, 5547.805826796042, 5548.556115510669, 5642.633228840113, 5647.519960803378, 5649.135785190795, 5654.40628733977, 5666.829995100154, 4581.2669972805525, 5324.148136298174, 5431.511163995259, 5452.454754524516, 5515.806946756844], "value": 3891.964305611889, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.783900507858, 775.1666958020127, 793.5595744361879, 839.7564706235194, 848.8726690996353, 14787.63030924226, 15130.394784208645, 15488.767668484954, 15863.682726345467, 16047.67904641908, 14297.785533216984, 14612.561623151296, 14628.0, 14636.853631463682, 14666.340014699337, 682.689376333767, 856.1353033436212, 869.1265278395203, 871.4509858788954, 873.5326600268858, 700.8002719225021, 847.9703210387606, 868.9913100868973, 871.9564021798916, 877.7191298784392, 881.3610132653806, 881.9647214111428, 881.9867701984475, 882.9161229683202, 885.8006948436615, 720.2545365546634, 873.366809063424, 875.5184648443362, 876.3865294293988, 877.5612193903048, 667.5127157175241, 687.2577616174519, 689.2383915773099, 692.5152393324643, 692.8891377379646, 15160.241987900672, 15219.543413697607, 15315.770263446057, 15329.0, 15386.461473848361, 13862.306884655827, 14131.858681413158, 14191.929040354835, 14290.285485725672, 14435.71128577433, 12851.935740321238, 12908.483660653563, 12981.675458113528, 13098.934505327508, 13188.868111318956, 3628.4194528875255, 4526.4794548627, 4577.725336479822, 4706.411698537683, 4837.975810120962, 88009.0, 105583.0, 112274.87725122726, 116127.0, 117623.64752704983, 10812.94593527043, 11053.115750739922, 11120.277181983145, 11159.274647148071, 11369.602063927723, 18319.358822441416, 21846.0, 22267.443313916956, 22322.10711571519, 22338.106475740788, 7566.470347075652, 7666.731664391719, 7764.0, 7826.4130190235965, 7855.253306713252, 4256.735608428024, 4287.228298906164, 4295.694396274055, 4346.413234213404, 4458.9331160032625, 3429.2112814052907, 3553.573571171476, 3553.911152221215, 3555.484454754046, 3556.0220939241917, 634.1660716158237, 641.6535071061575, 657.3196741372752, 658.3613894522306, 671.9193696756421, 522.1984254169831, 579.9710014499258, 609.9207103076571, 617.718937883262, 641.6214433484173, 8639.568021599021, 8794.736157915273, 8864.42381245217, 8948.239399651102, 8992.717658054893, 593.7387549478216, 618.7927044440073, 629.7071861584294, 635.9491240700733, 645.1999520594522, 3938.369860822255, 4056.7971601419813, 4263.232618128704, 4315.8489452869, 4336.956630433655, 144512.0, 147255.0, 152586.0, 155096.0, 158067.0, 132236.0, 132578.0, 136968.0, 138870.61129388577, 140115.29942350223, 66081.99999999904, 90592.0, 91836.6224506633, 99599.00801984065, 116040.41979790047, 8475.643896976455, 9468.763280918032, 9528.802084893598, 9730.495604713662, 9850.950745246228, 47511.762441188956, 57533.71233143807, 59453.40546594652, 61845.38154618396, 62590.374096258456, 92177.0, 94807.52596236975, 95031.0, 96441.0, 98468.50765746126, 12826.679333016376, 12939.676508086997, 12965.481380744664, 13080.869191307966, 13315.933420332836, 14422.711545768816, 14829.555113346616, 15047.0, 15064.623384415478, 15329.770053448985, 10193.694189174337, 13621.110281304456, 13712.725745484835, 13772.24252666113, 14233.074850134708, 13464.663383415493, 13773.104748191336, 14066.718665626835, 14191.006629535834, 14379.424823006962, 13313.800292995806, 13536.187828730308, 13660.590182294547, 13883.298799000308, 14039.859601404263, 10936.289141205796, 11302.773944521228, 11350.54597816078, 11627.593034244099, 11628.94185529067, 9330.440173589606, 10897.291676041032, 11368.488418021374, 11607.709807254549, 11628.883711163118], "value": 7805.816590715736, "unit": "score"}], "name": "dromaeo_css", "value": 7207.095770025724, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 118.0, 114.0, 112.0, 115.0, 114.0, 111.0, 112.0, 122.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 114.0, 132.0, 161.0, 106.0, 132.0, 130.0, 108.0, 157.0, 105.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 186.0, 185.0, 183.0, 182.0, 187.0, 185.0, 182.0, 184.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 78.0, 157.0, 59.0, 72.0, 82.0, 86.0, 148.0, 57.0], "value": 89.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 141.0, 70.0, 72.0, 76.0, 131.0, 99.0, 111.0, 131.0], "value": 99.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 89.0, 85.0, 87.0, 85.0, 88.0, 86.0, 87.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 116.0, 162.0, 161.0, 97.0, 96.0, 97.0, 97.0], "value": 111.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 83.0, 84.0, 83.0, 84.0, 84.0, 84.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 53.0, 54.0, 53.0, 53.0, 53.0, 53.0, 54.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 71.0, 72.0, 72.0, 75.0, 73.0, 70.0, 70.0, 70.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 89.0, 84.0, 84.0, 87.0, 89.0, 89.0, 89.0, 88.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 158.0, 140.0, 140.0, 152.0, 150.0, 152.0, 148.0, 187.0, 142.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 172.0, 173.0, 172.0, 173.0, 170.0, 182.0, 175.0, 172.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 68.0, 69.0, 68.0, 68.0, 66.0, 68.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1504.4999999999998, "alertThreshold": 2.0}]} 18:39:04 INFO - Return code: 0 18:39:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:39:04 INFO - # TBPL SUCCESS # 18:39:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:39:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:39:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:39:04 INFO - 2016-09-06 18:39:04.180 screenresolution[1661:9957] starting screenresolution argv=screenresolution get 18:39:04 INFO - 2016-09-06 18:39:04.183 screenresolution[1661:9957] Display 0: 1600x1200x32@60 18:39:04 INFO - 2016-09-06 18:39:04.190 screenresolution[1662:9959] starting screenresolution argv=screenresolution list 18:39:04 INFO - Available Modes on Display 0 18:39:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:39:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:39:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:39:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:39:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:39:04 INFO - Intel Iris: 18:39:04 INFO - Chipset Model: Intel Iris 18:39:04 INFO - Type: GPU 18:39:04 INFO - Bus: Built-In 18:39:04 INFO - VRAM (Dynamic, Max): 1536 MB 18:39:04 INFO - Vendor: Intel (0x8086) 18:39:04 INFO - Device ID: 0x0a2e 18:39:04 INFO - Revision ID: 0x0009 18:39:04 INFO - Displays: 18:39:04 INFO - Display: 18:39:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:39:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:39:04 INFO - Display Serial Number: 1600x1200 60 18:39:04 INFO - Main Display: Yes 18:39:04 INFO - Mirror: Off 18:39:04 INFO - Online: Yes 18:39:04 INFO - Rotation: Supported 18:39:04 INFO - Return code: 0 18:39:04 INFO - Running post-action listener: _resource_record_post_action 18:39:04 INFO - [mozharness: 2016-09-07 01:39:04.422505Z] Finished run-tests step (success) 18:39:04 INFO - Running post-run listener: _resource_record_post_run 18:39:04 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38294528; Write bytes: 704535040; Read time: 386; Write time: 2255 18:39:04 INFO - TinderboxPrint: CPU usage
53.6% 18:39:04 INFO - TinderboxPrint: I/O read bytes / time
38,294,528 / 386 18:39:04 INFO - TinderboxPrint: I/O write bytes / time
704,535,040 / 2,255 18:39:04 INFO - TinderboxPrint: CPU idle
2,251.9 (46.4%) 18:39:04 INFO - TinderboxPrint: CPU system
970.4 (20.0%) 18:39:04 INFO - TinderboxPrint: CPU user
1,627.5 (33.6%) 18:39:04 INFO - TinderboxPrint: Swap in / out
443,932,672 / 0 18:39:04 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 398348800; Write bytes: 393476096; Read time: 29505; Write time: 769 18:39:04 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36287488; Write bytes: 294433280; Read time: 361; Write time: 1448 18:39:04 INFO - Running post-run listener: _upload_blobber_files 18:39:04 INFO - Blob upload gear active. 18:39:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:39:04 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 18:39:04 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:39:04 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:39:05 INFO - (blobuploader) - INFO - Open directory for files ... 18:39:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:39:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:39:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:06 INFO - (blobuploader) - INFO - Done attempting. 18:39:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:39:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:39:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:08 INFO - (blobuploader) - INFO - Done attempting. 18:39:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:39:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:39:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:11 INFO - (blobuploader) - INFO - Done attempting. 18:39:11 INFO - (blobuploader) - INFO - Iteration through files over. 18:39:11 INFO - Return code: 0 18:39:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:39:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:39:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9dbb6a893afffd00b1946de3256090dadfe5676964d2efd9efba418b30bc84f08c73417c55616346b3fa0db209851a6445756928f934ad1957e7d53d9c2a0435", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a34423f98de04c6015e1922011a51dec5715e7969a12372b864fa44871e7a20c189544c47f8088d9d160305fbd83168915fd856ba2d0779d1f8d9f3f14e52b38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/efdb503314cc9a2d6ec9370753c95f71568605b7d5b6eaa99b92e262db21b4d04bfb39f9690762883e6bd4c7bcce8363944d10357b6d55d78bcaa0b89dc68469"} 18:39:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:39:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:39:11 INFO - Contents: 18:39:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9dbb6a893afffd00b1946de3256090dadfe5676964d2efd9efba418b30bc84f08c73417c55616346b3fa0db209851a6445756928f934ad1957e7d53d9c2a0435", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a34423f98de04c6015e1922011a51dec5715e7969a12372b864fa44871e7a20c189544c47f8088d9d160305fbd83168915fd856ba2d0779d1f8d9f3f14e52b38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/efdb503314cc9a2d6ec9370753c95f71568605b7d5b6eaa99b92e262db21b4d04bfb39f9690762883e6bd4c7bcce8363944d10357b6d55d78bcaa0b89dc68469"} 18:39:11 INFO - Running post-run listener: copy_logs_to_upload_dir 18:39:11 INFO - Copying logs to upload dir... 18:39:11 INFO - mkdir: /builds/slave/test/build/upload/logs 18:39:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.280315 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-06 18:39:11.520535) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 18:39:11.523957) ========= 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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9dbb6a893afffd00b1946de3256090dadfe5676964d2efd9efba418b30bc84f08c73417c55616346b3fa0db209851a6445756928f934ad1957e7d53d9c2a0435", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a34423f98de04c6015e1922011a51dec5715e7969a12372b864fa44871e7a20c189544c47f8088d9d160305fbd83168915fd856ba2d0779d1f8d9f3f14e52b38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/efdb503314cc9a2d6ec9370753c95f71568605b7d5b6eaa99b92e262db21b4d04bfb39f9690762883e6bd4c7bcce8363944d10357b6d55d78bcaa0b89dc68469"} build_url:https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010912 build_url: 'https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9dbb6a893afffd00b1946de3256090dadfe5676964d2efd9efba418b30bc84f08c73417c55616346b3fa0db209851a6445756928f934ad1957e7d53d9c2a0435", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a34423f98de04c6015e1922011a51dec5715e7969a12372b864fa44871e7a20c189544c47f8088d9d160305fbd83168915fd856ba2d0779d1f8d9f3f14e52b38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/efdb503314cc9a2d6ec9370753c95f71568605b7d5b6eaa99b92e262db21b4d04bfb39f9690762883e6bd4c7bcce8363944d10357b6d55d78bcaa0b89dc68469"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 18:39:11.557453) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:39:11.557796) ========= 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.t3gxWcRbhu/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.UZ2bRKUnEt/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.005225 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:39:11.606046) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 18:39:11.606386) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 18:39:11.606787) ========= ========= Total master_lag: 0.17 =========