builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0170 starttime: 1490617050.25 results: success (0) buildid: 20170327035815 builduid: 8d6ece6e690f4b5aa184b584b8ee1533 revision: 9577ddeaafd85554c2a855f385a87472a089d5c0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:30.250451) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:30.250878) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:30.251164) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:30.277628) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:30.277917) ========= 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.rzcsRia4XY/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.IzPZarhCHC/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-27 05:17:30-- 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% 13.4M=0.001s 2017-03-27 05:17:30 (13.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127655 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:30.430013) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:30.430418) ========= 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.rzcsRia4XY/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.IzPZarhCHC/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.028717 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:30.482168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 05:17:30.482601) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9577ddeaafd85554c2a855f385a87472a089d5c0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 9577ddeaafd85554c2a855f385a87472a089d5c0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rzcsRia4XY/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.IzPZarhCHC/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-27 05:17:30,588 truncating revision to first 12 chars 2017-03-27 05:17:30,588 Setting DEBUG logging. 2017-03-27 05:17:30,588 attempt 1/10 2017-03-27 05:17:30,588 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9577ddeaafd8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 05:17:31,368 unpacking tar archive at: mozilla-central-9577ddeaafd8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.048957 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 05:17:31.556035) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:31.556348) ========= script_repo_revision: 9577ddeaafd85554c2a855f385a87472a089d5c0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:31.556749) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:31.557013) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:31.578483) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-27 05:17:31.578780) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rzcsRia4XY/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.IzPZarhCHC/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:17:31 INFO - MultiFileLogger online at 20170327 05:17:31 in /builds/slave/test 05:17:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:17:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:17:31 INFO - {'append_to_log': False, 05:17:31 INFO - 'base_work_dir': '/builds/slave/test', 05:17:31 INFO - 'blob_upload_branch': 'Firefox', 05:17:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:17:31 INFO - 'branch': 'Firefox', 05:17:31 INFO - 'buildbot_json_path': 'buildprops.json', 05:17:31 INFO - 'config_files': ('talos/mac_config.py',), 05:17:31 INFO - 'default_actions': ('clobber', 05:17:31 INFO - 'read-buildbot-config', 05:17:31 INFO - 'download-and-extract', 05:17:31 INFO - 'populate-webroot', 05:17:31 INFO - 'create-virtualenv', 05:17:31 INFO - 'install', 05:17:31 INFO - 'run-tests'), 05:17:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:17:31 INFO - 'download_minidump_stackwalk': True, 05:17:31 INFO - 'download_symbols': 'ondemand', 05:17:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:17:31 INFO - 'tooltool.py': '/tools/tooltool.py', 05:17:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:17:31 INFO - '/tools/misc-python/virtualenv.py')}, 05:17:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:17:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:17:31 INFO - 'gecko_profile': False, 05:17:31 INFO - 'gecko_profile_interval': 0, 05:17:31 INFO - 'installer_path': 'installer.exe', 05:17:31 INFO - 'log_level': 'info', 05:17:31 INFO - 'log_name': 'talos', 05:17:31 INFO - 'log_to_console': True, 05:17:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:17:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:17:31 INFO - 'opt_config_files': (), 05:17:31 INFO - 'pip_index': False, 05:17:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:31 INFO - 'cmd': ('bash', 05:17:31 INFO - '-c', 05:17:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:31 INFO - 'enabled': True, 05:17:31 INFO - 'halt_on_failure': False, 05:17:31 INFO - 'name': 'check_screen_resolution'},), 05:17:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:31 INFO - 'cmd': ('bash', 05:17:31 INFO - '-c', 05:17:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:31 INFO - 'enabled': True, 05:17:31 INFO - 'halt_on_failure': False, 05:17:31 INFO - 'name': 'check_screen_resolution'},), 05:17:31 INFO - 'run_cmd_checks_enabled': True, 05:17:31 INFO - 'suite': 'dromaeojs', 05:17:31 INFO - 'system_bits': '32', 05:17:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:17:31 INFO - 'title': 't-yosemite-r7-0170', 05:17:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:17:31 INFO - 'use_talos_json': True, 05:17:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:17:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:17:31 INFO - 'work_dir': 'build'} 05:17:31 INFO - [mozharness: 2017-03-27 12:17:31.799137Z] Running clobber step. 05:17:31 INFO - Running pre-action listener: _resource_record_pre_action 05:17:31 INFO - Running main action method: clobber 05:17:31 INFO - rmtree: /builds/slave/test/build 05:17:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:17:36 INFO - Running post-action listener: _resource_record_post_action 05:17:36 INFO - [mozharness: 2017-03-27 12:17:36.225158Z] Finished clobber step (success) 05:17:36 INFO - [mozharness: 2017-03-27 12:17:36.225289Z] Running read-buildbot-config step. 05:17:36 INFO - Running pre-action listener: _resource_record_pre_action 05:17:36 INFO - Running main action method: read_buildbot_config 05:17:36 INFO - Using buildbot properties: 05:17:36 INFO - { 05:17:36 INFO - "project": "", 05:17:36 INFO - "product": "firefox", 05:17:36 INFO - "script_repo_revision": "production", 05:17:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:17:36 INFO - "repository": "", 05:17:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:17:36 INFO - "buildid": "20170327035815", 05:17:36 INFO - "pgo_build": "False", 05:17:36 INFO - "basedir": "/builds/slave/test", 05:17:36 INFO - "buildnumber": 89, 05:17:36 INFO - "slavename": "t-yosemite-r7-0170", 05:17:36 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:17:36 INFO - "platform": "yosemite_r7", 05:17:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:17:36 INFO - "branch": "mozilla-central", 05:17:36 INFO - "repo_path": "mozilla-central", 05:17:36 INFO - "revision": "9577ddeaafd85554c2a855f385a87472a089d5c0", 05:17:36 INFO - "stage_platform": "macosx64", 05:17:36 INFO - "builduid": "8d6ece6e690f4b5aa184b584b8ee1533", 05:17:36 INFO - "slavebuilddir": "test" 05:17:36 INFO - } 05:17:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:17:36 INFO - Running post-action listener: _resource_record_post_action 05:17:36 INFO - [mozharness: 2017-03-27 12:17:36.228664Z] Finished read-buildbot-config step (success) 05:17:36 INFO - [mozharness: 2017-03-27 12:17:36.228784Z] Running download-and-extract step. 05:17:36 INFO - Running pre-action listener: _resource_record_pre_action 05:17:36 INFO - Running main action method: download_and_extract 05:17:36 INFO - mkdir: /builds/slave/test/build/tests 05:17:36 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')]} 05:17:36 INFO - trying https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:17:36 INFO - Downloading https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/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 05:17:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/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 05:17:37 INFO - Downloaded 1325 bytes. 05:17:37 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:17:37 INFO - Using the following test package requirements: 05:17:37 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:17:37 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:17:37 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:17:37 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:17:37 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'jsshell-mac.zip'], 05:17:37 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:17:37 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:17:37 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:17:37 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:17:37 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:17:37 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:17:37 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:17:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:17:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:17:37 INFO - Fetch https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:17:39 INFO - Expected file size: 52418753 05:17:39 INFO - Obtained file size: 52418753 05:17:55 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 05:17:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:17:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:17:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:17:57 INFO - Expected file size: 52418753 05:17:57 INFO - Obtained file size: 52418753 05:18:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:18:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:18:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:18:13 INFO - Expected file size: 14404171 05:18:13 INFO - Obtained file size: 14404171 05:18:14 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')]} 05:18:14 INFO - trying https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:18:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:18:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/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 05:18:16 INFO - Downloaded 75541648 bytes. 05:18:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:18:16 INFO - mkdir: /builds/slave/test/properties 05:18:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:18:16 INFO - Writing to file /builds/slave/test/properties/build_url 05:18:16 INFO - Contents: 05:18:16 INFO - build_url:https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:18:17 INFO - Running post-action listener: _resource_record_post_action 05:18:17 INFO - Running post-action listener: set_extra_try_arguments 05:18:17 INFO - [mozharness: 2017-03-27 12:18:17.105868Z] Finished download-and-extract step (success) 05:18:17 INFO - [mozharness: 2017-03-27 12:18:17.105966Z] Running populate-webroot step. 05:18:17 INFO - Running pre-action listener: _resource_record_pre_action 05:18:17 INFO - Running main action method: populate_webroot 05:18:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:18:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:18:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:18:17 INFO - '--tppagecycles', 05:18:17 INFO - '7'], 05:18:17 INFO - 'tests': ['tsvgm']}}, 05:18:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:18:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:18:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:18:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:18:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:18:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:18:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:18:17 INFO - 'talos_options': ['--disable-e10s'], 05:18:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:18:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:18:17 INFO - 'talos_options': ['--disable-e10s'], 05:18:17 INFO - 'tests': ['damp', 'tps']}, 05:18:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:18:17 INFO - 'tests': ['damp', 'tps']}, 05:18:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:18:17 INFO - 'tests': ['dromaeo_dom']}, 05:18:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:18:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:18:17 INFO - 'tests': ['basic_compositor_video']}, 05:18:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:18:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:18:17 INFO - 'tests': ['a11yr', 05:18:17 INFO - 'ts_paint', 05:18:17 INFO - 'tpaint', 05:18:17 INFO - 'sessionrestore', 05:18:17 INFO - 'sessionrestore_no_auto_restore', 05:18:17 INFO - 'tabpaint']}, 05:18:17 INFO - 'other-e10s': {'tests': ['a11yr', 05:18:17 INFO - 'ts_paint', 05:18:17 INFO - 'tpaint', 05:18:17 INFO - 'sessionrestore', 05:18:17 INFO - 'sessionrestore_no_auto_restore', 05:18:17 INFO - 'tabpaint']}, 05:18:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:18:17 INFO - 'ts_paint', 05:18:17 INFO - 'tpaint', 05:18:17 INFO - 'sessionrestore', 05:18:17 INFO - 'sessionrestore_no_auto_restore', 05:18:17 INFO - 'tabpaint']}, 05:18:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:18:17 INFO - 'ts_paint', 05:18:17 INFO - 'tpaint', 05:18:17 INFO - 'sessionrestore', 05:18:17 INFO - 'sessionrestore_no_auto_restore', 05:18:17 INFO - 'tabpaint']}, 05:18:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:18:17 INFO - 'tests': ['a11yr', 05:18:17 INFO - 'ts_paint', 05:18:17 INFO - 'tpaint', 05:18:17 INFO - 'sessionrestore', 05:18:17 INFO - 'sessionrestore_no_auto_restore', 05:18:17 INFO - 'tabpaint']}, 05:18:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:18:17 INFO - 'tests': ['a11yr', 05:18:17 INFO - 'ts_paint', 05:18:17 INFO - 'tpaint', 05:18:17 INFO - 'sessionrestore', 05:18:17 INFO - 'sessionrestore_no_auto_restore', 05:18:17 INFO - 'tabpaint']}, 05:18:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:18:17 INFO - 'tests': ['tsvgx', 05:18:17 INFO - 'tsvgr_opacity', 05:18:17 INFO - 'tart', 05:18:17 INFO - 'tscrollx', 05:18:17 INFO - 'cart', 05:18:17 INFO - 'tsvg_static']}, 05:18:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:18:17 INFO - 'tsvgr_opacity', 05:18:17 INFO - 'tart', 05:18:17 INFO - 'tscrollx', 05:18:17 INFO - 'cart', 05:18:17 INFO - 'tsvg_static']}, 05:18:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:18:17 INFO - 'talos_options': ['--disable-e10s'], 05:18:17 INFO - 'tests': ['tp5o']}, 05:18:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:18:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:18:17 INFO - 'talos_options': ['--disable-e10s', 05:18:17 INFO - '--xperf_path', 05:18:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:17 INFO - 'tests': ['tp5n']}, 05:18:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:18:17 INFO - 'talos_options': ['--xperf_path', 05:18:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:17 INFO - 'tests': ['tp5n']}}, 05:18:17 INFO - 'talos.zip': {'path': '', 05:18:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:18:17 INFO - Running post-action listener: _resource_record_post_action 05:18:17 INFO - [mozharness: 2017-03-27 12:18:17.124565Z] Finished populate-webroot step (success) 05:18:17 INFO - [mozharness: 2017-03-27 12:18:17.124707Z] Running create-virtualenv step. 05:18:17 INFO - Running pre-action listener: _resource_record_pre_action 05:18:17 INFO - Running main action method: create_virtualenv 05:18:17 INFO - Creating virtualenv /builds/slave/test/build/venv 05:18:17 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 05:18:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:18:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:18:17 INFO - Using real prefix '/tools/python27' 05:18:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:18:17 INFO - Installing distribute.............................................................................................................................................................................................done. 05:18:20 INFO - Installing pip.................done. 05:18:20 INFO - Return code: 0 05:18:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:18:21 INFO - Reading from file tmpfile_stdout 05:18:21 INFO - Output received: 05:18:21 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) 05:18:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:18:21 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')]} 05:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:21 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')]} 05:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:21 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 0x100ad8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb0cd7a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b6d30>, '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 0x1007dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008abbe8>, '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.rzcsRia4XY/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.IzPZarhCHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:21 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 05:18:21 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 05:18:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzcsRia4XY/Render', 05:18:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:21 INFO - 'HOME': '/Users/cltbld', 05:18:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:21 INFO - 'LOGNAME': 'cltbld', 05:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:21 INFO - 'MOZ_NO_REMOTE': '1', 05:18:21 INFO - 'NO_EM_RESTART': '1', 05:18:21 INFO - 'PAGER': '/bin/cat', 05:18:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:21 INFO - 'PWD': '/builds/slave/test', 05:18:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:21 INFO - 'SHELL': '/bin/bash', 05:18:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IzPZarhCHC/Listeners', 05:18:21 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:18:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:21 INFO - 'USER': 'cltbld', 05:18:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:21 INFO - 'XPC_FLAGS': '0x0', 05:18:21 INFO - 'XPC_SERVICE_NAME': '0', 05:18:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:21 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 05:18:21 INFO - Cleaning up... 05:18:21 INFO - Return code: 0 05:18:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:18:21 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')]} 05:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:21 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')]} 05:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:21 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 0x100ad8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb0cd7a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b6d30>, '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 0x1007dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008abbe8>, '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.rzcsRia4XY/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.IzPZarhCHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:21 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 05:18:21 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 05:18:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzcsRia4XY/Render', 05:18:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:21 INFO - 'HOME': '/Users/cltbld', 05:18:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:21 INFO - 'LOGNAME': 'cltbld', 05:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:21 INFO - 'MOZ_NO_REMOTE': '1', 05:18:21 INFO - 'NO_EM_RESTART': '1', 05:18:21 INFO - 'PAGER': '/bin/cat', 05:18:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:21 INFO - 'PWD': '/builds/slave/test', 05:18:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:21 INFO - 'SHELL': '/bin/bash', 05:18:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IzPZarhCHC/Listeners', 05:18:21 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:18:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:21 INFO - 'USER': 'cltbld', 05:18:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:21 INFO - 'XPC_FLAGS': '0x0', 05:18:21 INFO - 'XPC_SERVICE_NAME': '0', 05:18:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:21 INFO - Downloading/unpacking psutil>=3.1.1 05:18:21 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 05:18:21 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 05:18:21 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 05:18:21 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 05:18:21 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 05:18:21 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 05:18:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:18:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:25 INFO - Installing collected packages: psutil 05:18:25 INFO - Running setup.py install for psutil 05:18:25 INFO - building 'psutil._psutil_osx' extension 05:18:25 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 05:18:25 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 05:18:25 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 05:18:26 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 05:18:26 INFO - building 'psutil._psutil_posix' extension 05:18:26 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 05:18:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:18:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:18:26 INFO - ^ 05:18:26 INFO - 1 warning generated. 05:18:26 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 05:18:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:26 INFO - Successfully installed psutil 05:18:26 INFO - Cleaning up... 05:18:26 INFO - Return code: 0 05:18:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:18:26 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')]} 05:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:26 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')]} 05:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:27 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 0x100ad8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb0cd7a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b6d30>, '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 0x1007dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008abbe8>, '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.rzcsRia4XY/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.IzPZarhCHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:27 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 05:18:27 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 05:18:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzcsRia4XY/Render', 05:18:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:27 INFO - 'HOME': '/Users/cltbld', 05:18:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:27 INFO - 'LOGNAME': 'cltbld', 05:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:27 INFO - 'MOZ_NO_REMOTE': '1', 05:18:27 INFO - 'NO_EM_RESTART': '1', 05:18:27 INFO - 'PAGER': '/bin/cat', 05:18:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:27 INFO - 'PWD': '/builds/slave/test', 05:18:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:27 INFO - 'SHELL': '/bin/bash', 05:18:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IzPZarhCHC/Listeners', 05:18:27 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:18:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:27 INFO - 'USER': 'cltbld', 05:18:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:27 INFO - 'XPC_FLAGS': '0x0', 05:18:27 INFO - 'XPC_SERVICE_NAME': '0', 05:18:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:18:27 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 05:18:27 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 05:18:27 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 05:18:27 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 05:18:27 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 05:18:27 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 05:18:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:18:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:18:30 INFO - Installing collected packages: mozsystemmonitor 05:18:30 INFO - Running setup.py install for mozsystemmonitor 05:18:30 INFO - Successfully installed mozsystemmonitor 05:18:30 INFO - Cleaning up... 05:18:30 INFO - Return code: 0 05:18:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:18:30 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')]} 05:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:30 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')]} 05:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:30 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 0x100ad8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb0cd7a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b6d30>, '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 0x1007dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008abbe8>, '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.rzcsRia4XY/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.IzPZarhCHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:30 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 05:18:30 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 05:18:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzcsRia4XY/Render', 05:18:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:30 INFO - 'HOME': '/Users/cltbld', 05:18:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:30 INFO - 'LOGNAME': 'cltbld', 05:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:30 INFO - 'MOZ_NO_REMOTE': '1', 05:18:30 INFO - 'NO_EM_RESTART': '1', 05:18:30 INFO - 'PAGER': '/bin/cat', 05:18:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:30 INFO - 'PWD': '/builds/slave/test', 05:18:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:30 INFO - 'SHELL': '/bin/bash', 05:18:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IzPZarhCHC/Listeners', 05:18:30 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:18:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:30 INFO - 'USER': 'cltbld', 05:18:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:30 INFO - 'XPC_FLAGS': '0x0', 05:18:30 INFO - 'XPC_SERVICE_NAME': '0', 05:18:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:31 INFO - Downloading/unpacking jsonschema==2.5.1 05:18:31 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 05:18:31 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 05:18:31 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 05:18:31 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 05:18:31 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 05:18:31 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 05:18:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:18:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:18:33 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 05:18:33 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 05:18:33 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 05:18:33 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 05:18:33 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 05:18:33 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 05:18:34 INFO - Downloading functools32-3.2.3-2.tar.gz 05:18:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:18:34 INFO - warning: no files found matching '*.txt' 05:18:34 INFO - no previously-included directories found matching 'build' 05:18:34 INFO - no previously-included directories found matching 'dist' 05:18:34 INFO - no previously-included directories found matching '.git*' 05:18:34 INFO - Installing collected packages: jsonschema, functools32 05:18:34 INFO - Running setup.py install for functools32 05:18:34 INFO - warning: no files found matching '*.txt' 05:18:34 INFO - no previously-included directories found matching 'build' 05:18:34 INFO - no previously-included directories found matching 'dist' 05:18:34 INFO - no previously-included directories found matching '.git*' 05:18:34 INFO - Successfully installed jsonschema functools32 05:18:34 INFO - Cleaning up... 05:18:34 INFO - Return code: 0 05:18:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:18:34 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')]} 05:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:34 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')]} 05:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:34 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 0x100ad8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb0cd7a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b6d30>, '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 0x1007dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008abbe8>, '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.rzcsRia4XY/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.IzPZarhCHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:34 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 05:18:34 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 05:18:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzcsRia4XY/Render', 05:18:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:34 INFO - 'HOME': '/Users/cltbld', 05:18:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:34 INFO - 'LOGNAME': 'cltbld', 05:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:34 INFO - 'MOZ_NO_REMOTE': '1', 05:18:34 INFO - 'NO_EM_RESTART': '1', 05:18:34 INFO - 'PAGER': '/bin/cat', 05:18:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:34 INFO - 'PWD': '/builds/slave/test', 05:18:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:34 INFO - 'SHELL': '/bin/bash', 05:18:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IzPZarhCHC/Listeners', 05:18:34 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:18:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:34 INFO - 'USER': 'cltbld', 05:18:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:34 INFO - 'XPC_FLAGS': '0x0', 05:18:34 INFO - 'XPC_SERVICE_NAME': '0', 05:18:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:18:34 INFO - Cleaning up... 05:18:34 INFO - Return code: 0 05:18:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:18:34 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')]} 05:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:34 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')]} 05:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:34 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 0x100ad8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb0cd7a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b6d30>, '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 0x1007dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008abbe8>, '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.rzcsRia4XY/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.IzPZarhCHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:34 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 05:18:34 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 05:18:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzcsRia4XY/Render', 05:18:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:34 INFO - 'HOME': '/Users/cltbld', 05:18:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:34 INFO - 'LOGNAME': 'cltbld', 05:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:34 INFO - 'MOZ_NO_REMOTE': '1', 05:18:34 INFO - 'NO_EM_RESTART': '1', 05:18:34 INFO - 'PAGER': '/bin/cat', 05:18:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:34 INFO - 'PWD': '/builds/slave/test', 05:18:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:34 INFO - 'SHELL': '/bin/bash', 05:18:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IzPZarhCHC/Listeners', 05:18:34 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:18:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:34 INFO - 'USER': 'cltbld', 05:18:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:34 INFO - 'XPC_FLAGS': '0x0', 05:18:34 INFO - 'XPC_SERVICE_NAME': '0', 05:18:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:35 INFO - Downloading/unpacking blobuploader==1.2.4 05:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:37 INFO - Downloading blobuploader-1.2.4.tar.gz 05:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:18:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:18:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:38 INFO - Downloading docopt-0.6.1.tar.gz 05:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:18:39 INFO - Installing collected packages: blobuploader, requests, docopt 05:18:39 INFO - Running setup.py install for blobuploader 05:18:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:18:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:18:39 INFO - Running setup.py install for requests 05:18:39 INFO - Running setup.py install for docopt 05:18:39 INFO - Successfully installed blobuploader requests docopt 05:18:39 INFO - Cleaning up... 05:18:40 INFO - Return code: 0 05:18:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ad8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb0cd7a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b6d30>, '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 0x1007dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008abbe8>, '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.rzcsRia4XY/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.IzPZarhCHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzcsRia4XY/Render', 05:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:40 INFO - 'HOME': '/Users/cltbld', 05:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:40 INFO - 'LOGNAME': 'cltbld', 05:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:40 INFO - 'MOZ_NO_REMOTE': '1', 05:18:40 INFO - 'NO_EM_RESTART': '1', 05:18:40 INFO - 'PAGER': '/bin/cat', 05:18:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:40 INFO - 'PWD': '/builds/slave/test', 05:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:40 INFO - 'SHELL': '/bin/bash', 05:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IzPZarhCHC/Listeners', 05:18:40 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:40 INFO - 'USER': 'cltbld', 05:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:40 INFO - 'XPC_FLAGS': '0x0', 05:18:40 INFO - 'XPC_SERVICE_NAME': '0', 05:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:40 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-8yRSZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:40 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-5UMIWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:40 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-hzsl9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:40 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-UywQJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:40 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-bycDqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:40 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-mVMQlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:40 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-_212LF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:41 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-wEiEnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:41 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-g0uAxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:41 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-QeSHpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:41 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-C_mMUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:41 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-yFDTJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:41 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-1gEkdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:41 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Wu4WAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:41 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-zxyWMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:41 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-tItJMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:42 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-rSq7ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:18:42 INFO - Running setup.py install for manifestparser 05:18:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:18:42 INFO - Running setup.py install for mozcrash 05:18:42 INFO - Running setup.py install for mozdebug 05:18:42 INFO - Running setup.py install for mozdevice 05:18:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:18:42 INFO - Running setup.py install for mozfile 05:18:42 INFO - Running setup.py install for mozhttpd 05:18:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:18:42 INFO - Running setup.py install for mozinfo 05:18:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:18:43 INFO - Running setup.py install for mozInstall 05:18:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:18:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:18:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:18:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:18:43 INFO - Running setup.py install for mozleak 05:18:43 INFO - Running setup.py install for mozlog 05:18:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:18:43 INFO - Running setup.py install for moznetwork 05:18:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:18:43 INFO - Running setup.py install for mozprocess 05:18:43 INFO - Running setup.py install for mozprofile 05:18:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:18:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:18:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:18:44 INFO - Running setup.py install for mozrunner 05:18:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:18:44 INFO - Running setup.py install for mozscreenshot 05:18:44 INFO - Running setup.py install for moztest 05:18:44 INFO - Running setup.py install for mozversion 05:18:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:18:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:18:44 INFO - Cleaning up... 05:18:44 INFO - Return code: 0 05:18:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:44 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 0x100ad8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb0cd7a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b6d30>, '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 0x1007dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008abbe8>, '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.rzcsRia4XY/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.IzPZarhCHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:44 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 05:18:44 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 05:18:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzcsRia4XY/Render', 05:18:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:44 INFO - 'HOME': '/Users/cltbld', 05:18:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:44 INFO - 'LOGNAME': 'cltbld', 05:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:44 INFO - 'MOZ_NO_REMOTE': '1', 05:18:44 INFO - 'NO_EM_RESTART': '1', 05:18:44 INFO - 'PAGER': '/bin/cat', 05:18:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:44 INFO - 'PWD': '/builds/slave/test', 05:18:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:44 INFO - 'SHELL': '/bin/bash', 05:18:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IzPZarhCHC/Listeners', 05:18:44 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:18:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:44 INFO - 'USER': 'cltbld', 05:18:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:44 INFO - 'XPC_FLAGS': '0x0', 05:18:44 INFO - 'XPC_SERVICE_NAME': '0', 05:18:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:44 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-iuTp7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:45 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-BwxmdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:45 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)) 05:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:45 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-4Ys_xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:45 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Fe5Olq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:45 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)) 05:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:45 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-VoT63W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:45 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-wzwuhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:45 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)) 05:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:45 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Vz8MTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:45 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)) 05:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:45 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-phq2cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:45 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)) 05:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:45 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-_sMtLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:45 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)) 05:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:45 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-H_SjXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:45 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)) 05:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:45 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-iyJckU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:46 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-5V0cEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:46 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)) 05:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:46 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-VeL9VL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:46 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-oD6xFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:46 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)) 05:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:46 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-3n11vS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:46 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)) 05:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:46 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ZuOv7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:46 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)) 05:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:46 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-VbRzgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:46 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)) 05:18:46 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)) 05:18:46 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)) 05:18:46 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)) 05:18:46 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)) 05:18:46 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)) 05:18:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:46 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 05:18:46 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 05:18:46 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 05:18:46 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 05:18:46 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 05:18:46 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 05:18:49 INFO - Downloading blessings-1.6.tar.gz 05:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:18:49 INFO - Installing collected packages: blessings 05:18:49 INFO - Running setup.py install for blessings 05:18:49 INFO - Successfully installed blessings 05:18:49 INFO - Cleaning up... 05:18:49 INFO - Return code: 0 05:18:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:18:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:49 INFO - Reading from file tmpfile_stdout 05:18:49 INFO - Current package versions: 05:18:49 INFO - blessings == 1.6 05:18:49 INFO - blobuploader == 1.2.4 05:18:49 INFO - docopt == 0.6.1 05:18:49 INFO - functools32 == 3.2.3-2 05:18:49 INFO - jsonschema == 2.5.1 05:18:49 INFO - manifestparser == 1.1 05:18:49 INFO - mozInstall == 1.12 05:18:49 INFO - mozcrash == 1.0 05:18:49 INFO - mozdebug == 0.1 05:18:49 INFO - mozdevice == 0.49 05:18:49 INFO - mozfile == 1.2 05:18:49 INFO - mozhttpd == 0.7 05:18:49 INFO - mozinfo == 0.9 05:18:49 INFO - mozleak == 0.1 05:18:49 INFO - mozlog == 3.4 05:18:49 INFO - moznetwork == 0.27 05:18:49 INFO - mozprocess == 0.25 05:18:49 INFO - mozprofile == 0.28 05:18:49 INFO - mozrunner == 6.13 05:18:49 INFO - mozscreenshot == 0.1 05:18:49 INFO - mozsystemmonitor == 0.3 05:18:49 INFO - moztest == 0.8 05:18:49 INFO - mozversion == 1.4 05:18:49 INFO - psutil == 3.1.1 05:18:49 INFO - requests == 1.2.3 05:18:49 INFO - wsgiref == 0.1.2 05:18:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:49 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 0x100ad8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb0cd7a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b6d30>, '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 0x1007dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008abbe8>, '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.rzcsRia4XY/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.IzPZarhCHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:49 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 05:18:49 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 05:18:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzcsRia4XY/Render', 05:18:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:49 INFO - 'HOME': '/Users/cltbld', 05:18:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:49 INFO - 'LOGNAME': 'cltbld', 05:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:49 INFO - 'MOZ_NO_REMOTE': '1', 05:18:49 INFO - 'NO_EM_RESTART': '1', 05:18:49 INFO - 'PAGER': '/bin/cat', 05:18:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:49 INFO - 'PWD': '/builds/slave/test', 05:18:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:49 INFO - 'SHELL': '/bin/bash', 05:18:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IzPZarhCHC/Listeners', 05:18:49 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:18:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:49 INFO - 'USER': 'cltbld', 05:18:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:49 INFO - 'XPC_FLAGS': '0x0', 05:18:49 INFO - 'XPC_SERVICE_NAME': '0', 05:18:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:18:50 INFO - Cleaning up... 05:18:50 INFO - Return code: 0 05:18:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ad8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cb0cd7a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b6d30>, '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 0x1007dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008abbe8>, '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.rzcsRia4XY/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.IzPZarhCHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:18:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzcsRia4XY/Render', 05:18:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:50 INFO - 'HOME': '/Users/cltbld', 05:18:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:50 INFO - 'LOGNAME': 'cltbld', 05:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:50 INFO - 'MOZ_NO_REMOTE': '1', 05:18:50 INFO - 'NO_EM_RESTART': '1', 05:18:50 INFO - 'PAGER': '/bin/cat', 05:18:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:50 INFO - 'PWD': '/builds/slave/test', 05:18:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:50 INFO - 'SHELL': '/bin/bash', 05:18:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IzPZarhCHC/Listeners', 05:18:50 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:18:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:50 INFO - 'USER': 'cltbld', 05:18:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:50 INFO - 'XPC_FLAGS': '0x0', 05:18:50 INFO - 'XPC_SERVICE_NAME': '0', 05:18:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:18:50 INFO - Cleaning up... 05:18:50 INFO - Return code: 0 05:18:50 INFO - Running post-action listener: _resource_record_post_action 05:18:50 INFO - Running post-action listener: _start_resource_monitoring 05:18:50 INFO - Starting resource monitoring. 05:18:50 INFO - [mozharness: 2017-03-27 12:18:50.515254Z] Finished create-virtualenv step (success) 05:18:50 INFO - [mozharness: 2017-03-27 12:18:50.516202Z] Running install step. 05:18:50 INFO - Running pre-action listener: _resource_record_pre_action 05:18:50 INFO - Running main action method: install 05:18:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:50 INFO - Reading from file tmpfile_stdout 05:18:50 INFO - Detecting whether we're running mozinstall >=1.0... 05:18:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:18:50 INFO - Reading from file tmpfile_stdout 05:18:50 INFO - Output received: 05:18:50 INFO - Usage: mozinstall [options] installer 05:18:50 INFO - Options: 05:18:50 INFO - -h, --help show this help message and exit 05:18:50 INFO - -d DEST, --destination=DEST 05:18:50 INFO - Directory to install application into. [default: 05:18:50 INFO - "/builds/slave/test"] 05:18:50 INFO - --app=APP Application being installed. [default: firefox] 05:18:50 INFO - mkdir: /builds/slave/test/build/application 05:18:50 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'] 05:18:50 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 05:19:11 INFO - Reading from file tmpfile_stdout 05:19:11 INFO - Output received: 05:19:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:19:11 INFO - Running post-action listener: _resource_record_post_action 05:19:11 INFO - [mozharness: 2017-03-27 12:19:11.427024Z] Finished install step (success) 05:19:11 INFO - [mozharness: 2017-03-27 12:19:11.427124Z] Running run-tests step. 05:19:11 INFO - Running pre-action listener: _resource_record_pre_action 05:19:11 INFO - Running main action method: run_tests 05:19:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:11 INFO - 2017-03-27 05:19:11.441 screenresolution[1623:7481] starting screenresolution argv=screenresolution get 05:19:11 INFO - 2017-03-27 05:19:11.444 screenresolution[1623:7481] Display 0: 1600x1200x32@60 05:19:11 INFO - 2017-03-27 05:19:11.449 screenresolution[1624:7483] starting screenresolution argv=screenresolution list 05:19:11 INFO - Available Modes on Display 0 05:19:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:11 INFO - Intel Iris: 05:19:11 INFO - Chipset Model: Intel Iris 05:19:11 INFO - Type: GPU 05:19:11 INFO - Bus: Built-In 05:19:11 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:11 INFO - Vendor: Intel (0x8086) 05:19:11 INFO - Device ID: 0x0a2e 05:19:11 INFO - Revision ID: 0x0009 05:19:11 INFO - Displays: 05:19:11 INFO - Display: 05:19:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:11 INFO - Display Serial Number: 1600x1200 60 05:19:11 INFO - Main Display: Yes 05:19:11 INFO - Mirror: Off 05:19:11 INFO - Online: Yes 05:19:11 INFO - Rotation: Supported 05:19:11 INFO - Return code: 0 05:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:19:11 INFO - Python 2.7.3 05:19:11 INFO - Return code: 0 05:19:11 INFO - grabbing minidump binary from tooltool 05:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:11 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 0x1008b6d30>, '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 0x1007dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008abbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:19:11 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 05:19:11 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 05:19:11 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 05:19:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:19:11 INFO - Return code: 0 05:19:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:19:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:19:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:19:11 INFO - ENV: RUST_BACKTRACE is now 1 05:19:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:19:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzcsRia4XY/Render', 05:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:11 INFO - 'HOME': '/Users/cltbld', 05:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:11 INFO - 'LOGNAME': 'cltbld', 05:19:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:19:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:11 INFO - 'MOZ_NO_REMOTE': '1', 05:19:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:19:11 INFO - 'NO_EM_RESTART': '1', 05:19:11 INFO - 'PAGER': '/bin/cat', 05:19:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:11 INFO - 'PWD': '/builds/slave/test', 05:19:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:11 INFO - 'RUST_BACKTRACE': '1', 05:19:11 INFO - 'SHELL': '/bin/bash', 05:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IzPZarhCHC/Listeners', 05:19:11 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:11 INFO - 'USER': 'cltbld', 05:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:11 INFO - 'XPC_FLAGS': '0x0', 05:19:11 INFO - 'XPC_SERVICE_NAME': '0', 05:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:19:12 INFO - mozversion application_buildid: 20170327035815 05:19:12 INFO - mozversion application_changeset: 9577ddeaafd85554c2a855f385a87472a089d5c0 05:19:12 INFO - mozversion application_display_name: Nightly 05:19:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:19:12 INFO - mozversion application_name: Firefox 05:19:12 INFO - mozversion application_remotingname: firefox 05:19:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:19:12 INFO - mozversion application_vendor: Mozilla 05:19:12 INFO - mozversion application_version: 55.0a1 05:19:12 INFO - mozversion platform_buildid: 20170327035815 05:19:12 INFO - mozversion platform_changeset: 9577ddeaafd85554c2a855f385a87472a089d5c0 05:19:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:19:12 INFO - mozversion platform_version: 55.0a1 05:19:12 INFO - using testdate: 1490617152 05:19:12 INFO - actual date: 1490617152 05:19:12 INFO - starting webserver on 'localhost:49241' 05:19:12 INFO - SUITE-START | Running 2 tests 05:19:12 INFO - TEST-START | dromaeo_css 05:19:12 INFO - operating with platform_type : mac_ 05:19:12 INFO - Initialising browser for dromaeo_css test... 05:19:12 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpqsgcjy/profile http://localhost:49241/getInfo.html) 05:19:14 INFO - PID 1634 | __metrics Screen width/height:1600/1200 05:19:14 INFO - PID 1634 | colorDepth:24 05:19:14 INFO - PID 1634 | Browser inner width/height: 1024/689 05:19:14 INFO - PID 1634 | __metrics 05:19:14 ERROR - PID 1634 | 1490617154701 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 05:19:14 INFO - PID 1634 | 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" 05:19:14 INFO - PID 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:19:15 INFO - TEST-INFO | 1634: exit 0 05:19:15 INFO - Browser initialized. 05:19:15 INFO - Running cycle 1/1 for dromaeo_css test... 05:19:15 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpqsgcjy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:27 INFO - PID 1636 | RSS: Main: 234061824 05:19:27 INFO - PID 1636 | 05:22:31 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 05:22:34 INFO - PID 1636 | RSS: Main: 408297472 05:22:34 INFO - PID 1636 | 05:24:17 INFO - PID 1636 | ************************* 05:24:17 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 05:24:17 INFO - PID 1636 | 05:24:17 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:24:17 INFO - PID 1636 | Full stack: 05:24:17 INFO - PID 1636 | ************************* 05:24:17 INFO - PID 1636 | ************************* 05:24:17 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 05:24:17 INFO - PID 1636 | 05:24:17 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:24:17 INFO - PID 1636 | Full stack: 05:24:17 INFO - PID 1636 | ************************* 05:24:17 INFO - PID 1636 | ************************* 05:24:17 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 05:24:17 INFO - PID 1636 | 05:24:17 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:24:17 INFO - PID 1636 | Full stack: 05:24:17 INFO - PID 1636 | ************************* 05:24:17 INFO - PID 1636 | ************************* 05:24:17 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 05:24:17 INFO - PID 1636 | 05:24:17 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:24:17 INFO - PID 1636 | Full stack: 05:24:17 INFO - PID 1636 | ************************* 05:25:37 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 05:25:38 INFO - PID 1636 | RSS: Main: 367050752 05:25:38 INFO - PID 1636 | 05:28:40 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 05:28:41 INFO - PID 1636 | RSS: Main: 343699456 05:28:41 INFO - PID 1636 | 05:31:43 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 05:31:44 INFO - PID 1636 | RSS: Main: 335605760 05:31:44 INFO - PID 1636 | 05:34:47 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 05:34:48 INFO - PID 1636 | RSS: Main: 343638016 05:34:48 INFO - PID 1636 | 05:37:52 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 05:37:52 INFO - PID 1636 | RSS: Main: 490889216 05:37:52 INFO - PID 1636 | 05:37:52 INFO - PID 1636 | __start_tp_report 05:37:52 INFO - PID 1636 | _x_x_mozilla_page_load 05:37:52 INFO - PID 1636 | _x_x_mozilla_page_load_details 05:37:52 INFO - PID 1636 | |i|pagename|runs| 05:37:52 INFO - PID 1636 | |0;dojo.html;3273.2307907641707;3448.7758295710782;3485.546878905745;3495.5106285120073;3560.8397622107004;5990.640561566309;6101.298350689672;6165.414285642868;6216.471599914007;6218.813435596933;6101.542384321183;6123.130109042722;6148.3851614838495;6174.228221472316;6177.783777567786;5358.785648574052;5519.668819870801;5523.861903452425;5538.723063846802;5580.4698553637545;5581.6371935824045;5652.547796176316;5653.462921022497;5657.405972372882;5714.428557144273;5405.594580406446;5485.149801780731;5495.752691128909;5506.256655351556;5571.6378435401775;5578.581606379537;5583.720813959286;5619.129034999582;5641.210230567724;5651.06757385031;5476.808311709111;5503.477169668875;5511.53151982082;5542.39033706292;5543.473370029841;6036.939630603725;6149.78475753351;6176.320604733476;6184.536159788034;6209.441150296495;6350.142730731338;6364.6181229126405;6450.774222902222;6560.93439065608;6578.210614726264;5944.673042982634;6588.703508342088;6649.268580456146;6672.065910772496;6674.666266686695;6103.29812071613;6241.750329986795;6260.843478913018;6269.46709529686;6269.780557680459;5566.944330556724;6508.186476690414;6521.510886683518;6761.222459417131;6779.559328643622;6311.747530098836;6356.3325850785;6404.935950640527;6412.198475190601;6417.614943103429;6562.409383155444;6596.538242323088;6632.403083722488;6632.933670663231;6647.036179753909;6515.315891831384;6520.956646936467;6522.054302126166;6529.869402611921;6531.0529973153625;6286.497080233662;6389.7763578274535;6416.486681065504;6498.480121590356;6509.739610415625;5288.04815133272;5324.787008519694;5326.733663316819;5329.733513324319;5339.599530035263;4648.535146485392;4700.835470758507;4702.435707715096;4703.17694403478;4740.431148262231;6203.689815509207;6219.657918814509;6226.40849119333;6226.59527130735;6233.065040244;6393.776217832354;6474.125992990886;6478.2873883872735;6481.773137940244;6492.188476440445;6132.8756461173125;6136.917362303815;6144.709610981655;6148.9854174061675;6162.476189523941;5848.707564621752;5878.882422351529;5911.290645122613;5934.198883150806;5941.673207973517;6822.761203357859;6832.45340372769;6836.897446538206;6842.281560436281;6842.726290948306;5512.283403157564;6675.299093595103;6695.397414232742;6702.296258892941;6708.26209116987;6484.1894763154605;6581.407673309425;6588.967055164693;6591.967040164768;6725.966370168314;6524.9673751630935;6546.639934803631;6555.80332590023;6572.948328267455;6583.539152259296;5598.272224610774;6644.368784965421;6644.700988455627;6646.966765166337;6648.634325112165;6262.530310226843;6340.207474065742;6423.775167869289;6428.582142160744;6445.0332450132855;6331.651759153291;6344.270408903035;6344.619322840645;6350.301466838737;6358;6036.939630603638;6101.481374083075;6283.02613094971;6306.054091886253;6306.779262725966;6310.558260921875;6397.808065758035;6405.807825765234;6410.230772307353;6419.614823110628;6273.059041143682;6278.529110316836;6281.497480201664;6287.811365659038;6289.371062893674 05:37:52 INFO - PID 1636 | |1;ext.html;699.9300069993002;1112.0380870546978;1147.4778975566119;1155.272178527527;1156.9247998880078;36373.63626363739;37046;37350.43974340384;37388.81305593468;37402;38667;39297;40876;40931.79534102325;40935.38596921049;6122.47958923492;8409.990801103877;8558.486490810541;8562.657493700244;8572.742817715478;6170.660613666247;8331.541765202912;8513.233808957193;8514.531700756455;8527.232549070583;6672.933270667304;7873.291403773659;8583;8631.827363452727;8639.43843650164;6101.786437474711;8251.051129120138;8591.140885911422;8592.957035214815;8608.698695545625;6091.2995005574485;6885.449164066863;8334.66661333546;8339.499630022217;8377.036640786326;37022.889313320644;37393.81303093494;37426.43860342097;37427.62572374296;37502;3026.576279320897;3040.498317777564;3043.9847800761077;3048.7713421493477;3086.33643766589;23027.539449210923;27012.729872701217;27163.320916977034;27205.591916121273;28065.719342806515;2804.901828435995;2845.772338212938;2846.131929761422;2846.51609226432;2857.4713677969644;295748;348582;351425.4857451418;353553;354184;38157.80921095377;38895.80552097278;38924.80537597294;49991.50008499941;50267.497325026285;42784.57215427806;42788.35817462741;42838.357424638656;42883.785581072516;48658.02683946301;13972.790408143886;14287.357068931922;14308;14321.212333321559;14393.712125757636;11945.641630751092;11986.64040078799;11986.820197697041;12008.099392545593;12036.518539258332;12271.282020517136;12301.876981230253;12312.50749970009;12318;12426.75146497065;4451.866444006685;4807.351007613927;5485.725713714299;5507.504324610804;5553.333599968029;918.6463211663424;969.3263182088381;975.8243516167158;976.8339382305026;981.4356744871727;24140.63789043166;24141.396465088514;24186.637200442008;24260;24341.26976190717;1031.7936412717486;1076.5478499030426;1096.9561217551368;1105.9336439813637;1117.4189421500773;11493.138014648935;12248.755024899452;12289.324087175291;12291.56979505713;12318.630441086782;455483;455564;455614;456530;457150;54620.72689636526;54688.45311546913;54761;54797.72601137048;54922.450775491736;1467.2736995187452;1487.9628009299854;1528.2817075974497;1530.946416875404;1539.6227924158652;19825.900870495556;21173;23029.424264392856;23527.882360588086;23605;38762.6123738759;38777.224455510164;38853;38872.416913746885;38917.221655566165;441378;442700;443444;444281;445491.7725411352;25339.113131040325;25894;28530.85734571314;32818;33132;39654.60345396624;40493.59506404898;40792.98017549466;40851;40926.59073409228;28290.43419131647;28780.280492987844;28949.276268093465;29278.70721292845;29947.70052299449;29396.55905161467;29476.705232947395;29508.704912950594;29695.25761855971;29726.25684357908;25903.740962590135;34572.654273456945;34702.13244668904;34964.650353497156;35266.471002934464;29443.558346625246;29450.410991780474;29879.701202988563;30114.54828177535;30195.396092078474;22122.611337650967;22157.335279941628;23456.061757529507;23925.282241532783;24240.393990150387 05:37:52 INFO - PID 1636 | |2;jquery.html;411.2884709452647;420.88004918598205;424.54573606241337;429.96990210685243;442.8538582267852;19643.508912277182;21430.678539821914;22229.55540889181;22317.44206394841;22344.21795237167;18090.638187236247;21582.676259856114;21730;21820.236291729794;22427.55144897101;6050.939490605104;6345.048242763576;6350.460210882081;6353.714082866282;6355.332690067546;5983.401659834025;6161.168242287301;6204.224471941007;6284.371562843725;6289.402506761873;5696.1171018492;5893.145493903381;6257.311695713491;6257.624542527463;6259.248890133168;5992.640441573498;6199.318075011745;6231.6572588507615;6271.560990730651;6277.623342599459;4580.587747102777;5461.617686761928;5516.289950115477;5550.611457197998;5552.250446189753;26947.05685301005;27090.5936410954;27102.78037488318;27112;27425.31436714078;2062.4431403521116;2068.141721185706;2078.4803799050237;2085.603735290305;2160.3950893749775;15569.143697096655;15713.05721656693;16559;16967.57581060471;16987.91506042474;1409.9083559568596;1499.4428082464367;1650.9339626414928;1672.1221358786613;1677.9580510487212;143721;146941;148709.99999999892;149510;152529;11051.557937682563;11833.644990650295;11869.821952670716;11985.580504682293;12218.083643726755;14355.425782968774;15976.440824571084;15993.68012639764;15997.040177589382;16010.599735006717;8508.215785401826;8793.384463087523;8854.911450885409;8963.686270980485;9005.504697241715;6706.497012723968;7074.8680211165965;8447.157121270491;8463.111373305837;8473.23740863313;7432.851342973218;7573.697052117854;7581.431392645573;7598.696052157852;7601.353884919844;2077.6883467479997;2080.989595052015;2083.5207902182583;2085.2493102483095;2112.3979665795173;848.866762871561;902.8329758994607;927.6335847340398;959.4579062829479;966.8066386722684;20378.286759963332;20984.475388115115;21040.78959210419;21092.367228983156;21099.78900211009;949.7530642033121;971.9805603888024;978.5107446276861;979.480875136179;981.4356744871727;9115.316351273548;9433.811323773487;10863.51114199863;10863.782724345627;10870.728231794268;706312;709876;716448;724417;730863;153995;159182.20408897882;161030;165773.17113414596;167912;1127.9887201127883;1173.8473998380157;1186.549111337686;1193.2542161149108;1194.904407647386;15103.741055946548;17797.288108475517;17807;18569.257229711202;18649.906750466704;59014.70492647509;62176.68911655413;62347.6882615584;63859.8070096484;64538.06385808433;81859.59070204849;84985;86870;91195.54402228212;95216;8120.75637730869;8166.366726654812;8363.707270245512;8435.746927592181;8491.74524764258;12406.379681016093;12900.741985160432;13132.474701011852;13927.58217253484;14008.299585020912;11509.366984815915;11828.704282392664;11879.58421455245;11944.64166075019;11952.983996360059;11054.281471704313;11175.776484470427;11334.829977550178;11533.308001519936;11555.133364997491;11753.647390578297;11894.583689570823;12147.149699520949;12230.877691222973;12252.448639811051;7392;8404.537750423786;8404.831903361775;8885.333599979898;8954.865677014981;11579.536818527165;11735.823962640396;12041.578544750892;12057.276563406223;12113.818292725793 05:37:52 INFO - PID 1636 | |3;mootools.html;1186.6380753870067;1310.3186343101586;1446.9710605787884;1472.6980968901375;1480.1563109027852;9245.4452732836;9660.130588247057;9665.855012174814;9671.613135474581;9675.322727409084;9079.137481939222;9375.90624093759;10327.27709060366;10784.406857622827;10796.298240614355;2410.6745589345433;2468.888899999495;2469.2468797016904;2472.4066224106264;2475.492524032578;2219.511707424364;2336.906523739048;2375.257081136533;2463.802895768343;2486.2292689266296;2057.6913462980524;2145.603063433262;2224.310463756233;2425.5876500994873;2427.1383658801137;2427.8421902576274;2447.2535876557554;2447.547203767309;2448.4368595223023;2450.9264722058365;2409.265174121892;2417.335232810991;2430.890609922558;2435.257246539805;2440.3289095498703;9269.44383337002;10310.948445257725;10386.16910647154;10592.993665601756;10741.194410419172;6060.33336333003;6284.591501552384;6297.779577714758;6299;6306.621602703853;3804.8478060877533;3831.080540670219;4003.97998010011;4091.4476545666253;4093.5497095319492;977.9364341317792;1190.2343304808032;1206.0532482001586;1206.2942283001364;1217.0993464836047;14751.92624036873;14894.70210595782;15067.246637668182;15071.006183361527;15078.773818392732;5224.164133738584;5415.972920135374;5508.6970216637665;5684.8863022739315;5716.256886604715;12607.306598137007;12626.494940202472;12641.557545485864;12655.93672031634;12660.873391266154;6374.171357723466;6375.904361434582;6380.680965951684;6385.680715964183;6406.743730250738;4073.877783666495;5674.092145256739;5748.7125643717645;5750.079987202028;5779.075347944393;4226.4716910386205;4310.568943105727;4412.867613971522;4456.019675671347;4469.821207151743;1344.28080976677;1383.4604504243352;1394.5816255123423;1398.7901814727873;1411.1462565148142;728.9635518224068;766.7354762606892;787.9921200788034;792.8216151366012;803.1085495100434;6218.1916350875;6421.486281097595;6636.800895973129;6664.200295964515;6686.860204734438;565.8387359602576;580.451473357673;590.2032256453753;597.0924195223323;860.2172023458622;4062.086030643082;4063.146739184745;4135.400366946776;4180.581941805856;4208.642265407413;13480.191188528719;13999.970004349516;14000;14007.08953917992;14040.719185616435;12980.15628984113;13503.729925401436;13548.119372240773;13890.93054534741;13903.4438622456;9763.707088787347;10037.949810251048;10049.598016079275;10051.949740251252;10054.396736195851;6372.107904893412;6670.83322916931;6863.347981941708;6912.27421120775;6959.965200173967;12234.44944977496;12357.567485137977;12369.381530923598;12509.87490125087;12669.93665031669;12704.36478176106;12775;12817.013362928761;12944.288064156184;13019.088663793444;8967.53870423531;9017.549122543714;9034.909650903406;9145.77135571616;9157.49633770149;9838.852417213604;9922.801543968935;10024.849627255451;10041.397516149054;10059.949700251453;8549;8557.700480483154;8566.229039386486;8597.957010214908;8610.741677749678;8312.168783121639;8338.666453341799;8407.369447291356;8446.746597602081;8498.107698691549;6549.886519291636;7619.4285428594185;7758.612069396395;7764.805879852823;7782.649780759766;5836.503897168788;5907.793227237026;5924.496417804535;6025.1564780931585;6030.668313242813;5885;5946.524278057832;5987;6100.206973093469;6131.198656832614 05:37:52 INFO - PID 1636 | |4;prototype.html;334.10126759018254;390.46506286387654;397.9104701442176;402.09729158040204;403.70327809060336;4688.695234809739;5641.887162256752;5969.492593129578;6020.789272375468;6084.72618732157;5906.586538942265;6068.33248342682;6091.573589848713;6103.7253323600435;6111.7249723762425;4442.400275962752;4596.81612735491;4614.700044497097;4615.653825963066;4633.559811817872;3919.510061242345;4565.178267911774;4600.2869555218995;4605.332226827108;4621.399218101642;3765.491658626078;4556.749378784166;4574.405327307462;4593.885152871171;4598.0344127733215;3903.945934597643;4401.86794396167;4560.042391097873;4586.266197408432;4606.424196975378;3191.64891861895;3879.2047630235734;3896.902577435558;3916.099297161669;3921.7058720595783;4618.445786505641;5226.5818734501145;5936.881262374772;5951.791687290924;5955.374685657986;4415.889602759925;4961.751912404401;5054.9241761373605;5124.743762811881;5302.893942121174;5344.91982620261;5929.6145750526075;5986.221791167164;6447.161868957052;6469.288378278386;3645.8541658333634;4372.07628643399;4375.562443755631;4382.8904277393;4383.342498625232;5322.414534401213;5722.942770572325;6763.695633696446;6830.795076147723;6891;4425.159219748302;4437.755923424178;4438.467383913951;4449.51055383914;4450.176717307244;4193.874183774492;4507.864764057083;4534.7052441591195;4538.88652783683;4541.159885421142;4899.853004409873;5055.721935293521;5250.632455728062;5343.145096784496;5357.946420535823;5437.918431223535;5873.676947767828;6419.395151212197;6544.4882342068995;6558.573692709959;1508.9849101508844;1529.495266562032;1546.180524322111;1547.667251540924;1551.1391177896376;1005.1255407795265;1017.2726500552141;1020.1175982774846;1021.7942827463515;1030.1295405382439;6159.81520554384;6200.81397558074;6205.007198848253;6206.875862482725;6208.844778880564;6512.6092434454085;6734.326567343324;6768.289329620319;6782.864342713118;6800.395790231185;5448.564114870801;6099.664518451527;6115.4801841843055;6179.104029915727;6190.41191086846;2919.824810511376;3491.355990251598;3506;3531.5762108547137;3554.2358392945125;6727.899081513781;6755;6860.770249668878;6863.279355667583;6872.113712240771;6046.939530604725;6585.73657053722;6790.456763458911;6890.586564806128;6909.230792288395;5387.919181212206;6019.909701354483;6532.45626366384;6731.899021514681;6883;6157.507399407947;6218.689065546614;6746.359095885883;6757.763478278237;6784.93215067843;6378.170837791057;6589.046572056727;6633.6351500666;6672.733090676318;6701.425165086103;6500.602370490367;6504.219493660791;6904.4131248842405;6922.757703480354;6941.5140940135725;3934.291827471083;4398.824047038082;4421.756803375845;4435.756033418063;4439.22313595126;6389.112329068359;6732.461403087839;6765.729370825112;6788.830279243059;6812.14848143982;6508.642024688687;6540.247871494839;6540.672966351759;6551.5413921026975;6552.246491653521;6177.2587289525545;6495.837604059936;6506.3493650634555;6548.672566371567;6608.240052393844;5384.165454354581;5606.523445507178;6044.667543285162;6392.20097487814;6439.356064393523;6384.744610215539;6490.592438969578;6506.3818937200895;6549.770758023446;6550.443212327005;5484.1773733938635;5725.456081672234;6128.693565321627;6329.556931014785;6393.584417012879 05:37:52 INFO - PID 1636 | |5;yui.html;722.584513904505;726.6621021225129;738.4055835052782;742.829149295662;771.5062360089544;14765;15607.531774046782;15713.764293535605;17450.476485705418;18408.263669453205;16660.416885409013;16881.24034418454;16968.4061057863;17112.914435427807;17125.82874171258;959.5106495687197;973.9464329461877;976.8485884687884;977.5649835823061;979.0258692600836;938.938968967015;967.632299726103;968.2737946540095;971.1065819446127;972.3873959405564;1014.442056868723;1016.4511163971447;1017.1354348803532;1018.551837191637;1030.407515678484;1009.2783659683288;1009.399407352624;1009.9747506312327;1010.3129871687249;1011.7116621762835;754.4115589839879;756.735142700056;757.829488365119;763.5266134996282;765.5789315876245;15945.601359965978;16254.51236462908;16414.015159090373;16735.66528669432;17009.319627214896;14593.708125837424;14631.341589628386;14736.410543578244;14795.186264755434;14825.555233343017;10272.383656980604;11603.6518904433;11624.59313924017;12090.516379344816;12102.939485302606;2900.9564856527168;3028.197527655173;3095.7213850753315;3097.1173215633435;3123.8125712457327;96468;102166.97833021649;103369.48315258451;104622.47688761508;104664;10518.444666001995;11176.776464470666;11228.494717737722;11242.831357529643;11830.290182589073;24044.75955240425;24505;24534.631980520306;24612.384690382525;24684.38289042788;7604.8479030419085;7669.808254793563;7727.38180945523;7856.489328193649;7857;4094.9795251023556;4144.295469770146;4166.0834616384345;4202.225235284761;4211.3516920343645;4054.1486287879284;4122.42286079949;4158.500979882433;4162.354835000579;4173.624373806372;780.484879979211;786.661735453749;788.8106854354913;795.8686816675299;814.1939479914842;544.9320326561434;560.3555910702725;564.4637594285363;575.6373484704609;607.9118527813531;8332.33341332692;9871.309008369346;9956.701298961043;10031.69904902854;10095.394276343442;557.760163129858;578.2136294639287;595.487880422836;597.6264834478451;607.8176547035872;4332.220200363965;4446.3552784846015;4553.59017688403;4557.703749256354;4651.558101980307;98978.03065908034;100772.49613751884;102558.48720756348;103251;104085;65241.34758652353;87385.64592350455;88704;89340.10659893448;91369;45152.77423612861;53272.93454130973;65439.36401589903;67058.99411508831;67676.323236767;9966.700998970042;10590.546493630145;10849.783004339712;10863.402512861869;10934.398608076632;34272;34290.48564271588;34323.828380857936;34620.653793461744;34925.82537087298;46307.53692463167;46345.76827115978;46393.6081917543;46798.53201468078;47014.52985470102;12891.806622900476;13432.597022089352;13519.256440895846;13651.317434128452;14048.78926816119;14089.084209526349;14690.63273418173;14692.265386730835;14829.555113346616;15019.474318399241;13736.587902362946;13919.373628186959;14233.50182743599;14235.572932812032;14370.281485925452;13233.404496797859;13931.442742290195;14255.514934467325;14391.568252952427;14863.108213507225;13091.476340946636;13402.597922062354;13550.59348219555;13865.861341386457;14178.220197889215;9193.954030229806;9510.769077007757;9522;9640.228781697308;10167.186625070124;10942.726431839268;11056.557737690402;11350.772984540428;11359.375234362189;11371.601993930504 05:37:52 INFO - PID 1636 | __end_tp_report 05:37:52 INFO - PID 1636 | __start_cc_report 05:37:52 INFO - PID 1636 | _x_x_mozilla_cycle_collect,2869 05:37:52 INFO - PID 1636 | __end_cc_report 05:37:52 INFO - PID 1636 | __startTimestamp1490618272539__endTimestamp 05:37:52 INFO - PID 1636 | 05:37:52 INFO - PID 1636 | ------- Summary: start ------- 05:37:52 INFO - PID 1636 | Number of tests: 6 05:37:52 INFO - PID 1636 | 05:37:52 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6074.84 Median:6273.06 stddev:666.28 (10.6%) stddev-sans-first:631.03 05:37:52 INFO - PID 1636 | Values: 3273.2 3448.8 3485.5 3495.5 3560.8 5990.6 6101.3 6165.4 6216.5 6218.8 6101.5 6123.1 6148.4 6174.2 6177.8 5358.8 5519.7 5523.9 5538.7 5580.5 5581.6 5652.5 5653.5 5657.4 5714.4 5405.6 5485.1 5495.8 5506.3 5571.6 5578.6 5583.7 5619.1 5641.2 5651.1 5476.8 5503.5 5511.5 5542.4 5543.5 6036.9 6149.8 6176.3 6184.5 6209.4 6350.1 6364.6 6450.8 6560.9 6578.2 5944.7 6588.7 6649.3 6672.1 6674.7 6103.3 6241.8 6260.8 6269.5 6269.8 5566.9 6508.2 6521.5 6761.2 6779.6 6311.7 6356.3 6404.9 6412.2 6417.6 6562.4 6596.5 6632.4 6632.9 6647.0 6515.3 6521.0 6522.1 6529.9 6531.1 6286.5 6389.8 6416.5 6498.5 6509.7 5288.0 5324.8 5326.7 5329.7 5339.6 4648.5 4700.8 4702.4 4703.2 4740.4 6203.7 6219.7 6226.4 6226.6 6233.1 6393.8 6474.1 6478.3 6481.8 6492.2 6132.9 6136.9 6144.7 6149.0 6162.5 5848.7 5878.9 5911.3 5934.2 5941.7 6822.8 6832.5 6836.9 6842.3 6842.7 5512.3 6675.3 6695.4 6702.3 6708.3 6484.2 6581.4 6589.0 6592.0 6726.0 6525.0 6546.6 6555.8 6572.9 6583.5 5598.3 6644.4 6644.7 6647.0 6648.6 6262.5 6340.2 6423.8 6428.6 6445.0 6331.7 6344.3 6344.6 6350.3 6358.0 6036.9 6101.5 6283.0 6306.1 6306.8 6310.6 6397.8 6405.8 6410.2 6419.6 6273.1 6278.5 6281.5 6287.8 6289.4 05:37:52 INFO - PID 1636 | 05:37:52 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:53586.79 Median:193345.98 stddev:110882.86 (57.3%) stddev-sans-first:111122.82 05:37:52 INFO - PID 1636 | Values: 699.9 1112.0 1147.5 1155.3 1156.9 36373.6 37046.0 37350.4 37388.8 37402.0 38667.0 39297.0 40876.0 40931.8 40935.4 6122.5 8410.0 8558.5 8562.7 8572.7 6170.7 8331.5 8513.2 8514.5 8527.2 6672.9 7873.3 8583.0 8631.8 8639.4 6101.8 8251.1 8591.1 8593.0 8608.7 6091.3 6885.4 8334.7 8339.5 8377.0 37022.9 37393.8 37426.4 37427.6 37502.0 3026.6 3040.5 3044.0 3048.8 3086.3 23027.5 27012.7 27163.3 27205.6 28065.7 2804.9 2845.8 2846.1 2846.5 2857.5 295748.0 348582.0 351425.5 353553.0 354184.0 38157.8 38895.8 38924.8 49991.5 50267.5 42784.6 42788.4 42838.4 42883.8 48658.0 13972.8 14287.4 14308.0 14321.2 14393.7 11945.6 11986.6 11986.8 12008.1 12036.5 12271.3 12301.9 12312.5 12318.0 12426.8 4451.9 4807.4 5485.7 5507.5 5553.3 918.6 969.3 975.8 976.8 981.4 24140.6 24141.4 24186.6 24260.0 24341.3 1031.8 1076.5 1097.0 1105.9 1117.4 11493.1 12248.8 12289.3 12291.6 12318.6 455483.0 455564.0 455614.0 456530.0 457150.0 54620.7 54688.5 54761.0 54797.7 54922.5 1467.3 1488.0 1528.3 1530.9 1539.6 19825.9 21173.0 23029.4 23527.9 23605.0 38762.6 38777.2 38853.0 38872.4 38917.2 441378.0 442700.0 443444.0 444281.0 445491.8 25339.1 25894.0 28530.9 32818.0 33132.0 39654.6 40493.6 40793.0 40851.0 40926.6 28290.4 28780.3 28949.3 29278.7 29947.7 29396.6 29476.7 29508.7 29695.3 29726.3 25903.7 34572.7 34702.1 34964.7 35266.5 29443.6 29450.4 29879.7 30114.5 30195.4 22122.6 22157.3 23456.1 23925.3 24240.4 05:37:52 INFO - PID 1636 | 05:37:52 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:41136.88 Median:22330.83 stddev:120323.86 (538.8%) stddev-sans-first:120622.54 05:37:52 INFO - PID 1636 | Values: 411.3 420.9 424.5 430.0 442.9 19643.5 21430.7 22229.6 22317.4 22344.2 18090.6 21582.7 21730.0 21820.2 22427.6 6050.9 6345.0 6350.5 6353.7 6355.3 5983.4 6161.2 6204.2 6284.4 6289.4 5696.1 5893.1 6257.3 6257.6 6259.2 5992.6 6199.3 6231.7 6271.6 6277.6 4580.6 5461.6 5516.3 5550.6 5552.3 26947.1 27090.6 27102.8 27112.0 27425.3 2062.4 2068.1 2078.5 2085.6 2160.4 15569.1 15713.1 16559.0 16967.6 16987.9 1409.9 1499.4 1650.9 1672.1 1678.0 143721.0 146941.0 148710.0 149510.0 152529.0 11051.6 11833.6 11869.8 11985.6 12218.1 14355.4 15976.4 15993.7 15997.0 16010.6 8508.2 8793.4 8854.9 8963.7 9005.5 6706.5 7074.9 8447.2 8463.1 8473.2 7432.9 7573.7 7581.4 7598.7 7601.4 2077.7 2081.0 2083.5 2085.2 2112.4 848.9 902.8 927.6 959.5 966.8 20378.3 20984.5 21040.8 21092.4 21099.8 949.8 972.0 978.5 979.5 981.4 9115.3 9433.8 10863.5 10863.8 10870.7 706312.0 709876.0 716448.0 724417.0 730863.0 153995.0 159182.2 161030.0 165773.2 167912.0 1128.0 1173.8 1186.5 1193.3 1194.9 15103.7 17797.3 17807.0 18569.3 18649.9 59014.7 62176.7 62347.7 63859.8 64538.1 81859.6 84985.0 86870.0 91195.5 95216.0 8120.8 8166.4 8363.7 8435.7 8491.7 12406.4 12900.7 13132.5 13927.6 14008.3 11509.4 11828.7 11879.6 11944.6 11953.0 11054.3 11175.8 11334.8 11533.3 11555.1 11753.6 11894.6 12147.1 12230.9 12252.4 7392.0 8404.5 8404.8 8885.3 8954.9 11579.5 11735.8 12041.6 12057.3 12113.8 05:37:52 INFO - PID 1636 | 05:37:52 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:6731.75 Median:4114.48 stddev:4153.97 (101.0%) stddev-sans-first:4144.72 05:37:52 INFO - PID 1636 | Values: 1186.6 1310.3 1447.0 1472.7 1480.2 9245.4 9660.1 9665.9 9671.6 9675.3 9079.1 9375.9 10327.3 10784.4 10796.3 2410.7 2468.9 2469.2 2472.4 2475.5 2219.5 2336.9 2375.3 2463.8 2486.2 2057.7 2145.6 2224.3 2425.6 2427.1 2427.8 2447.3 2447.5 2448.4 2450.9 2409.3 2417.3 2430.9 2435.3 2440.3 9269.4 10310.9 10386.2 10593.0 10741.2 6060.3 6284.6 6297.8 6299.0 6306.6 3804.8 3831.1 4004.0 4091.4 4093.5 977.9 1190.2 1206.1 1206.3 1217.1 14751.9 14894.7 15067.2 15071.0 15078.8 5224.2 5416.0 5508.7 5684.9 5716.3 12607.3 12626.5 12641.6 12655.9 12660.9 6374.2 6375.9 6380.7 6385.7 6406.7 4073.9 5674.1 5748.7 5750.1 5779.1 4226.5 4310.6 4412.9 4456.0 4469.8 1344.3 1383.5 1394.6 1398.8 1411.1 729.0 766.7 788.0 792.8 803.1 6218.2 6421.5 6636.8 6664.2 6686.9 565.8 580.5 590.2 597.1 860.2 4062.1 4063.1 4135.4 4180.6 4208.6 13480.2 14000.0 14000.0 14007.1 14040.7 12980.2 13503.7 13548.1 13890.9 13903.4 9763.7 10037.9 10049.6 10051.9 10054.4 6372.1 6670.8 6863.3 6912.3 6960.0 12234.4 12357.6 12369.4 12509.9 12669.9 12704.4 12775.0 12817.0 12944.3 13019.1 8967.5 9017.5 9034.9 9145.8 9157.5 9838.9 9922.8 10024.8 10041.4 10059.9 8549.0 8557.7 8566.2 8598.0 8610.7 8312.2 8338.7 8407.4 8446.7 8498.1 6549.9 7619.4 7758.6 7764.8 7782.6 5836.5 5907.8 5924.5 6025.2 6030.7 5885.0 5946.5 5987.0 6100.2 6131.2 05:37:52 INFO - PID 1636 | 05:37:52 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5226.01 Median:5918.10 stddev:1637.00 (27.7%) stddev-sans-first:1599.88 05:37:52 INFO - PID 1636 | Values: 334.1 390.5 397.9 402.1 403.7 4688.7 5641.9 5969.5 6020.8 6084.7 5906.6 6068.3 6091.6 6103.7 6111.7 4442.4 4596.8 4614.7 4615.7 4633.6 3919.5 4565.2 4600.3 4605.3 4621.4 3765.5 4556.7 4574.4 4593.9 4598.0 3903.9 4401.9 4560.0 4586.3 4606.4 3191.6 3879.2 3896.9 3916.1 3921.7 4618.4 5226.6 5936.9 5951.8 5955.4 4415.9 4961.8 5054.9 5124.7 5302.9 5344.9 5929.6 5986.2 6447.2 6469.3 3645.9 4372.1 4375.6 4382.9 4383.3 5322.4 5722.9 6763.7 6830.8 6891.0 4425.2 4437.8 4438.5 4449.5 4450.2 4193.9 4507.9 4534.7 4538.9 4541.2 4899.9 5055.7 5250.6 5343.1 5357.9 5437.9 5873.7 6419.4 6544.5 6558.6 1509.0 1529.5 1546.2 1547.7 1551.1 1005.1 1017.3 1020.1 1021.8 1030.1 6159.8 6200.8 6205.0 6206.9 6208.8 6512.6 6734.3 6768.3 6782.9 6800.4 5448.6 6099.7 6115.5 6179.1 6190.4 2919.8 3491.4 3506.0 3531.6 3554.2 6727.9 6755.0 6860.8 6863.3 6872.1 6046.9 6585.7 6790.5 6890.6 6909.2 5387.9 6019.9 6532.5 6731.9 6883.0 6157.5 6218.7 6746.4 6757.8 6784.9 6378.2 6589.0 6633.6 6672.7 6701.4 6500.6 6504.2 6904.4 6922.8 6941.5 3934.3 4398.8 4421.8 4435.8 4439.2 6389.1 6732.5 6765.7 6788.8 6812.1 6508.6 6540.2 6540.7 6551.5 6552.2 6177.3 6495.8 6506.3 6548.7 6608.2 5384.2 5606.5 6044.7 6392.2 6439.4 6384.7 6490.6 6506.4 6549.8 6550.4 5484.2 5725.5 6128.7 6329.6 6393.6 05:37:52 INFO - PID 1636 | 05:37:52 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:18979.94 Median:2964.58 stddev:26718.15 (901.2%) stddev-sans-first:26757.93 05:37:52 INFO - PID 1636 | Values: 722.6 726.7 738.4 742.8 771.5 14765.0 15607.5 15713.8 17450.5 18408.3 16660.4 16881.2 16968.4 17112.9 17125.8 959.5 973.9 976.8 977.6 979.0 938.9 967.6 968.3 971.1 972.4 1014.4 1016.5 1017.1 1018.6 1030.4 1009.3 1009.4 1010.0 1010.3 1011.7 754.4 756.7 757.8 763.5 765.6 15945.6 16254.5 16414.0 16735.7 17009.3 14593.7 14631.3 14736.4 14795.2 14825.6 10272.4 11603.7 11624.6 12090.5 12102.9 2901.0 3028.2 3095.7 3097.1 3123.8 96468.0 102167.0 103369.5 104622.5 104664.0 10518.4 11176.8 11228.5 11242.8 11830.3 24044.8 24505.0 24534.6 24612.4 24684.4 7604.8 7669.8 7727.4 7856.5 7857.0 4095.0 4144.3 4166.1 4202.2 4211.4 4054.1 4122.4 4158.5 4162.4 4173.6 780.5 786.7 788.8 795.9 814.2 544.9 560.4 564.5 575.6 607.9 8332.3 9871.3 9956.7 10031.7 10095.4 557.8 578.2 595.5 597.6 607.8 4332.2 4446.4 4553.6 4557.7 4651.6 98978.0 100772.5 102558.5 103251.0 104085.0 65241.3 87385.6 88704.0 89340.1 91369.0 45152.8 53272.9 65439.4 67059.0 67676.3 9966.7 10590.5 10849.8 10863.4 10934.4 34272.0 34290.5 34323.8 34620.7 34925.8 46307.5 46345.8 46393.6 46798.5 47014.5 12891.8 13432.6 13519.3 13651.3 14048.8 14089.1 14690.6 14692.3 14829.6 15019.5 13736.6 13919.4 14233.5 14235.6 14370.3 13233.4 13931.4 14255.5 14391.6 14863.1 13091.5 13402.6 13550.6 13865.9 14178.2 9194.0 9510.8 9522.0 9640.2 10167.2 10942.7 11056.6 11350.8 11359.4 11371.6 05:37:52 INFO - PID 1636 | -------- Summary: end -------- 05:37:52 INFO - PID 1636 | 05:37:53 INFO - TEST-INFO | 1636: exit 0 05:37:53 INFO - TEST-OK | dromaeo_css | took 1121246ms 05:37:53 INFO - TEST-START | kraken 05:37:53 INFO - operating with platform_type : mac_ 05:37:53 INFO - Initialising browser for kraken test... 05:37:53 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpYBTPu_/profile http://localhost:49241/getInfo.html) 05:37:55 INFO - PID 1646 | __metrics Screen width/height:1600/1200 05:37:55 INFO - PID 1646 | colorDepth:24 05:37:55 INFO - PID 1646 | Browser inner width/height: 1024/689 05:37:55 INFO - PID 1646 | __metrics 05:37:55 ERROR - PID 1646 | 1490618275633 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 05:37:55 INFO - PID 1646 | 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" 05:37:55 INFO - PID 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:37:56 INFO - TEST-INFO | 1646: exit 0 05:37:56 INFO - Browser initialized. 05:37:56 INFO - Running cycle 1/1 for kraken test... 05:37:56 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpYBTPu_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:08 INFO - PID 1647 | RSS: Main: 233877504 05:38:08 INFO - PID 1647 | 05:38:11 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 05:38:12 INFO - PID 1647 | RSS: Main: 285294592 05:38:12 INFO - PID 1647 | 05:38:15 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 05:38:15 INFO - PID 1647 | RSS: Main: 287535104 05:38:15 INFO - PID 1647 | 05:38:19 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 05:38:19 INFO - PID 1647 | RSS: Main: 378761216 05:38:19 INFO - PID 1647 | 05:38:22 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 05:38:22 INFO - PID 1647 | RSS: Main: 280678400 05:38:22 INFO - PID 1647 | 05:38:24 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:38:25 INFO - PID 1647 | RSS: Main: 284160000 05:38:25 INFO - PID 1647 | 05:38:28 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 05:38:29 INFO - PID 1647 | RSS: Main: 325914624 05:38:29 INFO - PID 1647 | 05:38:32 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 05:38:32 INFO - PID 1647 | RSS: Main: 336269312 05:38:32 INFO - PID 1647 | 05:38:35 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 05:38:36 INFO - PID 1647 | RSS: Main: 340205568 05:38:36 INFO - PID 1647 | 05:38:38 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:38:38 INFO - PID 1647 | RSS: Main: 303071232 05:38:38 INFO - PID 1647 | 05:38:40 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:38:41 INFO - PID 1647 | RSS: Main: 307863552 05:38:41 INFO - PID 1647 | 05:38:43 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:38:43 INFO - PID 1647 | RSS: Main: 310349824 05:38:43 INFO - PID 1647 | 05:38:46 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:38:47 INFO - PID 1647 | RSS: Main: 327036928 05:38:47 INFO - PID 1647 | 05:38:50 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:38:51 INFO - PID 1647 | RSS: Main: 321306624 05:38:51 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 05:38:53 INFO - PID 1647 | RSS: Main: 384258048 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | __start_tp_report 05:38:53 INFO - PID 1647 | _x_x_mozilla_page_load 05:38:53 INFO - PID 1647 | _x_x_mozilla_page_load_details 05:38:53 INFO - PID 1647 | |i|pagename|runs| 05:38:53 INFO - PID 1647 | |0;ai-astar;112;111;112;112;111;111;111;112;111;112 05:38:53 INFO - PID 1647 | |1;audio-beat-detection;165;94;132;185;148;108;115;143;167;132 05:38:53 INFO - PID 1647 | |2;audio-dft;185;185;185;184;186;184;184;184;187;185 05:38:53 INFO - PID 1647 | |3;audio-fft;78;59;77;115;90;66;57;59;72;59 05:38:53 INFO - PID 1647 | |4;audio-oscillator;86;71;84;102;102;101;92;68;69;84 05:38:53 INFO - PID 1647 | |5;imaging-gaussian-blur;91;90;91;90;91;91;92;98;119;131 05:38:53 INFO - PID 1647 | |6;imaging-darkroom;95;94;94;95;95;95;96;96;95;103 05:38:53 INFO - PID 1647 | |7;imaging-desaturate;85;84;87;84;83;85;85;86;86;83 05:38:53 INFO - PID 1647 | |8;json-parse-financial;64;64;62;60;59;61;61;63;61;63 05:38:53 INFO - PID 1647 | |9;json-stringify-tinderbox;52;60;54;68;61;58;58;59;58;57 05:38:53 INFO - PID 1647 | |10;stanford-crypto-aes;83;80;82;83;83;83;79;84;80;84 05:38:53 INFO - PID 1647 | |11;stanford-crypto-ccm;139;143;143;145;149;143;130;149;202;161 05:38:53 INFO - PID 1647 | |12;stanford-crypto-pbkdf2;192;188;183;226;184;183;187;185;185;183 05:38:53 INFO - PID 1647 | |13;stanford-crypto-sha256-iterative;68;67;69;68;67;69;69;69;69;68 05:38:53 INFO - PID 1647 | __end_tp_report 05:38:53 INFO - PID 1647 | __start_cc_report 05:38:53 INFO - PID 1647 | _x_x_mozilla_cycle_collect,2161 05:38:53 INFO - PID 1647 | __end_cc_report 05:38:53 INFO - PID 1647 | __startTimestamp1490618333363__endTimestamp 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | ------- Summary: start ------- 05:38:53 INFO - PID 1647 | Number of tests: 14 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 05:38:53 INFO - PID 1647 | Values: 112.0 111.0 112.0 112.0 111.0 111.0 111.0 112.0 111.0 112.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:156.50 stddev:28.54 (18.2%) stddev-sans-first:28.67 05:38:53 INFO - PID 1647 | Values: 165.0 94.0 132.0 185.0 148.0 108.0 115.0 143.0 167.0 132.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.00 stddev:0.99 (0.5%) stddev-sans-first:1.05 05:38:53 INFO - PID 1647 | Values: 185.0 185.0 185.0 184.0 186.0 184.0 184.0 184.0 187.0 185.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#3] audio-fft Cycles:10 Average:73.20 Median:69.00 stddev:18.22 (26.4%) stddev-sans-first:19.24 05:38:53 INFO - PID 1647 | Values: 78.0 59.0 77.0 115.0 90.0 66.0 57.0 59.0 72.0 59.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:77.50 stddev:13.41 (17.3%) stddev-sans-first:14.23 05:38:53 INFO - PID 1647 | Values: 86.0 71.0 84.0 102.0 102.0 101.0 92.0 68.0 69.0 84.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:91.00 stddev:14.49 (15.9%) stddev-sans-first:15.11 05:38:53 INFO - PID 1647 | Values: 91.0 90.0 91.0 90.0 91.0 91.0 92.0 98.0 119.0 131.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:95.00 stddev:2.62 (2.8%) stddev-sans-first:2.76 05:38:53 INFO - PID 1647 | Values: 95.0 94.0 94.0 95.0 95.0 95.0 96.0 96.0 95.0 103.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.32 (1.5%) stddev-sans-first:1.39 05:38:53 INFO - PID 1647 | Values: 85.0 84.0 87.0 84.0 83.0 85.0 85.0 86.0 86.0 83.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:62.50 stddev:1.69 (2.7%) stddev-sans-first:1.59 05:38:53 INFO - PID 1647 | Values: 64.0 64.0 62.0 60.0 59.0 61.0 61.0 63.0 61.0 63.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:58.50 stddev:4.28 (7.3%) stddev-sans-first:3.83 05:38:53 INFO - PID 1647 | Values: 52.0 60.0 54.0 68.0 61.0 58.0 58.0 59.0 58.0 57.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:83.00 stddev:1.79 (2.2%) stddev-sans-first:1.87 05:38:53 INFO - PID 1647 | Values: 83.0 80.0 82.0 83.0 83.0 83.0 79.0 84.0 80.0 84.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:147.00 stddev:19.77 (13.5%) stddev-sans-first:20.54 05:38:53 INFO - PID 1647 | Values: 139.0 143.0 143.0 145.0 149.0 143.0 130.0 149.0 202.0 161.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.60 Median:186.00 stddev:13.10 (7.0%) stddev-sans-first:13.87 05:38:53 INFO - PID 1647 | Values: 192.0 188.0 183.0 226.0 184.0 183.0 187.0 185.0 185.0 183.0 05:38:53 INFO - PID 1647 | 05:38:53 INFO - PID 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 05:38:53 INFO - PID 1647 | Values: 68.0 67.0 69.0 68.0 67.0 69.0 69.0 69.0 69.0 68.0 05:38:53 INFO - PID 1647 | -------- Summary: end -------- 05:38:53 INFO - PID 1647 | 05:38:54 INFO - TEST-INFO | 1647: exit 0 05:38:54 INFO - TEST-OK | kraken | took 61027ms 05:38:54 INFO - SUITE-END | took 1182s 05:38:54 INFO - Completed test suite (00:19:42) 05:38:54 INFO - javascript benchmark 05:38:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7601.995569985928, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3273.2307907641707, 3448.7758295710782, 3485.546878905745, 3495.5106285120073, 3560.8397622107004, 5990.640561566309, 6101.298350689672, 6165.414285642868, 6216.471599914007, 6218.813435596933, 6101.542384321183, 6123.130109042722, 6148.3851614838495, 6174.228221472316, 6177.783777567786, 5358.785648574052, 5519.668819870801, 5523.861903452425, 5538.723063846802, 5580.4698553637545, 5581.6371935824045, 5652.547796176316, 5653.462921022497, 5657.405972372882, 5714.428557144273, 5405.594580406446, 5485.149801780731, 5495.752691128909, 5506.256655351556, 5571.6378435401775, 5578.581606379537, 5583.720813959286, 5619.129034999582, 5641.210230567724, 5651.06757385031, 5476.808311709111, 5503.477169668875, 5511.53151982082, 5542.39033706292, 5543.473370029841, 6036.939630603725, 6149.78475753351, 6176.320604733476, 6184.536159788034, 6209.441150296495, 6350.142730731338, 6364.6181229126405, 6450.774222902222, 6560.93439065608, 6578.210614726264, 5944.673042982634, 6588.703508342088, 6649.268580456146, 6672.065910772496, 6674.666266686695, 6103.29812071613, 6241.750329986795, 6260.843478913018, 6269.46709529686, 6269.780557680459, 5566.944330556724, 6508.186476690414, 6521.510886683518, 6761.222459417131, 6779.559328643622, 6311.747530098836, 6356.3325850785, 6404.935950640527, 6412.198475190601, 6417.614943103429, 6562.409383155444, 6596.538242323088, 6632.403083722488, 6632.933670663231, 6647.036179753909, 6515.315891831384, 6520.956646936467, 6522.054302126166, 6529.869402611921, 6531.0529973153625, 6286.497080233662, 6389.7763578274535, 6416.486681065504, 6498.480121590356, 6509.739610415625, 5288.04815133272, 5324.787008519694, 5326.733663316819, 5329.733513324319, 5339.599530035263, 4648.535146485392, 4700.835470758507, 4702.435707715096, 4703.17694403478, 4740.431148262231, 6203.689815509207, 6219.657918814509, 6226.40849119333, 6226.59527130735, 6233.065040244, 6393.776217832354, 6474.125992990886, 6478.2873883872735, 6481.773137940244, 6492.188476440445, 6132.8756461173125, 6136.917362303815, 6144.709610981655, 6148.9854174061675, 6162.476189523941, 5848.707564621752, 5878.882422351529, 5911.290645122613, 5934.198883150806, 5941.673207973517, 6822.761203357859, 6832.45340372769, 6836.897446538206, 6842.281560436281, 6842.726290948306, 5512.283403157564, 6675.299093595103, 6695.397414232742, 6702.296258892941, 6708.26209116987, 6484.1894763154605, 6581.407673309425, 6588.967055164693, 6591.967040164768, 6725.966370168314, 6524.9673751630935, 6546.639934803631, 6555.80332590023, 6572.948328267455, 6583.539152259296, 5598.272224610774, 6644.368784965421, 6644.700988455627, 6646.966765166337, 6648.634325112165, 6262.530310226843, 6340.207474065742, 6423.775167869289, 6428.582142160744, 6445.0332450132855, 6331.651759153291, 6344.270408903035, 6344.619322840645, 6350.301466838737, 6358.0, 6036.939630603638, 6101.481374083075, 6283.02613094971, 6306.054091886253, 6306.779262725966, 6310.558260921875, 6397.808065758035, 6405.807825765234, 6410.230772307353, 6419.614823110628, 6273.059041143682, 6278.529110316836, 6281.497480201664, 6287.811365659038, 6289.371062893674], "value": 6032.3518110926425, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.9300069993002, 1112.0380870546978, 1147.4778975566119, 1155.272178527527, 1156.9247998880078, 36373.63626363739, 37046.0, 37350.43974340384, 37388.81305593468, 37402.0, 38667.0, 39297.0, 40876.0, 40931.79534102325, 40935.38596921049, 6122.47958923492, 8409.990801103877, 8558.486490810541, 8562.657493700244, 8572.742817715478, 6170.660613666247, 8331.541765202912, 8513.233808957193, 8514.531700756455, 8527.232549070583, 6672.933270667304, 7873.291403773659, 8583.0, 8631.827363452727, 8639.43843650164, 6101.786437474711, 8251.051129120138, 8591.140885911422, 8592.957035214815, 8608.698695545625, 6091.2995005574485, 6885.449164066863, 8334.66661333546, 8339.499630022217, 8377.036640786326, 37022.889313320644, 37393.81303093494, 37426.43860342097, 37427.62572374296, 37502.0, 3026.576279320897, 3040.498317777564, 3043.9847800761077, 3048.7713421493477, 3086.33643766589, 23027.539449210923, 27012.729872701217, 27163.320916977034, 27205.591916121273, 28065.719342806515, 2804.901828435995, 2845.772338212938, 2846.131929761422, 2846.51609226432, 2857.4713677969644, 295748.0, 348582.0, 351425.4857451418, 353553.0, 354184.0, 38157.80921095377, 38895.80552097278, 38924.80537597294, 49991.50008499941, 50267.497325026285, 42784.57215427806, 42788.35817462741, 42838.357424638656, 42883.785581072516, 48658.02683946301, 13972.790408143886, 14287.357068931922, 14308.0, 14321.212333321559, 14393.712125757636, 11945.641630751092, 11986.64040078799, 11986.820197697041, 12008.099392545593, 12036.518539258332, 12271.282020517136, 12301.876981230253, 12312.50749970009, 12318.0, 12426.75146497065, 4451.866444006685, 4807.351007613927, 5485.725713714299, 5507.504324610804, 5553.333599968029, 918.6463211663424, 969.3263182088381, 975.8243516167158, 976.8339382305026, 981.4356744871727, 24140.63789043166, 24141.396465088514, 24186.637200442008, 24260.0, 24341.26976190717, 1031.7936412717486, 1076.5478499030426, 1096.9561217551368, 1105.9336439813637, 1117.4189421500773, 11493.138014648935, 12248.755024899452, 12289.324087175291, 12291.56979505713, 12318.630441086782, 455483.0, 455564.0, 455614.0, 456530.0, 457150.0, 54620.72689636526, 54688.45311546913, 54761.0, 54797.72601137048, 54922.450775491736, 1467.2736995187452, 1487.9628009299854, 1528.2817075974497, 1530.946416875404, 1539.6227924158652, 19825.900870495556, 21173.0, 23029.424264392856, 23527.882360588086, 23605.0, 38762.6123738759, 38777.224455510164, 38853.0, 38872.416913746885, 38917.221655566165, 441378.0, 442700.0, 443444.0, 444281.0, 445491.7725411352, 25339.113131040325, 25894.0, 28530.85734571314, 32818.0, 33132.0, 39654.60345396624, 40493.59506404898, 40792.98017549466, 40851.0, 40926.59073409228, 28290.43419131647, 28780.280492987844, 28949.276268093465, 29278.70721292845, 29947.70052299449, 29396.55905161467, 29476.705232947395, 29508.704912950594, 29695.25761855971, 29726.25684357908, 25903.740962590135, 34572.654273456945, 34702.13244668904, 34964.650353497156, 35266.471002934464, 29443.558346625246, 29450.410991780474, 29879.701202988563, 30114.54828177535, 30195.396092078474, 22122.611337650967, 22157.335279941628, 23456.061757529507, 23925.282241532783, 24240.393990150387], "value": 17217.08813975654, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.2884709452647, 420.88004918598205, 424.54573606241337, 429.96990210685243, 442.8538582267852, 19643.508912277182, 21430.678539821914, 22229.55540889181, 22317.44206394841, 22344.21795237167, 18090.638187236247, 21582.676259856114, 21730.0, 21820.236291729794, 22427.55144897101, 6050.939490605104, 6345.048242763576, 6350.460210882081, 6353.714082866282, 6355.332690067546, 5983.401659834025, 6161.168242287301, 6204.224471941007, 6284.371562843725, 6289.402506761873, 5696.1171018492, 5893.145493903381, 6257.311695713491, 6257.624542527463, 6259.248890133168, 5992.640441573498, 6199.318075011745, 6231.6572588507615, 6271.560990730651, 6277.623342599459, 4580.587747102777, 5461.617686761928, 5516.289950115477, 5550.611457197998, 5552.250446189753, 26947.05685301005, 27090.5936410954, 27102.78037488318, 27112.0, 27425.31436714078, 2062.4431403521116, 2068.141721185706, 2078.4803799050237, 2085.603735290305, 2160.3950893749775, 15569.143697096655, 15713.05721656693, 16559.0, 16967.57581060471, 16987.91506042474, 1409.9083559568596, 1499.4428082464367, 1650.9339626414928, 1672.1221358786613, 1677.9580510487212, 143721.0, 146941.0, 148709.99999999892, 149510.0, 152529.0, 11051.557937682563, 11833.644990650295, 11869.821952670716, 11985.580504682293, 12218.083643726755, 14355.425782968774, 15976.440824571084, 15993.68012639764, 15997.040177589382, 16010.599735006717, 8508.215785401826, 8793.384463087523, 8854.911450885409, 8963.686270980485, 9005.504697241715, 6706.497012723968, 7074.8680211165965, 8447.157121270491, 8463.111373305837, 8473.23740863313, 7432.851342973218, 7573.697052117854, 7581.431392645573, 7598.696052157852, 7601.353884919844, 2077.6883467479997, 2080.989595052015, 2083.5207902182583, 2085.2493102483095, 2112.3979665795173, 848.866762871561, 902.8329758994607, 927.6335847340398, 959.4579062829479, 966.8066386722684, 20378.286759963332, 20984.475388115115, 21040.78959210419, 21092.367228983156, 21099.78900211009, 949.7530642033121, 971.9805603888024, 978.5107446276861, 979.480875136179, 981.4356744871727, 9115.316351273548, 9433.811323773487, 10863.51114199863, 10863.782724345627, 10870.728231794268, 706312.0, 709876.0, 716448.0, 724417.0, 730863.0, 153995.0, 159182.20408897882, 161030.0, 165773.17113414596, 167912.0, 1127.9887201127883, 1173.8473998380157, 1186.549111337686, 1193.2542161149108, 1194.904407647386, 15103.741055946548, 17797.288108475517, 17807.0, 18569.257229711202, 18649.906750466704, 59014.70492647509, 62176.68911655413, 62347.6882615584, 63859.8070096484, 64538.06385808433, 81859.59070204849, 84985.0, 86870.0, 91195.54402228212, 95216.0, 8120.75637730869, 8166.366726654812, 8363.707270245512, 8435.746927592181, 8491.74524764258, 12406.379681016093, 12900.741985160432, 13132.474701011852, 13927.58217253484, 14008.299585020912, 11509.366984815915, 11828.704282392664, 11879.58421455245, 11944.64166075019, 11952.983996360059, 11054.281471704313, 11175.776484470427, 11334.829977550178, 11533.308001519936, 11555.133364997491, 11753.647390578297, 11894.583689570823, 12147.149699520949, 12230.877691222973, 12252.448639811051, 7392.0, 8404.537750423786, 8404.831903361775, 8885.333599979898, 8954.865677014981, 11579.536818527165, 11735.823962640396, 12041.578544750892, 12057.276563406223, 12113.818292725793], "value": 10311.125651088532, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1186.6380753870067, 1310.3186343101586, 1446.9710605787884, 1472.6980968901375, 1480.1563109027852, 9245.4452732836, 9660.130588247057, 9665.855012174814, 9671.613135474581, 9675.322727409084, 9079.137481939222, 9375.90624093759, 10327.27709060366, 10784.406857622827, 10796.298240614355, 2410.6745589345433, 2468.888899999495, 2469.2468797016904, 2472.4066224106264, 2475.492524032578, 2219.511707424364, 2336.906523739048, 2375.257081136533, 2463.802895768343, 2486.2292689266296, 2057.6913462980524, 2145.603063433262, 2224.310463756233, 2425.5876500994873, 2427.1383658801137, 2427.8421902576274, 2447.2535876557554, 2447.547203767309, 2448.4368595223023, 2450.9264722058365, 2409.265174121892, 2417.335232810991, 2430.890609922558, 2435.257246539805, 2440.3289095498703, 9269.44383337002, 10310.948445257725, 10386.16910647154, 10592.993665601756, 10741.194410419172, 6060.33336333003, 6284.591501552384, 6297.779577714758, 6299.0, 6306.621602703853, 3804.8478060877533, 3831.080540670219, 4003.97998010011, 4091.4476545666253, 4093.5497095319492, 977.9364341317792, 1190.2343304808032, 1206.0532482001586, 1206.2942283001364, 1217.0993464836047, 14751.92624036873, 14894.70210595782, 15067.246637668182, 15071.006183361527, 15078.773818392732, 5224.164133738584, 5415.972920135374, 5508.6970216637665, 5684.8863022739315, 5716.256886604715, 12607.306598137007, 12626.494940202472, 12641.557545485864, 12655.93672031634, 12660.873391266154, 6374.171357723466, 6375.904361434582, 6380.680965951684, 6385.680715964183, 6406.743730250738, 4073.877783666495, 5674.092145256739, 5748.7125643717645, 5750.079987202028, 5779.075347944393, 4226.4716910386205, 4310.568943105727, 4412.867613971522, 4456.019675671347, 4469.821207151743, 1344.28080976677, 1383.4604504243352, 1394.5816255123423, 1398.7901814727873, 1411.1462565148142, 728.9635518224068, 766.7354762606892, 787.9921200788034, 792.8216151366012, 803.1085495100434, 6218.1916350875, 6421.486281097595, 6636.800895973129, 6664.200295964515, 6686.860204734438, 565.8387359602576, 580.451473357673, 590.2032256453753, 597.0924195223323, 860.2172023458622, 4062.086030643082, 4063.146739184745, 4135.400366946776, 4180.581941805856, 4208.642265407413, 13480.191188528719, 13999.970004349516, 14000.0, 14007.08953917992, 14040.719185616435, 12980.15628984113, 13503.729925401436, 13548.119372240773, 13890.93054534741, 13903.4438622456, 9763.707088787347, 10037.949810251048, 10049.598016079275, 10051.949740251252, 10054.396736195851, 6372.107904893412, 6670.83322916931, 6863.347981941708, 6912.27421120775, 6959.965200173967, 12234.44944977496, 12357.567485137977, 12369.381530923598, 12509.87490125087, 12669.93665031669, 12704.36478176106, 12775.0, 12817.013362928761, 12944.288064156184, 13019.088663793444, 8967.53870423531, 9017.549122543714, 9034.909650903406, 9145.77135571616, 9157.49633770149, 9838.852417213604, 9922.801543968935, 10024.849627255451, 10041.397516149054, 10059.949700251453, 8549.0, 8557.700480483154, 8566.229039386486, 8597.957010214908, 8610.741677749678, 8312.168783121639, 8338.666453341799, 8407.369447291356, 8446.746597602081, 8498.107698691549, 6549.886519291636, 7619.4285428594185, 7758.612069396395, 7764.805879852823, 7782.649780759766, 5836.503897168788, 5907.793227237026, 5924.496417804535, 6025.1564780931585, 6030.668313242813, 5885.0, 5946.524278057832, 5987.0, 6100.206973093469, 6131.198656832614], "value": 5110.121166193977, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.10126759018254, 390.46506286387654, 397.9104701442176, 402.09729158040204, 403.70327809060336, 4688.695234809739, 5641.887162256752, 5969.492593129578, 6020.789272375468, 6084.72618732157, 5906.586538942265, 6068.33248342682, 6091.573589848713, 6103.7253323600435, 6111.7249723762425, 4442.400275962752, 4596.81612735491, 4614.700044497097, 4615.653825963066, 4633.559811817872, 3919.510061242345, 4565.178267911774, 4600.2869555218995, 4605.332226827108, 4621.399218101642, 3765.491658626078, 4556.749378784166, 4574.405327307462, 4593.885152871171, 4598.0344127733215, 3903.945934597643, 4401.86794396167, 4560.042391097873, 4586.266197408432, 4606.424196975378, 3191.64891861895, 3879.2047630235734, 3896.902577435558, 3916.099297161669, 3921.7058720595783, 4618.445786505641, 5226.5818734501145, 5936.881262374772, 5951.791687290924, 5955.374685657986, 4415.889602759925, 4961.751912404401, 5054.9241761373605, 5124.743762811881, 5302.893942121174, 5344.91982620261, 5929.6145750526075, 5986.221791167164, 6447.161868957052, 6469.288378278386, 3645.8541658333634, 4372.07628643399, 4375.562443755631, 4382.8904277393, 4383.342498625232, 5322.414534401213, 5722.942770572325, 6763.695633696446, 6830.795076147723, 6891.0, 4425.159219748302, 4437.755923424178, 4438.467383913951, 4449.51055383914, 4450.176717307244, 4193.874183774492, 4507.864764057083, 4534.7052441591195, 4538.88652783683, 4541.159885421142, 4899.853004409873, 5055.721935293521, 5250.632455728062, 5343.145096784496, 5357.946420535823, 5437.918431223535, 5873.676947767828, 6419.395151212197, 6544.4882342068995, 6558.573692709959, 1508.9849101508844, 1529.495266562032, 1546.180524322111, 1547.667251540924, 1551.1391177896376, 1005.1255407795265, 1017.2726500552141, 1020.1175982774846, 1021.7942827463515, 1030.1295405382439, 6159.81520554384, 6200.81397558074, 6205.007198848253, 6206.875862482725, 6208.844778880564, 6512.6092434454085, 6734.326567343324, 6768.289329620319, 6782.864342713118, 6800.395790231185, 5448.564114870801, 6099.664518451527, 6115.4801841843055, 6179.104029915727, 6190.41191086846, 2919.824810511376, 3491.355990251598, 3506.0, 3531.5762108547137, 3554.2358392945125, 6727.899081513781, 6755.0, 6860.770249668878, 6863.279355667583, 6872.113712240771, 6046.939530604725, 6585.73657053722, 6790.456763458911, 6890.586564806128, 6909.230792288395, 5387.919181212206, 6019.909701354483, 6532.45626366384, 6731.899021514681, 6883.0, 6157.507399407947, 6218.689065546614, 6746.359095885883, 6757.763478278237, 6784.93215067843, 6378.170837791057, 6589.046572056727, 6633.6351500666, 6672.733090676318, 6701.425165086103, 6500.602370490367, 6504.219493660791, 6904.4131248842405, 6922.757703480354, 6941.5140940135725, 3934.291827471083, 4398.824047038082, 4421.756803375845, 4435.756033418063, 4439.22313595126, 6389.112329068359, 6732.461403087839, 6765.729370825112, 6788.830279243059, 6812.14848143982, 6508.642024688687, 6540.247871494839, 6540.672966351759, 6551.5413921026975, 6552.246491653521, 6177.2587289525545, 6495.837604059936, 6506.3493650634555, 6548.672566371567, 6608.240052393844, 5384.165454354581, 5606.523445507178, 6044.667543285162, 6392.20097487814, 6439.356064393523, 6384.744610215539, 6490.592438969578, 6506.3818937200895, 6549.770758023446, 6550.443212327005, 5484.1773733938635, 5725.456081672234, 6128.693565321627, 6329.556931014785, 6393.584417012879], "value": 4715.994612740453, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.584513904505, 726.6621021225129, 738.4055835052782, 742.829149295662, 771.5062360089544, 14765.0, 15607.531774046782, 15713.764293535605, 17450.476485705418, 18408.263669453205, 16660.416885409013, 16881.24034418454, 16968.4061057863, 17112.914435427807, 17125.82874171258, 959.5106495687197, 973.9464329461877, 976.8485884687884, 977.5649835823061, 979.0258692600836, 938.938968967015, 967.632299726103, 968.2737946540095, 971.1065819446127, 972.3873959405564, 1014.442056868723, 1016.4511163971447, 1017.1354348803532, 1018.551837191637, 1030.407515678484, 1009.2783659683288, 1009.399407352624, 1009.9747506312327, 1010.3129871687249, 1011.7116621762835, 754.4115589839879, 756.735142700056, 757.829488365119, 763.5266134996282, 765.5789315876245, 15945.601359965978, 16254.51236462908, 16414.015159090373, 16735.66528669432, 17009.319627214896, 14593.708125837424, 14631.341589628386, 14736.410543578244, 14795.186264755434, 14825.555233343017, 10272.383656980604, 11603.6518904433, 11624.59313924017, 12090.516379344816, 12102.939485302606, 2900.9564856527168, 3028.197527655173, 3095.7213850753315, 3097.1173215633435, 3123.8125712457327, 96468.0, 102166.97833021649, 103369.48315258451, 104622.47688761508, 104664.0, 10518.444666001995, 11176.776464470666, 11228.494717737722, 11242.831357529643, 11830.290182589073, 24044.75955240425, 24505.0, 24534.631980520306, 24612.384690382525, 24684.38289042788, 7604.8479030419085, 7669.808254793563, 7727.38180945523, 7856.489328193649, 7857.0, 4094.9795251023556, 4144.295469770146, 4166.0834616384345, 4202.225235284761, 4211.3516920343645, 4054.1486287879284, 4122.42286079949, 4158.500979882433, 4162.354835000579, 4173.624373806372, 780.484879979211, 786.661735453749, 788.8106854354913, 795.8686816675299, 814.1939479914842, 544.9320326561434, 560.3555910702725, 564.4637594285363, 575.6373484704609, 607.9118527813531, 8332.33341332692, 9871.309008369346, 9956.701298961043, 10031.69904902854, 10095.394276343442, 557.760163129858, 578.2136294639287, 595.487880422836, 597.6264834478451, 607.8176547035872, 4332.220200363965, 4446.3552784846015, 4553.59017688403, 4557.703749256354, 4651.558101980307, 98978.03065908034, 100772.49613751884, 102558.48720756348, 103251.0, 104085.0, 65241.34758652353, 87385.64592350455, 88704.0, 89340.10659893448, 91369.0, 45152.77423612861, 53272.93454130973, 65439.36401589903, 67058.99411508831, 67676.323236767, 9966.700998970042, 10590.546493630145, 10849.783004339712, 10863.402512861869, 10934.398608076632, 34272.0, 34290.48564271588, 34323.828380857936, 34620.653793461744, 34925.82537087298, 46307.53692463167, 46345.76827115978, 46393.6081917543, 46798.53201468078, 47014.52985470102, 12891.806622900476, 13432.597022089352, 13519.256440895846, 13651.317434128452, 14048.78926816119, 14089.084209526349, 14690.63273418173, 14692.265386730835, 14829.555113346616, 15019.474318399241, 13736.587902362946, 13919.373628186959, 14233.50182743599, 14235.572932812032, 14370.281485925452, 13233.404496797859, 13931.442742290195, 14255.514934467325, 14391.568252952427, 14863.108213507225, 13091.476340946636, 13402.597922062354, 13550.59348219555, 13865.861341386457, 14178.220197889215, 9193.954030229806, 9510.769077007757, 9522.0, 9640.228781697308, 10167.186625070124, 10942.726431839268, 11056.557737690402, 11350.772984540428, 11359.375234362189, 11371.601993930504], "value": 7477.854681663609, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 112.0, 112.0, 111.0, 111.0, 111.0, 112.0, 111.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 94.0, 132.0, 185.0, 148.0, 108.0, 115.0, 143.0, 167.0, 132.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 185.0, 184.0, 186.0, 184.0, 184.0, 184.0, 187.0, 185.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 77.0, 115.0, 90.0, 66.0, 57.0, 59.0, 72.0, 59.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 84.0, 102.0, 102.0, 101.0, 92.0, 68.0, 69.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 90.0, 91.0, 91.0, 92.0, 98.0, 119.0, 131.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 103.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 87.0, 84.0, 83.0, 85.0, 85.0, 86.0, 86.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 62.0, 60.0, 59.0, 61.0, 61.0, 63.0, 61.0, 63.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 60.0, 54.0, 68.0, 61.0, 58.0, 58.0, 59.0, 58.0, 57.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 82.0, 83.0, 83.0, 83.0, 79.0, 84.0, 80.0, 84.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 143.0, 143.0, 145.0, 149.0, 143.0, 130.0, 149.0, 202.0, 161.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 188.0, 183.0, 226.0, 184.0, 183.0, 187.0, 185.0, 185.0, 183.0], "value": 189.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 68.0, 67.0, 69.0, 69.0, 69.0, 69.0, 68.0], "value": 68.3, "unit": "score"}]}]} 05:38:54 INFO - Return code: 0 05:38:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:38:54 ERROR - # TBPL SUCCESS # 05:38:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:54 INFO - 2017-03-27 05:38:54.712 screenresolution[1651:9739] starting screenresolution argv=screenresolution get 05:38:54 INFO - 2017-03-27 05:38:54.715 screenresolution[1651:9739] Display 0: 1600x1200x32@60 05:38:54 INFO - 2017-03-27 05:38:54.721 screenresolution[1652:9742] starting screenresolution argv=screenresolution list 05:38:54 INFO - Available Modes on Display 0 05:38:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:54 INFO - Intel Iris: 05:38:54 INFO - Chipset Model: Intel Iris 05:38:54 INFO - Type: GPU 05:38:54 INFO - Bus: Built-In 05:38:54 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:54 INFO - Vendor: Intel (0x8086) 05:38:54 INFO - Device ID: 0x0a2e 05:38:54 INFO - Revision ID: 0x0009 05:38:54 INFO - Displays: 05:38:54 INFO - Display: 05:38:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:54 INFO - Display Serial Number: 1600x1200 60 05:38:54 INFO - Main Display: Yes 05:38:54 INFO - Mirror: Off 05:38:54 INFO - Online: Yes 05:38:54 INFO - Rotation: Supported 05:38:54 INFO - Return code: 0 05:38:54 INFO - Running post-action listener: _resource_record_post_action 05:38:54 INFO - [mozharness: 2017-03-27 12:38:54.958586Z] Finished run-tests step (success) 05:38:54 INFO - Running post-run listener: _resource_record_post_run 05:38:55 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 38675456; Write bytes: 509528064; Read time: 381; Write time: 1627 05:38:55 INFO - TinderboxPrint: CPU usage
53.4% 05:38:55 INFO - TinderboxPrint: I/O read bytes / time
38,675,456 / 381 05:38:55 INFO - TinderboxPrint: I/O write bytes / time
509,528,064 / 1,627 05:38:55 INFO - TinderboxPrint: CPU idle
2,245.4 (46.6%) 05:38:55 INFO - TinderboxPrint: CPU system
960.0 (19.9%) 05:38:55 INFO - TinderboxPrint: CPU user
1,608.3 (33.4%) 05:38:55 INFO - TinderboxPrint: Swap in / out
249,192,448 / 0 05:38:55 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202969600; Write bytes: 203782144; Read time: 15817; Write time: 406 05:38:55 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 37704704; Write bytes: 295776256; Read time: 365; Write time: 1186 05:38:55 INFO - Running post-run listener: _upload_blobber_files 05:38:55 INFO - Blob upload gear active. 05:38:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:38:55 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 05:38:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:38:55 INFO - (blobuploader) - INFO - Open directory for files ... 05:38:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:38:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:38:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:57 INFO - (blobuploader) - INFO - Done attempting. 05:38:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:38:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:38:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:58 INFO - (blobuploader) - INFO - Done attempting. 05:38:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:38:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:38:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:59 INFO - (blobuploader) - INFO - Done attempting. 05:38:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:38:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:39:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:01 INFO - (blobuploader) - INFO - Done attempting. 05:39:01 INFO - (blobuploader) - INFO - Iteration through files over. 05:39:01 INFO - Return code: 0 05:39:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:39:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:39:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c11da837b5a22cdb2ffccde15d2741477e07cc28250847170f556253f1b838e8618d5eeb024983bdc5fc4e7b15f042ee022399dce2c3b817489667ab360879a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2250d6155095151ed6b79b4ee0ba464c047aca1ca78e5a2e59c2c7b34c883bce0c225bad42809d6b6b28be7c6bfb1108c9c2db00d17de530a915b88b8d574126", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb0a3e83460daf2d2061948633c5827549556807077e76ed17826eee21576d37fc07912b466bf2bd20b7fef6e69e0082b50658b3a41586e28c29c0a2a25f302", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/729d0b4640b129641ff92691e79161a5570546bb248e6399f642855d8bd4ce64a8dc9a810dd571a9d286d7e5a0f11e26e86a16ec9ef8884bee1d3319cee10dcc"} 05:39:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:39:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:39:01 INFO - Contents: 05:39:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c11da837b5a22cdb2ffccde15d2741477e07cc28250847170f556253f1b838e8618d5eeb024983bdc5fc4e7b15f042ee022399dce2c3b817489667ab360879a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2250d6155095151ed6b79b4ee0ba464c047aca1ca78e5a2e59c2c7b34c883bce0c225bad42809d6b6b28be7c6bfb1108c9c2db00d17de530a915b88b8d574126", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb0a3e83460daf2d2061948633c5827549556807077e76ed17826eee21576d37fc07912b466bf2bd20b7fef6e69e0082b50658b3a41586e28c29c0a2a25f302", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/729d0b4640b129641ff92691e79161a5570546bb248e6399f642855d8bd4ce64a8dc9a810dd571a9d286d7e5a0f11e26e86a16ec9ef8884bee1d3319cee10dcc"} 05:39:01 INFO - Running post-run listener: copy_logs_to_upload_dir 05:39:01 INFO - Copying logs to upload dir... 05:39:01 INFO - mkdir: /builds/slave/test/build/upload/logs 05:39:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.638544 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-27 05:39:01.260985) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 05:39:01.264753) ========= 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.rzcsRia4XY/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.IzPZarhCHC/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c11da837b5a22cdb2ffccde15d2741477e07cc28250847170f556253f1b838e8618d5eeb024983bdc5fc4e7b15f042ee022399dce2c3b817489667ab360879a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2250d6155095151ed6b79b4ee0ba464c047aca1ca78e5a2e59c2c7b34c883bce0c225bad42809d6b6b28be7c6bfb1108c9c2db00d17de530a915b88b8d574126", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb0a3e83460daf2d2061948633c5827549556807077e76ed17826eee21576d37fc07912b466bf2bd20b7fef6e69e0082b50658b3a41586e28c29c0a2a25f302", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/729d0b4640b129641ff92691e79161a5570546bb248e6399f642855d8bd4ce64a8dc9a810dd571a9d286d7e5a0f11e26e86a16ec9ef8884bee1d3319cee10dcc"} build_url:https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011387 build_url: 'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c11da837b5a22cdb2ffccde15d2741477e07cc28250847170f556253f1b838e8618d5eeb024983bdc5fc4e7b15f042ee022399dce2c3b817489667ab360879a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2250d6155095151ed6b79b4ee0ba464c047aca1ca78e5a2e59c2c7b34c883bce0c225bad42809d6b6b28be7c6bfb1108c9c2db00d17de530a915b88b8d574126", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb0a3e83460daf2d2061948633c5827549556807077e76ed17826eee21576d37fc07912b466bf2bd20b7fef6e69e0082b50658b3a41586e28c29c0a2a25f302", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/729d0b4640b129641ff92691e79161a5570546bb248e6399f642855d8bd4ce64a8dc9a810dd571a9d286d7e5a0f11e26e86a16ec9ef8884bee1d3319cee10dcc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 05:39:01.299150) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:39:01.299476) ========= 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.rzcsRia4XY/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.IzPZarhCHC/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005128 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:39:01.347979) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 05:39:01.348275) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 05:39:01.348590) ========= ========= Total master_lag: 0.18 =========