builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0175 starttime: 1490646825.33 results: success (0) buildid: 20170327120222 builduid: 12106fb808a84dd3adf12f4a4d6fdd1c revision: 194abb0bdbc1cf9ab24c32c53f40afb6d0566069 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:45.330533) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:45.330979) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:45.331263) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:45.356854) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:45.357244) ========= 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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 --2017-03-27 13:33:45-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 155M=0s 2017-03-27 13:33:45 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.097390 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:45.479049) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:45.479395) ========= 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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.020639 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:45.529946) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-27 13:33:45.530751) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 194abb0bdbc1cf9ab24c32c53f40afb6d0566069 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 194abb0bdbc1cf9ab24c32c53f40afb6d0566069 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 2017-03-27 13:33:45,616 truncating revision to first 12 chars 2017-03-27 13:33:45,616 Setting DEBUG logging. 2017-03-27 13:33:45,616 attempt 1/10 2017-03-27 13:33:45,616 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/194abb0bdbc1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 13:33:47,544 unpacking tar archive at: pine-194abb0bdbc1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.190729 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-27 13:33:47.745914) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:47.746230) ========= script_repo_revision: 194abb0bdbc1cf9ab24c32c53f40afb6d0566069 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:47.746641) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:47.746925) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 13:33:47.766833) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-27 13:33:47.767201) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 13:33:47 INFO - MultiFileLogger online at 20170327 13:33:47 in /builds/slave/test 13:33:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 13:33:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:33:47 INFO - {'append_to_log': False, 13:33:47 INFO - 'base_work_dir': '/builds/slave/test', 13:33:47 INFO - 'blob_upload_branch': 'Pine', 13:33:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:33:47 INFO - 'branch': 'Pine', 13:33:47 INFO - 'buildbot_json_path': 'buildprops.json', 13:33:47 INFO - 'config_files': ('talos/mac_config.py',), 13:33:47 INFO - 'default_actions': ('clobber', 13:33:47 INFO - 'read-buildbot-config', 13:33:47 INFO - 'download-and-extract', 13:33:47 INFO - 'populate-webroot', 13:33:47 INFO - 'create-virtualenv', 13:33:47 INFO - 'install', 13:33:47 INFO - 'run-tests'), 13:33:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:33:47 INFO - 'download_minidump_stackwalk': True, 13:33:47 INFO - 'download_symbols': 'ondemand', 13:33:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:33:47 INFO - 'tooltool.py': '/tools/tooltool.py', 13:33:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:33:47 INFO - '/tools/misc-python/virtualenv.py')}, 13:33:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:33:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:33:47 INFO - 'gecko_profile': False, 13:33:47 INFO - 'gecko_profile_interval': 0, 13:33:47 INFO - 'installer_path': 'installer.exe', 13:33:47 INFO - 'log_level': 'info', 13:33:47 INFO - 'log_name': 'talos', 13:33:47 INFO - 'log_to_console': True, 13:33:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:33:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:33:47 INFO - 'opt_config_files': (), 13:33:47 INFO - 'pip_index': False, 13:33:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:33:47 INFO - 'cmd': ('bash', 13:33:47 INFO - '-c', 13:33:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:33:47 INFO - 'enabled': True, 13:33:47 INFO - 'halt_on_failure': False, 13:33:47 INFO - 'name': 'check_screen_resolution'},), 13:33:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:33:47 INFO - 'cmd': ('bash', 13:33:47 INFO - '-c', 13:33:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:33:47 INFO - 'enabled': True, 13:33:47 INFO - 'halt_on_failure': False, 13:33:47 INFO - 'name': 'check_screen_resolution'},), 13:33:47 INFO - 'run_cmd_checks_enabled': True, 13:33:47 INFO - 'suite': 'dromaeojs', 13:33:47 INFO - 'system_bits': '32', 13:33:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:33:47 INFO - 'title': 't-yosemite-r7-0175', 13:33:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:33:47 INFO - 'use_talos_json': True, 13:33:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:33:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:33:47 INFO - 'work_dir': 'build'} 13:33:47 INFO - [mozharness: 2017-03-27 20:33:47.979818Z] Running clobber step. 13:33:47 INFO - Running pre-action listener: _resource_record_pre_action 13:33:47 INFO - Running main action method: clobber 13:33:47 INFO - rmtree: /builds/slave/test/build 13:33:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:33:49 INFO - Running post-action listener: _resource_record_post_action 13:33:49 INFO - [mozharness: 2017-03-27 20:33:49.662866Z] Finished clobber step (success) 13:33:49 INFO - [mozharness: 2017-03-27 20:33:49.662994Z] Running read-buildbot-config step. 13:33:49 INFO - Running pre-action listener: _resource_record_pre_action 13:33:49 INFO - Running main action method: read_buildbot_config 13:33:49 INFO - Using buildbot properties: 13:33:49 INFO - { 13:33:49 INFO - "project": "", 13:33:49 INFO - "product": "firefox", 13:33:49 INFO - "script_repo_revision": "default", 13:33:49 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 13:33:49 INFO - "repository": "", 13:33:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 13:33:49 INFO - "buildid": "20170327120222", 13:33:49 INFO - "pgo_build": "False", 13:33:49 INFO - "basedir": "/builds/slave/test", 13:33:49 INFO - "buildnumber": 3, 13:33:49 INFO - "slavename": "t-yosemite-r7-0175", 13:33:49 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 13:33:49 INFO - "platform": "yosemite_r7", 13:33:49 INFO - "scheduler": "tests-pine-macosx64-talos", 13:33:49 INFO - "branch": "pine", 13:33:49 INFO - "repo_path": "projects/pine", 13:33:49 INFO - "revision": "194abb0bdbc1cf9ab24c32c53f40afb6d0566069", 13:33:49 INFO - "stage_platform": "macosx64", 13:33:49 INFO - "builduid": "12106fb808a84dd3adf12f4a4d6fdd1c", 13:33:49 INFO - "slavebuilddir": "test" 13:33:49 INFO - } 13:33:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 13:33:49 INFO - Running post-action listener: _resource_record_post_action 13:33:49 INFO - [mozharness: 2017-03-27 20:33:49.666321Z] Finished read-buildbot-config step (success) 13:33:49 INFO - [mozharness: 2017-03-27 20:33:49.666460Z] Running download-and-extract step. 13:33:49 INFO - Running pre-action listener: _resource_record_pre_action 13:33:49 INFO - Running main action method: download_and_extract 13:33:49 INFO - mkdir: /builds/slave/test/build/tests 13:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:33:49 INFO - trying https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 13:33:49 INFO - Downloading https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 13:33:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 13:33:51 INFO - Downloaded 1325 bytes. 13:33:51 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 13:33:51 INFO - Using the following test package requirements: 13:33:51 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:33:51 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 13:33:51 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:33:51 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:33:51 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 13:33:51 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:33:51 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 13:33:51 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:33:51 INFO - u'jsshell-mac.zip'], 13:33:51 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:33:51 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 13:33:51 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:33:51 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:33:51 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 13:33:51 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:33:51 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 13:33:51 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:33:51 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 13:33:51 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:33:51 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 13:33:51 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:33:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:33:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:33:51 INFO - Fetch https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:33:52 INFO - Expected file size: 52417303 13:33:52 INFO - Obtained file size: 52417303 13:34:09 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:34:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:34:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:34:09 INFO - Fetch https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:34:10 INFO - Expected file size: 52417303 13:34:10 INFO - Obtained file size: 52417303 13:34:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 13:34:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:34:26 INFO - Fetch https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 13:34:28 INFO - Expected file size: 14404171 13:34:28 INFO - Obtained file size: 14404171 13:34: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')]} 13:34:28 INFO - trying https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:34:28 INFO - Downloading https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 13:34:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 13:34:30 INFO - Downloaded 75623938 bytes. 13:34:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:34:30 INFO - mkdir: /builds/slave/test/properties 13:34:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:34:30 INFO - Writing to file /builds/slave/test/properties/build_url 13:34:30 INFO - Contents: 13:34:30 INFO - build_url:https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:34:31 INFO - Running post-action listener: _resource_record_post_action 13:34:31 INFO - Running post-action listener: set_extra_try_arguments 13:34:31 INFO - [mozharness: 2017-03-27 20:34:31.305811Z] Finished download-and-extract step (success) 13:34:31 INFO - [mozharness: 2017-03-27 20:34:31.305937Z] Running populate-webroot step. 13:34:31 INFO - Running pre-action listener: _resource_record_pre_action 13:34:31 INFO - Running main action method: populate_webroot 13:34:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:34:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:34:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:34:31 INFO - '--tppagecycles', 13:34:31 INFO - '7'], 13:34:31 INFO - 'tests': ['tsvgm']}}, 13:34:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:34:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:34:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:34:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:34:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:34:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:34:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:34:31 INFO - 'talos_options': ['--disable-e10s'], 13:34:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:34:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:34:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:34:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:34:31 INFO - 'talos_options': ['--disable-e10s'], 13:34:31 INFO - 'tests': ['damp', 'tps']}, 13:34:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:34:31 INFO - 'tests': ['damp', 'tps']}, 13:34:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:34:31 INFO - 'tests': ['dromaeo_dom']}, 13:34:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:34:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:34:31 INFO - 'tests': ['basic_compositor_video']}, 13:34:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:34:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:34:31 INFO - 'tests': ['a11yr', 13:34:31 INFO - 'ts_paint', 13:34:31 INFO - 'tpaint', 13:34:31 INFO - 'sessionrestore', 13:34:31 INFO - 'sessionrestore_no_auto_restore', 13:34:31 INFO - 'tabpaint']}, 13:34:31 INFO - 'other-e10s': {'tests': ['a11yr', 13:34:31 INFO - 'ts_paint', 13:34:31 INFO - 'tpaint', 13:34:31 INFO - 'sessionrestore', 13:34:31 INFO - 'sessionrestore_no_auto_restore', 13:34:31 INFO - 'tabpaint']}, 13:34:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:34:31 INFO - 'ts_paint', 13:34:31 INFO - 'tpaint', 13:34:31 INFO - 'sessionrestore', 13:34:31 INFO - 'sessionrestore_no_auto_restore', 13:34:31 INFO - 'tabpaint']}, 13:34:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:34:31 INFO - 'ts_paint', 13:34:31 INFO - 'tpaint', 13:34:31 INFO - 'sessionrestore', 13:34:31 INFO - 'sessionrestore_no_auto_restore', 13:34:31 INFO - 'tabpaint']}, 13:34:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:34:31 INFO - 'tests': ['a11yr', 13:34:31 INFO - 'ts_paint', 13:34:31 INFO - 'tpaint', 13:34:31 INFO - 'sessionrestore', 13:34:31 INFO - 'sessionrestore_no_auto_restore', 13:34:31 INFO - 'tabpaint']}, 13:34:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:34:31 INFO - 'tests': ['a11yr', 13:34:31 INFO - 'ts_paint', 13:34:31 INFO - 'tpaint', 13:34:31 INFO - 'sessionrestore', 13:34:31 INFO - 'sessionrestore_no_auto_restore', 13:34:31 INFO - 'tabpaint']}, 13:34:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:34:31 INFO - 'tests': ['tsvgx', 13:34:31 INFO - 'tsvgr_opacity', 13:34:31 INFO - 'tart', 13:34:31 INFO - 'tscrollx', 13:34:31 INFO - 'cart', 13:34:31 INFO - 'tsvg_static']}, 13:34:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:34:31 INFO - 'tsvgr_opacity', 13:34:31 INFO - 'tart', 13:34:31 INFO - 'tscrollx', 13:34:31 INFO - 'cart', 13:34:31 INFO - 'tsvg_static']}, 13:34:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:34:31 INFO - 'talos_options': ['--disable-e10s'], 13:34:31 INFO - 'tests': ['tp5o']}, 13:34:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:34:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:34:31 INFO - 'talos_options': ['--disable-e10s', 13:34:31 INFO - '--xperf_path', 13:34:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:34:31 INFO - 'tests': ['tp5n']}, 13:34:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:34:31 INFO - 'talos_options': ['--xperf_path', 13:34:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:34:31 INFO - 'tests': ['tp5n']}}, 13:34:31 INFO - 'talos.zip': {'path': '', 13:34:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:34:31 INFO - Running post-action listener: _resource_record_post_action 13:34:31 INFO - [mozharness: 2017-03-27 20:34:31.323991Z] Finished populate-webroot step (success) 13:34:31 INFO - [mozharness: 2017-03-27 20:34:31.324102Z] Running create-virtualenv step. 13:34:31 INFO - Running pre-action listener: _resource_record_pre_action 13:34:31 INFO - Running main action method: create_virtualenv 13:34:31 INFO - Creating virtualenv /builds/slave/test/build/venv 13:34:31 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 13:34:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:34:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:34:31 INFO - Using real prefix '/tools/python27' 13:34:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:34:32 INFO - Installing distribute.............................................................................................................................................................................................done. 13:34:35 INFO - Installing pip.................done. 13:34:35 INFO - Return code: 0 13:34:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:34:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:34:35 INFO - Reading from file tmpfile_stdout 13:34:35 INFO - Output received: 13:34:35 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 13:34:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:34: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')]} 13:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34: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')]} 13:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f26eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6434e7780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f04cd30>, '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 0x10ef73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f041be8>, '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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 13:34: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', 'pip>=1.5'] in /builds/slave/test/build 13:34: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 pip>=1.5 13:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AZMOHv1rLI/Render', 13:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:35 INFO - 'HOME': '/Users/cltbld', 13:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:35 INFO - 'LOGNAME': 'cltbld', 13:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:35 INFO - 'MOZ_NO_REMOTE': '1', 13:34:35 INFO - 'NO_EM_RESTART': '1', 13:34:35 INFO - 'PAGER': '/bin/cat', 13:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:35 INFO - 'PWD': '/builds/slave/test', 13:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:35 INFO - 'SHELL': '/bin/bash', 13:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOUX8Sa8KB/Listeners', 13:34:35 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 13:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:35 INFO - 'USER': 'cltbld', 13:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:35 INFO - 'XPC_FLAGS': '0x0', 13:34:35 INFO - 'XPC_SERVICE_NAME': '0', 13:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:35 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 13:34:35 INFO - Cleaning up... 13:34:35 INFO - Return code: 0 13:34:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:34: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')]} 13:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34: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')]} 13:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34: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', '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 0x10f26eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6434e7780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f04cd30>, '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 0x10ef73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f041be8>, '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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 13:34: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', 'psutil>=3.1.1'] in /builds/slave/test/build 13:34: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 psutil>=3.1.1 13:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AZMOHv1rLI/Render', 13:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:35 INFO - 'HOME': '/Users/cltbld', 13:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:35 INFO - 'LOGNAME': 'cltbld', 13:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:35 INFO - 'MOZ_NO_REMOTE': '1', 13:34:35 INFO - 'NO_EM_RESTART': '1', 13:34:35 INFO - 'PAGER': '/bin/cat', 13:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:35 INFO - 'PWD': '/builds/slave/test', 13:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:35 INFO - 'SHELL': '/bin/bash', 13:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOUX8Sa8KB/Listeners', 13:34:35 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 13:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:35 INFO - 'USER': 'cltbld', 13:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:35 INFO - 'XPC_FLAGS': '0x0', 13:34:35 INFO - 'XPC_SERVICE_NAME': '0', 13:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:35 INFO - Downloading/unpacking psutil>=3.1.1 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:34:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:34:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:34:39 INFO - Installing collected packages: psutil 13:34:39 INFO - Running setup.py install for psutil 13:34:39 INFO - building 'psutil._psutil_osx' extension 13:34:39 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 13:34:39 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 13:34:39 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 13:34:40 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 13:34:40 INFO - building 'psutil._psutil_posix' extension 13:34:40 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 13:34:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:34:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:34:40 INFO - ^ 13:34:40 INFO - 1 warning generated. 13:34:40 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 13:34:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:34:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:34:40 INFO - Successfully installed psutil 13:34:40 INFO - Cleaning up... 13:34:40 INFO - Return code: 0 13:34:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:34: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')]} 13:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34: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')]} 13:34:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:41 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 0x10f26eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6434e7780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f04cd30>, '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 0x10ef73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f041be8>, '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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 13:34:41 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 13:34:41 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 13:34:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AZMOHv1rLI/Render', 13:34:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:41 INFO - 'HOME': '/Users/cltbld', 13:34:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:41 INFO - 'LOGNAME': 'cltbld', 13:34:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:41 INFO - 'MOZ_NO_REMOTE': '1', 13:34:41 INFO - 'NO_EM_RESTART': '1', 13:34:41 INFO - 'PAGER': '/bin/cat', 13:34:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:41 INFO - 'PWD': '/builds/slave/test', 13:34:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:41 INFO - 'SHELL': '/bin/bash', 13:34:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOUX8Sa8KB/Listeners', 13:34:41 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 13:34:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:41 INFO - 'USER': 'cltbld', 13:34:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:41 INFO - 'XPC_FLAGS': '0x0', 13:34:41 INFO - 'XPC_SERVICE_NAME': '0', 13:34:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:34:41 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 13:34:41 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 13:34:41 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 13:34:41 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 13:34:41 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 13:34:41 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 13:34:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:34:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:34:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:34:44 INFO - Installing collected packages: mozsystemmonitor 13:34:44 INFO - Running setup.py install for mozsystemmonitor 13:34:44 INFO - Successfully installed mozsystemmonitor 13:34:44 INFO - Cleaning up... 13:34:44 INFO - Return code: 0 13:34:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:44 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 0x10f26eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6434e7780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f04cd30>, '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 0x10ef73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f041be8>, '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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 13:34:44 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 13:34:44 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 13:34:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AZMOHv1rLI/Render', 13:34:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:44 INFO - 'HOME': '/Users/cltbld', 13:34:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:44 INFO - 'LOGNAME': 'cltbld', 13:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:44 INFO - 'MOZ_NO_REMOTE': '1', 13:34:44 INFO - 'NO_EM_RESTART': '1', 13:34:44 INFO - 'PAGER': '/bin/cat', 13:34:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:44 INFO - 'PWD': '/builds/slave/test', 13:34:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:44 INFO - 'SHELL': '/bin/bash', 13:34:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOUX8Sa8KB/Listeners', 13:34:44 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 13:34:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:44 INFO - 'USER': 'cltbld', 13:34:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:44 INFO - 'XPC_FLAGS': '0x0', 13:34:44 INFO - 'XPC_SERVICE_NAME': '0', 13:34:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:44 INFO - Downloading/unpacking jsonschema==2.5.1 13:34:44 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 13:34:44 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 13:34:44 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 13:34:44 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 13:34:44 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 13:34:44 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 13:34:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:34:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34:47 INFO - Downloading functools32-3.2.3-2.tar.gz 13:34:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:34:47 INFO - warning: no files found matching '*.txt' 13:34:47 INFO - no previously-included directories found matching 'build' 13:34:47 INFO - no previously-included directories found matching 'dist' 13:34:47 INFO - no previously-included directories found matching '.git*' 13:34:47 INFO - Installing collected packages: jsonschema, functools32 13:34:47 INFO - Running setup.py install for functools32 13:34:48 INFO - warning: no files found matching '*.txt' 13:34:48 INFO - no previously-included directories found matching 'build' 13:34:48 INFO - no previously-included directories found matching 'dist' 13:34:48 INFO - no previously-included directories found matching '.git*' 13:34:48 INFO - Successfully installed jsonschema functools32 13:34:48 INFO - Cleaning up... 13:34:48 INFO - Return code: 0 13:34:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f26eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6434e7780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f04cd30>, '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 0x10ef73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f041be8>, '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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 13:34:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:34:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:34:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AZMOHv1rLI/Render', 13:34:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:48 INFO - 'HOME': '/Users/cltbld', 13:34:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:48 INFO - 'LOGNAME': 'cltbld', 13:34:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:48 INFO - 'MOZ_NO_REMOTE': '1', 13:34:48 INFO - 'NO_EM_RESTART': '1', 13:34:48 INFO - 'PAGER': '/bin/cat', 13:34:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:48 INFO - 'PWD': '/builds/slave/test', 13:34:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:48 INFO - 'SHELL': '/bin/bash', 13:34:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOUX8Sa8KB/Listeners', 13:34:48 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 13:34:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:48 INFO - 'USER': 'cltbld', 13:34:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:48 INFO - 'XPC_FLAGS': '0x0', 13:34:48 INFO - 'XPC_SERVICE_NAME': '0', 13:34:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:34:48 INFO - Cleaning up... 13:34:48 INFO - Return code: 0 13:34:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f26eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6434e7780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f04cd30>, '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 0x10ef73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f041be8>, '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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 13:34:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:34:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:34:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AZMOHv1rLI/Render', 13:34:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:48 INFO - 'HOME': '/Users/cltbld', 13:34:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:48 INFO - 'LOGNAME': 'cltbld', 13:34:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:48 INFO - 'MOZ_NO_REMOTE': '1', 13:34:48 INFO - 'NO_EM_RESTART': '1', 13:34:48 INFO - 'PAGER': '/bin/cat', 13:34:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:48 INFO - 'PWD': '/builds/slave/test', 13:34:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:48 INFO - 'SHELL': '/bin/bash', 13:34:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOUX8Sa8KB/Listeners', 13:34:48 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 13:34:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:48 INFO - 'USER': 'cltbld', 13:34:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:48 INFO - 'XPC_FLAGS': '0x0', 13:34:48 INFO - 'XPC_SERVICE_NAME': '0', 13:34:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:48 INFO - Downloading/unpacking blobuploader==1.2.4 13:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:51 INFO - Downloading blobuploader-1.2.4.tar.gz 13:34:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:34:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:34:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:52 INFO - Downloading docopt-0.6.1.tar.gz 13:34:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:34:52 INFO - Installing collected packages: blobuploader, requests, docopt 13:34:52 INFO - Running setup.py install for blobuploader 13:34:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:34:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:34:52 INFO - Running setup.py install for requests 13:34:53 INFO - Running setup.py install for docopt 13:34:53 INFO - Successfully installed blobuploader requests docopt 13:34:53 INFO - Cleaning up... 13:34:53 INFO - Return code: 0 13:34:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:34:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f26eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6434e7780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f04cd30>, '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 0x10ef73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f041be8>, '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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 13:34:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:34:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:34:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AZMOHv1rLI/Render', 13:34:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:53 INFO - 'HOME': '/Users/cltbld', 13:34:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:53 INFO - 'LOGNAME': 'cltbld', 13:34:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:53 INFO - 'MOZ_NO_REMOTE': '1', 13:34:53 INFO - 'NO_EM_RESTART': '1', 13:34:53 INFO - 'PAGER': '/bin/cat', 13:34:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:53 INFO - 'PWD': '/builds/slave/test', 13:34:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:53 INFO - 'SHELL': '/bin/bash', 13:34:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOUX8Sa8KB/Listeners', 13:34:53 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 13:34:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:53 INFO - 'USER': 'cltbld', 13:34:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:53 INFO - 'XPC_FLAGS': '0x0', 13:34:53 INFO - 'XPC_SERVICE_NAME': '0', 13:34:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:34:53 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-8UgLiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:34:53 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-lRVJq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:34:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:34:53 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-BP1WFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:34:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-91Djzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:34:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-iA1FFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:34:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-0lsEKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:34:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-EjfPpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:34:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-_ZOEzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:34:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-v_FSC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:34:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Y20taC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:34:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ghNnh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:34:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:34:54 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-oPLhMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:34:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-85hsA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:34:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Eo99HK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-LCNUrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:34:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-uYK6g7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:34:55 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-5X81si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:34:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:34:55 INFO - Running setup.py install for manifestparser 13:34:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:34:55 INFO - Running setup.py install for mozcrash 13:34:55 INFO - Running setup.py install for mozdebug 13:34:55 INFO - Running setup.py install for mozdevice 13:34:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:34:56 INFO - Running setup.py install for mozfile 13:34:56 INFO - Running setup.py install for mozhttpd 13:34:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:34:56 INFO - Running setup.py install for mozinfo 13:34:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:34:56 INFO - Running setup.py install for mozInstall 13:34:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:34:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:34:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:34:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:34:56 INFO - Running setup.py install for mozleak 13:34:56 INFO - Running setup.py install for mozlog 13:34:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:34:57 INFO - Running setup.py install for moznetwork 13:34:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:34:57 INFO - Running setup.py install for mozprocess 13:34:57 INFO - Running setup.py install for mozprofile 13:34:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:34:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:34:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:34:57 INFO - Running setup.py install for mozrunner 13:34:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:34:57 INFO - Running setup.py install for mozscreenshot 13:34:57 INFO - Running setup.py install for moztest 13:34:57 INFO - Running setup.py install for mozversion 13:34:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:34:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:34:58 INFO - Cleaning up... 13:34:58 INFO - Return code: 0 13:34:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:34: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')]} 13:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34: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')]} 13:34:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f26eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6434e7780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f04cd30>, '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 0x10ef73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f041be8>, '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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 13:34:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:34:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:34:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AZMOHv1rLI/Render', 13:34:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:34:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:34:58 INFO - 'HOME': '/Users/cltbld', 13:34:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:34:58 INFO - 'LOGNAME': 'cltbld', 13:34:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:58 INFO - 'MOZ_NO_REMOTE': '1', 13:34:58 INFO - 'NO_EM_RESTART': '1', 13:34:58 INFO - 'PAGER': '/bin/cat', 13:34:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:34:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:58 INFO - 'PWD': '/builds/slave/test', 13:34:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:34:58 INFO - 'SHELL': '/bin/bash', 13:34:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOUX8Sa8KB/Listeners', 13:34:58 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 13:34:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:34:58 INFO - 'USER': 'cltbld', 13:34:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:34:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:34:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:58 INFO - 'XPC_FLAGS': '0x0', 13:34:58 INFO - 'XPC_SERVICE_NAME': '0', 13:34:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:34:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:34:58 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Gj4Efx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:34:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:34:58 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-gt4O3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:34:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 13:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:34:58 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-_WF2Vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:34:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:34:58 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-s1NUtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:34:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 13:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:34:58 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-OUkz0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:34:58 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)) 13:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:34:58 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Q6IkYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:34:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:34:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-bfCpCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:34:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:34:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-7BO2sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:34:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:34:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-pcYlWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:34:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:34:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-8eJHxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:34:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 13:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:34:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-hqw7XW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:34:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:34:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-lFO4vA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:34:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 13:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:34:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-sPRs7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:34:59 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)) 13:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:34:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-gIMu2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:34:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 13:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:59 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-2V6900-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:35:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:35:00 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-c0BXEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:35:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 13:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:35:00 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-hRQfAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:35:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:35:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:35:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:35:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:35:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:35:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:35:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:35:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:35:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:35:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:35:02 INFO - Downloading blessings-1.6.tar.gz 13:35:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:35:03 INFO - Installing collected packages: blessings 13:35:03 INFO - Running setup.py install for blessings 13:35:03 INFO - Successfully installed blessings 13:35:03 INFO - Cleaning up... 13:35:03 INFO - Return code: 0 13:35:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:35:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:35:03 INFO - Reading from file tmpfile_stdout 13:35:03 INFO - Current package versions: 13:35:03 INFO - blessings == 1.6 13:35:03 INFO - blobuploader == 1.2.4 13:35:03 INFO - docopt == 0.6.1 13:35:03 INFO - functools32 == 3.2.3-2 13:35:03 INFO - jsonschema == 2.5.1 13:35:03 INFO - manifestparser == 1.1 13:35:03 INFO - mozInstall == 1.12 13:35:03 INFO - mozcrash == 1.0 13:35:03 INFO - mozdebug == 0.1 13:35:03 INFO - mozdevice == 0.49 13:35:03 INFO - mozfile == 1.2 13:35:03 INFO - mozhttpd == 0.7 13:35:03 INFO - mozinfo == 0.9 13:35:03 INFO - mozleak == 0.1 13:35:03 INFO - mozlog == 3.4 13:35:03 INFO - moznetwork == 0.27 13:35:03 INFO - mozprocess == 0.25 13:35:03 INFO - mozprofile == 0.28 13:35:03 INFO - mozrunner == 6.13 13:35:03 INFO - mozscreenshot == 0.1 13:35:03 INFO - mozsystemmonitor == 0.3 13:35:03 INFO - moztest == 0.8 13:35:03 INFO - mozversion == 1.4 13:35:03 INFO - psutil == 3.1.1 13:35:03 INFO - requests == 1.2.3 13:35:03 INFO - wsgiref == 0.1.2 13:35:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:35:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:35:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:35:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:35:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:35:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:35:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f26eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6434e7780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f04cd30>, '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 0x10ef73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f041be8>, '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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 13:35:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:35:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AZMOHv1rLI/Render', 13:35:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:35:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:35:03 INFO - 'HOME': '/Users/cltbld', 13:35:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:35:03 INFO - 'LOGNAME': 'cltbld', 13:35:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:35:03 INFO - 'MOZ_NO_REMOTE': '1', 13:35:03 INFO - 'NO_EM_RESTART': '1', 13:35:03 INFO - 'PAGER': '/bin/cat', 13:35:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:35:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:35:03 INFO - 'PWD': '/builds/slave/test', 13:35:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:35:03 INFO - 'SHELL': '/bin/bash', 13:35:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOUX8Sa8KB/Listeners', 13:35:03 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 13:35:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:35:03 INFO - 'USER': 'cltbld', 13:35:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:35:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:35:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:35:03 INFO - 'XPC_FLAGS': '0x0', 13:35:03 INFO - 'XPC_SERVICE_NAME': '0', 13:35:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:35:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:35:03 INFO - Cleaning up... 13:35:03 INFO - Return code: 0 13:35:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:35:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:35:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:35:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:35:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:35:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:35:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f26eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6434e7780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f04cd30>, '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 0x10ef73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f041be8>, '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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 13:35:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:35:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AZMOHv1rLI/Render', 13:35:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:35:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:35:03 INFO - 'HOME': '/Users/cltbld', 13:35:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:35:03 INFO - 'LOGNAME': 'cltbld', 13:35:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:35:03 INFO - 'MOZ_NO_REMOTE': '1', 13:35:03 INFO - 'NO_EM_RESTART': '1', 13:35:03 INFO - 'PAGER': '/bin/cat', 13:35:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:35:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:35:03 INFO - 'PWD': '/builds/slave/test', 13:35:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:35:03 INFO - 'SHELL': '/bin/bash', 13:35:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOUX8Sa8KB/Listeners', 13:35:03 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 13:35:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:35:03 INFO - 'USER': 'cltbld', 13:35:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:35:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:35:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:35:03 INFO - 'XPC_FLAGS': '0x0', 13:35:03 INFO - 'XPC_SERVICE_NAME': '0', 13:35:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:35:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:35:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:35:04 INFO - Cleaning up... 13:35:04 INFO - Return code: 0 13:35:04 INFO - Running post-action listener: _resource_record_post_action 13:35:04 INFO - Running post-action listener: _start_resource_monitoring 13:35:04 INFO - Starting resource monitoring. 13:35:04 INFO - [mozharness: 2017-03-27 20:35:04.125051Z] Finished create-virtualenv step (success) 13:35:04 INFO - [mozharness: 2017-03-27 20:35:04.126039Z] Running install step. 13:35:04 INFO - Running pre-action listener: _resource_record_pre_action 13:35:04 INFO - Running main action method: install 13:35:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:35:04 INFO - Reading from file tmpfile_stdout 13:35:04 INFO - Detecting whether we're running mozinstall >=1.0... 13:35:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:35:04 INFO - Reading from file tmpfile_stdout 13:35:04 INFO - Output received: 13:35:04 INFO - Usage: mozinstall [options] installer 13:35:04 INFO - Options: 13:35:04 INFO - -h, --help show this help message and exit 13:35:04 INFO - -d DEST, --destination=DEST 13:35:04 INFO - Directory to install application into. [default: 13:35:04 INFO - "/builds/slave/test"] 13:35:04 INFO - --app=APP Application being installed. [default: firefox] 13:35:04 INFO - mkdir: /builds/slave/test/build/application 13:35:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:35:25 INFO - Reading from file tmpfile_stdout 13:35:25 INFO - Output received: 13:35:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:35:25 INFO - Running post-action listener: _resource_record_post_action 13:35:25 INFO - [mozharness: 2017-03-27 20:35:25.274285Z] Finished install step (success) 13:35:25 INFO - [mozharness: 2017-03-27 20:35:25.274432Z] Running run-tests step. 13:35:25 INFO - Running pre-action listener: _resource_record_pre_action 13:35:25 INFO - Running main action method: run_tests 13:35:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:35:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:35:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:35:25 INFO - 2017-03-27 13:35:25.289 screenresolution[2175:28153] starting screenresolution argv=screenresolution get 13:35:25 INFO - 2017-03-27 13:35:25.291 screenresolution[2175:28153] Display 0: 1600x1200x32@60 13:35:25 INFO - 2017-03-27 13:35:25.297 screenresolution[2176:28155] starting screenresolution argv=screenresolution list 13:35:25 INFO - Available Modes on Display 0 13:35:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:35:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:35:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:35:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:35:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:35:25 INFO - Intel Iris: 13:35:25 INFO - Chipset Model: Intel Iris 13:35:25 INFO - Type: GPU 13:35:25 INFO - Bus: Built-In 13:35:25 INFO - VRAM (Dynamic, Max): 1536 MB 13:35:25 INFO - Vendor: Intel (0x8086) 13:35:25 INFO - Device ID: 0x0a2e 13:35:25 INFO - Revision ID: 0x0009 13:35:25 INFO - Displays: 13:35:25 INFO - Display: 13:35:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:35:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:35:25 INFO - Display Serial Number: 1600x1200 60 13:35:25 INFO - Main Display: Yes 13:35:25 INFO - Mirror: Off 13:35:25 INFO - Online: Yes 13:35:25 INFO - Rotation: Supported 13:35:25 INFO - Return code: 0 13:35:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:35:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:35:25 INFO - Python 2.7.3 13:35:25 INFO - Return code: 0 13:35:25 INFO - grabbing minidump binary from tooltool 13:35: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')]} 13:35: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 0x10f04cd30>, '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 0x10ef73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f041be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:35: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 13:35: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 13:35: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 13:35:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:35:25 INFO - Return code: 0 13:35:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:35:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:35:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:35:25 INFO - ENV: RUST_BACKTRACE is now 1 13:35:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:35:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:35:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 13:35:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0175 --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 13:35:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AZMOHv1rLI/Render', 13:35:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:35:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:35:25 INFO - 'HOME': '/Users/cltbld', 13:35:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:35:25 INFO - 'LOGNAME': 'cltbld', 13:35:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:35:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:35:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:35:25 INFO - 'MOZ_NO_REMOTE': '1', 13:35:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:35:25 INFO - 'NO_EM_RESTART': '1', 13:35:25 INFO - 'PAGER': '/bin/cat', 13:35:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:35:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:35:25 INFO - 'PWD': '/builds/slave/test', 13:35:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:35:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:35:25 INFO - 'RUST_BACKTRACE': '1', 13:35:25 INFO - 'SHELL': '/bin/bash', 13:35:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOUX8Sa8KB/Listeners', 13:35:25 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 13:35:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:35:25 INFO - 'USER': 'cltbld', 13:35:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:35:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:35:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:35:25 INFO - 'XPC_FLAGS': '0x0', 13:35:25 INFO - 'XPC_SERVICE_NAME': '0', 13:35:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:35:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 13:35:25 INFO - mozversion application_buildid: 20170327120222 13:35:25 INFO - mozversion application_changeset: 194abb0bdbc1cf9ab24c32c53f40afb6d0566069 13:35:25 INFO - mozversion application_display_name: Nightly 13:35:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:35:25 INFO - mozversion application_name: Firefox 13:35:25 INFO - mozversion application_remotingname: firefox 13:35:25 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 13:35:25 INFO - mozversion application_vendor: Mozilla 13:35:25 INFO - mozversion application_version: 55.0a1 13:35:25 INFO - mozversion platform_buildid: 20170327120222 13:35:25 INFO - mozversion platform_changeset: 194abb0bdbc1cf9ab24c32c53f40afb6d0566069 13:35:25 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 13:35:25 INFO - mozversion platform_version: 55.0a1 13:35:25 INFO - using testdate: 1490646925 13:35:25 INFO - actual date: 1490646925 13:35:25 INFO - starting webserver on 'localhost:56657' 13:35:25 INFO - SUITE-START | Running 2 tests 13:35:25 INFO - TEST-START | dromaeo_css 13:35:25 INFO - operating with platform_type : mac_ 13:35:25 INFO - Initialising browser for dromaeo_css test... 13:35:25 INFO - TEST-INFO | started process 2186 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpJbBJ4D/profile http://localhost:56657/getInfo.html) 13:35:28 INFO - PID 2186 | __metrics Screen width/height:1600/1200 13:35:28 INFO - PID 2186 | colorDepth:24 13:35:28 INFO - PID 2186 | Browser inner width/height: 1024/689 13:35:28 INFO - PID 2186 | __metrics 13:35:28 ERROR - PID 2186 | 1490646928465 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 13:35:28 INFO - PID 2186 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:35:28 INFO - PID 2186 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:35:29 INFO - TEST-INFO | 2186: exit 0 13:35:29 INFO - Browser initialized. 13:35:29 INFO - Running cycle 1/1 for dromaeo_css test... 13:35:29 INFO - TEST-INFO | started process 2188 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpJbBJ4D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:35:41 INFO - PID 2188 | RSS: Main: 234254336 13:35:41 INFO - PID 2188 | 13:38:46 INFO - PID 2188 | Cycle 1(1): loaded http://localhost:56657/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56657/tests/dromaeo/cssquery-ext.html) 13:38:48 INFO - PID 2188 | RSS: Main: 389009408 13:38:48 INFO - PID 2188 | 13:40:31 INFO - PID 2188 | ************************* 13:40:31 INFO - PID 2188 | A coding exception was thrown and uncaught in a Task. 13:40:31 INFO - PID 2188 | 13:40:31 ERROR - PID 2188 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:40:31 INFO - PID 2188 | Full stack: 13:40:31 INFO - PID 2188 | ************************* 13:40:31 INFO - PID 2188 | ************************* 13:40:31 INFO - PID 2188 | A coding exception was thrown and uncaught in a Task. 13:40:31 INFO - PID 2188 | 13:40:31 ERROR - PID 2188 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:40:31 INFO - PID 2188 | Full stack: 13:40:31 INFO - PID 2188 | ************************* 13:40:31 INFO - PID 2188 | ************************* 13:40:31 INFO - PID 2188 | A coding exception was thrown and uncaught in a Task. 13:40:31 INFO - PID 2188 | 13:40:31 ERROR - PID 2188 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:40:31 INFO - PID 2188 | Full stack: 13:40:31 INFO - PID 2188 | ************************* 13:40:31 INFO - PID 2188 | ************************* 13:40:31 INFO - PID 2188 | A coding exception was thrown and uncaught in a Task. 13:40:31 INFO - PID 2188 | 13:40:31 ERROR - PID 2188 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:40:31 INFO - PID 2188 | Full stack: 13:40:31 INFO - PID 2188 | ************************* 13:41:51 INFO - PID 2188 | Cycle 1(1): loaded http://localhost:56657/tests/dromaeo/cssquery-ext.html (next: http://localhost:56657/tests/dromaeo/cssquery-jquery.html) 13:41:51 INFO - PID 2188 | RSS: Main: 372125696 13:41:51 INFO - PID 2188 | 13:44:54 INFO - PID 2188 | Cycle 1(1): loaded http://localhost:56657/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56657/tests/dromaeo/cssquery-mootools.html) 13:44:55 INFO - PID 2188 | RSS: Main: 338722816 13:44:55 INFO - PID 2188 | 13:47:57 INFO - PID 2188 | Cycle 1(1): loaded http://localhost:56657/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56657/tests/dromaeo/cssquery-prototype.html) 13:47:58 INFO - PID 2188 | RSS: Main: 333533184 13:47:58 INFO - PID 2188 | 13:51:01 INFO - PID 2188 | Cycle 1(1): loaded http://localhost:56657/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56657/tests/dromaeo/cssquery-yui.html) 13:51:02 INFO - PID 2188 | RSS: Main: 339509248 13:51:02 INFO - PID 2188 | 13:54:05 INFO - PID 2188 | Cycle 1(1): loaded http://localhost:56657/tests/dromaeo/cssquery-yui.html (next: http://localhost:56657/tests/dromaeo/cssquery-dojo.html) 13:54:05 INFO - PID 2188 | RSS: Main: 453369856 13:54:05 INFO - PID 2188 | 13:54:05 INFO - PID 2188 | __start_tp_report 13:54:05 INFO - PID 2188 | _x_x_mozilla_page_load 13:54:05 INFO - PID 2188 | _x_x_mozilla_page_load_details 13:54:05 INFO - PID 2188 | |i|pagename|runs| 13:54:05 INFO - PID 2188 | |0;dojo.html;3434.1929646533044;3434.536337594425;3445.500402441646;3498.4927185558095;3500.2124521982555;5973.88052238955;6069.939300606992;6069.969650151748;6074.392560743923;6096.542759293048;6058.939410605893;6086.117512960618;6087;6094.299155597097;6099.359567245442;5399.811006614769;5407.324084489439;5421.864453388657;5431.619786614939;5563.638363506359;5539.861503462405;5542.972285138569;5547.55619550435;5553.389127196006;5606.943930560683;5572.470615291561;5585.832425027255;5595.356533998582;5601.467860553241;5602.467765562265;5573.8606534836545;5586;5589.916151257735;5595.412481689405;5605.7197140143035;5399.487048730364;5476.534494567966;5496.642718223343;5504.944950550524;5510.779568817243;6106.6336019838955;6122.387761223887;6124.479419249369;6130.448259656652;6136.417040381157;6184.381561843825;6427.485801135898;6439.291677915426;6456.741730330781;6473.223213214397;4646.744429056401;6399.872002559922;6431.8392040198905;6450;6548.934510654928;6131.132004888128;6142.0172772356655;6158.445739883432;6158.815235542941;6167.229096362955;6681.866362672719;6689.063531105649;6689.76585819494;6694.297098804653;6695.899561506581;6148.538859585549;6215.502759779207;6215.844603884939;6324.367563243731;6343.3339499351905;6424.614523128627;6438.77464288757;6498.675066246668;6511.934880651228;6527.249366322839;5279.498447647467;6372.617642941345;6402.135711678864;6408.67956602168;6415.166028416276;6201.782937597162;6390.712417941204;6404.879146149406;6409.519286053564;6412.7114279857515;5158.716270605153;5172.5344718975475;5215.269862219292;5244.895102097937;5247.2129180623215;4652.511486293891;4652.883677908079;4661.254199328091;4682.41469816273;4699.083678682624;6192.628442293387;6198.163247961557;6208.348123446973;6209.96895015531;6233.750649974041;6299.4330510253385;6348.523860710465;6372.58578192416;6447.774327898501;6493.545451818327;6179.134921110958;6187.876242475125;6200.906986395207;6222.782202622887;6230.563860529719;5799.4490523401055;5808.5062769665055;5810.360860305363;5825.475707186373;5840.766369345263;6364.331745166691;6437.420632143129;6580;6681;6697.899531506934;6387.840303992437;6472.741090356333;6544.934550654432;6556.508261880283;6566.8358291043105;6571.868562628816;6665.500087493554;6666.666666666551;6680.098186744727;6680.565763225375;6386.201724784402;6433.099366088845;6438.6458744767615;6461.224653041665;6463.192100987377;6290.779822706183;6344.841378965562;6491.35086491366;6497.577657452251;6515.12045873801;6122.15420935445;6300.149479820349;6302.401271879165;6307.590006649553;6318.336574659595;6155.045967874987;6161.014237721943;6189.442950134509;6195.039768835838;6313.494920406265;6224.813255602339;6231.470325022424;6256.812295631138;6266.561340706289;6285.277193122849;5357.651752636066;6154.599951003171;6208.9689551551955;6328.608105813069;6364.87270254583;6106.248323414515;6163.229596300463;6217.378262173746;6230.252369715663;6230.283517395557 13:54:05 INFO - PID 2188 | |1;ext.html;741.7478057460462;784.4626430894837;806.5201205282858;1133.6599020293916;1140.3101123820084;27027.459450810995;35763.82118089409;36246.81876590614;36377.45433818495;36393.27213455729;37821.621783782226;38326.42510362347;38335.61664383356;38373;38418.42372364417;6063.727132279058;8400.957995210047;8589.441686290402;8605.182507661762;8606.87089693655;6143.385661433866;6236.126942228092;8260.958695206515;8423.284020858235;8434.198751118665;8451.408401411903;8494.362922780785;8500.574971251444;8606.913930860674;8617.095205003447;8526.062133165347;8560.657573697044;8566.186212309884;8571.400001999862;8574.69988550404;7786.909832623437;8039.95980020102;8414.495130292202;8478.194571515696;8504.744857654281;37775.622243777485;38057.858264252114;38108.23783524314;38136.61863381358;38143.618563814285;2865.5988161657383;2933.838638874861;3003.3993201359817;3014.6382434108045;3058.3118798270434;25863.437084647576;26124.738752612422;26220.9511619535;26310.342241443926;27055.86472067627;2631.460550587125;2649.7085320614715;2754.2425832895915;2779.7359250871136;2842.488352096622;257533.42466575283;259272;259690;261974;297416.5129174362;47471.28793068037;47960.28059579109;48013.75993120012;48274.03451930942;48311.516884831406;49835.75082124566;50056;50078;50138;50178;11808.822867656992;14482.420703171754;14513.637159071108;14516.782248266285;14521.346539405753;9712.514374281258;11635.360055196876;11637.592684256011;11649.359285239223;11652.70868228284;11729.296242225322;11837.76324473523;11848.348340841336;11854.525818967317;11875.228110172811;4853.405235633118;5808.767649294065;5953.642781433128;5968.4926781224085;5973.133895585116;951.7810903492235;981.8183636027358;982.8230918434607;1017.4302390661253;1036.4765793274348;22312.888435557717;23380.415489612897;23793.881030594737;24615.50768984646;24727;1094.7700982793704;1101.1906248907208;1136.6984802401205;1136.7442325476702;1147.9311241325547;11733.530658773725;11789.410529473493;12423.875761242452;12425.068119891044;12454.564090256797;461514;464507;464565;464887;465174;55772.721136394866;56033.71983140058;56054;56058;56156.71921640365;1491.6345495353708;1497.0867770660107;1550.0856442293984;1553.6271294889145;1555.478914563634;23827.733137334806;24010.212038881273;24038.87980560086;24106.51786964286;24150.154744584557;31971.840140799148;38869.02827429337;38905.6109438902;39104.41343379795;39119;443476.5652343435;445308;445449;445522;445659;33052.678637956014;33613.49579756355;33866.153346166546;33928.491072634424;33940.1514962118;42662.93342666458;43311.783441082596;43327;43386.349204761325;44576.77711611551;29245.85377073101;29626.246177065892;29929.55105673373;30128.548071778503;30360;27225.04712335059;30013.399732005673;30211.244718882204;30354;30448.391032179676;32343;33747.66252337445;34164.975050748515;35960;36346.4548031785;22919.823342093445;28039.859800700866;28799.42401152007;29039.709602904546;29661.703382966756;16616;22423.29110886732;23618.409539761644;23842.40393990164;24468.26595202147 13:54:05 INFO - PID 2188 | |2;jquery.html;344.2838895098193;416.2029713099413;427.828868452619;432.7879339123829;443.4789122780734;12838.935805320973;14709.043912145717;18151.364702235423;20700.792992070074;20797.480062998413;17192.226349814257;19303.938283394407;21208.36374908751;22308.107675692954;22377.77622223777;5872.295324561059;5906.438888305604;6073.757049718006;6096.634201947875;6153.476954458854;3539.132912436444;6005.759769609211;6032.849178770522;6069.08963655453;6074.392560743934;3583.4266517357096;3628.663746822548;3784.867529636463;3794.4687743715904;3819.4079917612817;5107.565856902168;5835.124731290298;5969.731362088716;5983.132445795379;5983.311919129291;5057.468965758616;5240.004399164147;5280.947190528084;5299.231611416323;5301.7084060376665;25819.483610327687;25861.612075818877;26126.73873261262;27192.04827831036;27221.591676124874;2000.679891217413;2000.98999505003;2022.1810166882435;2041.4794227472048;2089.4567412472716;14631.195284259362;15358.769618455732;15439.691206175927;15541.689166216725;15741.980613570431;1456.5434565434566;1630.1686140068532;1662.7339625659836;1664.1429663723193;1691.7631531585587;135094.3245283777;135858;135966;136882.26235475234;137845.3107734455;10055.446950417798;10550.89449105499;11492.94253528727;11768.292682926727;11940.402979850973;15804.525864224091;15809.762853557206;15825.287862046236;15847.603809904615;15870.60323491922;8495.787605309917;8852.62067945365;8914.37599368038;9029.593668284942;9062.003179650234;8639.265662418638;8640.783980400414;8646.740597782076;8844.380893337404;8847.823043539222;7307.488475806749;7485.962570187115;7541.622918854036;7542.698292068256;7549.3583045440655;2045.1410407628832;2048.3752455500917;2049.6454252171766;2050.794920507967;2052.48687828043;769.6651956398985;797.3860127701637;947.9383090938194;961.4183419031385;982.8009828009816;20358.18567257323;20874.582508350053;20875.478113046993;20990.79009209919;21251.46871328198;964.4117088575963;980.9547094308305;994.9602015919427;1006.7030226083323;1008.5713571731984;9305.488198149167;10531.684049478528;10766.569337226423;10767.784644307227;10775.892241077489;691404;695827;698674;729695;735254;124320.37839810744;155239;158429;162719;164693;1160.4139909345895;1166.2375211125004;1224.7305592769756;1231.445849367788;1235.3452670084869;19879.801201987793;21059.578808424052;21111.472213194793;21117.788822111583;21142.26002089981;59965.700171498866;60238;61078.69460652668;61079.69460152671;61274;80558.59720701359;81747;82706.17293827226;84428;87191.12808872084;8126.918730812617;8322.750317490485;8353.37349698763;8624.266937310398;8636.654533818577;12829.42267597979;12841.550545730855;12935.676608084874;13037;13143.53997610079;9896.120541902148;11064.778704426028;11107.111431085332;11113.555457781922;11303.886961130283;10296.382217067;10328.793424131325;10372.688819335432;10770.976757208053;10787.730306742395;11135.438849064598;11241.269317494336;11365.261258018203;11376.829347559627;11610.94194529022;8187.5087494750505;8287.834243315221;8289.088200298083;8325.375596830383;8353.582320884054;11101.333919964827;11380.975712185944;11533.826992595286;11544.480498377425;11550.711232219262 13:54:05 INFO - PID 2188 | |3;mootools.html;1387.4380875745321;1392.7144935288268;1393.2894223945789;1459.3943513441923;1483.4140514496773;9598.088181622743;10358.844617330746;10449.738756531091;10907.072898803612;10930.781384372327;10512.632057877976;10827.187960902944;10832.350058996466;10840.78318433631;10880.945595272033;2447.926562203128;2490.1533478617266;2491.0533997081084;2492.7258001619807;2495.987520062406;2418.343434747204;2442.7557244275517;2490;2494.189388448752;2495.500899820034;2058.8558800883943;2378.1319818266293;2474.158786012755;2475.3687809608614;2478.3432390416556;2390.964135537968;2445.7676520730593;2472.4807790364043;2475.1460746042585;2479.7148327942155;2425.357280320716;2443.4135807406274;2444.4744379958393;2445.4864478459544;2446.1193970170725;10901.400422976732;11105.389203593799;11136.888631113667;11168.274062185932;11171.944140279327;6200.441960223601;6450.322716114834;6450.9677451612915;6457.483401327882;6458.838529036765;3968.8319980844344;4024.215278020787;4047.2310261050307;4129.442525259082;4134.524529679095;1157.5716984715625;1158.727698990745;1175.7413369058793;1205.2587658589991;1232.5501192064833;13766;14140.787888181685;14184.148951062865;15127.621809454742;15285.159316237603;3990.082281075369;4798.136335459651;4879.341288926021;5013.147764879979;5810.50610698087;12604.243745375306;12634.368281585885;12666;12826.294553799444;12870.549530766566;5514.6691198528215;6422.839429014312;6481.189851268591;6487.3836985486305;6501.512386570931;4962.751862406865;5279.656822306538;5927.555433342431;5962.34414214436;5973;4263.573642635712;4375.299952007664;4533.863984080483;4559.703619264737;4576.748278844629;1175.641429364052;1378.1868697468542;1409.4137258131443;1423.3452611798689;1434.5624584501618;697.9895301570481;764.7935057534435;767.2902565127347;791.4064451661254;795.3319211862064;6365.395287447685;6500.869982600321;6750.392464678104;6771.187457505131;6773.593584384953;578.1963071330854;580.9941900581025;589.9587028908021;601.667306915185;607.2530787131852;3665.321915445651;3971.860984865516;4067.2272268269526;4118.670506359484;4163.417121602978;13390.866091339156;13906.165630062229;13913.652158696113;13923.025388222728;13941.930290348484;13806.861931380558;13814.963877709011;13825.654358640913;13848.238346891017;13852.16886986784;9678.75803104928;10092.343997640277;10100.646477373257;10105.292629515863;10132.594696212069;6664.266930637723;6666.966665166643;6714.932850671431;6803;6977.092977912839;12215.26708397499;12388.690282743004;12658.810117848423;12701.110922235355;12919;10571.2600117991;12815.359232038547;12837;12850.100492965403;12859.614211573667;7953.8409231813885;8210.137935516923;8238.052623948082;8843.336749743667;9251.67619133327;10006.599736010477;10057.24570657189;10277.84583231267;10285.919978402459;10302.02130797573;8463.53450560225;8519.914800851911;8526;8632.78418039554;8651.567421629019;8263.173682631688;8278.958605206935;8332.6666933322;8386.951631046119;8540.44487108336;7274.454415918722;7851.1363749988595;7866.174051724486;7951.721689740832;8013.2387423194705;5813.331466881194;5866.67733274674;5962.493188079062;5999.790007349722;6087.695615219132;5878.647281163144;6002.2197114376595;6102.7253773579405;6157.96921015392;6283.905741413791 13:54:05 INFO - PID 2188 | |4;prototype.html;386.2738052461374;388.2506761949437;394.1073468593634;394.26666400495077;400.0618549501419;6045.607035542692;6099.603525770833;6101.877962440749;6130.448259656636;6135.478484328832;5611.7474713637885;5652.378238393773;5798.797042103527;5844.415558444164;5906.114082887569;3834.501514803079;4047.372657238133;4605.572272595489;4686.320483529886;4705.176594096037;3885.30064588374;3941.8029098545103;4689.062187562484;4695.436547614274;4706.929396059062;4253.44705188325;4469.815498892796;4539.909201815978;4667.15991121598;4702.576768090871;3870.632289932452;4460.8661740147845;4466.8883277917985;4477.955220447787;4493.505714371417;3351.463765797485;3402.2855200407944;3857.5756666766633;3905.5508616509183;3914.2562913046713;4513.097380523908;5779.566532510036;5910.822675319747;5921.585489015771;5950.345461999176;4570.862874113782;5211.114110601169;5211.644972307189;5245.082110630625;5251.422343542207;5579.358373787026;5996.070609055603;6375.394337537927;6375.87248255037;6390.795175664388;4155.355919832401;4228.746275223496;4280.978595107035;4286.371178951978;4297.355396690521;6114.541409394357;6332.68336583169;6524.902126468107;6525.249596296392;6693.1298931138645;4020.2361551305744;4254.829806807693;4353.978230108829;4358.4769827620885;4379.5401482843845;4413.830334961238;4426.734395936254;4448.488423831236;4449.488308844459;4450.399196108484;4875.268709693574;4898.804047838118;4998.450170481244;5186.974065129651;5211.348581427322;5570.944290557123;6145.938540614626;6382.744690212432;6457.418832305115;6464.353564643592;1367.4530187924909;1432.2695425333154;1449.7100579884066;1496.9775453368209;1514.5683480208088;863.3869952333788;986.358866736627;1004.6282875336173;1007.8387458006683;1011.0092109732503;5913.526917846562;5934.317553481319;5940.584159108821;5956.731947062306;5992.7938552874;6070.969645151746;6590.637514936724;6595.6691870606;6611.636360000246;6621.966890165615;5062.265971434122;5871.647701137946;5942.910856337158;5950.85122871923;6015.428534289235;3063.8161710297454;3078.5074388097796;3437.810920399402;3470.2538954124984;3495.8951231463097;5242.7378631068295;5934.783369409261;6413.647249401234;6731.394174524316;6766.594004359754;6756.0879281297375;6757.837492002512;6787.796366109024;6793.72825086991;6875.5874647521305;5757.424257574209;5976.982383682076;6002.919474494545;6643.468522518284;6724.260331363644;6038.698065096815;6172.7345894092505;6474.125992991075;6602.943529035331;6633.9668301658185;5571;6141.692915354304;6500.349965003651;6554.4756419485375;6598.967005164944;5804.303483581997;6340.682965851781;6392.488600912009;6682.465402767864;6763.323667633197;3915.4126880968083;4365.585269399402;4387.539308372575;4409.80155892992;4435.778211089497;5755.5683323750045;6229.657368844757;6485.448736857231;6564.277929427663;6643.30245324234;5277.313949186581;5281.551068159096;5944.970275148597;6278.623282603058;6307.3377295383325;5316.760745766373;5721.485066344049;6366.840828979312;6382.5532212744665;6454;6146.907796382969;6242.250929888442;6257.558342911471;6305.680608782998;6318.62088274705;5614.915776263441;5914.2311499504785;6395.392437718596;6429.32492088324;6465;5929.584929054925;6131.478824299985;6197.504199663926;6255.593386429868;6269.404406581368 13:54:06 INFO - PID 2188 | |5;yui.html;713.9433638215442;718.0169622817642;726.114140748287;733.1641928201851;771.4484143837155;14984.400623975042;15903.761443578356;15916.68166636668;15947.681046379066;15957.441489547868;13809;13815.930920345409;13879;13897;13918.373673184706;916.6470908700131;928.3455164109321;949.1410273702311;950.6577632052456;951.4243882451121;931.6646007437317;941.6892425499603;942.8350038743226;942.9717108486757;944.6740874398322;973.990260097397;993.3096497933939;1011.1607365886297;1014.695591322604;1014.8071866345408;1002.8395456726962;1005.9446730429823;1007.5818535307839;1008.4100801031406;1013.989860101397;741.2772546766913;741.3920585120204;741.4550305525435;743.8846978718308;745.8023623739713;16121.838781612269;16203.756943645854;16376;16525.6694866102;16777.32890684371;14461.855381446156;14797.885448547231;14910.329035193334;14918.776218356734;14997.3251203695;11456.828147577793;11514.424278786028;11652;11837.816218378179;11976.940115299367;2535.366158460385;2996.6703662597097;3015.0256039460764;3053.6640969493337;3124.1626390639503;107898.46050769775;108544.4572777139;108928.4553577227;110342;112022.43988780004;9459;11354.602588909349;11354.772904541864;11824.881751182378;11892.94053529744;24395.02419903184;24628.876855615967;25005.12482063119;25029.749702503104;25138.497230055298;7724;7806.453548251568;7889.487183333065;7925.28672419485;8061.959690201512;4046.4941882264716;4128.318827393447;4132.40080188377;4139.586041395836;4177.665786737114;4079.6124368184974;4105.404716316177;4168.583141685807;4192.224438478891;4201.684873634428;768.5964868444112;775.693601027591;781.6834182156235;783.7687882074802;784.3607459920147;525.4981492674486;541.6316904504896;546.8222777976265;584.4681339980597;598.0938877600439;8789.208971192624;9069.501177435304;9257.953710231406;9516.95241523788;9812.754681133028;535.3682654467765;564.8569772223842;570.4865620941187;574.2592056247394;587.1544975235647;4330.35044743291;4384.846530371486;4565.840195593139;4566.74882881438;4650.441946966318;78722.42555148865;81774;105549.47225263824;107577.4621126905;107949;48467.091974480936;81210.18789812026;88058.55970720106;89891;96280;73715.26284737085;75805.86291205636;75934;76318.61840690761;76943.61528192324;8706.782330441789;10244.252301071794;10787.6224332148;10889;10938.56245750161;36430.8178459106;37227;37721.056973574785;37725;38869.41695874621;48441.27338090002;54089.91820163496;63168.68415657892;63783;67502;11326.660200194008;13581.777640012446;13772.193606653162;13935.372908218951;14308.069975451563;10861.664742080491;14203.857961420666;14591.854081459049;14832.184229867029;15310.770338445154;13630.454781808616;13661.521846735317;13814;13822.585322440344;13987.720245595234;9018.909810902069;11037.503312350804;11910.940445297718;13657.931710341385;14120.717585648435;13108.737825243252;13220.934386535928;13273.933630331787;13639.046357878924;13829.446822127002;9650.131488165818;9707.70876873695;9763;9993.550290236812;10291.84562231552;8211;8353.707620233263;8593.62501999677;11009.220109522903;11302.095832333558 13:54:06 INFO - PID 2188 | __end_tp_report 13:54:06 INFO - PID 2188 | __start_cc_report 13:54:06 INFO - PID 2188 | _x_x_mozilla_cycle_collect,1608 13:54:06 INFO - PID 2188 | __end_cc_report 13:54:06 INFO - PID 2188 | __startTimestamp1490648045987__endTimestamp 13:54:06 INFO - PID 2188 | 13:54:06 INFO - PID 2188 | ------- Summary: start ------- 13:54:06 INFO - PID 2188 | Number of tests: 6 13:54:06 INFO - PID 2188 | 13:54:06 INFO - PID 2188 | [#0] dojo.html Cycles:165 Average:6002.03 Median:6200.91 stddev:651.00 (10.5%) stddev-sans-first:621.05 13:54:06 INFO - PID 2188 | Values: 3434.2 3434.5 3445.5 3498.5 3500.2 5973.9 6069.9 6070.0 6074.4 6096.5 6058.9 6086.1 6087.0 6094.3 6099.4 5399.8 5407.3 5421.9 5431.6 5563.6 5539.9 5543.0 5547.6 5553.4 5606.9 5572.5 5585.8 5595.4 5601.5 5602.5 5573.9 5586.0 5589.9 5595.4 5605.7 5399.5 5476.5 5496.6 5504.9 5510.8 6106.6 6122.4 6124.5 6130.4 6136.4 6184.4 6427.5 6439.3 6456.7 6473.2 4646.7 6399.9 6431.8 6450.0 6548.9 6131.1 6142.0 6158.4 6158.8 6167.2 6681.9 6689.1 6689.8 6694.3 6695.9 6148.5 6215.5 6215.8 6324.4 6343.3 6424.6 6438.8 6498.7 6511.9 6527.2 5279.5 6372.6 6402.1 6408.7 6415.2 6201.8 6390.7 6404.9 6409.5 6412.7 5158.7 5172.5 5215.3 5244.9 5247.2 4652.5 4652.9 4661.3 4682.4 4699.1 6192.6 6198.2 6208.3 6210.0 6233.8 6299.4 6348.5 6372.6 6447.8 6493.5 6179.1 6187.9 6200.9 6222.8 6230.6 5799.4 5808.5 5810.4 5825.5 5840.8 6364.3 6437.4 6580.0 6681.0 6697.9 6387.8 6472.7 6544.9 6556.5 6566.8 6571.9 6665.5 6666.7 6680.1 6680.6 6386.2 6433.1 6438.6 6461.2 6463.2 6290.8 6344.8 6491.4 6497.6 6515.1 6122.2 6300.1 6302.4 6307.6 6318.3 6155.0 6161.0 6189.4 6195.0 6313.5 6224.8 6231.5 6256.8 6266.6 6285.3 5357.7 6154.6 6209.0 6328.6 6364.9 6106.2 6163.2 6217.4 6230.3 6230.3 13:54:06 INFO - PID 2188 | 13:54:06 INFO - PID 2188 | [#1] ext.html Cycles:180 Average:52221.37 Median:33934.32 stddev:107085.94 (315.6%) stddev-sans-first:107316.59 13:54:06 INFO - PID 2188 | Values: 741.7 784.5 806.5 1133.7 1140.3 27027.5 35763.8 36246.8 36377.5 36393.3 37821.6 38326.4 38335.6 38373.0 38418.4 6063.7 8401.0 8589.4 8605.2 8606.9 6143.4 6236.1 8261.0 8423.3 8434.2 8451.4 8494.4 8500.6 8606.9 8617.1 8526.1 8560.7 8566.2 8571.4 8574.7 7786.9 8040.0 8414.5 8478.2 8504.7 37775.6 38057.9 38108.2 38136.6 38143.6 2865.6 2933.8 3003.4 3014.6 3058.3 25863.4 26124.7 26221.0 26310.3 27055.9 2631.5 2649.7 2754.2 2779.7 2842.5 257533.4 259272.0 259690.0 261974.0 297416.5 47471.3 47960.3 48013.8 48274.0 48311.5 49835.8 50056.0 50078.0 50138.0 50178.0 11808.8 14482.4 14513.6 14516.8 14521.3 9712.5 11635.4 11637.6 11649.4 11652.7 11729.3 11837.8 11848.3 11854.5 11875.2 4853.4 5808.8 5953.6 5968.5 5973.1 951.8 981.8 982.8 1017.4 1036.5 22312.9 23380.4 23793.9 24615.5 24727.0 1094.8 1101.2 1136.7 1136.7 1147.9 11733.5 11789.4 12423.9 12425.1 12454.6 461514.0 464507.0 464565.0 464887.0 465174.0 55772.7 56033.7 56054.0 56058.0 56156.7 1491.6 1497.1 1550.1 1553.6 1555.5 23827.7 24010.2 24038.9 24106.5 24150.2 31971.8 38869.0 38905.6 39104.4 39119.0 443476.6 445308.0 445449.0 445522.0 445659.0 33052.7 33613.5 33866.2 33928.5 33940.2 42662.9 43311.8 43327.0 43386.3 44576.8 29245.9 29626.2 29929.6 30128.5 30360.0 27225.0 30013.4 30211.2 30354.0 30448.4 32343.0 33747.7 34165.0 35960.0 36346.5 22919.8 28039.9 28799.4 29039.7 29661.7 16616.0 22423.3 23618.4 23842.4 24468.3 13:54:06 INFO - PID 2188 | 13:54:06 INFO - PID 2188 | [#2] jquery.html Cycles:180 Average:39914.11 Median:21175.31 stddev:118698.39 (560.6%) stddev-sans-first:118994.18 13:54:06 INFO - PID 2188 | Values: 344.3 416.2 427.8 432.8 443.5 12838.9 14709.0 18151.4 20700.8 20797.5 17192.2 19303.9 21208.4 22308.1 22377.8 5872.3 5906.4 6073.8 6096.6 6153.5 3539.1 6005.8 6032.8 6069.1 6074.4 3583.4 3628.7 3784.9 3794.5 3819.4 5107.6 5835.1 5969.7 5983.1 5983.3 5057.5 5240.0 5280.9 5299.2 5301.7 25819.5 25861.6 26126.7 27192.0 27221.6 2000.7 2001.0 2022.2 2041.5 2089.5 14631.2 15358.8 15439.7 15541.7 15742.0 1456.5 1630.2 1662.7 1664.1 1691.8 135094.3 135858.0 135966.0 136882.3 137845.3 10055.4 10550.9 11492.9 11768.3 11940.4 15804.5 15809.8 15825.3 15847.6 15870.6 8495.8 8852.6 8914.4 9029.6 9062.0 8639.3 8640.8 8646.7 8844.4 8847.8 7307.5 7486.0 7541.6 7542.7 7549.4 2045.1 2048.4 2049.6 2050.8 2052.5 769.7 797.4 947.9 961.4 982.8 20358.2 20874.6 20875.5 20990.8 21251.5 964.4 981.0 995.0 1006.7 1008.6 9305.5 10531.7 10766.6 10767.8 10775.9 691404.0 695827.0 698674.0 729695.0 735254.0 124320.4 155239.0 158429.0 162719.0 164693.0 1160.4 1166.2 1224.7 1231.4 1235.3 19879.8 21059.6 21111.5 21117.8 21142.3 59965.7 60238.0 61078.7 61079.7 61274.0 80558.6 81747.0 82706.2 84428.0 87191.1 8126.9 8322.8 8353.4 8624.3 8636.7 12829.4 12841.6 12935.7 13037.0 13143.5 9896.1 11064.8 11107.1 11113.6 11303.9 10296.4 10328.8 10372.7 10771.0 10787.7 11135.4 11241.3 11365.3 11376.8 11610.9 8187.5 8287.8 8289.1 8325.4 8353.6 11101.3 11381.0 11533.8 11544.5 11550.7 13:54:06 INFO - PID 2188 | 13:54:06 INFO - PID 2188 | [#3] mootools.html Cycles:180 Average:6785.31 Median:2494.85 stddev:4183.42 (167.7%) stddev-sans-first:4175.49 13:54:06 INFO - PID 2188 | Values: 1387.4 1392.7 1393.3 1459.4 1483.4 9598.1 10358.8 10449.7 10907.1 10930.8 10512.6 10827.2 10832.4 10840.8 10880.9 2447.9 2490.2 2491.1 2492.7 2496.0 2418.3 2442.8 2490.0 2494.2 2495.5 2058.9 2378.1 2474.2 2475.4 2478.3 2391.0 2445.8 2472.5 2475.1 2479.7 2425.4 2443.4 2444.5 2445.5 2446.1 10901.4 11105.4 11136.9 11168.3 11171.9 6200.4 6450.3 6451.0 6457.5 6458.8 3968.8 4024.2 4047.2 4129.4 4134.5 1157.6 1158.7 1175.7 1205.3 1232.6 13766.0 14140.8 14184.1 15127.6 15285.2 3990.1 4798.1 4879.3 5013.1 5810.5 12604.2 12634.4 12666.0 12826.3 12870.5 5514.7 6422.8 6481.2 6487.4 6501.5 4962.8 5279.7 5927.6 5962.3 5973.0 4263.6 4375.3 4533.9 4559.7 4576.7 1175.6 1378.2 1409.4 1423.3 1434.6 698.0 764.8 767.3 791.4 795.3 6365.4 6500.9 6750.4 6771.2 6773.6 578.2 581.0 590.0 601.7 607.3 3665.3 3971.9 4067.2 4118.7 4163.4 13390.9 13906.2 13913.7 13923.0 13941.9 13806.9 13815.0 13825.7 13848.2 13852.2 9678.8 10092.3 10100.6 10105.3 10132.6 6664.3 6667.0 6714.9 6803.0 6977.1 12215.3 12388.7 12658.8 12701.1 12919.0 10571.3 12815.4 12837.0 12850.1 12859.6 7953.8 8210.1 8238.1 8843.3 9251.7 10006.6 10057.2 10277.8 10285.9 10302.0 8463.5 8519.9 8526.0 8632.8 8651.6 8263.2 8279.0 8332.7 8387.0 8540.4 7274.5 7851.1 7866.2 7951.7 8013.2 5813.3 5866.7 5962.5 5999.8 6087.7 5878.6 6002.2 6102.7 6158.0 6283.9 13:54:06 INFO - PID 2188 | 13:54:06 INFO - PID 2188 | [#4] prototype.html Cycles:180 Average:5122.10 Median:5824.36 stddev:1589.38 (27.3%) stddev-sans-first:1553.59 13:54:06 INFO - PID 2188 | Values: 386.3 388.3 394.1 394.3 400.1 6045.6 6099.6 6101.9 6130.4 6135.5 5611.7 5652.4 5798.8 5844.4 5906.1 3834.5 4047.4 4605.6 4686.3 4705.2 3885.3 3941.8 4689.1 4695.4 4706.9 4253.4 4469.8 4539.9 4667.2 4702.6 3870.6 4460.9 4466.9 4478.0 4493.5 3351.5 3402.3 3857.6 3905.6 3914.3 4513.1 5779.6 5910.8 5921.6 5950.3 4570.9 5211.1 5211.6 5245.1 5251.4 5579.4 5996.1 6375.4 6375.9 6390.8 4155.4 4228.7 4281.0 4286.4 4297.4 6114.5 6332.7 6524.9 6525.2 6693.1 4020.2 4254.8 4354.0 4358.5 4379.5 4413.8 4426.7 4448.5 4449.5 4450.4 4875.3 4898.8 4998.5 5187.0 5211.3 5570.9 6145.9 6382.7 6457.4 6464.4 1367.5 1432.3 1449.7 1497.0 1514.6 863.4 986.4 1004.6 1007.8 1011.0 5913.5 5934.3 5940.6 5956.7 5992.8 6071.0 6590.6 6595.7 6611.6 6622.0 5062.3 5871.6 5942.9 5950.9 6015.4 3063.8 3078.5 3437.8 3470.3 3495.9 5242.7 5934.8 6413.6 6731.4 6766.6 6756.1 6757.8 6787.8 6793.7 6875.6 5757.4 5977.0 6002.9 6643.5 6724.3 6038.7 6172.7 6474.1 6602.9 6634.0 5571.0 6141.7 6500.3 6554.5 6599.0 5804.3 6340.7 6392.5 6682.5 6763.3 3915.4 4365.6 4387.5 4409.8 4435.8 5755.6 6229.7 6485.4 6564.3 6643.3 5277.3 5281.6 5945.0 6278.6 6307.3 5316.8 5721.5 6366.8 6382.6 6454.0 6146.9 6242.3 6257.6 6305.7 6318.6 5614.9 5914.2 6395.4 6429.3 6465.0 5929.6 6131.5 6197.5 6255.6 6269.4 13:54:06 INFO - PID 2188 | 13:54:06 INFO - PID 2188 | [#5] yui.html Cycles:180 Average:19562.38 Median:21457.96 stddev:28181.58 (131.3%) stddev-sans-first:28225.10 13:54:06 INFO - PID 2188 | Values: 713.9 718.0 726.1 733.2 771.4 14984.4 15903.8 15916.7 15947.7 15957.4 13809.0 13815.9 13879.0 13897.0 13918.4 916.6 928.3 949.1 950.7 951.4 931.7 941.7 942.8 943.0 944.7 974.0 993.3 1011.2 1014.7 1014.8 1002.8 1005.9 1007.6 1008.4 1014.0 741.3 741.4 741.5 743.9 745.8 16121.8 16203.8 16376.0 16525.7 16777.3 14461.9 14797.9 14910.3 14918.8 14997.3 11456.8 11514.4 11652.0 11837.8 11976.9 2535.4 2996.7 3015.0 3053.7 3124.2 107898.5 108544.5 108928.5 110342.0 112022.4 9459.0 11354.6 11354.8 11824.9 11892.9 24395.0 24628.9 25005.1 25029.7 25138.5 7724.0 7806.5 7889.5 7925.3 8062.0 4046.5 4128.3 4132.4 4139.6 4177.7 4079.6 4105.4 4168.6 4192.2 4201.7 768.6 775.7 781.7 783.8 784.4 525.5 541.6 546.8 584.5 598.1 8789.2 9069.5 9258.0 9517.0 9812.8 535.4 564.9 570.5 574.3 587.2 4330.4 4384.8 4565.8 4566.7 4650.4 78722.4 81774.0 105549.5 107577.5 107949.0 48467.1 81210.2 88058.6 89891.0 96280.0 73715.3 75805.9 75934.0 76318.6 76943.6 8706.8 10244.3 10787.6 10889.0 10938.6 36430.8 37227.0 37721.1 37725.0 38869.4 48441.3 54089.9 63168.7 63783.0 67502.0 11326.7 13581.8 13772.2 13935.4 14308.1 10861.7 14203.9 14591.9 14832.2 15310.8 13630.5 13661.5 13814.0 13822.6 13987.7 9018.9 11037.5 11910.9 13657.9 14120.7 13108.7 13220.9 13273.9 13639.0 13829.4 9650.1 9707.7 9763.0 9993.6 10291.8 8211.0 8353.7 8593.6 11009.2 11302.1 13:54:06 INFO - PID 2188 | -------- Summary: end -------- 13:54:06 INFO - PID 2188 | 13:54:06 INFO - TEST-INFO | 2188: exit 0 13:54:06 INFO - TEST-OK | dromaeo_css | took 1120786ms 13:54:06 INFO - TEST-START | kraken 13:54:06 INFO - operating with platform_type : mac_ 13:54:06 INFO - Initialising browser for kraken test... 13:54:06 INFO - TEST-INFO | started process 2191 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpRU6VFm/profile http://localhost:56657/getInfo.html) 13:54:09 INFO - PID 2191 | __metrics Screen width/height:1600/1200 13:54:09 INFO - PID 2191 | colorDepth:24 13:54:09 INFO - PID 2191 | Browser inner width/height: 1024/689 13:54:09 INFO - PID 2191 | __metrics 13:54:09 ERROR - PID 2191 | 1490648049322 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 13:54:09 INFO - PID 2191 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:54:09 INFO - PID 2191 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:54:10 INFO - TEST-INFO | 2191: exit 0 13:54:10 INFO - Browser initialized. 13:54:10 INFO - Running cycle 1/1 for kraken test... 13:54:10 INFO - TEST-INFO | started process 2192 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpRU6VFm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:54:22 INFO - PID 2192 | RSS: Main: 225869824 13:54:22 INFO - PID 2192 | 13:54:25 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56657/tests/kraken/driver.html?testName=audio-beat-detection) 13:54:26 INFO - PID 2192 | RSS: Main: 284741632 13:54:26 INFO - PID 2192 | 13:54:29 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56657/tests/kraken/driver.html?testName=audio-dft) 13:54:29 INFO - PID 2192 | RSS: Main: 288026624 13:54:29 INFO - PID 2192 | 13:54:33 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56657/tests/kraken/driver.html?testName=audio-fft) 13:54:33 INFO - PID 2192 | RSS: Main: 382836736 13:54:33 INFO - PID 2192 | 13:54:35 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56657/tests/kraken/driver.html?testName=audio-oscillator) 13:54:36 INFO - PID 2192 | RSS: Main: 285618176 13:54:36 INFO - PID 2192 | 13:54:38 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56657/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:54:39 INFO - PID 2192 | RSS: Main: 287989760 13:54:39 INFO - PID 2192 | 13:54:42 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56657/tests/kraken/driver.html?testName=imaging-darkroom) 13:54:42 INFO - PID 2192 | RSS: Main: 328785920 13:54:42 INFO - PID 2192 | 13:54:45 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56657/tests/kraken/driver.html?testName=imaging-desaturate) 13:54:46 INFO - PID 2192 | RSS: Main: 337719296 13:54:46 INFO - PID 2192 | 13:54:49 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56657/tests/kraken/driver.html?testName=json-parse-financial) 13:54:50 INFO - PID 2192 | RSS: Main: 343261184 13:54:50 INFO - PID 2192 | 13:54:51 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56657/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:54:52 INFO - PID 2192 | RSS: Main: 301780992 13:54:52 INFO - PID 2192 | 13:54:54 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56657/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:54:55 INFO - PID 2192 | RSS: Main: 307142656 13:54:55 INFO - PID 2192 | 13:54:57 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56657/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:54:57 INFO - PID 2192 | RSS: Main: 308965376 13:54:57 INFO - PID 2192 | 13:55:00 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56657/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:55:01 INFO - PID 2192 | RSS: Main: 324624384 13:55:01 INFO - PID 2192 | 13:55:04 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56657/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:55:05 INFO - PID 2192 | RSS: Main: 318242816 13:55:05 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | Cycle 1(1): loaded http://localhost:56657/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56657/tests/kraken/driver.html?testName=ai-astar) 13:55:07 INFO - PID 2192 | RSS: Main: 379564032 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | __start_tp_report 13:55:07 INFO - PID 2192 | _x_x_mozilla_page_load 13:55:07 INFO - PID 2192 | _x_x_mozilla_page_load_details 13:55:07 INFO - PID 2192 | |i|pagename|runs| 13:55:07 INFO - PID 2192 | |0;ai-astar;102;112;112;111;112;112;112;110;112;112 13:55:07 INFO - PID 2192 | |1;audio-beat-detection;153;136;135;132;131;147;138;92;161;144 13:55:07 INFO - PID 2192 | |2;audio-dft;189;186;186;183;187;185;184;185;187;187 13:55:07 INFO - PID 2192 | |3;audio-fft;82;59;74;109;86;87;62;58;74;61 13:55:07 INFO - PID 2192 | |4;audio-oscillator;87;73;117;104;102;69;85;69;68;85 13:55:07 INFO - PID 2192 | |5;imaging-gaussian-blur;91;90;89;90;90;90;91;91;89;90 13:55:07 INFO - PID 2192 | |6;imaging-darkroom;96;96;96;96;96;95;96;97;97;96 13:55:07 INFO - PID 2192 | |7;imaging-desaturate;128;136;85;86;86;87;83;86;88;87 13:55:07 INFO - PID 2192 | |8;json-parse-financial;64;63;62;61;61;62;64;62;61;62 13:55:07 INFO - PID 2192 | |9;json-stringify-tinderbox;58;58;76;92;86;80;57;57;57;61 13:55:07 INFO - PID 2192 | |10;stanford-crypto-aes;83;84;83;83;83;82;83;83;83;83 13:55:07 INFO - PID 2192 | |11;stanford-crypto-ccm;139;142;134;138;135;139;138;140;182;138 13:55:07 INFO - PID 2192 | |12;stanford-crypto-pbkdf2;187;184;188;186;215;189;187;188;184;185 13:55:07 INFO - PID 2192 | |13;stanford-crypto-sha256-iterative;70;70;71;67;68;69;68;68;68;68 13:55:07 INFO - PID 2192 | __end_tp_report 13:55:07 INFO - PID 2192 | __start_cc_report 13:55:07 INFO - PID 2192 | _x_x_mozilla_cycle_collect,2204 13:55:07 INFO - PID 2192 | __end_cc_report 13:55:07 INFO - PID 2192 | __startTimestamp1490648107409__endTimestamp 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | ------- Summary: start ------- 13:55:07 INFO - PID 2192 | Number of tests: 14 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:3.13 (2.8%) stddev-sans-first:0.71 13:55:07 INFO - PID 2192 | Values: 102.0 112.0 112.0 111.0 112.0 112.0 112.0 110.0 112.0 112.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#1] audio-beat-detection Cycles:10 Average:136.90 Median:145.50 stddev:18.48 (12.7%) stddev-sans-first:18.66 13:55:07 INFO - PID 2192 | Values: 153.0 136.0 135.0 132.0 131.0 147.0 138.0 92.0 161.0 144.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#2] audio-dft Cycles:10 Average:185.90 Median:186.50 stddev:1.73 (0.9%) stddev-sans-first:1.42 13:55:07 INFO - PID 2192 | Values: 189.0 186.0 186.0 183.0 187.0 185.0 184.0 185.0 187.0 187.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#3] audio-fft Cycles:10 Average:75.20 Median:74.00 stddev:16.27 (22.0%) stddev-sans-first:17.07 13:55:07 INFO - PID 2192 | Values: 82.0 59.0 74.0 109.0 86.0 87.0 62.0 58.0 74.0 61.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:71.00 stddev:17.05 (24.0%) stddev-sans-first:18.07 13:55:07 INFO - PID 2192 | Values: 87.0 73.0 117.0 104.0 102.0 69.0 85.0 69.0 68.0 85.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:90.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 13:55:07 INFO - PID 2192 | Values: 91.0 90.0 89.0 90.0 90.0 90.0 91.0 91.0 89.0 90.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 13:55:07 INFO - PID 2192 | Values: 96.0 96.0 96.0 96.0 96.0 95.0 96.0 97.0 97.0 96.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:86.00 stddev:19.53 (22.7%) stddev-sans-first:16.73 13:55:07 INFO - PID 2192 | Values: 128.0 136.0 85.0 86.0 86.0 87.0 83.0 86.0 88.0 87.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:62.00 stddev:1.14 (1.8%) stddev-sans-first:1.00 13:55:07 INFO - PID 2192 | Values: 64.0 63.0 62.0 61.0 61.0 62.0 64.0 62.0 61.0 62.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.50 stddev:13.82 (20.2%) stddev-sans-first:14.16 13:55:07 INFO - PID 2192 | Values: 58.0 58.0 76.0 92.0 86.0 80.0 57.0 57.0 57.0 61.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:0.47 (0.6%) stddev-sans-first:0.50 13:55:07 INFO - PID 2192 | Values: 83.0 84.0 83.0 83.0 83.0 82.0 83.0 83.0 83.0 83.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#11] stanford-crypto-ccm Cycles:10 Average:142.50 Median:139.00 stddev:14.07 (10.1%) stddev-sans-first:14.86 13:55:07 INFO - PID 2192 | Values: 139.0 142.0 134.0 138.0 135.0 139.0 138.0 140.0 182.0 138.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.30 Median:187.50 stddev:9.19 (4.9%) stddev-sans-first:9.71 13:55:07 INFO - PID 2192 | Values: 187.0 184.0 188.0 186.0 215.0 189.0 187.0 188.0 184.0 185.0 13:55:07 INFO - PID 2192 | 13:55:07 INFO - PID 2192 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.50 stddev:1.25 (1.8%) stddev-sans-first:1.24 13:55:07 INFO - PID 2192 | Values: 70.0 70.0 71.0 67.0 68.0 69.0 68.0 68.0 68.0 68.0 13:55:07 INFO - PID 2192 | -------- Summary: end -------- 13:55:07 INFO - PID 2192 | 13:55:08 INFO - TEST-INFO | 2192: exit 0 13:55:08 INFO - TEST-OK | kraken | took 61733ms 13:55:08 INFO - SUITE-END | took 1182s 13:55:08 INFO - Completed test suite (00:19:42) 13:55:08 INFO - javascript benchmark 13:55:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7512.9079703834705, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3434.1929646533044, 3434.536337594425, 3445.500402441646, 3498.4927185558095, 3500.2124521982555, 5973.88052238955, 6069.939300606992, 6069.969650151748, 6074.392560743923, 6096.542759293048, 6058.939410605893, 6086.117512960618, 6087.0, 6094.299155597097, 6099.359567245442, 5399.811006614769, 5407.324084489439, 5421.864453388657, 5431.619786614939, 5563.638363506359, 5539.861503462405, 5542.972285138569, 5547.55619550435, 5553.389127196006, 5606.943930560683, 5572.470615291561, 5585.832425027255, 5595.356533998582, 5601.467860553241, 5602.467765562265, 5573.8606534836545, 5586.0, 5589.916151257735, 5595.412481689405, 5605.7197140143035, 5399.487048730364, 5476.534494567966, 5496.642718223343, 5504.944950550524, 5510.779568817243, 6106.6336019838955, 6122.387761223887, 6124.479419249369, 6130.448259656652, 6136.417040381157, 6184.381561843825, 6427.485801135898, 6439.291677915426, 6456.741730330781, 6473.223213214397, 4646.744429056401, 6399.872002559922, 6431.8392040198905, 6450.0, 6548.934510654928, 6131.132004888128, 6142.0172772356655, 6158.445739883432, 6158.815235542941, 6167.229096362955, 6681.866362672719, 6689.063531105649, 6689.76585819494, 6694.297098804653, 6695.899561506581, 6148.538859585549, 6215.502759779207, 6215.844603884939, 6324.367563243731, 6343.3339499351905, 6424.614523128627, 6438.77464288757, 6498.675066246668, 6511.934880651228, 6527.249366322839, 5279.498447647467, 6372.617642941345, 6402.135711678864, 6408.67956602168, 6415.166028416276, 6201.782937597162, 6390.712417941204, 6404.879146149406, 6409.519286053564, 6412.7114279857515, 5158.716270605153, 5172.5344718975475, 5215.269862219292, 5244.895102097937, 5247.2129180623215, 4652.511486293891, 4652.883677908079, 4661.254199328091, 4682.41469816273, 4699.083678682624, 6192.628442293387, 6198.163247961557, 6208.348123446973, 6209.96895015531, 6233.750649974041, 6299.4330510253385, 6348.523860710465, 6372.58578192416, 6447.774327898501, 6493.545451818327, 6179.134921110958, 6187.876242475125, 6200.906986395207, 6222.782202622887, 6230.563860529719, 5799.4490523401055, 5808.5062769665055, 5810.360860305363, 5825.475707186373, 5840.766369345263, 6364.331745166691, 6437.420632143129, 6580.0, 6681.0, 6697.899531506934, 6387.840303992437, 6472.741090356333, 6544.934550654432, 6556.508261880283, 6566.8358291043105, 6571.868562628816, 6665.500087493554, 6666.666666666551, 6680.098186744727, 6680.565763225375, 6386.201724784402, 6433.099366088845, 6438.6458744767615, 6461.224653041665, 6463.192100987377, 6290.779822706183, 6344.841378965562, 6491.35086491366, 6497.577657452251, 6515.12045873801, 6122.15420935445, 6300.149479820349, 6302.401271879165, 6307.590006649553, 6318.336574659595, 6155.045967874987, 6161.014237721943, 6189.442950134509, 6195.039768835838, 6313.494920406265, 6224.813255602339, 6231.470325022424, 6256.812295631138, 6266.561340706289, 6285.277193122849, 5357.651752636066, 6154.599951003171, 6208.9689551551955, 6328.608105813069, 6364.87270254583, 6106.248323414515, 6163.229596300463, 6217.378262173746, 6230.252369715663, 6230.283517395557], "value": 5961.923033045352, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.7478057460462, 784.4626430894837, 806.5201205282858, 1133.6599020293916, 1140.3101123820084, 27027.459450810995, 35763.82118089409, 36246.81876590614, 36377.45433818495, 36393.27213455729, 37821.621783782226, 38326.42510362347, 38335.61664383356, 38373.0, 38418.42372364417, 6063.727132279058, 8400.957995210047, 8589.441686290402, 8605.182507661762, 8606.87089693655, 6143.385661433866, 6236.126942228092, 8260.958695206515, 8423.284020858235, 8434.198751118665, 8451.408401411903, 8494.362922780785, 8500.574971251444, 8606.913930860674, 8617.095205003447, 8526.062133165347, 8560.657573697044, 8566.186212309884, 8571.400001999862, 8574.69988550404, 7786.909832623437, 8039.95980020102, 8414.495130292202, 8478.194571515696, 8504.744857654281, 37775.622243777485, 38057.858264252114, 38108.23783524314, 38136.61863381358, 38143.618563814285, 2865.5988161657383, 2933.838638874861, 3003.3993201359817, 3014.6382434108045, 3058.3118798270434, 25863.437084647576, 26124.738752612422, 26220.9511619535, 26310.342241443926, 27055.86472067627, 2631.460550587125, 2649.7085320614715, 2754.2425832895915, 2779.7359250871136, 2842.488352096622, 257533.42466575283, 259272.0, 259690.0, 261974.0, 297416.5129174362, 47471.28793068037, 47960.28059579109, 48013.75993120012, 48274.03451930942, 48311.516884831406, 49835.75082124566, 50056.0, 50078.0, 50138.0, 50178.0, 11808.822867656992, 14482.420703171754, 14513.637159071108, 14516.782248266285, 14521.346539405753, 9712.514374281258, 11635.360055196876, 11637.592684256011, 11649.359285239223, 11652.70868228284, 11729.296242225322, 11837.76324473523, 11848.348340841336, 11854.525818967317, 11875.228110172811, 4853.405235633118, 5808.767649294065, 5953.642781433128, 5968.4926781224085, 5973.133895585116, 951.7810903492235, 981.8183636027358, 982.8230918434607, 1017.4302390661253, 1036.4765793274348, 22312.888435557717, 23380.415489612897, 23793.881030594737, 24615.50768984646, 24727.0, 1094.7700982793704, 1101.1906248907208, 1136.6984802401205, 1136.7442325476702, 1147.9311241325547, 11733.530658773725, 11789.410529473493, 12423.875761242452, 12425.068119891044, 12454.564090256797, 461514.0, 464507.0, 464565.0, 464887.0, 465174.0, 55772.721136394866, 56033.71983140058, 56054.0, 56058.0, 56156.71921640365, 1491.6345495353708, 1497.0867770660107, 1550.0856442293984, 1553.6271294889145, 1555.478914563634, 23827.733137334806, 24010.212038881273, 24038.87980560086, 24106.51786964286, 24150.154744584557, 31971.840140799148, 38869.02827429337, 38905.6109438902, 39104.41343379795, 39119.0, 443476.5652343435, 445308.0, 445449.0, 445522.0, 445659.0, 33052.678637956014, 33613.49579756355, 33866.153346166546, 33928.491072634424, 33940.1514962118, 42662.93342666458, 43311.783441082596, 43327.0, 43386.349204761325, 44576.77711611551, 29245.85377073101, 29626.246177065892, 29929.55105673373, 30128.548071778503, 30360.0, 27225.04712335059, 30013.399732005673, 30211.244718882204, 30354.0, 30448.391032179676, 32343.0, 33747.66252337445, 34164.975050748515, 35960.0, 36346.4548031785, 22919.823342093445, 28039.859800700866, 28799.42401152007, 29039.709602904546, 29661.703382966756, 16616.0, 22423.29110886732, 23618.409539761644, 23842.40393990164, 24468.26595202147], "value": 17269.224492099398, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.2838895098193, 416.2029713099413, 427.828868452619, 432.7879339123829, 443.4789122780734, 12838.935805320973, 14709.043912145717, 18151.364702235423, 20700.792992070074, 20797.480062998413, 17192.226349814257, 19303.938283394407, 21208.36374908751, 22308.107675692954, 22377.77622223777, 5872.295324561059, 5906.438888305604, 6073.757049718006, 6096.634201947875, 6153.476954458854, 3539.132912436444, 6005.759769609211, 6032.849178770522, 6069.08963655453, 6074.392560743934, 3583.4266517357096, 3628.663746822548, 3784.867529636463, 3794.4687743715904, 3819.4079917612817, 5107.565856902168, 5835.124731290298, 5969.731362088716, 5983.132445795379, 5983.311919129291, 5057.468965758616, 5240.004399164147, 5280.947190528084, 5299.231611416323, 5301.7084060376665, 25819.483610327687, 25861.612075818877, 26126.73873261262, 27192.04827831036, 27221.591676124874, 2000.679891217413, 2000.98999505003, 2022.1810166882435, 2041.4794227472048, 2089.4567412472716, 14631.195284259362, 15358.769618455732, 15439.691206175927, 15541.689166216725, 15741.980613570431, 1456.5434565434566, 1630.1686140068532, 1662.7339625659836, 1664.1429663723193, 1691.7631531585587, 135094.3245283777, 135858.0, 135966.0, 136882.26235475234, 137845.3107734455, 10055.446950417798, 10550.89449105499, 11492.94253528727, 11768.292682926727, 11940.402979850973, 15804.525864224091, 15809.762853557206, 15825.287862046236, 15847.603809904615, 15870.60323491922, 8495.787605309917, 8852.62067945365, 8914.37599368038, 9029.593668284942, 9062.003179650234, 8639.265662418638, 8640.783980400414, 8646.740597782076, 8844.380893337404, 8847.823043539222, 7307.488475806749, 7485.962570187115, 7541.622918854036, 7542.698292068256, 7549.3583045440655, 2045.1410407628832, 2048.3752455500917, 2049.6454252171766, 2050.794920507967, 2052.48687828043, 769.6651956398985, 797.3860127701637, 947.9383090938194, 961.4183419031385, 982.8009828009816, 20358.18567257323, 20874.582508350053, 20875.478113046993, 20990.79009209919, 21251.46871328198, 964.4117088575963, 980.9547094308305, 994.9602015919427, 1006.7030226083323, 1008.5713571731984, 9305.488198149167, 10531.684049478528, 10766.569337226423, 10767.784644307227, 10775.892241077489, 691404.0, 695827.0, 698674.0, 729695.0, 735254.0, 124320.37839810744, 155239.0, 158429.0, 162719.0, 164693.0, 1160.4139909345895, 1166.2375211125004, 1224.7305592769756, 1231.445849367788, 1235.3452670084869, 19879.801201987793, 21059.578808424052, 21111.472213194793, 21117.788822111583, 21142.26002089981, 59965.700171498866, 60238.0, 61078.69460652668, 61079.69460152671, 61274.0, 80558.59720701359, 81747.0, 82706.17293827226, 84428.0, 87191.12808872084, 8126.918730812617, 8322.750317490485, 8353.37349698763, 8624.266937310398, 8636.654533818577, 12829.42267597979, 12841.550545730855, 12935.676608084874, 13037.0, 13143.53997610079, 9896.120541902148, 11064.778704426028, 11107.111431085332, 11113.555457781922, 11303.886961130283, 10296.382217067, 10328.793424131325, 10372.688819335432, 10770.976757208053, 10787.730306742395, 11135.438849064598, 11241.269317494336, 11365.261258018203, 11376.829347559627, 11610.94194529022, 8187.5087494750505, 8287.834243315221, 8289.088200298083, 8325.375596830383, 8353.582320884054, 11101.333919964827, 11380.975712185944, 11533.826992595286, 11544.480498377425, 11550.711232219262], "value": 9923.681509046239, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.4380875745321, 1392.7144935288268, 1393.2894223945789, 1459.3943513441923, 1483.4140514496773, 9598.088181622743, 10358.844617330746, 10449.738756531091, 10907.072898803612, 10930.781384372327, 10512.632057877976, 10827.187960902944, 10832.350058996466, 10840.78318433631, 10880.945595272033, 2447.926562203128, 2490.1533478617266, 2491.0533997081084, 2492.7258001619807, 2495.987520062406, 2418.343434747204, 2442.7557244275517, 2490.0, 2494.189388448752, 2495.500899820034, 2058.8558800883943, 2378.1319818266293, 2474.158786012755, 2475.3687809608614, 2478.3432390416556, 2390.964135537968, 2445.7676520730593, 2472.4807790364043, 2475.1460746042585, 2479.7148327942155, 2425.357280320716, 2443.4135807406274, 2444.4744379958393, 2445.4864478459544, 2446.1193970170725, 10901.400422976732, 11105.389203593799, 11136.888631113667, 11168.274062185932, 11171.944140279327, 6200.441960223601, 6450.322716114834, 6450.9677451612915, 6457.483401327882, 6458.838529036765, 3968.8319980844344, 4024.215278020787, 4047.2310261050307, 4129.442525259082, 4134.524529679095, 1157.5716984715625, 1158.727698990745, 1175.7413369058793, 1205.2587658589991, 1232.5501192064833, 13766.0, 14140.787888181685, 14184.148951062865, 15127.621809454742, 15285.159316237603, 3990.082281075369, 4798.136335459651, 4879.341288926021, 5013.147764879979, 5810.50610698087, 12604.243745375306, 12634.368281585885, 12666.0, 12826.294553799444, 12870.549530766566, 5514.6691198528215, 6422.839429014312, 6481.189851268591, 6487.3836985486305, 6501.512386570931, 4962.751862406865, 5279.656822306538, 5927.555433342431, 5962.34414214436, 5973.0, 4263.573642635712, 4375.299952007664, 4533.863984080483, 4559.703619264737, 4576.748278844629, 1175.641429364052, 1378.1868697468542, 1409.4137258131443, 1423.3452611798689, 1434.5624584501618, 697.9895301570481, 764.7935057534435, 767.2902565127347, 791.4064451661254, 795.3319211862064, 6365.395287447685, 6500.869982600321, 6750.392464678104, 6771.187457505131, 6773.593584384953, 578.1963071330854, 580.9941900581025, 589.9587028908021, 601.667306915185, 607.2530787131852, 3665.321915445651, 3971.860984865516, 4067.2272268269526, 4118.670506359484, 4163.417121602978, 13390.866091339156, 13906.165630062229, 13913.652158696113, 13923.025388222728, 13941.930290348484, 13806.861931380558, 13814.963877709011, 13825.654358640913, 13848.238346891017, 13852.16886986784, 9678.75803104928, 10092.343997640277, 10100.646477373257, 10105.292629515863, 10132.594696212069, 6664.266930637723, 6666.966665166643, 6714.932850671431, 6803.0, 6977.092977912839, 12215.26708397499, 12388.690282743004, 12658.810117848423, 12701.110922235355, 12919.0, 10571.2600117991, 12815.359232038547, 12837.0, 12850.100492965403, 12859.614211573667, 7953.8409231813885, 8210.137935516923, 8238.052623948082, 8843.336749743667, 9251.67619133327, 10006.599736010477, 10057.24570657189, 10277.84583231267, 10285.919978402459, 10302.02130797573, 8463.53450560225, 8519.914800851911, 8526.0, 8632.78418039554, 8651.567421629019, 8263.173682631688, 8278.958605206935, 8332.6666933322, 8386.951631046119, 8540.44487108336, 7274.454415918722, 7851.1363749988595, 7866.174051724486, 7951.721689740832, 8013.2387423194705, 5813.331466881194, 5866.67733274674, 5962.493188079062, 5999.790007349722, 6087.695615219132, 5878.647281163144, 6002.2197114376595, 6102.7253773579405, 6157.96921015392, 6283.905741413791], "value": 5139.879238377021, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.2738052461374, 388.2506761949437, 394.1073468593634, 394.26666400495077, 400.0618549501419, 6045.607035542692, 6099.603525770833, 6101.877962440749, 6130.448259656636, 6135.478484328832, 5611.7474713637885, 5652.378238393773, 5798.797042103527, 5844.415558444164, 5906.114082887569, 3834.501514803079, 4047.372657238133, 4605.572272595489, 4686.320483529886, 4705.176594096037, 3885.30064588374, 3941.8029098545103, 4689.062187562484, 4695.436547614274, 4706.929396059062, 4253.44705188325, 4469.815498892796, 4539.909201815978, 4667.15991121598, 4702.576768090871, 3870.632289932452, 4460.8661740147845, 4466.8883277917985, 4477.955220447787, 4493.505714371417, 3351.463765797485, 3402.2855200407944, 3857.5756666766633, 3905.5508616509183, 3914.2562913046713, 4513.097380523908, 5779.566532510036, 5910.822675319747, 5921.585489015771, 5950.345461999176, 4570.862874113782, 5211.114110601169, 5211.644972307189, 5245.082110630625, 5251.422343542207, 5579.358373787026, 5996.070609055603, 6375.394337537927, 6375.87248255037, 6390.795175664388, 4155.355919832401, 4228.746275223496, 4280.978595107035, 4286.371178951978, 4297.355396690521, 6114.541409394357, 6332.68336583169, 6524.902126468107, 6525.249596296392, 6693.1298931138645, 4020.2361551305744, 4254.829806807693, 4353.978230108829, 4358.4769827620885, 4379.5401482843845, 4413.830334961238, 4426.734395936254, 4448.488423831236, 4449.488308844459, 4450.399196108484, 4875.268709693574, 4898.804047838118, 4998.450170481244, 5186.974065129651, 5211.348581427322, 5570.944290557123, 6145.938540614626, 6382.744690212432, 6457.418832305115, 6464.353564643592, 1367.4530187924909, 1432.2695425333154, 1449.7100579884066, 1496.9775453368209, 1514.5683480208088, 863.3869952333788, 986.358866736627, 1004.6282875336173, 1007.8387458006683, 1011.0092109732503, 5913.526917846562, 5934.317553481319, 5940.584159108821, 5956.731947062306, 5992.7938552874, 6070.969645151746, 6590.637514936724, 6595.6691870606, 6611.636360000246, 6621.966890165615, 5062.265971434122, 5871.647701137946, 5942.910856337158, 5950.85122871923, 6015.428534289235, 3063.8161710297454, 3078.5074388097796, 3437.810920399402, 3470.2538954124984, 3495.8951231463097, 5242.7378631068295, 5934.783369409261, 6413.647249401234, 6731.394174524316, 6766.594004359754, 6756.0879281297375, 6757.837492002512, 6787.796366109024, 6793.72825086991, 6875.5874647521305, 5757.424257574209, 5976.982383682076, 6002.919474494545, 6643.468522518284, 6724.260331363644, 6038.698065096815, 6172.7345894092505, 6474.125992991075, 6602.943529035331, 6633.9668301658185, 5571.0, 6141.692915354304, 6500.349965003651, 6554.4756419485375, 6598.967005164944, 5804.303483581997, 6340.682965851781, 6392.488600912009, 6682.465402767864, 6763.323667633197, 3915.4126880968083, 4365.585269399402, 4387.539308372575, 4409.80155892992, 4435.778211089497, 5755.5683323750045, 6229.657368844757, 6485.448736857231, 6564.277929427663, 6643.30245324234, 5277.313949186581, 5281.551068159096, 5944.970275148597, 6278.623282603058, 6307.3377295383325, 5316.760745766373, 5721.485066344049, 6366.840828979312, 6382.5532212744665, 6454.0, 6146.907796382969, 6242.250929888442, 6257.558342911471, 6305.680608782998, 6318.62088274705, 5614.915776263441, 5914.2311499504785, 6395.392437718596, 6429.32492088324, 6465.0, 5929.584929054925, 6131.478824299985, 6197.504199663926, 6255.593386429868, 6269.404406581368], "value": 4625.164975043062, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.9433638215442, 718.0169622817642, 726.114140748287, 733.1641928201851, 771.4484143837155, 14984.400623975042, 15903.761443578356, 15916.68166636668, 15947.681046379066, 15957.441489547868, 13809.0, 13815.930920345409, 13879.0, 13897.0, 13918.373673184706, 916.6470908700131, 928.3455164109321, 949.1410273702311, 950.6577632052456, 951.4243882451121, 931.6646007437317, 941.6892425499603, 942.8350038743226, 942.9717108486757, 944.6740874398322, 973.990260097397, 993.3096497933939, 1011.1607365886297, 1014.695591322604, 1014.8071866345408, 1002.8395456726962, 1005.9446730429823, 1007.5818535307839, 1008.4100801031406, 1013.989860101397, 741.2772546766913, 741.3920585120204, 741.4550305525435, 743.8846978718308, 745.8023623739713, 16121.838781612269, 16203.756943645854, 16376.0, 16525.6694866102, 16777.32890684371, 14461.855381446156, 14797.885448547231, 14910.329035193334, 14918.776218356734, 14997.3251203695, 11456.828147577793, 11514.424278786028, 11652.0, 11837.816218378179, 11976.940115299367, 2535.366158460385, 2996.6703662597097, 3015.0256039460764, 3053.6640969493337, 3124.1626390639503, 107898.46050769775, 108544.4572777139, 108928.4553577227, 110342.0, 112022.43988780004, 9459.0, 11354.602588909349, 11354.772904541864, 11824.881751182378, 11892.94053529744, 24395.02419903184, 24628.876855615967, 25005.12482063119, 25029.749702503104, 25138.497230055298, 7724.0, 7806.453548251568, 7889.487183333065, 7925.28672419485, 8061.959690201512, 4046.4941882264716, 4128.318827393447, 4132.40080188377, 4139.586041395836, 4177.665786737114, 4079.6124368184974, 4105.404716316177, 4168.583141685807, 4192.224438478891, 4201.684873634428, 768.5964868444112, 775.693601027591, 781.6834182156235, 783.7687882074802, 784.3607459920147, 525.4981492674486, 541.6316904504896, 546.8222777976265, 584.4681339980597, 598.0938877600439, 8789.208971192624, 9069.501177435304, 9257.953710231406, 9516.95241523788, 9812.754681133028, 535.3682654467765, 564.8569772223842, 570.4865620941187, 574.2592056247394, 587.1544975235647, 4330.35044743291, 4384.846530371486, 4565.840195593139, 4566.74882881438, 4650.441946966318, 78722.42555148865, 81774.0, 105549.47225263824, 107577.4621126905, 107949.0, 48467.091974480936, 81210.18789812026, 88058.55970720106, 89891.0, 96280.0, 73715.26284737085, 75805.86291205636, 75934.0, 76318.61840690761, 76943.61528192324, 8706.782330441789, 10244.252301071794, 10787.6224332148, 10889.0, 10938.56245750161, 36430.8178459106, 37227.0, 37721.056973574785, 37725.0, 38869.41695874621, 48441.27338090002, 54089.91820163496, 63168.68415657892, 63783.0, 67502.0, 11326.660200194008, 13581.777640012446, 13772.193606653162, 13935.372908218951, 14308.069975451563, 10861.664742080491, 14203.857961420666, 14591.854081459049, 14832.184229867029, 15310.770338445154, 13630.454781808616, 13661.521846735317, 13814.0, 13822.585322440344, 13987.720245595234, 9018.909810902069, 11037.503312350804, 11910.940445297718, 13657.931710341385, 14120.717585648435, 13108.737825243252, 13220.934386535928, 13273.933630331787, 13639.046357878924, 13829.446822127002, 9650.131488165818, 9707.70876873695, 9763.0, 9993.550290236812, 10291.84562231552, 8211.0, 8353.707620233263, 8593.62501999677, 11009.220109522903, 11302.095832333558], "value": 7402.940935840879, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1489.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 112.0, 111.0, 112.0, 112.0, 112.0, 110.0, 112.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 136.0, 135.0, 132.0, 131.0, 147.0, 138.0, 92.0, 161.0, 144.0], "value": 136.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 186.0, 186.0, 183.0, 187.0, 185.0, 184.0, 185.0, 187.0, 187.0], "value": 185.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 74.0, 109.0, 86.0, 87.0, 62.0, 58.0, 74.0, 61.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 117.0, 104.0, 102.0, 69.0, 85.0, 69.0, 68.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 89.0, 90.0, 90.0, 90.0, 91.0, 91.0, 89.0, 90.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 97.0, 97.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 136.0, 85.0, 86.0, 86.0, 87.0, 83.0, 86.0, 88.0, 87.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 61.0, 61.0, 62.0, 64.0, 62.0, 61.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 76.0, 92.0, 86.0, 80.0, 57.0, 57.0, 57.0, 61.0], "value": 68.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 83.0, 83.0, 82.0, 83.0, 83.0, 83.0, 83.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 142.0, 134.0, 138.0, 135.0, 139.0, 138.0, 140.0, 182.0, 138.0], "value": 142.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 188.0, 186.0, 215.0, 189.0, 187.0, 188.0, 184.0, 185.0], "value": 189.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 71.0, 67.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0], "value": 68.7, "unit": "score"}]}]} 13:55:08 INFO - Return code: 0 13:55:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:55:08 ERROR - # TBPL SUCCESS # 13:55:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:55:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:55:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:55:08 INFO - 2017-03-27 13:55:08.741 screenresolution[2196:30257] starting screenresolution argv=screenresolution get 13:55:08 INFO - 2017-03-27 13:55:08.744 screenresolution[2196:30257] Display 0: 1600x1200x32@60 13:55:08 INFO - 2017-03-27 13:55:08.750 screenresolution[2197:30260] starting screenresolution argv=screenresolution list 13:55:08 INFO - Available Modes on Display 0 13:55:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:55:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:55:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:55:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:55:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:55:08 INFO - Intel Iris: 13:55:08 INFO - Chipset Model: Intel Iris 13:55:08 INFO - Type: GPU 13:55:08 INFO - Bus: Built-In 13:55:08 INFO - VRAM (Dynamic, Max): 1536 MB 13:55:08 INFO - Vendor: Intel (0x8086) 13:55:08 INFO - Device ID: 0x0a2e 13:55:08 INFO - Revision ID: 0x0009 13:55:08 INFO - Displays: 13:55:08 INFO - Display: 13:55:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:55:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:55:08 INFO - Display Serial Number: 1600x1200 60 13:55:08 INFO - Main Display: Yes 13:55:08 INFO - Mirror: Off 13:55:08 INFO - Online: Yes 13:55:08 INFO - Rotation: Supported 13:55:08 INFO - Return code: 0 13:55:08 INFO - Running post-action listener: _resource_record_post_action 13:55:08 INFO - [mozharness: 2017-03-27 20:55:08.992451Z] Finished run-tests step (success) 13:55:08 INFO - Running post-run listener: _resource_record_post_run 13:55:09 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 213504; Write bytes: 515335168; Read time: 3; Write time: 1614 13:55:09 INFO - TinderboxPrint: CPU usage
53.4% 13:55:09 INFO - TinderboxPrint: I/O read bytes / time
213,504 / 3 13:55:09 INFO - TinderboxPrint: I/O write bytes / time
515,335,168 / 1,614 13:55:09 INFO - TinderboxPrint: CPU idle
2,243.2 (46.6%) 13:55:09 INFO - TinderboxPrint: CPU system
961.0 (19.9%) 13:55:09 INFO - TinderboxPrint: CPU user
1,613.5 (33.5%) 13:55:09 INFO - TinderboxPrint: Swap in / out
211,116,032 / 0 13:55:09 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 212443648; Write bytes: 213870080; Read time: 16745; Write time: 487 13:55:09 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 212992; Write bytes: 300047872; Read time: 3; Write time: 1119 13:55:09 INFO - Running post-run listener: _upload_blobber_files 13:55:09 INFO - Blob upload gear active. 13:55:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:55:09 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 13:55:09 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:55:09 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:55:09 INFO - (blobuploader) - INFO - Open directory for files ... 13:55:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:55:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:55:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:55:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:55:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:55:10 INFO - (blobuploader) - INFO - Done attempting. 13:55:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:55:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:55:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:55:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:55:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:55:11 INFO - (blobuploader) - INFO - Done attempting. 13:55:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:55:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:55:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:55:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:55:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:55:12 INFO - (blobuploader) - INFO - Done attempting. 13:55:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:55:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:55:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:55:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:55:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:55:15 INFO - (blobuploader) - INFO - Done attempting. 13:55:15 INFO - (blobuploader) - INFO - Iteration through files over. 13:55:15 INFO - Return code: 0 13:55:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:55:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:55:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d7916ef41dc59acfb9b947f47bc4c9a0623d25c953506f944e4bfb66427ee1b29c129d6e86bac0d582e591266371fa31644acfb9740018991ff9af70cf6a46a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/22b2cf8e110e3d1a91c227fc27890da4e69b8db8f0331725bcde2382d309cb5d6985e23347eb3c8df443befb2f5e6f6224599f18a96f69445a65188a9a5a21e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6ab624ad9cb487e0a70a57189843e36043ecb285a111bd1a192cdf88356a262ae53d8f5dd31086a89899b8c156d66d4fe675686d6b0a86eca6ebe4100eae0186", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/39d4f1ea356791b374537cb5f896651dad7fb5c25a75816f9f16d9657f1daefac6437700ca421a4bb1bd708e2bbca2cc262576de9e2ac864866bc804c9d428be"} 13:55:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:55:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:55:15 INFO - Contents: 13:55:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d7916ef41dc59acfb9b947f47bc4c9a0623d25c953506f944e4bfb66427ee1b29c129d6e86bac0d582e591266371fa31644acfb9740018991ff9af70cf6a46a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/22b2cf8e110e3d1a91c227fc27890da4e69b8db8f0331725bcde2382d309cb5d6985e23347eb3c8df443befb2f5e6f6224599f18a96f69445a65188a9a5a21e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6ab624ad9cb487e0a70a57189843e36043ecb285a111bd1a192cdf88356a262ae53d8f5dd31086a89899b8c156d66d4fe675686d6b0a86eca6ebe4100eae0186", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/39d4f1ea356791b374537cb5f896651dad7fb5c25a75816f9f16d9657f1daefac6437700ca421a4bb1bd708e2bbca2cc262576de9e2ac864866bc804c9d428be"} 13:55:15 INFO - Running post-run listener: copy_logs_to_upload_dir 13:55:15 INFO - Copying logs to upload dir... 13:55:15 INFO - mkdir: /builds/slave/test/build/upload/logs 13:55:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.282407 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-27 13:55:15.089541) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 13:55:15.093186) ========= 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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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/Pine/sha512/d7916ef41dc59acfb9b947f47bc4c9a0623d25c953506f944e4bfb66427ee1b29c129d6e86bac0d582e591266371fa31644acfb9740018991ff9af70cf6a46a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/22b2cf8e110e3d1a91c227fc27890da4e69b8db8f0331725bcde2382d309cb5d6985e23347eb3c8df443befb2f5e6f6224599f18a96f69445a65188a9a5a21e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6ab624ad9cb487e0a70a57189843e36043ecb285a111bd1a192cdf88356a262ae53d8f5dd31086a89899b8c156d66d4fe675686d6b0a86eca6ebe4100eae0186", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/39d4f1ea356791b374537cb5f896651dad7fb5c25a75816f9f16d9657f1daefac6437700ca421a4bb1bd708e2bbca2cc262576de9e2ac864866bc804c9d428be"} build_url:https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011422 build_url: 'https://queue.taskcluster.net/v1/task/XGH5Y6JgSuWBg4OudkkNxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d7916ef41dc59acfb9b947f47bc4c9a0623d25c953506f944e4bfb66427ee1b29c129d6e86bac0d582e591266371fa31644acfb9740018991ff9af70cf6a46a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/22b2cf8e110e3d1a91c227fc27890da4e69b8db8f0331725bcde2382d309cb5d6985e23347eb3c8df443befb2f5e6f6224599f18a96f69445a65188a9a5a21e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6ab624ad9cb487e0a70a57189843e36043ecb285a111bd1a192cdf88356a262ae53d8f5dd31086a89899b8c156d66d4fe675686d6b0a86eca6ebe4100eae0186", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/39d4f1ea356791b374537cb5f896651dad7fb5c25a75816f9f16d9657f1daefac6437700ca421a4bb1bd708e2bbca2cc262576de9e2ac864866bc804c9d428be"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 13:55:15.128805) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 13:55:15.129149) ========= 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.AZMOHv1rLI/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.YOUX8Sa8KB/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.005441 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 13:55:15.178671) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 13:55:15.178986) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 13:55:15.179319) ========= ========= Total master_lag: 0.19 =========