builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0321 starttime: 1494028760.67 results: success (0) buildid: 20170505161344 builduid: c6384675c533465fb250ab23704247da revision: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.673898) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.674334) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.674606) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.701343) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.701699) ========= 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.9bysca6FWz/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.Yspqbs65xC/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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-05-05 16:59:20-- 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% 39.6M=0s 2017-05-05 16:59:20 (39.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148886 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.875930) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.876244) ========= 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.9bysca6FWz/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.Yspqbs65xC/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.031322 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.931186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 16:59:20.931698) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 --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 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9bysca6FWz/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.Yspqbs65xC/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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-05-05 16:59:21,029 truncating revision to first 12 chars 2017-05-05 16:59:21,029 Setting DEBUG logging. 2017-05-05 16:59:21,029 attempt 1/10 2017-05-05 16:59:21,029 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/37a5b7f6f101?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-05 16:59:22,121 unpacking tar archive at: mozilla-central-37a5b7f6f101/testing/mozharness/ program finished with exit code 0 elapsedTime=1.375571 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 16:59:22.332892) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:22.333245) ========= script_repo_revision: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:22.333698) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:22.334043) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:22.354737) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-05 16:59:22.355093) ========= /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.9bysca6FWz/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.Yspqbs65xC/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:59:22 INFO - MultiFileLogger online at 20170505 16:59:22 in /builds/slave/test 16:59:22 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 16:59:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:59:22 INFO - {'append_to_log': False, 16:59:22 INFO - 'base_work_dir': '/builds/slave/test', 16:59:22 INFO - 'blob_upload_branch': 'Firefox', 16:59:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:59:22 INFO - 'branch': 'Firefox', 16:59:22 INFO - 'buildbot_json_path': 'buildprops.json', 16:59:22 INFO - 'config_files': ('talos/mac_config.py',), 16:59:22 INFO - 'default_actions': ('clobber', 16:59:22 INFO - 'read-buildbot-config', 16:59:22 INFO - 'download-and-extract', 16:59:22 INFO - 'populate-webroot', 16:59:22 INFO - 'create-virtualenv', 16:59:22 INFO - 'install', 16:59:22 INFO - 'run-tests'), 16:59:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:59:22 INFO - 'download_minidump_stackwalk': True, 16:59:22 INFO - 'download_symbols': 'ondemand', 16:59:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:59:22 INFO - 'tooltool.py': '/tools/tooltool.py', 16:59:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:59:22 INFO - '/tools/misc-python/virtualenv.py')}, 16:59:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:59:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:59:22 INFO - 'gecko_profile': False, 16:59:22 INFO - 'gecko_profile_interval': 0, 16:59:22 INFO - 'installer_path': 'installer.exe', 16:59:22 INFO - 'log_level': 'info', 16:59:22 INFO - 'log_name': 'talos', 16:59:22 INFO - 'log_to_console': True, 16:59:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:59:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:59:22 INFO - 'opt_config_files': (), 16:59:22 INFO - 'pip_index': False, 16:59:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:59:22 INFO - 'cmd': ('bash', 16:59:22 INFO - '-c', 16:59:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:59:22 INFO - 'enabled': True, 16:59:22 INFO - 'halt_on_failure': False, 16:59:22 INFO - 'name': 'check_screen_resolution'},), 16:59:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:59:22 INFO - 'cmd': ('bash', 16:59:22 INFO - '-c', 16:59:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:59:22 INFO - 'enabled': True, 16:59:22 INFO - 'halt_on_failure': False, 16:59:22 INFO - 'name': 'check_screen_resolution'},), 16:59:22 INFO - 'run_cmd_checks_enabled': True, 16:59:22 INFO - 'suite': 'dromaeojs', 16:59:22 INFO - 'system_bits': '32', 16:59:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:59:22 INFO - 'title': 't-yosemite-r7-0321', 16:59:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:59:22 INFO - 'use_talos_json': True, 16:59:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:59:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:59:22 INFO - 'work_dir': 'build'} 16:59:22 INFO - [mozharness: 2017-05-05 23:59:22.559967Z] Running clobber step. 16:59:22 INFO - Running pre-action listener: _resource_record_pre_action 16:59:22 INFO - Running main action method: clobber 16:59:22 INFO - rmtree: /builds/slave/test/build 16:59:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:59:25 INFO - Running post-action listener: _resource_record_post_action 16:59:25 INFO - [mozharness: 2017-05-05 23:59:25.059656Z] Finished clobber step (success) 16:59:25 INFO - [mozharness: 2017-05-05 23:59:25.059775Z] Running read-buildbot-config step. 16:59:25 INFO - Running pre-action listener: _resource_record_pre_action 16:59:25 INFO - Running main action method: read_buildbot_config 16:59:25 INFO - Using buildbot properties: 16:59:25 INFO - { 16:59:25 INFO - "project": "", 16:59:25 INFO - "product": "firefox", 16:59:25 INFO - "script_repo_revision": "production", 16:59:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:59:25 INFO - "repository": "", 16:59:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:59:25 INFO - "buildid": "20170505161344", 16:59:25 INFO - "pgo_build": "False", 16:59:25 INFO - "basedir": "/builds/slave/test", 16:59:25 INFO - "buildnumber": 25, 16:59:25 INFO - "slavename": "t-yosemite-r7-0321", 16:59:25 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:59:25 INFO - "platform": "yosemite_r7", 16:59:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:59:25 INFO - "branch": "mozilla-central", 16:59:25 INFO - "repo_path": "mozilla-central", 16:59:25 INFO - "revision": "37a5b7f6f101df2eb292b1b6baaf1540c9920e20", 16:59:25 INFO - "stage_platform": "macosx64", 16:59:25 INFO - "builduid": "c6384675c533465fb250ab23704247da", 16:59:25 INFO - "slavebuilddir": "test" 16:59:25 INFO - } 16:59:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:59:25 INFO - Running post-action listener: _resource_record_post_action 16:59:25 INFO - [mozharness: 2017-05-05 23:59:25.063039Z] Finished read-buildbot-config step (success) 16:59:25 INFO - [mozharness: 2017-05-05 23:59:25.063169Z] Running download-and-extract step. 16:59:25 INFO - Running pre-action listener: _resource_record_pre_action 16:59:25 INFO - Running main action method: download_and_extract 16:59:25 INFO - mkdir: /builds/slave/test/build/tests 16:59:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:25 INFO - trying https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:59:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/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 16:59:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/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 16:59:25 INFO - Downloaded 1325 bytes. 16:59:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:59:25 INFO - Using the following test package requirements: 16:59:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:59:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:59:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:59:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:59:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:59:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:59:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:59:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:59:25 INFO - u'jsshell-mac.zip'], 16:59:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:59:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:59:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:59:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:59:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:59:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:59:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:59:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:59:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:59:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:59:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:59:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:59:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:59:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:59:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:59:27 INFO - Content-Length response header: 52703722 16:59:27 INFO - Bytes received: 52703722 16:59:43 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 16:59:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:59:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:59:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:59:45 INFO - Content-Length response header: 52703722 16:59:45 INFO - Bytes received: 52703722 17:00:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:00:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:00:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:00:01 INFO - Content-Length response header: 14408697 17:00:01 INFO - Bytes received: 14408697 17:00:01 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')]} 17:00:01 INFO - trying https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:00:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:00:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/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 17:00:03 INFO - Downloaded 70883507 bytes. 17:00:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:00:03 INFO - mkdir: /builds/slave/test/properties 17:00:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:00:03 INFO - Writing to file /builds/slave/test/properties/build_url 17:00:03 INFO - Contents: 17:00:03 INFO - build_url:https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:00:04 INFO - Running post-action listener: _resource_record_post_action 17:00:04 INFO - Running post-action listener: set_extra_try_arguments 17:00:04 INFO - [mozharness: 2017-05-06 00:00:04.601398Z] Finished download-and-extract step (success) 17:00:04 INFO - [mozharness: 2017-05-06 00:00:04.601520Z] Running populate-webroot step. 17:00:04 INFO - Running pre-action listener: _resource_record_pre_action 17:00:04 INFO - Running main action method: populate_webroot 17:00:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:00:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:00:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:00:04 INFO - '--tppagecycles', 17:00:04 INFO - '7'], 17:00:04 INFO - 'tests': ['tsvgm']}}, 17:00:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:00:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:00:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:00:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:00:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:00:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:00:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:00:04 INFO - 'talos_options': ['--disable-e10s'], 17:00:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:00:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:00:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:00:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:00:04 INFO - 'talos_options': ['--disable-e10s'], 17:00:04 INFO - 'tests': ['damp', 'tps']}, 17:00:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:00:04 INFO - 'tests': ['damp', 'tps']}, 17:00:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:00:04 INFO - 'tests': ['dromaeo_dom']}, 17:00:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:00:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:00:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:00:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:00:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:00:04 INFO - 'tests': ['a11yr', 17:00:04 INFO - 'ts_paint', 17:00:04 INFO - 'tpaint', 17:00:04 INFO - 'sessionrestore', 17:00:04 INFO - 'sessionrestore_no_auto_restore', 17:00:04 INFO - 'tabpaint']}, 17:00:04 INFO - 'other-e10s': {'tests': ['a11yr', 17:00:04 INFO - 'ts_paint', 17:00:04 INFO - 'tpaint', 17:00:04 INFO - 'sessionrestore', 17:00:04 INFO - 'sessionrestore_no_auto_restore', 17:00:04 INFO - 'tabpaint']}, 17:00:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:00:04 INFO - 'ts_paint', 17:00:04 INFO - 'tpaint', 17:00:04 INFO - 'sessionrestore', 17:00:04 INFO - 'sessionrestore_no_auto_restore', 17:00:04 INFO - 'tabpaint']}, 17:00:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:00:04 INFO - 'ts_paint', 17:00:04 INFO - 'tpaint', 17:00:04 INFO - 'sessionrestore', 17:00:04 INFO - 'sessionrestore_no_auto_restore', 17:00:04 INFO - 'tabpaint']}, 17:00:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:00:04 INFO - 'tests': ['a11yr', 17:00:04 INFO - 'ts_paint', 17:00:04 INFO - 'tpaint', 17:00:04 INFO - 'sessionrestore', 17:00:04 INFO - 'sessionrestore_no_auto_restore', 17:00:04 INFO - 'tabpaint']}, 17:00:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:00:04 INFO - 'tests': ['a11yr', 17:00:04 INFO - 'ts_paint', 17:00:04 INFO - 'tpaint', 17:00:04 INFO - 'sessionrestore', 17:00:04 INFO - 'sessionrestore_no_auto_restore', 17:00:04 INFO - 'tabpaint']}, 17:00:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:00:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:00:04 INFO - 'bloom_basic_ref']}, 17:00:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:00:04 INFO - 'tests': ['tsvgx', 17:00:04 INFO - 'tsvgr_opacity', 17:00:04 INFO - 'tart', 17:00:04 INFO - 'tscrollx', 17:00:04 INFO - 'cart', 17:00:04 INFO - 'tsvg_static']}, 17:00:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:00:04 INFO - 'tsvgr_opacity', 17:00:04 INFO - 'tart', 17:00:04 INFO - 'tscrollx', 17:00:04 INFO - 'cart', 17:00:04 INFO - 'tsvg_static']}, 17:00:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:00:04 INFO - 'talos_options': ['--disable-e10s'], 17:00:04 INFO - 'tests': ['tp5o']}, 17:00:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:00:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:00:04 INFO - 'talos_options': ['--disable-e10s', 17:00:04 INFO - '--xperf_path', 17:00:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:00:04 INFO - 'tests': ['tp5n']}, 17:00:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:00:04 INFO - 'talos_options': ['--xperf_path', 17:00:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:00:04 INFO - 'tests': ['tp5n']}}, 17:00:04 INFO - 'talos.zip': {'path': '', 17:00:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:00:04 INFO - Running post-action listener: _resource_record_post_action 17:00:04 INFO - [mozharness: 2017-05-06 00:00:04.620229Z] Finished populate-webroot step (success) 17:00:04 INFO - [mozharness: 2017-05-06 00:00:04.620377Z] Running create-virtualenv step. 17:00:04 INFO - Running pre-action listener: _resource_record_pre_action 17:00:04 INFO - Running main action method: create_virtualenv 17:00:04 INFO - Creating virtualenv /builds/slave/test/build/venv 17:00:04 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 17:00:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:00:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:00:04 INFO - Using real prefix '/tools/python27' 17:00:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:00:05 INFO - Installing distribute.............................................................................................................................................................................................done. 17:00:08 INFO - Installing pip.................done. 17:00:08 INFO - Return code: 0 17:00:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:00:08 INFO - Reading from file tmpfile_stdout 17:00:08 INFO - Output received: 17:00:08 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) 17:00:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:00:08 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')]} 17:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:08 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')]} 17:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:08 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:00:08 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 17:00:08 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 17:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render', 17:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:08 INFO - 'HOME': '/Users/cltbld', 17:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:08 INFO - 'LOGNAME': 'cltbld', 17:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:08 INFO - 'MOZ_NO_REMOTE': '1', 17:00:08 INFO - 'NO_EM_RESTART': '1', 17:00:08 INFO - 'PAGER': '/bin/cat', 17:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:08 INFO - 'PWD': '/builds/slave/test', 17:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:08 INFO - 'SHELL': '/bin/bash', 17:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners', 17:00:08 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:08 INFO - 'USER': 'cltbld', 17:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:08 INFO - 'XPC_FLAGS': '0x0', 17:00:08 INFO - 'XPC_SERVICE_NAME': '0', 17:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:08 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 17:00:08 INFO - Cleaning up... 17:00:08 INFO - Return code: 0 17:00:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:00:08 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')]} 17:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:08 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')]} 17:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:08 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:00:08 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 17:00:08 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 17:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render', 17:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:08 INFO - 'HOME': '/Users/cltbld', 17:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:08 INFO - 'LOGNAME': 'cltbld', 17:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:08 INFO - 'MOZ_NO_REMOTE': '1', 17:00:08 INFO - 'NO_EM_RESTART': '1', 17:00:08 INFO - 'PAGER': '/bin/cat', 17:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:08 INFO - 'PWD': '/builds/slave/test', 17:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:08 INFO - 'SHELL': '/bin/bash', 17:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners', 17:00:08 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:08 INFO - 'USER': 'cltbld', 17:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:08 INFO - 'XPC_FLAGS': '0x0', 17:00:08 INFO - 'XPC_SERVICE_NAME': '0', 17:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:09 INFO - Downloading/unpacking psutil>=3.1.1 17:00:09 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 17:00:09 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 17:00:09 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 17:00:09 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 17:00:09 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 17:00:09 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 17:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:00:11 INFO - Installing collected packages: psutil 17:00:11 INFO - Running setup.py install for psutil 17:00:11 INFO - building 'psutil._psutil_osx' extension 17:00:11 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 17:00:11 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 17:00:12 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 17:00:12 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 17:00:12 INFO - building 'psutil._psutil_posix' extension 17:00:12 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 17:00:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:00:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:00:12 INFO - ^ 17:00:12 INFO - 1 warning generated. 17:00:12 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 17:00:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:00:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:00:12 INFO - Successfully installed psutil 17:00:12 INFO - Cleaning up... 17:00:12 INFO - Return code: 0 17:00:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:12 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:00:12 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 17:00:12 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 17:00:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render', 17:00:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:12 INFO - 'HOME': '/Users/cltbld', 17:00:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:12 INFO - 'LOGNAME': 'cltbld', 17:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:12 INFO - 'MOZ_NO_REMOTE': '1', 17:00:12 INFO - 'NO_EM_RESTART': '1', 17:00:12 INFO - 'PAGER': '/bin/cat', 17:00:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:12 INFO - 'PWD': '/builds/slave/test', 17:00:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:12 INFO - 'SHELL': '/bin/bash', 17:00:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners', 17:00:12 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:00:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:12 INFO - 'USER': 'cltbld', 17:00:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:12 INFO - 'XPC_FLAGS': '0x0', 17:00:12 INFO - 'XPC_SERVICE_NAME': '0', 17:00:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:00:12 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 17:00:12 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 17:00:12 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 17:00:12 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 17:00:12 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 17:00:12 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 17:00:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:00:14 INFO - Installing collected packages: mozsystemmonitor 17:00:14 INFO - Running setup.py install for mozsystemmonitor 17:00:14 INFO - Successfully installed mozsystemmonitor 17:00:14 INFO - Cleaning up... 17:00:14 INFO - Return code: 0 17:00:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:00: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')]} 17:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00: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')]} 17:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:14 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:00:14 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 17:00:14 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 17:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render', 17:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:14 INFO - 'HOME': '/Users/cltbld', 17:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:14 INFO - 'LOGNAME': 'cltbld', 17:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:14 INFO - 'MOZ_NO_REMOTE': '1', 17:00:14 INFO - 'NO_EM_RESTART': '1', 17:00:14 INFO - 'PAGER': '/bin/cat', 17:00:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:14 INFO - 'PWD': '/builds/slave/test', 17:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:14 INFO - 'SHELL': '/bin/bash', 17:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners', 17:00:14 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:14 INFO - 'USER': 'cltbld', 17:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:14 INFO - 'XPC_FLAGS': '0x0', 17:00:14 INFO - 'XPC_SERVICE_NAME': '0', 17:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:14 INFO - Downloading/unpacking jsonschema==2.5.1 17:00:14 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 17:00:14 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 17:00:14 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 17:00:14 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 17:00:14 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 17:00:14 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 17:00:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:00:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:00:16 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 17:00:16 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 17:00:16 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 17:00:16 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 17:00:16 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 17:00:16 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 17:00:16 INFO - Downloading functools32-3.2.3-2.tar.gz 17:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:00:16 INFO - warning: no files found matching '*.txt' 17:00:16 INFO - no previously-included directories found matching 'build' 17:00:16 INFO - no previously-included directories found matching 'dist' 17:00:16 INFO - no previously-included directories found matching '.git*' 17:00:16 INFO - Installing collected packages: jsonschema, functools32 17:00:16 INFO - Running setup.py install for functools32 17:00:16 INFO - warning: no files found matching '*.txt' 17:00:16 INFO - no previously-included directories found matching 'build' 17:00:16 INFO - no previously-included directories found matching 'dist' 17:00:16 INFO - no previously-included directories found matching '.git*' 17:00:16 INFO - Successfully installed jsonschema functools32 17:00:16 INFO - Cleaning up... 17:00:16 INFO - Return code: 0 17:00:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:00:16 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')]} 17:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:16 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')]} 17:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:17 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:00:17 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 17:00:17 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 17:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render', 17:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:17 INFO - 'HOME': '/Users/cltbld', 17:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:17 INFO - 'LOGNAME': 'cltbld', 17:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:17 INFO - 'MOZ_NO_REMOTE': '1', 17:00:17 INFO - 'NO_EM_RESTART': '1', 17:00:17 INFO - 'PAGER': '/bin/cat', 17:00:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:17 INFO - 'PWD': '/builds/slave/test', 17:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:17 INFO - 'SHELL': '/bin/bash', 17:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners', 17:00:17 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:17 INFO - 'USER': 'cltbld', 17:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:17 INFO - 'XPC_FLAGS': '0x0', 17:00:17 INFO - 'XPC_SERVICE_NAME': '0', 17:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:00:17 INFO - Cleaning up... 17:00:17 INFO - Return code: 0 17:00:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:00:17 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')]} 17:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:17 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')]} 17:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:18 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:00:18 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 17:00:18 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 17:00:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render', 17:00:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:18 INFO - 'HOME': '/Users/cltbld', 17:00:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:18 INFO - 'LOGNAME': 'cltbld', 17:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:18 INFO - 'MOZ_NO_REMOTE': '1', 17:00:18 INFO - 'NO_EM_RESTART': '1', 17:00:18 INFO - 'PAGER': '/bin/cat', 17:00:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:18 INFO - 'PWD': '/builds/slave/test', 17:00:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:18 INFO - 'SHELL': '/bin/bash', 17:00:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners', 17:00:18 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:00:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:18 INFO - 'USER': 'cltbld', 17:00:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:18 INFO - 'XPC_FLAGS': '0x0', 17:00:18 INFO - 'XPC_SERVICE_NAME': '0', 17:00:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:18 INFO - Downloading/unpacking blobuploader==1.2.4 17:00:18 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 17:00:18 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 17:00:18 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 17:00:18 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 17:00:18 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 17:00:18 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 17:00:20 INFO - Downloading blobuploader-1.2.4.tar.gz 17:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:00:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:00:20 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 17:00:20 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 17:00:20 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 17:00:20 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 17:00:20 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 17:00:20 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 17:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:00:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:00:20 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 17:00:20 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 17:00:20 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 17:00:20 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 17:00:20 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 17:00:20 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 17:00:21 INFO - Downloading docopt-0.6.1.tar.gz 17:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:00:21 INFO - Installing collected packages: blobuploader, requests, docopt 17:00:21 INFO - Running setup.py install for blobuploader 17:00:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:00:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:00:21 INFO - Running setup.py install for requests 17:00:21 INFO - Running setup.py install for docopt 17:00:22 INFO - Successfully installed blobuploader requests docopt 17:00:22 INFO - Cleaning up... 17:00:22 INFO - Return code: 0 17:00:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:00:22 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')]} 17:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:22 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')]} 17:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:22 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:00:22 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 17:00:22 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 17:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render', 17:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:22 INFO - 'HOME': '/Users/cltbld', 17:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:22 INFO - 'LOGNAME': 'cltbld', 17:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:22 INFO - 'MOZ_NO_REMOTE': '1', 17:00:22 INFO - 'NO_EM_RESTART': '1', 17:00:22 INFO - 'PAGER': '/bin/cat', 17:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:22 INFO - 'PWD': '/builds/slave/test', 17:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:22 INFO - 'SHELL': '/bin/bash', 17:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners', 17:00:22 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:22 INFO - 'USER': 'cltbld', 17:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:22 INFO - 'XPC_FLAGS': '0x0', 17:00:22 INFO - 'XPC_SERVICE_NAME': '0', 17:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-fB11xH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-9EJ9ot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-4wgMv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-fnL7sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-2sxgXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-67_dYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Jv2jUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-J8qniX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-_H82T8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-fLW4wJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-gSXdwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-9MgsZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-TpVQm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-y1qgRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-mSWRIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-ddE7zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:00:24 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-A5WIUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:00:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:00:24 INFO - Running setup.py install for manifestparser 17:00:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:00:24 INFO - Running setup.py install for mozcrash 17:00:24 INFO - Running setup.py install for mozdebug 17:00:24 INFO - Running setup.py install for mozdevice 17:00:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:00:24 INFO - Running setup.py install for mozfile 17:00:24 INFO - Running setup.py install for mozhttpd 17:00:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:00:24 INFO - Running setup.py install for mozinfo 17:00:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:00:25 INFO - Running setup.py install for mozInstall 17:00:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:00:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:00:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:00:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:00:25 INFO - Running setup.py install for mozleak 17:00:25 INFO - Running setup.py install for mozlog 17:00:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:00:25 INFO - Running setup.py install for moznetwork 17:00:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:00:25 INFO - Running setup.py install for mozprocess 17:00:25 INFO - Running setup.py install for mozprofile 17:00:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:00:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:00:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:00:26 INFO - Running setup.py install for mozrunner 17:00:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:00:26 INFO - Running setup.py install for mozscreenshot 17:00:26 INFO - Running setup.py install for moztest 17:00:26 INFO - Running setup.py install for mozversion 17:00:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:00:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:00:26 INFO - Cleaning up... 17:00:26 INFO - Return code: 0 17:00:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:00: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')]} 17:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00: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')]} 17:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:26 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:00:26 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 17:00:26 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 17:00:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render', 17:00:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:26 INFO - 'HOME': '/Users/cltbld', 17:00:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:26 INFO - 'LOGNAME': 'cltbld', 17:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:26 INFO - 'MOZ_NO_REMOTE': '1', 17:00:26 INFO - 'NO_EM_RESTART': '1', 17:00:26 INFO - 'PAGER': '/bin/cat', 17:00:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:26 INFO - 'PWD': '/builds/slave/test', 17:00:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:26 INFO - 'SHELL': '/bin/bash', 17:00:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners', 17:00:26 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:00:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:26 INFO - 'USER': 'cltbld', 17:00:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:26 INFO - 'XPC_FLAGS': '0x0', 17:00:26 INFO - 'XPC_SERVICE_NAME': '0', 17:00:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:00:26 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Cj81g9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:00:27 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)) 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-O_JnMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:00:27 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)) 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-F0J6BO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:00:27 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)) 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-sBaBfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-ygOQP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:00:27 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)) 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-0a8aLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:00:27 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)) 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Zba_7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:00:27 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)) 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-LY6qeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:00:27 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)) 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Ew0mTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:00:27 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)) 17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-k_m2hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:00:28 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)) 17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-k_oUQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:00:28 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)) 17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-VGhe6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:00:28 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)) 17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-8rgFgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:00:28 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)) 17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-wT33Tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:00:28 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)) 17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-ZsV5U8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:28 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)) 17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-VRoXSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:00:28 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)) 17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-oqd3VG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:00:28 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)) 17:00:28 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)) 17:00:28 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)) 17:00:28 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)) 17:00:28 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:00:28 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:00:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:00:28 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 17:00:28 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 17:00:28 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 17:00:28 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 17:00:28 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 17:00:28 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 17:00:30 INFO - Downloading blessings-1.6.tar.gz 17:00:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:00:30 INFO - Installing collected packages: blessings 17:00:30 INFO - Running setup.py install for blessings 17:00:30 INFO - Successfully installed blessings 17:00:30 INFO - Cleaning up... 17:00:30 INFO - Return code: 0 17:00:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:00:30 INFO - Reading from file tmpfile_stdout 17:00:30 INFO - Current package versions: 17:00:30 INFO - blessings == 1.6 17:00:30 INFO - blobuploader == 1.2.4 17:00:30 INFO - docopt == 0.6.1 17:00:30 INFO - functools32 == 3.2.3-2 17:00:30 INFO - jsonschema == 2.5.1 17:00:30 INFO - manifestparser == 1.1 17:00:30 INFO - mozInstall == 1.12 17:00:30 INFO - mozcrash == 1.0 17:00:30 INFO - mozdebug == 0.1 17:00:30 INFO - mozdevice == 0.50 17:00:30 INFO - mozfile == 1.2 17:00:30 INFO - mozhttpd == 0.7 17:00:30 INFO - mozinfo == 0.9 17:00:30 INFO - mozleak == 0.1 17:00:30 INFO - mozlog == 3.4 17:00:30 INFO - moznetwork == 0.27 17:00:30 INFO - mozprocess == 0.25 17:00:30 INFO - mozprofile == 0.28 17:00:30 INFO - mozrunner == 6.13 17:00:30 INFO - mozscreenshot == 0.1 17:00:30 INFO - mozsystemmonitor == 0.3 17:00:30 INFO - moztest == 0.8 17:00:30 INFO - mozversion == 1.4 17:00:30 INFO - psutil == 3.1.1 17:00:30 INFO - requests == 1.2.3 17:00:30 INFO - wsgiref == 0.1.2 17:00:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:00: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')]} 17:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00: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')]} 17:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:30 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:00:30 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 17:00:30 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 17:00:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render', 17:00:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:30 INFO - 'HOME': '/Users/cltbld', 17:00:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:30 INFO - 'LOGNAME': 'cltbld', 17:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:30 INFO - 'MOZ_NO_REMOTE': '1', 17:00:30 INFO - 'NO_EM_RESTART': '1', 17:00:30 INFO - 'PAGER': '/bin/cat', 17:00:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:30 INFO - 'PWD': '/builds/slave/test', 17:00:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:30 INFO - 'SHELL': '/bin/bash', 17:00:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners', 17:00:30 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:00:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:30 INFO - 'USER': 'cltbld', 17:00:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:30 INFO - 'XPC_FLAGS': '0x0', 17:00:30 INFO - 'XPC_SERVICE_NAME': '0', 17:00:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:30 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)) 17:00:30 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)) 17:00:30 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)) 17:00:30 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)) 17:00:30 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)) 17:00:30 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)) 17:00:30 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)) 17:00:30 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)) 17:00:30 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)) 17:00:30 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)) 17:00:30 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)) 17:00:30 INFO - Cleaning up... 17:00:30 INFO - Return code: 0 17:00:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:00: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')]} 17:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:00:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render', 17:00:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:31 INFO - 'HOME': '/Users/cltbld', 17:00:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:31 INFO - 'LOGNAME': 'cltbld', 17:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:31 INFO - 'MOZ_NO_REMOTE': '1', 17:00:31 INFO - 'NO_EM_RESTART': '1', 17:00:31 INFO - 'PAGER': '/bin/cat', 17:00:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:31 INFO - 'PWD': '/builds/slave/test', 17:00:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:31 INFO - 'SHELL': '/bin/bash', 17:00:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners', 17:00:31 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:00:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:31 INFO - 'USER': 'cltbld', 17:00:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:31 INFO - 'XPC_FLAGS': '0x0', 17:00:31 INFO - 'XPC_SERVICE_NAME': '0', 17:00:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:00:31 INFO - Cleaning up... 17:00:31 INFO - Return code: 0 17:00:31 INFO - Running post-action listener: _resource_record_post_action 17:00:31 INFO - Running post-action listener: _start_resource_monitoring 17:00:31 INFO - Starting resource monitoring. 17:00:31 INFO - [mozharness: 2017-05-06 00:00:31.292654Z] Finished create-virtualenv step (success) 17:00:31 INFO - [mozharness: 2017-05-06 00:00:31.293612Z] Running install step. 17:00:31 INFO - Running pre-action listener: _resource_record_pre_action 17:00:31 INFO - Running main action method: install 17:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:00:31 INFO - Reading from file tmpfile_stdout 17:00:31 INFO - Detecting whether we're running mozinstall >=1.0... 17:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:00:31 INFO - Reading from file tmpfile_stdout 17:00:31 INFO - Output received: 17:00:31 INFO - Usage: mozinstall [options] installer 17:00:31 INFO - Options: 17:00:31 INFO - -h, --help show this help message and exit 17:00:31 INFO - -d DEST, --destination=DEST 17:00:31 INFO - Directory to install application into. [default: 17:00:31 INFO - "/builds/slave/test"] 17:00:31 INFO - --app=APP Application being installed. [default: firefox] 17:00:31 INFO - mkdir: /builds/slave/test/build/application 17:00:31 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'] 17:00:31 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 17:00:51 INFO - Reading from file tmpfile_stdout 17:00:51 INFO - Output received: 17:00:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:00:51 INFO - Running post-action listener: _resource_record_post_action 17:00:51 INFO - [mozharness: 2017-05-06 00:00:51.623276Z] Finished install step (success) 17:00:51 INFO - [mozharness: 2017-05-06 00:00:51.623413Z] Running run-tests step. 17:00:51 INFO - Running pre-action listener: _resource_record_pre_action 17:00:51 INFO - Running main action method: run_tests 17:00:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:00:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:00:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:00:51 INFO - 2017-05-05 17:00:51.638 screenresolution[1620:7310] starting screenresolution argv=screenresolution get 17:00:51 INFO - 2017-05-05 17:00:51.640 screenresolution[1620:7310] Display 0: 1600x1200x32@60 17:00:51 INFO - 2017-05-05 17:00:51.646 screenresolution[1621:7313] starting screenresolution argv=screenresolution list 17:00:51 INFO - Available Modes on Display 0 17:00:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:00:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:00:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:00:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:00:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:00:51 INFO - Intel Iris: 17:00:51 INFO - Chipset Model: Intel Iris 17:00:51 INFO - Type: GPU 17:00:51 INFO - Bus: Built-In 17:00:51 INFO - VRAM (Dynamic, Max): 1536 MB 17:00:51 INFO - Vendor: Intel (0x8086) 17:00:51 INFO - Device ID: 0x0a2e 17:00:51 INFO - Revision ID: 0x0009 17:00:51 INFO - Displays: 17:00:51 INFO - Display: 17:00:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:00:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:00:51 INFO - Display Serial Number: 1600x1200 60 17:00:51 INFO - Main Display: Yes 17:00:51 INFO - Mirror: Off 17:00:51 INFO - Online: Yes 17:00:51 INFO - Rotation: Supported 17:00:51 INFO - Return code: 0 17:00:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:00:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:00:51 INFO - Python 2.7.3 17:00:51 INFO - Return code: 0 17:00:51 INFO - grabbing minidump binary from tooltool 17:00:51 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')]} 17:00:51 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 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:00:51 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 17:00:51 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 17:00:51 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 17:00:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:00:52 INFO - Return code: 0 17:00:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:00:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:00:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:00:52 INFO - ENV: RUST_BACKTRACE is now 1 17:00:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:00:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:00:52 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/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 17:00:52 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/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0321 --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 17:00:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render', 17:00:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:52 INFO - 'HOME': '/Users/cltbld', 17:00:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:52 INFO - 'LOGNAME': 'cltbld', 17:00:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:00:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:52 INFO - 'MOZ_NO_REMOTE': '1', 17:00:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:00:52 INFO - 'NO_EM_RESTART': '1', 17:00:52 INFO - 'PAGER': '/bin/cat', 17:00:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:52 INFO - 'PWD': '/builds/slave/test', 17:00:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:00:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:52 INFO - 'RUST_BACKTRACE': '1', 17:00:52 INFO - 'SHELL': '/bin/bash', 17:00:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners', 17:00:52 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:00:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:52 INFO - 'USER': 'cltbld', 17:00:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:52 INFO - 'XPC_FLAGS': '0x0', 17:00:52 INFO - 'XPC_SERVICE_NAME': '0', 17:00:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:52 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/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 17:00:52 INFO - mozversion application_buildid: 20170505161344 17:00:52 INFO - mozversion application_changeset: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 17:00:52 INFO - mozversion application_display_name: Nightly 17:00:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:00:52 INFO - mozversion application_name: Firefox 17:00:52 INFO - mozversion application_remotingname: firefox 17:00:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:00:52 INFO - mozversion application_vendor: Mozilla 17:00:52 INFO - mozversion application_version: 55.0a1 17:00:52 INFO - mozversion platform_buildid: 20170505161344 17:00:52 INFO - mozversion platform_changeset: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 17:00:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:00:52 INFO - mozversion platform_version: 55.0a1 17:00:52 INFO - using testdate: 1494028852 17:00:52 INFO - actual date: 1494028852 17:00:52 INFO - starting webserver on 'localhost:49234' 17:00:52 INFO - SUITE-START | Running 2 tests 17:00:52 INFO - TEST-START | dromaeo_css 17:00:52 INFO - operating with platform_type : mac_ 17:00:52 INFO - Initialising browser for dromaeo_css test... 17:00:52 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmp5RHsmO/profile http://localhost:49234/getInfo.html) 17:00:54 INFO - PID 1631 | __metrics Screen width/height:1600/1200 17:00:54 INFO - PID 1631 | colorDepth:24 17:00:54 INFO - PID 1631 | Browser inner width/height: 1024/691 17:00:54 INFO - PID 1631 | __metrics 17:00:55 INFO - PID 1631 | 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" 17:00:55 INFO - PID 1631 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:00:55 INFO - TEST-INFO | 1631: exit 0 17:00:55 INFO - Browser initialized. 17:00:55 INFO - Running cycle 1/1 for dromaeo_css test... 17:00:55 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmp5RHsmO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:01:08 INFO - PID 1633 | RSS: Main: 239194112 17:01:08 INFO - PID 1633 | 17:04:13 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49234/tests/dromaeo/cssquery-ext.html) 17:04:15 INFO - PID 1633 | RSS: Main: 381886464 17:04:15 INFO - PID 1633 | 17:07:17 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-ext.html (next: http://localhost:49234/tests/dromaeo/cssquery-jquery.html) 17:07:18 INFO - PID 1633 | RSS: Main: 364462080 17:07:18 INFO - PID 1633 | 17:10:20 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49234/tests/dromaeo/cssquery-mootools.html) 17:10:21 INFO - PID 1633 | RSS: Main: 327933952 17:10:21 INFO - PID 1633 | 17:13:23 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49234/tests/dromaeo/cssquery-prototype.html) 17:13:24 INFO - PID 1633 | RSS: Main: 328589312 17:13:24 INFO - PID 1633 | 17:16:27 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49234/tests/dromaeo/cssquery-yui.html) 17:16:27 INFO - PID 1633 | RSS: Main: 325541888 17:16:27 INFO - PID 1633 | 17:19:30 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-yui.html (next: http://localhost:49234/tests/dromaeo/cssquery-dojo.html) 17:19:30 INFO - PID 1633 | RSS: Main: 368103424 17:19:30 INFO - PID 1633 | 17:19:30 INFO - PID 1633 | __start_tp_report 17:19:30 INFO - PID 1633 | _x_x_mozilla_page_load 17:19:30 INFO - PID 1633 | _x_x_mozilla_page_load_details 17:19:30 INFO - PID 1633 | |i|pagename|runs| 17:19:30 INFO - PID 1633 | |0;dojo.html;3381.628020917697;3513.6837684608395;3569.6251893551166;3577.3739595570787;3594.5506811648543;5745.109508026252;6094.969525152379;6267.749290028399;6278.654673992928;6312.274088479815;5775.682337471438;6224.719887605057;6294.874102517958;6298.9921612542;6306.337834527366;5263.394709608406;5323.707196104213;5413.485718856702;5439.755211015514;5474.206240095204;4620.798592365975;5541.168824676291;5543.168524721284;5549.861253468675;5561.416051314614;5349.277847490617;5415.6479828811;5426.891462170774;5472.863178420532;5508.1462373332;5348.812791552277;5364.651297665679;5385.703786291752;5390.407055223922;5413.756380962866;5281.339832520935;5326.307580014612;5347.438518955492;5358.544523715489;5360.142377219665;6151.354107818659;6248.343923888017;6318.74725010999;6319.399657032574;6319.8736025279695;6628.536002479828;6632.568883022588;6632.6683665817;6636.800895973129;6660.2340730816095;6256.374362563753;6643.103181070542;6749.7300107995625;6763.797086087426;6764.729410823561;6223.564350495421;6265.530085243625;6295.96852015737;6299.590526615755;6343.524235682296;6722.260551339349;6743.123393958754;6759.966200168968;6859.108315918898;6932.272111428329;6531.804045878631;6542.21493420783;6545.836354091186;6545.869082618321;6558.344165583499;6694.263631000586;6694.564853284521;6696.229933557605;6697.196336439659;6706.932930670729;6399.648019358887;6457.2574153971955;6601.339866013456;6621.9337806621315;6627.37039981201;6602.669866506655;6673.733050678015;6705.161854768154;6705.530612857151;6716.1604799400075;5299.4435584263865;5324.041672498987;5326.92009619856;5343.171808369709;5377.892442151135;4592.265237561974;4649.325847752057;4714.3871296731895;4728.59806916416;4775.283707443842;6217.813465596039;6280.591761535486;6302.527310451735;6352.968235158794;6357.682115894187;6734.6632668367365;6760.932390676128;6776.525643204928;6785.762498312634;6798.660066996729;5342.492463215988;6289.685515724195;6334.778282760173;6364.331745166691;6364.42720155179;5761.942380576141;5818.8254352369495;5888.4700376966275;5929;5935;5866.472017518444;6780.186377634715;6923.238443771081;6998.16022077354;7026.086608740831;6517.608943463408;6594.637294948824;6611.206655201407;6736.225334086643;6828.487863410165;6823.863522729617;6829.141664753067;6905.274946130584;6907.13660792401;6936.445084393337;6534.477241820738;6544.770933017321;6553.246376666744;6560.606363618198;6564.80305590833;6543.247526534319;6614.140161778938;6681.064650948774;6689.364510371507;6740.12378390806;6486.4486518646445;6503.349665033459;6519.250286217146;6521;6541.672916354068;4984.501549844987;6441.259255185526;6453.838654033687;6457.192850893638;6520.836979075561;5517.813670060957;6241.282252541016;6294.402031806971;6358.395952384516;6371.267304260069;6500.609963402211;6527.738890444519;6528.053432252202;6536.901946470712;6553.836154096186;5270.578353731769;6341.55609107358;6343.873122537616;6351.618902865843;6352.047192920914 17:19:30 INFO - PID 1633 | |1;ext.html;737.3842841227574;769.3768047881217;802.7792357101798;1106.363840791545;1126.003486914081;25715.871420642892;35492.75775347863;35714.285714285696;35819.64180358199;35831;37346.25307493848;37742.43386349207;37755.62244377555;37770.8111459443;37804.43293350595;5887.0580707086665;6008.879822403549;8124.228198321165;8262.628181731836;8281;8043.356531477468;8179.631916563769;8194.713185038525;8206.425550211488;8210.630521626541;8226.136255693156;8283.917160828405;8293.377996650244;8335.166483351646;8341.332693384546;8213.466124701905;8218.753437396888;8267.503949763033;8290.41967062306;8296.58517074147;8073.6366863491285;8173.386995975326;8207.95896020522;8227.465214761021;8228.382871284617;35301.646983530096;35440.936771896886;35467.4679879802;35470.11324716903;35627.4655880162;3573.3925232710503;3593.5508061492314;3596.334678084537;3597.4244120940784;3598.5501812273465;29247.56128658072;29401.823927042893;29435;29452.410951781058;29504.26239344012;3072.5698402223707;3141.5444760509827;3158.6367567729776;3169.2869104451543;3179.82510961897;235840;293071;293645.0635493639;296008;296502;47113.5288647116;47172.292415613796;47173.5282647176;47205.29192062122;47266.5273347269;50123.24815127776;50201.99596008132;50336.49663503318;50386.24420633693;50445.74777126091;13992.230427326595;14080.577582672537;14093.436262549383;14104.576862694135;14119.576412707635;9853.950730246446;9871.851922221173;9885.25860560461;11488.94255528717;11531.827022594667;11549.88450115505;11569.363684997406;11635.825462618068;11661.35862527569;11685.298882067103;4365.628921541641;5137.17805151174;5171.922421163686;5197.480251974772;5199.700074981255;1008.7276435362411;1010.3988127064385;1012.4026824173626;1044.3368461027303;1045.7281106912258;17752.91123544374;21267.893660531598;22550.32349029532;23690.28929132129;23715.40711482227;1105.789899919013;1106.5407855739777;1137.4256000719583;1138.6014894786758;1156.1329003247565;11533.480993355319;12027.759444811054;12198.14612977083;12235.632931012084;12258.08064395174;302880;303742;305174;305478;306124;49937.750311248936;50054.74972625114;50126.997460050596;50137.74931125394;50210.748946255764;1463.7072585482733;1514.3109885002298;1553.2854886751995;1555.4400415850284;1569.3016607609504;20758.06588703542;22957.311280661608;22960.885195573916;23082.307530774102;23230.767692323538;36677.63322366849;36703.89888303355;36738.8163059183;36804.89585312445;36979.26041479102;266612;267597;268435;268442;269147;24076.51846963086;27895;31374;32276.83861580677;32397.676023239466;34351.4847277286;34407;39195.41206881842;39960;42037.78981105075;28494.287642809097;29133.417331652825;29645.25886852846;29725;30437.543436848024;25784.484310314063;28231.43537129287;28716.42567148687;29219.85390073036;29344.11967640974;33864.322713546084;34210.65789342174;35074.82462587671;35199.64800351964;35253.942381728586;28587.28531786722;28927.69825357906;29308.267293316985;29558.078724882813;30448.695513045473;22638.773612263667;22921.541569168858;23224.76775232226;23380.532389351778;23407.41481462977 17:19:30 INFO - PID 1633 | |2;jquery.html;372.9533808273966;426.9709998902645;440.709131972898;440.76419115773064;454.12354156478006;16069.51791446257;20853.165873365066;21494.785052149495;21977.890110549466;22238.99924503397;21082;21676.89161554194;21963.450913727123;22186.556268874654;22617.54764904701;4784.899473121167;6041.909371359433;6235.688215589225;6250.749970001195;6272.090546870701;5999.370066143058;6255.061740738903;6281.842953926142;6282.7172777224905;6285.245770507523;6032.245969253843;6108.205933228674;6194.00895856663;6196.1944947157035;6250.374962503759;5342.893142137155;5546.69493177875;6002.439365764889;6077.088436734503;6162.983107787232;5422.349318081816;5439.700816455093;5513.283273174502;5513.338399392059;5514.420985796473;20741.481462963395;22127.668084978737;26610;26720.866395667897;26807;2181.596404665142;2254.1434254983164;2254.5490901819703;2256.4697296135396;2260.2089268755967;17576;17634.91182544079;17779.73330400032;18094.63810723778;18297.359592414203;2080.5318803269297;2101.8423618228694;2154.1598776477185;2155.805977462036;2156.590247852903;125009;138301.925471118;142925;142989;143492;11373;12065;12486.50053997848;12546.749065018648;12591.748165036648;13782.517611883535;15258.694826103416;15261.160636165117;15596;16317.673646527002;8787.956060219658;8891;9008.144226298491;9104.544772761468;9111.498867562215;8509.959523857018;8530.914690853137;8536.829263414696;8717.259032982141;8717.912820871838;5863.29640443141;7409.222031686703;7486.737964171228;7596.962015189999;7633.679349482993;1871.4666320098706;2176.738791345049;2192.6820611011312;2197.7582465928735;2209.304069218191;942.4863449420083;974.2692980264801;975.5609975510906;1014.7970405918845;1033.6382266206917;20980.685289720666;21607.02768375427;21619.243326483498;21639;21673.916304184728;1005.7938122684832;1020.5764607687875;1027.3476342522404;1038.2557303729657;1040.8126537223372;10584.364938103738;10640.989106034915;10649.627263045757;10946;10948.34309941406;530170;538125;538902;552705;554745;112271.43864280626;118513.11151663659;130303;133342;134018;1189.6728399689982;1204.28345134643;1214.860291066521;1231.815227715832;1234.3889774561649;18309.633807324044;18364.265429382674;18425.907870460564;18443.631127377645;18503.907480462512;54156.458435415145;56486.43513564812;58494.707526462094;58831.70584147052;59033.40966590279;75116;76051.61974190094;78129.60935195515;78618.6069069651;79154.81267780873;7196.352328290479;8620.568971551524;8869.246114080117;8908.10918908104;8947.552622368725;13631.182129072287;13669.384877680726;13672.316384180951;14050.367733452224;14148.575542733735;11663.300201987908;11722.118076446826;11795.52817887275;11939.940300298444;12119.151659383759;10291.691249262534;11059.28114672576;11140.220184587;11396.088312935111;11415.543378264776;10614.522346494581;11297.548098075986;11823.172377933462;12094.153409261267;12295.446704898122;8597.914020859711;8786.516741579275;8789.868151977598;8865.468071915706;9117.77205569866;10025.649102281384;11834.940825295818;11924.821127682919;11997.220180688228;12044.698882528008 17:19:30 INFO - PID 1633 | |3;mootools.html;1422.4523558430005;1452.2520901735602;1482.4737218287507;1500.579837645459;1503.0906301687478;11194.776104477916;11702.707432314186;11715.707107322321;11729.003034742058;11732.413379331032;11361;11584.13119016073;11608.767824643503;11610.883891161086;11617.941910290438;2494.4761600064007;2543.707473640527;2544.427503811646;2544.9618255726177;2550.885210165547;2485.4159272570932;2529.0895277700015;2532.189699296226;2533.5946248600226;2536.5687833068423;2461.433870209853;2501.9749802502015;2526.734692857251;2530.266222795387;2537.1880998080624;2229;2426.7694569015916;2497.8501289922574;2506;2514.0823599386276;2439.4755127647463;2457.741937096615;2459.938501537458;2483.8261321707487;2487.8756062197;11388.202825802196;11421.086313094933;11539.134564907581;11546.076313894868;11562.826557601642;6285.968570157165;6530.6408147551865;6536.836579085513;6561.212654481445;6573.375529324707;3825.6365645263654;4111.835526578963;4150.481189851269;4161.687873409476;4188.727732697395;1225.6261840138754;1238.851337839456;1300.478440224939;1303.758804621148;1311.757324894888;15238.923805381013;15620.984635998737;15643.843561564467;16138.354465821352;16241.512754617263;5292.153255479105;5430.8099216527235;5558.86102847424;5575.135853942646;5593.74828132735;11658.475368608264;11659.941700291443;11664.708382290512;11667.299962002307;11686.94156529212;5738.08190689496;5933.258342707161;6530.379613936669;6535.281119076898;6553.737850485927;6008.789692360747;6059.8485037873525;6229.688515574203;6236.407541283571;6236.438720515175;4290.420793192941;4374.343848422762;4392.692511524163;4440.089781594766;4456.7548784817145;1083.3554526597193;1253.3106791264768;1261.9495220191272;1273.2933222061845;1277.0294576122214;742.3726950726627;767.1944458318743;797.908240552344;812.3947658993969;816.5019338203691;6548.377904099103;6793.184817821893;6956.95645653156;6964.338387853146;7023.051887995158;942.9009953954874;954.9856752148723;964.392432767352;966.8114717629994;988.2193067476757;3608.6030536640947;3830.482884810571;3872.3029854625875;3954.387070004154;3970.3250447424007;14664.780028299583;14908.47820326305;15089.849101509064;15097.471588494569;15119.924400377928;14459.27703614815;14979.775303370458;15021.098734075991;15023.02350347224;15051.849481505264;7536.962315188389;8480.194381533744;8689.782755431164;8701.17338852808;8752.387332886765;6108.328083910677;6438.291787903233;7029.26192749754;7066.57600543969;7076.824673442694;12449.751004980031;12581.245125292511;12655.430505627084;13673.794893076412;13678.179309241477;13145;13463.932680336535;13729.382177802223;13761.862381376059;13931.30343482842;9078.45529268246;9218.01812610292;9243.676471323472;9385.812283754423;9418.293627977791;10611.09805666527;10682.679519614114;10687.25189236746;10688.89311106879;10704.411257380918;8153.143919888326;8154.9806274215725;8899.510526921082;8995.55022248898;9001.234895033995;8777.473351598925;8857.911420885966;8880.822383552422;8943.821123577362;9072.546372681207;7860.646270917708;7926.643301051351;7975.601219939096;8091.716789912325;8113.878291825746;5924.437178468039;6012.398760123952;6047.213862197887;6053.303870055;6064.696765161636;6043.12374705674;6102.725377358119;6119.816405507842;6137.60105593135;6214.627122372672 17:19:30 INFO - PID 1633 | |4;prototype.html;397.61828644501276;398.11220977220796;401.05951543630187;402.38033428520083;405.40000798817766;5037.848864534065;5443.401225865152;6065.514758819295;6155.907661385083;6187.412195841393;5899.380565040673;5962.463378295953;5990.311114221866;6051.788187413442;6094.878102437949;3814.5231846019246;4596.655250856183;4597.954020459786;4602.585767280944;4619.683390233792;4623.052274283764;4624;4631.212693842038;4631.745254011028;4633.953660463403;4416.955830441702;4417.249067658489;4548.567886050836;4556.2254416749065;4580.175568397687;3874.786886721229;4479.3281007848755;4544.909101817978;4560.680752347321;4565.589096981271;3698.685611723007;3928.8213535939103;4048.352263637804;4058.939115913264;4069.6337329640473;5560.999020176367;5646.2377579027125;5860.6850294134;5904.645721256738;5945.197398351211;5211.791528338862;5215.765290561933;5237.79048838046;5261.053010458116;5268.631195816294;5291.3385826771655;5887.646741195542;6067.514598832127;6397.296297407282;6423.454006409461;3596.496490491333;4238.682098842568;4287.142571485715;4291.334843099294;4305.677074219446;6545.9018114728315;6682.331766823376;6707.228668703064;6769.763058292985;6878.27778083299;3518.3842827505086;4255.468066491689;4296.419983302277;4326.394304797331;4336.978315108404;4388.253996820485;4409.5149533551285;4412.84555040572;4414.580614841585;4464.17412778637;5030.245463180552;5107.284980102789;5182.948170518322;5191.792328306901;5217.660852044605;5201.557867581222;6341.850721822436;6419.807405777834;6426.228852537725;6449.516286278548;1481.7629179331254;1499.130504307499;1500.3398504657916;1504.5561559340022;1505.4956589542408;809.7489778168787;836.3685417509741;911.5715613661702;986.4180133721139;1004.1924535835612;5033.7734801934;5201.635885487979;6128.601640893327;6155.384461553898;6168.352323006109;5579.888402231933;6438.130852334969;6648.368405001517;6666;6671.366220209073;6023.789167379121;6129.387061293835;6143.508519318444;6151.784687536005;6182.412670796267;2988.4770165221435;3173.841307934594;3286.274705779437;3415.8121303328558;3461.44616861301;6227.532935029891;6234.501239900797;6520.18497687789;6723.226828914738;6885.414739747077;5599.116455025665;5608.663480191201;5689.800856969986;6835.418989385858;6873.381395674413;5705.0635061807225;5824.6704265597355;6824.488163387766;6889.965550172217;6908.481863860231;6352.936470635419;6609.735610575523;6629.834254143685;6650.434713049445;6655;6381.106645069601;6494.935050649433;6504.674766261763;6538.738450461929;6588.8023359299295;6795.422389096982;6799;6811.523193376416;6822.658867056528;6874.175098988154;4318.784060796885;4341.305391137403;4341.565843415633;4349.151915376598;4350.21696094706;5655.586103474131;6075.27096748393;6380.055457020846;6407;6729.495287853529;6448.387403196689;6461.547691661539;6484.546081774419;6488.448481879093;6498.415142637185;5042.4705405931845;5557.972210139085;6486.2216534016225;6502.60984340941;6504.252011018793;5866.266716660418;6348.650824204713;6359.332270111757;6359.8092057238355;6365.331640177715;6313.715882785377;6468.094466774562;6511.511636627367;6532.41208291256;6560.573562718408;6032.276126864629;6294.716737746904;6325.304216536269;6325.564657606763;6361.332060133619 17:19:30 INFO - PID 1633 | |5;yui.html;818.930390916772;839.6431516605443;845.4800297816845;847.6736456464263;863.0420233540768;13514.391852366642;14146.858531414682;16074.276657550408;16532.66934661306;16575.834241657594;11805.586804461846;12882.098253122285;17283.74074388885;17335.82664173358;17367.47897563072;980.0640388429068;986.2553771902204;992.1566668331932;992.8212921674095;994.8955359687237;1008.0574662690408;1009.878814542256;1010.9090181883629;1011.1152741351318;1011.8988101189896;686.2588404523103;704.8907419350008;1000.2797985450501;1022.2486472442748;1031.7730099378125;1021.0555236406369;1021.1065317846884;1024.2318261304022;1026.2303272545591;1036.5387402605843;762.4662736084763;763.7823220382164;766.1151370167465;767.1178145133085;773.234497847133;15912.283947222402;16288.755668664979;16430.260638271186;16685.749713754303;16988.405405810736;16346.509604711877;16399.016059036498;16629.584260393465;16818.24317905697;16846.915765421218;13644.727105457934;13814.723705525934;13935.512257070954;14024.018318717694;14147.29263536819;3409.829508524564;3417.419038763416;3421.7091547218515;3425.0067480430644;3428.6914177724125;118406;119620.40189798996;122012;122676;122731.77268227293;10461.581536738446;11907.34509601963;11940.940295298642;12110.515579376726;12125.818112728317;20259.696104558443;20324.69512957307;20928.47678808042;20946.371608851758;20988.685169722467;7229.855402892018;7367.81580460482;7370.631468426557;7392.075990501187;7395.778126656209;3583.5699716033723;3617.0957260684827;3618.5476815398074;3624.1664417184197;3665.9633403666157;4065.735727177724;4130.173965206971;4187;4195.119025004722;4195.286801243796;843.9831203375898;867.7526904832218;878.1481962496368;880.9075047120089;883.4566741454088;629.2040568680623;661.3518751623436;676.7631329034798;681.1655721740926;685.9657017149223;10874.619388321371;11221.60724374643;11505.769884602261;11932.343721095423;12058.517659293706;637.0953246390045;650.9121268628769;654.6268626882631;663.5587334422652;681.9897701534481;5333.413324534298;5434.592405569598;5512.3660779010925;5521.116621340647;5580.91628625571;106278.46860765801;108075;108342;108504.4574777121;108836;98181;99211.5433651156;99428.00571994332;100222.49888750656;101405.47891781482;55057.724711376984;71445.6427717858;74762.62618686871;76165.61917190379;76611;10949.678433401297;11591.88408115908;12027.699307517032;12146.635600931986;12402.75194496123;42031;42249.78875105605;42317.78841105775;43046.56953430425;43062.78468607762;53450.73274633602;54266.728666357994;54770.17844732253;55180.89638207293;55656.443435565125;15847.52457426279;15961.920190399438;16083.35666573324;16428.425005125242;16443.58891027734;17406.73889891678;17895.10524473797;18141.818581814012;18244;18423.723644145615;15972.28124734413;16125.596860078593;16609.252583633523;16720.498385048468;16721.331146753993;16021.839781602035;16050.67898642044;16400.507984760476;16532.917335413247;16646.084465354037;16430.17849107516;16716.331346745996;16774;17150.485485435456;17155.399561015303;11683.415829208336;12021.939890300493;12086.15396922207;12202.81695774582;12209.267443953391;11447.370394628375;12780.552680656132;13390.330483475593;13790.069830098373;14029.228392438514 17:19:30 INFO - PID 1633 | __end_tp_report 17:19:30 INFO - PID 1633 | __start_cc_report 17:19:30 INFO - PID 1633 | _x_x_mozilla_cycle_collect,1472 17:19:30 INFO - PID 1633 | __end_cc_report 17:19:30 INFO - PID 1633 | __startTimestamp1494029970477__endTimestamp 17:19:30 INFO - PID 1633 | 17:19:30 INFO - PID 1633 | ------- Summary: start ------- 17:19:30 INFO - PID 1633 | Number of tests: 6 17:19:30 INFO - PID 1633 | 17:19:30 INFO - PID 1633 | [#0] dojo.html Cycles:165 Average:6118.61 Median:6352.97 stddev:742.04 (11.7%) stddev-sans-first:712.57 17:19:30 INFO - PID 1633 | Values: 3381.6 3513.7 3569.6 3577.4 3594.6 5745.1 6095.0 6267.7 6278.7 6312.3 5775.7 6224.7 6294.9 6299.0 6306.3 5263.4 5323.7 5413.5 5439.8 5474.2 4620.8 5541.2 5543.2 5549.9 5561.4 5349.3 5415.6 5426.9 5472.9 5508.1 5348.8 5364.7 5385.7 5390.4 5413.8 5281.3 5326.3 5347.4 5358.5 5360.1 6151.4 6248.3 6318.7 6319.4 6319.9 6628.5 6632.6 6632.7 6636.8 6660.2 6256.4 6643.1 6749.7 6763.8 6764.7 6223.6 6265.5 6296.0 6299.6 6343.5 6722.3 6743.1 6760.0 6859.1 6932.3 6531.8 6542.2 6545.8 6545.9 6558.3 6694.3 6694.6 6696.2 6697.2 6706.9 6399.6 6457.3 6601.3 6621.9 6627.4 6602.7 6673.7 6705.2 6705.5 6716.2 5299.4 5324.0 5326.9 5343.2 5377.9 4592.3 4649.3 4714.4 4728.6 4775.3 6217.8 6280.6 6302.5 6353.0 6357.7 6734.7 6760.9 6776.5 6785.8 6798.7 5342.5 6289.7 6334.8 6364.3 6364.4 5761.9 5818.8 5888.5 5929.0 5935.0 5866.5 6780.2 6923.2 6998.2 7026.1 6517.6 6594.6 6611.2 6736.2 6828.5 6823.9 6829.1 6905.3 6907.1 6936.4 6534.5 6544.8 6553.2 6560.6 6564.8 6543.2 6614.1 6681.1 6689.4 6740.1 6486.4 6503.3 6519.3 6521.0 6541.7 4984.5 6441.3 6453.8 6457.2 6520.8 5517.8 6241.3 6294.4 6358.4 6371.3 6500.6 6527.7 6528.1 6536.9 6553.8 5270.6 6341.6 6343.9 6351.6 6352.0 17:19:30 INFO - PID 1633 | 17:19:30 INFO - PID 1633 | [#1] ext.html Cycles:180 Average:42726.01 Median:3163.96 stddev:75024.00 (2371.2%) stddev-sans-first:75168.23 17:19:30 INFO - PID 1633 | Values: 737.4 769.4 802.8 1106.4 1126.0 25715.9 35492.8 35714.3 35819.6 35831.0 37346.3 37742.4 37755.6 37770.8 37804.4 5887.1 6008.9 8124.2 8262.6 8281.0 8043.4 8179.6 8194.7 8206.4 8210.6 8226.1 8283.9 8293.4 8335.2 8341.3 8213.5 8218.8 8267.5 8290.4 8296.6 8073.6 8173.4 8208.0 8227.5 8228.4 35301.6 35440.9 35467.5 35470.1 35627.5 3573.4 3593.6 3596.3 3597.4 3598.6 29247.6 29401.8 29435.0 29452.4 29504.3 3072.6 3141.5 3158.6 3169.3 3179.8 235840.0 293071.0 293645.1 296008.0 296502.0 47113.5 47172.3 47173.5 47205.3 47266.5 50123.2 50202.0 50336.5 50386.2 50445.7 13992.2 14080.6 14093.4 14104.6 14119.6 9854.0 9871.9 9885.3 11488.9 11531.8 11549.9 11569.4 11635.8 11661.4 11685.3 4365.6 5137.2 5171.9 5197.5 5199.7 1008.7 1010.4 1012.4 1044.3 1045.7 17752.9 21267.9 22550.3 23690.3 23715.4 1105.8 1106.5 1137.4 1138.6 1156.1 11533.5 12027.8 12198.1 12235.6 12258.1 302880.0 303742.0 305174.0 305478.0 306124.0 49937.8 50054.7 50127.0 50137.7 50210.7 1463.7 1514.3 1553.3 1555.4 1569.3 20758.1 22957.3 22960.9 23082.3 23230.8 36677.6 36703.9 36738.8 36804.9 36979.3 266612.0 267597.0 268435.0 268442.0 269147.0 24076.5 27895.0 31374.0 32276.8 32397.7 34351.5 34407.0 39195.4 39960.0 42037.8 28494.3 29133.4 29645.3 29725.0 30437.5 25784.5 28231.4 28716.4 29219.9 29344.1 33864.3 34210.7 35074.8 35199.6 35253.9 28587.3 28927.7 29308.3 29558.1 30448.7 22638.8 22921.5 23224.8 23380.5 23407.4 17:19:30 INFO - PID 1633 | 17:19:30 INFO - PID 1633 | [#2] jquery.html Cycles:180 Average:34633.98 Median:2187.14 stddev:91618.34 (4189.0%) stddev-sans-first:91839.24 17:19:30 INFO - PID 1633 | Values: 373.0 427.0 440.7 440.8 454.1 16069.5 20853.2 21494.8 21977.9 22239.0 21082.0 21676.9 21963.5 22186.6 22617.5 4784.9 6041.9 6235.7 6250.7 6272.1 5999.4 6255.1 6281.8 6282.7 6285.2 6032.2 6108.2 6194.0 6196.2 6250.4 5342.9 5546.7 6002.4 6077.1 6163.0 5422.3 5439.7 5513.3 5513.3 5514.4 20741.5 22127.7 26610.0 26720.9 26807.0 2181.6 2254.1 2254.5 2256.5 2260.2 17576.0 17634.9 17779.7 18094.6 18297.4 2080.5 2101.8 2154.2 2155.8 2156.6 125009.0 138301.9 142925.0 142989.0 143492.0 11373.0 12065.0 12486.5 12546.7 12591.7 13782.5 15258.7 15261.2 15596.0 16317.7 8788.0 8891.0 9008.1 9104.5 9111.5 8510.0 8530.9 8536.8 8717.3 8717.9 5863.3 7409.2 7486.7 7597.0 7633.7 1871.5 2176.7 2192.7 2197.8 2209.3 942.5 974.3 975.6 1014.8 1033.6 20980.7 21607.0 21619.2 21639.0 21673.9 1005.8 1020.6 1027.3 1038.3 1040.8 10584.4 10641.0 10649.6 10946.0 10948.3 530170.0 538125.0 538902.0 552705.0 554745.0 112271.4 118513.1 130303.0 133342.0 134018.0 1189.7 1204.3 1214.9 1231.8 1234.4 18309.6 18364.3 18425.9 18443.6 18503.9 54156.5 56486.4 58494.7 58831.7 59033.4 75116.0 76051.6 78129.6 78618.6 79154.8 7196.4 8620.6 8869.2 8908.1 8947.6 13631.2 13669.4 13672.3 14050.4 14148.6 11663.3 11722.1 11795.5 11939.9 12119.2 10291.7 11059.3 11140.2 11396.1 11415.5 10614.5 11297.5 11823.2 12094.2 12295.4 8597.9 8786.5 8789.9 8865.5 9117.8 10025.6 11834.9 11924.8 11997.2 12044.7 17:19:30 INFO - PID 1633 | 17:19:30 INFO - PID 1633 | [#3] mootools.html Cycles:180 Average:7033.11 Median:3717.12 stddev:4423.09 (119.0%) stddev-sans-first:4415.40 17:19:30 INFO - PID 1633 | Values: 1422.5 1452.3 1482.5 1500.6 1503.1 11194.8 11702.7 11715.7 11729.0 11732.4 11361.0 11584.1 11608.8 11610.9 11617.9 2494.5 2543.7 2544.4 2545.0 2550.9 2485.4 2529.1 2532.2 2533.6 2536.6 2461.4 2502.0 2526.7 2530.3 2537.2 2229.0 2426.8 2497.9 2506.0 2514.1 2439.5 2457.7 2459.9 2483.8 2487.9 11388.2 11421.1 11539.1 11546.1 11562.8 6286.0 6530.6 6536.8 6561.2 6573.4 3825.6 4111.8 4150.5 4161.7 4188.7 1225.6 1238.9 1300.5 1303.8 1311.8 15238.9 15621.0 15643.8 16138.4 16241.5 5292.2 5430.8 5558.9 5575.1 5593.7 11658.5 11659.9 11664.7 11667.3 11686.9 5738.1 5933.3 6530.4 6535.3 6553.7 6008.8 6059.8 6229.7 6236.4 6236.4 4290.4 4374.3 4392.7 4440.1 4456.8 1083.4 1253.3 1261.9 1273.3 1277.0 742.4 767.2 797.9 812.4 816.5 6548.4 6793.2 6957.0 6964.3 7023.1 942.9 955.0 964.4 966.8 988.2 3608.6 3830.5 3872.3 3954.4 3970.3 14664.8 14908.5 15089.8 15097.5 15119.9 14459.3 14979.8 15021.1 15023.0 15051.8 7537.0 8480.2 8689.8 8701.2 8752.4 6108.3 6438.3 7029.3 7066.6 7076.8 12449.8 12581.2 12655.4 13673.8 13678.2 13145.0 13463.9 13729.4 13761.9 13931.3 9078.5 9218.0 9243.7 9385.8 9418.3 10611.1 10682.7 10687.3 10688.9 10704.4 8153.1 8155.0 8899.5 8995.6 9001.2 8777.5 8857.9 8880.8 8943.8 9072.5 7860.6 7926.6 7975.6 8091.7 8113.9 5924.4 6012.4 6047.2 6053.3 6064.7 6043.1 6102.7 6119.8 6137.6 6214.6 17:19:30 INFO - PID 1633 | 17:19:30 INFO - PID 1633 | [#4] prototype.html Cycles:180 Average:5191.60 Median:5893.51 stddev:1622.50 (27.5%) stddev-sans-first:1586.65 17:19:30 INFO - PID 1633 | Values: 397.6 398.1 401.1 402.4 405.4 5037.8 5443.4 6065.5 6155.9 6187.4 5899.4 5962.5 5990.3 6051.8 6094.9 3814.5 4596.7 4598.0 4602.6 4619.7 4623.1 4624.0 4631.2 4631.7 4634.0 4417.0 4417.2 4548.6 4556.2 4580.2 3874.8 4479.3 4544.9 4560.7 4565.6 3698.7 3928.8 4048.4 4058.9 4069.6 5561.0 5646.2 5860.7 5904.6 5945.2 5211.8 5215.8 5237.8 5261.1 5268.6 5291.3 5887.6 6067.5 6397.3 6423.5 3596.5 4238.7 4287.1 4291.3 4305.7 6545.9 6682.3 6707.2 6769.8 6878.3 3518.4 4255.5 4296.4 4326.4 4337.0 4388.3 4409.5 4412.8 4414.6 4464.2 5030.2 5107.3 5182.9 5191.8 5217.7 5201.6 6341.9 6419.8 6426.2 6449.5 1481.8 1499.1 1500.3 1504.6 1505.5 809.7 836.4 911.6 986.4 1004.2 5033.8 5201.6 6128.6 6155.4 6168.4 5579.9 6438.1 6648.4 6666.0 6671.4 6023.8 6129.4 6143.5 6151.8 6182.4 2988.5 3173.8 3286.3 3415.8 3461.4 6227.5 6234.5 6520.2 6723.2 6885.4 5599.1 5608.7 5689.8 6835.4 6873.4 5705.1 5824.7 6824.5 6890.0 6908.5 6352.9 6609.7 6629.8 6650.4 6655.0 6381.1 6494.9 6504.7 6538.7 6588.8 6795.4 6799.0 6811.5 6822.7 6874.2 4318.8 4341.3 4341.6 4349.2 4350.2 5655.6 6075.3 6380.1 6407.0 6729.5 6448.4 6461.5 6484.5 6488.4 6498.4 5042.5 5558.0 6486.2 6502.6 6504.3 5866.3 6348.7 6359.3 6359.8 6365.3 6313.7 6468.1 6511.5 6532.4 6560.6 6032.3 6294.7 6325.3 6325.6 6361.3 17:19:30 INFO - PID 1633 | 17:19:30 INFO - PID 1633 | [#5] yui.html Cycles:180 Average:21413.38 Median:17152.94 stddev:30616.07 (178.5%) stddev-sans-first:30662.90 17:19:30 INFO - PID 1633 | Values: 818.9 839.6 845.5 847.7 863.0 13514.4 14146.9 16074.3 16532.7 16575.8 11805.6 12882.1 17283.7 17335.8 17367.5 980.1 986.3 992.2 992.8 994.9 1008.1 1009.9 1010.9 1011.1 1011.9 686.3 704.9 1000.3 1022.2 1031.8 1021.1 1021.1 1024.2 1026.2 1036.5 762.5 763.8 766.1 767.1 773.2 15912.3 16288.8 16430.3 16685.7 16988.4 16346.5 16399.0 16629.6 16818.2 16846.9 13644.7 13814.7 13935.5 14024.0 14147.3 3409.8 3417.4 3421.7 3425.0 3428.7 118406.0 119620.4 122012.0 122676.0 122731.8 10461.6 11907.3 11940.9 12110.5 12125.8 20259.7 20324.7 20928.5 20946.4 20988.7 7229.9 7367.8 7370.6 7392.1 7395.8 3583.6 3617.1 3618.5 3624.2 3666.0 4065.7 4130.2 4187.0 4195.1 4195.3 844.0 867.8 878.1 880.9 883.5 629.2 661.4 676.8 681.2 686.0 10874.6 11221.6 11505.8 11932.3 12058.5 637.1 650.9 654.6 663.6 682.0 5333.4 5434.6 5512.4 5521.1 5580.9 106278.5 108075.0 108342.0 108504.5 108836.0 98181.0 99211.5 99428.0 100222.5 101405.5 55057.7 71445.6 74762.6 76165.6 76611.0 10949.7 11591.9 12027.7 12146.6 12402.8 42031.0 42249.8 42317.8 43046.6 43062.8 53450.7 54266.7 54770.2 55180.9 55656.4 15847.5 15961.9 16083.4 16428.4 16443.6 17406.7 17895.1 18141.8 18244.0 18423.7 15972.3 16125.6 16609.3 16720.5 16721.3 16021.8 16050.7 16400.5 16532.9 16646.1 16430.2 16716.3 16774.0 17150.5 17155.4 11683.4 12021.9 12086.2 12202.8 12209.3 11447.4 12780.6 13390.3 13790.1 14029.2 17:19:30 INFO - PID 1633 | -------- Summary: end -------- 17:19:30 INFO - PID 1633 | 17:19:31 INFO - TEST-INFO | 1633: exit 0 17:19:31 INFO - TEST-OK | dromaeo_css | took 1118856ms 17:19:31 INFO - TEST-START | kraken 17:19:31 INFO - operating with platform_type : mac_ 17:19:31 INFO - Initialising browser for kraken test... 17:19:31 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpKyTP4h/profile http://localhost:49234/getInfo.html) 17:19:33 INFO - PID 1644 | __metrics Screen width/height:1600/1200 17:19:33 INFO - PID 1644 | colorDepth:24 17:19:33 INFO - PID 1644 | Browser inner width/height: 1024/691 17:19:33 INFO - PID 1644 | __metrics 17:19:33 INFO - PID 1644 | 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" 17:19:33 INFO - PID 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:19:34 INFO - TEST-INFO | 1644: exit 0 17:19:34 INFO - Browser initialized. 17:19:34 INFO - Running cycle 1/1 for kraken test... 17:19:34 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpKyTP4h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:19:47 INFO - PID 1645 | RSS: Main: 240234496 17:19:47 INFO - PID 1645 | 17:19:49 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection) 17:19:50 INFO - PID 1645 | RSS: Main: 308248576 17:19:50 INFO - PID 1645 | 17:19:53 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-dft) 17:19:54 INFO - PID 1645 | RSS: Main: 290197504 17:19:54 INFO - PID 1645 | 17:19:57 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-fft) 17:19:58 INFO - PID 1645 | RSS: Main: 381939712 17:19:58 INFO - PID 1645 | 17:20:00 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator) 17:20:00 INFO - PID 1645 | RSS: Main: 285028352 17:20:00 INFO - PID 1645 | 17:20:02 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:20:03 INFO - PID 1645 | RSS: Main: 288608256 17:20:03 INFO - PID 1645 | 17:20:06 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom) 17:20:06 INFO - PID 1645 | RSS: Main: 351752192 17:20:06 INFO - PID 1645 | 17:20:09 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate) 17:20:10 INFO - PID 1645 | RSS: Main: 367099904 17:20:10 INFO - PID 1645 | 17:20:13 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial) 17:20:13 INFO - PID 1645 | RSS: Main: 371908608 17:20:13 INFO - PID 1645 | 17:20:15 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:20:16 INFO - PID 1645 | RSS: Main: 310738944 17:20:16 INFO - PID 1645 | 17:20:17 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:20:18 INFO - PID 1645 | RSS: Main: 331259904 17:20:18 INFO - PID 1645 | 17:20:20 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:20:21 INFO - PID 1645 | RSS: Main: 314503168 17:20:21 INFO - PID 1645 | 17:20:24 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:20:24 INFO - PID 1645 | RSS: Main: 333996032 17:20:24 INFO - PID 1645 | 17:20:28 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:20:28 INFO - PID 1645 | RSS: Main: 344621056 17:20:28 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49234/tests/kraken/driver.html?testName=ai-astar) 17:20:30 INFO - PID 1645 | RSS: Main: 387248128 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | __start_tp_report 17:20:30 INFO - PID 1645 | _x_x_mozilla_page_load 17:20:30 INFO - PID 1645 | _x_x_mozilla_page_load_details 17:20:30 INFO - PID 1645 | |i|pagename|runs| 17:20:30 INFO - PID 1645 | |0;ai-astar;111;112;110;112;112;98;139;106;149;163 17:20:30 INFO - PID 1645 | |1;audio-beat-detection;140;103;107;148;108;134;131;108;132;107 17:20:30 INFO - PID 1645 | |2;audio-dft;185;199;257;228;186;184;181;184;176;185 17:20:30 INFO - PID 1645 | |3;audio-fft;81;59;59;86;59;58;60;60;80;72 17:20:30 INFO - PID 1645 | |4;audio-oscillator;86;71;84;69;68;68;88;69;67;88 17:20:30 INFO - PID 1645 | |5;imaging-gaussian-blur;90;90;89;90;90;133;91;92;91;89 17:20:30 INFO - PID 1645 | |6;imaging-darkroom;97;96;96;96;97;96;98;96;98;142 17:20:30 INFO - PID 1645 | |7;imaging-desaturate;97;128;87;85;86;85;86;85;83;85 17:20:30 INFO - PID 1645 | |8;json-parse-financial;47;48;47;70;68;70;69;60;48;48 17:20:30 INFO - PID 1645 | |9;json-stringify-tinderbox;52;61;54;59;62;62;57;57;56;57 17:20:30 INFO - PID 1645 | |10;stanford-crypto-aes;83;83;82;80;83;80;83;81;82;81 17:20:30 INFO - PID 1645 | |11;stanford-crypto-ccm;137;135;133;145;150;137;137;133;181;149 17:20:30 INFO - PID 1645 | |12;stanford-crypto-pbkdf2;179;181;183;210;225;180;182;184;183;178 17:20:30 INFO - PID 1645 | |13;stanford-crypto-sha256-iterative;68;67;68;64;67;67;67;66;67;66 17:20:30 INFO - PID 1645 | __end_tp_report 17:20:30 INFO - PID 1645 | __start_cc_report 17:20:30 INFO - PID 1645 | _x_x_mozilla_cycle_collect,2102 17:20:30 INFO - PID 1645 | __end_cc_report 17:20:30 INFO - PID 1645 | __startTimestamp1494030030640__endTimestamp 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | ------- Summary: start ------- 17:20:30 INFO - PID 1645 | Number of tests: 14 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#0] ai-astar Cycles:10 Average:121.20 Median:125.50 stddev:21.32 (17.0%) stddev-sans-first:22.29 17:20:30 INFO - PID 1645 | Values: 111.0 112.0 110.0 112.0 112.0 98.0 139.0 106.0 149.0 163.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:131.50 stddev:16.76 (12.7%) stddev-sans-first:16.43 17:20:30 INFO - PID 1645 | Values: 140.0 103.0 107.0 148.0 108.0 134.0 131.0 108.0 132.0 107.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#2] audio-dft Cycles:10 Average:196.50 Median:185.50 stddev:25.83 (13.9%) stddev-sans-first:27.06 17:20:30 INFO - PID 1645 | Values: 185.0 199.0 257.0 228.0 186.0 184.0 181.0 184.0 176.0 185.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#3] audio-fft Cycles:10 Average:67.40 Median:66.00 stddev:11.16 (16.9%) stddev-sans-first:10.69 17:20:30 INFO - PID 1645 | Values: 81.0 59.0 59.0 86.0 59.0 58.0 60.0 60.0 80.0 72.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:77.50 stddev:9.33 (12.0%) stddev-sans-first:9.14 17:20:30 INFO - PID 1645 | Values: 86.0 71.0 84.0 69.0 68.0 68.0 88.0 69.0 67.0 88.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:90.00 stddev:13.56 (15.1%) stddev-sans-first:14.28 17:20:30 INFO - PID 1645 | Values: 90.0 90.0 89.0 90.0 90.0 133.0 91.0 92.0 91.0 89.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#6] imaging-darkroom Cycles:10 Average:101.20 Median:96.50 stddev:14.36 (14.9%) stddev-sans-first:15.15 17:20:30 INFO - PID 1645 | Values: 97.0 96.0 96.0 96.0 97.0 96.0 98.0 96.0 98.0 142.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#7] imaging-desaturate Cycles:10 Average:90.70 Median:85.50 stddev:13.65 (16.0%) stddev-sans-first:14.29 17:20:30 INFO - PID 1645 | Values: 97.0 128.0 87.0 85.0 86.0 85.0 86.0 85.0 83.0 85.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:64.00 stddev:10.81 (16.9%) stddev-sans-first:10.78 17:20:30 INFO - PID 1645 | Values: 47.0 48.0 47.0 70.0 68.0 70.0 69.0 60.0 48.0 48.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:57.70 Median:58.00 stddev:3.33 (5.7%) stddev-sans-first:2.83 17:20:30 INFO - PID 1645 | Values: 52.0 61.0 54.0 59.0 62.0 62.0 57.0 57.0 56.0 57.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.50 stddev:1.23 (1.5%) stddev-sans-first:1.22 17:20:30 INFO - PID 1645 | Values: 83.0 83.0 82.0 80.0 83.0 80.0 83.0 81.0 82.0 81.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:141.00 stddev:14.53 (10.3%) stddev-sans-first:15.21 17:20:30 INFO - PID 1645 | Values: 137.0 135.0 133.0 145.0 150.0 137.0 137.0 133.0 181.0 149.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.50 Median:183.00 stddev:15.80 (8.6%) stddev-sans-first:16.38 17:20:30 INFO - PID 1645 | Values: 179.0 181.0 183.0 210.0 225.0 180.0 182.0 184.0 183.0 178.0 17:20:30 INFO - PID 1645 | 17:20:30 INFO - PID 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.16 (1.7%) stddev-sans-first:1.13 17:20:30 INFO - PID 1645 | Values: 68.0 67.0 68.0 64.0 67.0 67.0 67.0 66.0 67.0 66.0 17:20:30 INFO - PID 1645 | -------- Summary: end -------- 17:20:30 INFO - PID 1645 | 17:20:31 INFO - TEST-INFO | 1645: exit 0 17:20:31 INFO - TEST-OK | kraken | took 60619ms 17:20:31 INFO - SUITE-END | took 1179s 17:20:31 INFO - Completed test suite (00:19:39) 17:20:31 INFO - javascript benchmark 17:20:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7717.435112748657, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3381.628020917697, 3513.6837684608395, 3569.6251893551166, 3577.3739595570787, 3594.5506811648543, 5745.109508026252, 6094.969525152379, 6267.749290028399, 6278.654673992928, 6312.274088479815, 5775.682337471438, 6224.719887605057, 6294.874102517958, 6298.9921612542, 6306.337834527366, 5263.394709608406, 5323.707196104213, 5413.485718856702, 5439.755211015514, 5474.206240095204, 4620.798592365975, 5541.168824676291, 5543.168524721284, 5549.861253468675, 5561.416051314614, 5349.277847490617, 5415.6479828811, 5426.891462170774, 5472.863178420532, 5508.1462373332, 5348.812791552277, 5364.651297665679, 5385.703786291752, 5390.407055223922, 5413.756380962866, 5281.339832520935, 5326.307580014612, 5347.438518955492, 5358.544523715489, 5360.142377219665, 6151.354107818659, 6248.343923888017, 6318.74725010999, 6319.399657032574, 6319.8736025279695, 6628.536002479828, 6632.568883022588, 6632.6683665817, 6636.800895973129, 6660.2340730816095, 6256.374362563753, 6643.103181070542, 6749.7300107995625, 6763.797086087426, 6764.729410823561, 6223.564350495421, 6265.530085243625, 6295.96852015737, 6299.590526615755, 6343.524235682296, 6722.260551339349, 6743.123393958754, 6759.966200168968, 6859.108315918898, 6932.272111428329, 6531.804045878631, 6542.21493420783, 6545.836354091186, 6545.869082618321, 6558.344165583499, 6694.263631000586, 6694.564853284521, 6696.229933557605, 6697.196336439659, 6706.932930670729, 6399.648019358887, 6457.2574153971955, 6601.339866013456, 6621.9337806621315, 6627.37039981201, 6602.669866506655, 6673.733050678015, 6705.161854768154, 6705.530612857151, 6716.1604799400075, 5299.4435584263865, 5324.041672498987, 5326.92009619856, 5343.171808369709, 5377.892442151135, 4592.265237561974, 4649.325847752057, 4714.3871296731895, 4728.59806916416, 4775.283707443842, 6217.813465596039, 6280.591761535486, 6302.527310451735, 6352.968235158794, 6357.682115894187, 6734.6632668367365, 6760.932390676128, 6776.525643204928, 6785.762498312634, 6798.660066996729, 5342.492463215988, 6289.685515724195, 6334.778282760173, 6364.331745166691, 6364.42720155179, 5761.942380576141, 5818.8254352369495, 5888.4700376966275, 5929.0, 5935.0, 5866.472017518444, 6780.186377634715, 6923.238443771081, 6998.16022077354, 7026.086608740831, 6517.608943463408, 6594.637294948824, 6611.206655201407, 6736.225334086643, 6828.487863410165, 6823.863522729617, 6829.141664753067, 6905.274946130584, 6907.13660792401, 6936.445084393337, 6534.477241820738, 6544.770933017321, 6553.246376666744, 6560.606363618198, 6564.80305590833, 6543.247526534319, 6614.140161778938, 6681.064650948774, 6689.364510371507, 6740.12378390806, 6486.4486518646445, 6503.349665033459, 6519.250286217146, 6521.0, 6541.672916354068, 4984.501549844987, 6441.259255185526, 6453.838654033687, 6457.192850893638, 6520.836979075561, 5517.813670060957, 6241.282252541016, 6294.402031806971, 6358.395952384516, 6371.267304260069, 6500.609963402211, 6527.738890444519, 6528.053432252202, 6536.901946470712, 6553.836154096186, 5270.578353731769, 6341.55609107358, 6343.873122537616, 6351.618902865843, 6352.047192920914], "value": 6069.351053420486, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.3842841227574, 769.3768047881217, 802.7792357101798, 1106.363840791545, 1126.003486914081, 25715.871420642892, 35492.75775347863, 35714.285714285696, 35819.64180358199, 35831.0, 37346.25307493848, 37742.43386349207, 37755.62244377555, 37770.8111459443, 37804.43293350595, 5887.0580707086665, 6008.879822403549, 8124.228198321165, 8262.628181731836, 8281.0, 8043.356531477468, 8179.631916563769, 8194.713185038525, 8206.425550211488, 8210.630521626541, 8226.136255693156, 8283.917160828405, 8293.377996650244, 8335.166483351646, 8341.332693384546, 8213.466124701905, 8218.753437396888, 8267.503949763033, 8290.41967062306, 8296.58517074147, 8073.6366863491285, 8173.386995975326, 8207.95896020522, 8227.465214761021, 8228.382871284617, 35301.646983530096, 35440.936771896886, 35467.4679879802, 35470.11324716903, 35627.4655880162, 3573.3925232710503, 3593.5508061492314, 3596.334678084537, 3597.4244120940784, 3598.5501812273465, 29247.56128658072, 29401.823927042893, 29435.0, 29452.410951781058, 29504.26239344012, 3072.5698402223707, 3141.5444760509827, 3158.6367567729776, 3169.2869104451543, 3179.82510961897, 235840.0, 293071.0, 293645.0635493639, 296008.0, 296502.0, 47113.5288647116, 47172.292415613796, 47173.5282647176, 47205.29192062122, 47266.5273347269, 50123.24815127776, 50201.99596008132, 50336.49663503318, 50386.24420633693, 50445.74777126091, 13992.230427326595, 14080.577582672537, 14093.436262549383, 14104.576862694135, 14119.576412707635, 9853.950730246446, 9871.851922221173, 9885.25860560461, 11488.94255528717, 11531.827022594667, 11549.88450115505, 11569.363684997406, 11635.825462618068, 11661.35862527569, 11685.298882067103, 4365.628921541641, 5137.17805151174, 5171.922421163686, 5197.480251974772, 5199.700074981255, 1008.7276435362411, 1010.3988127064385, 1012.4026824173626, 1044.3368461027303, 1045.7281106912258, 17752.91123544374, 21267.893660531598, 22550.32349029532, 23690.28929132129, 23715.40711482227, 1105.789899919013, 1106.5407855739777, 1137.4256000719583, 1138.6014894786758, 1156.1329003247565, 11533.480993355319, 12027.759444811054, 12198.14612977083, 12235.632931012084, 12258.08064395174, 302880.0, 303742.0, 305174.0, 305478.0, 306124.0, 49937.750311248936, 50054.74972625114, 50126.997460050596, 50137.74931125394, 50210.748946255764, 1463.7072585482733, 1514.3109885002298, 1553.2854886751995, 1555.4400415850284, 1569.3016607609504, 20758.06588703542, 22957.311280661608, 22960.885195573916, 23082.307530774102, 23230.767692323538, 36677.63322366849, 36703.89888303355, 36738.8163059183, 36804.89585312445, 36979.26041479102, 266612.0, 267597.0, 268435.0, 268442.0, 269147.0, 24076.51846963086, 27895.0, 31374.0, 32276.83861580677, 32397.676023239466, 34351.4847277286, 34407.0, 39195.41206881842, 39960.0, 42037.78981105075, 28494.287642809097, 29133.417331652825, 29645.25886852846, 29725.0, 30437.543436848024, 25784.484310314063, 28231.43537129287, 28716.42567148687, 29219.85390073036, 29344.11967640974, 33864.322713546084, 34210.65789342174, 35074.82462587671, 35199.64800351964, 35253.942381728586, 28587.28531786722, 28927.69825357906, 29308.267293316985, 29558.078724882813, 30448.695513045473, 22638.773612263667, 22921.541569168858, 23224.76775232226, 23380.532389351778, 23407.41481462977], "value": 16645.05157757496, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.9533808273966, 426.9709998902645, 440.709131972898, 440.76419115773064, 454.12354156478006, 16069.51791446257, 20853.165873365066, 21494.785052149495, 21977.890110549466, 22238.99924503397, 21082.0, 21676.89161554194, 21963.450913727123, 22186.556268874654, 22617.54764904701, 4784.899473121167, 6041.909371359433, 6235.688215589225, 6250.749970001195, 6272.090546870701, 5999.370066143058, 6255.061740738903, 6281.842953926142, 6282.7172777224905, 6285.245770507523, 6032.245969253843, 6108.205933228674, 6194.00895856663, 6196.1944947157035, 6250.374962503759, 5342.893142137155, 5546.69493177875, 6002.439365764889, 6077.088436734503, 6162.983107787232, 5422.349318081816, 5439.700816455093, 5513.283273174502, 5513.338399392059, 5514.420985796473, 20741.481462963395, 22127.668084978737, 26610.0, 26720.866395667897, 26807.0, 2181.596404665142, 2254.1434254983164, 2254.5490901819703, 2256.4697296135396, 2260.2089268755967, 17576.0, 17634.91182544079, 17779.73330400032, 18094.63810723778, 18297.359592414203, 2080.5318803269297, 2101.8423618228694, 2154.1598776477185, 2155.805977462036, 2156.590247852903, 125009.0, 138301.925471118, 142925.0, 142989.0, 143492.0, 11373.0, 12065.0, 12486.50053997848, 12546.749065018648, 12591.748165036648, 13782.517611883535, 15258.694826103416, 15261.160636165117, 15596.0, 16317.673646527002, 8787.956060219658, 8891.0, 9008.144226298491, 9104.544772761468, 9111.498867562215, 8509.959523857018, 8530.914690853137, 8536.829263414696, 8717.259032982141, 8717.912820871838, 5863.29640443141, 7409.222031686703, 7486.737964171228, 7596.962015189999, 7633.679349482993, 1871.4666320098706, 2176.738791345049, 2192.6820611011312, 2197.7582465928735, 2209.304069218191, 942.4863449420083, 974.2692980264801, 975.5609975510906, 1014.7970405918845, 1033.6382266206917, 20980.685289720666, 21607.02768375427, 21619.243326483498, 21639.0, 21673.916304184728, 1005.7938122684832, 1020.5764607687875, 1027.3476342522404, 1038.2557303729657, 1040.8126537223372, 10584.364938103738, 10640.989106034915, 10649.627263045757, 10946.0, 10948.34309941406, 530170.0, 538125.0, 538902.0, 552705.0, 554745.0, 112271.43864280626, 118513.11151663659, 130303.0, 133342.0, 134018.0, 1189.6728399689982, 1204.28345134643, 1214.860291066521, 1231.815227715832, 1234.3889774561649, 18309.633807324044, 18364.265429382674, 18425.907870460564, 18443.631127377645, 18503.907480462512, 54156.458435415145, 56486.43513564812, 58494.707526462094, 58831.70584147052, 59033.40966590279, 75116.0, 76051.61974190094, 78129.60935195515, 78618.6069069651, 79154.81267780873, 7196.352328290479, 8620.568971551524, 8869.246114080117, 8908.10918908104, 8947.552622368725, 13631.182129072287, 13669.384877680726, 13672.316384180951, 14050.367733452224, 14148.575542733735, 11663.300201987908, 11722.118076446826, 11795.52817887275, 11939.940300298444, 12119.151659383759, 10291.691249262534, 11059.28114672576, 11140.220184587, 11396.088312935111, 11415.543378264776, 10614.522346494581, 11297.548098075986, 11823.172377933462, 12094.153409261267, 12295.446704898122, 8597.914020859711, 8786.516741579275, 8789.868151977598, 8865.468071915706, 9117.77205569866, 10025.649102281384, 11834.940825295818, 11924.821127682919, 11997.220180688228, 12044.698882528008], "value": 10270.170575331169, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.4523558430005, 1452.2520901735602, 1482.4737218287507, 1500.579837645459, 1503.0906301687478, 11194.776104477916, 11702.707432314186, 11715.707107322321, 11729.003034742058, 11732.413379331032, 11361.0, 11584.13119016073, 11608.767824643503, 11610.883891161086, 11617.941910290438, 2494.4761600064007, 2543.707473640527, 2544.427503811646, 2544.9618255726177, 2550.885210165547, 2485.4159272570932, 2529.0895277700015, 2532.189699296226, 2533.5946248600226, 2536.5687833068423, 2461.433870209853, 2501.9749802502015, 2526.734692857251, 2530.266222795387, 2537.1880998080624, 2229.0, 2426.7694569015916, 2497.8501289922574, 2506.0, 2514.0823599386276, 2439.4755127647463, 2457.741937096615, 2459.938501537458, 2483.8261321707487, 2487.8756062197, 11388.202825802196, 11421.086313094933, 11539.134564907581, 11546.076313894868, 11562.826557601642, 6285.968570157165, 6530.6408147551865, 6536.836579085513, 6561.212654481445, 6573.375529324707, 3825.6365645263654, 4111.835526578963, 4150.481189851269, 4161.687873409476, 4188.727732697395, 1225.6261840138754, 1238.851337839456, 1300.478440224939, 1303.758804621148, 1311.757324894888, 15238.923805381013, 15620.984635998737, 15643.843561564467, 16138.354465821352, 16241.512754617263, 5292.153255479105, 5430.8099216527235, 5558.86102847424, 5575.135853942646, 5593.74828132735, 11658.475368608264, 11659.941700291443, 11664.708382290512, 11667.299962002307, 11686.94156529212, 5738.08190689496, 5933.258342707161, 6530.379613936669, 6535.281119076898, 6553.737850485927, 6008.789692360747, 6059.8485037873525, 6229.688515574203, 6236.407541283571, 6236.438720515175, 4290.420793192941, 4374.343848422762, 4392.692511524163, 4440.089781594766, 4456.7548784817145, 1083.3554526597193, 1253.3106791264768, 1261.9495220191272, 1273.2933222061845, 1277.0294576122214, 742.3726950726627, 767.1944458318743, 797.908240552344, 812.3947658993969, 816.5019338203691, 6548.377904099103, 6793.184817821893, 6956.95645653156, 6964.338387853146, 7023.051887995158, 942.9009953954874, 954.9856752148723, 964.392432767352, 966.8114717629994, 988.2193067476757, 3608.6030536640947, 3830.482884810571, 3872.3029854625875, 3954.387070004154, 3970.3250447424007, 14664.780028299583, 14908.47820326305, 15089.849101509064, 15097.471588494569, 15119.924400377928, 14459.27703614815, 14979.775303370458, 15021.098734075991, 15023.02350347224, 15051.849481505264, 7536.962315188389, 8480.194381533744, 8689.782755431164, 8701.17338852808, 8752.387332886765, 6108.328083910677, 6438.291787903233, 7029.26192749754, 7066.57600543969, 7076.824673442694, 12449.751004980031, 12581.245125292511, 12655.430505627084, 13673.794893076412, 13678.179309241477, 13145.0, 13463.932680336535, 13729.382177802223, 13761.862381376059, 13931.30343482842, 9078.45529268246, 9218.01812610292, 9243.676471323472, 9385.812283754423, 9418.293627977791, 10611.09805666527, 10682.679519614114, 10687.25189236746, 10688.89311106879, 10704.411257380918, 8153.143919888326, 8154.9806274215725, 8899.510526921082, 8995.55022248898, 9001.234895033995, 8777.473351598925, 8857.911420885966, 8880.822383552422, 8943.821123577362, 9072.546372681207, 7860.646270917708, 7926.643301051351, 7975.601219939096, 8091.716789912325, 8113.878291825746, 5924.437178468039, 6012.398760123952, 6047.213862197887, 6053.303870055, 6064.696765161636, 6043.12374705674, 6102.725377358119, 6119.816405507842, 6137.60105593135, 6214.627122372672], "value": 5342.4130796299705, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.61828644501276, 398.11220977220796, 401.05951543630187, 402.38033428520083, 405.40000798817766, 5037.848864534065, 5443.401225865152, 6065.514758819295, 6155.907661385083, 6187.412195841393, 5899.380565040673, 5962.463378295953, 5990.311114221866, 6051.788187413442, 6094.878102437949, 3814.5231846019246, 4596.655250856183, 4597.954020459786, 4602.585767280944, 4619.683390233792, 4623.052274283764, 4624.0, 4631.212693842038, 4631.745254011028, 4633.953660463403, 4416.955830441702, 4417.249067658489, 4548.567886050836, 4556.2254416749065, 4580.175568397687, 3874.786886721229, 4479.3281007848755, 4544.909101817978, 4560.680752347321, 4565.589096981271, 3698.685611723007, 3928.8213535939103, 4048.352263637804, 4058.939115913264, 4069.6337329640473, 5560.999020176367, 5646.2377579027125, 5860.6850294134, 5904.645721256738, 5945.197398351211, 5211.791528338862, 5215.765290561933, 5237.79048838046, 5261.053010458116, 5268.631195816294, 5291.3385826771655, 5887.646741195542, 6067.514598832127, 6397.296297407282, 6423.454006409461, 3596.496490491333, 4238.682098842568, 4287.142571485715, 4291.334843099294, 4305.677074219446, 6545.9018114728315, 6682.331766823376, 6707.228668703064, 6769.763058292985, 6878.27778083299, 3518.3842827505086, 4255.468066491689, 4296.419983302277, 4326.394304797331, 4336.978315108404, 4388.253996820485, 4409.5149533551285, 4412.84555040572, 4414.580614841585, 4464.17412778637, 5030.245463180552, 5107.284980102789, 5182.948170518322, 5191.792328306901, 5217.660852044605, 5201.557867581222, 6341.850721822436, 6419.807405777834, 6426.228852537725, 6449.516286278548, 1481.7629179331254, 1499.130504307499, 1500.3398504657916, 1504.5561559340022, 1505.4956589542408, 809.7489778168787, 836.3685417509741, 911.5715613661702, 986.4180133721139, 1004.1924535835612, 5033.7734801934, 5201.635885487979, 6128.601640893327, 6155.384461553898, 6168.352323006109, 5579.888402231933, 6438.130852334969, 6648.368405001517, 6666.0, 6671.366220209073, 6023.789167379121, 6129.387061293835, 6143.508519318444, 6151.784687536005, 6182.412670796267, 2988.4770165221435, 3173.841307934594, 3286.274705779437, 3415.8121303328558, 3461.44616861301, 6227.532935029891, 6234.501239900797, 6520.18497687789, 6723.226828914738, 6885.414739747077, 5599.116455025665, 5608.663480191201, 5689.800856969986, 6835.418989385858, 6873.381395674413, 5705.0635061807225, 5824.6704265597355, 6824.488163387766, 6889.965550172217, 6908.481863860231, 6352.936470635419, 6609.735610575523, 6629.834254143685, 6650.434713049445, 6655.0, 6381.106645069601, 6494.935050649433, 6504.674766261763, 6538.738450461929, 6588.8023359299295, 6795.422389096982, 6799.0, 6811.523193376416, 6822.658867056528, 6874.175098988154, 4318.784060796885, 4341.305391137403, 4341.565843415633, 4349.151915376598, 4350.21696094706, 5655.586103474131, 6075.27096748393, 6380.055457020846, 6407.0, 6729.495287853529, 6448.387403196689, 6461.547691661539, 6484.546081774419, 6488.448481879093, 6498.415142637185, 5042.4705405931845, 5557.972210139085, 6486.2216534016225, 6502.60984340941, 6504.252011018793, 5866.266716660418, 6348.650824204713, 6359.332270111757, 6359.8092057238355, 6365.331640177715, 6313.715882785377, 6468.094466774562, 6511.511636627367, 6532.41208291256, 6560.573562718408, 6032.276126864629, 6294.716737746904, 6325.304216536269, 6325.564657606763, 6361.332060133619], "value": 4679.32834179229, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.930390916772, 839.6431516605443, 845.4800297816845, 847.6736456464263, 863.0420233540768, 13514.391852366642, 14146.858531414682, 16074.276657550408, 16532.66934661306, 16575.834241657594, 11805.586804461846, 12882.098253122285, 17283.74074388885, 17335.82664173358, 17367.47897563072, 980.0640388429068, 986.2553771902204, 992.1566668331932, 992.8212921674095, 994.8955359687237, 1008.0574662690408, 1009.878814542256, 1010.9090181883629, 1011.1152741351318, 1011.8988101189896, 686.2588404523103, 704.8907419350008, 1000.2797985450501, 1022.2486472442748, 1031.7730099378125, 1021.0555236406369, 1021.1065317846884, 1024.2318261304022, 1026.2303272545591, 1036.5387402605843, 762.4662736084763, 763.7823220382164, 766.1151370167465, 767.1178145133085, 773.234497847133, 15912.283947222402, 16288.755668664979, 16430.260638271186, 16685.749713754303, 16988.405405810736, 16346.509604711877, 16399.016059036498, 16629.584260393465, 16818.24317905697, 16846.915765421218, 13644.727105457934, 13814.723705525934, 13935.512257070954, 14024.018318717694, 14147.29263536819, 3409.829508524564, 3417.419038763416, 3421.7091547218515, 3425.0067480430644, 3428.6914177724125, 118406.0, 119620.40189798996, 122012.0, 122676.0, 122731.77268227293, 10461.581536738446, 11907.34509601963, 11940.940295298642, 12110.515579376726, 12125.818112728317, 20259.696104558443, 20324.69512957307, 20928.47678808042, 20946.371608851758, 20988.685169722467, 7229.855402892018, 7367.81580460482, 7370.631468426557, 7392.075990501187, 7395.778126656209, 3583.5699716033723, 3617.0957260684827, 3618.5476815398074, 3624.1664417184197, 3665.9633403666157, 4065.735727177724, 4130.173965206971, 4187.0, 4195.119025004722, 4195.286801243796, 843.9831203375898, 867.7526904832218, 878.1481962496368, 880.9075047120089, 883.4566741454088, 629.2040568680623, 661.3518751623436, 676.7631329034798, 681.1655721740926, 685.9657017149223, 10874.619388321371, 11221.60724374643, 11505.769884602261, 11932.343721095423, 12058.517659293706, 637.0953246390045, 650.9121268628769, 654.6268626882631, 663.5587334422652, 681.9897701534481, 5333.413324534298, 5434.592405569598, 5512.3660779010925, 5521.116621340647, 5580.91628625571, 106278.46860765801, 108075.0, 108342.0, 108504.4574777121, 108836.0, 98181.0, 99211.5433651156, 99428.00571994332, 100222.49888750656, 101405.47891781482, 55057.724711376984, 71445.6427717858, 74762.62618686871, 76165.61917190379, 76611.0, 10949.678433401297, 11591.88408115908, 12027.699307517032, 12146.635600931986, 12402.75194496123, 42031.0, 42249.78875105605, 42317.78841105775, 43046.56953430425, 43062.78468607762, 53450.73274633602, 54266.728666357994, 54770.17844732253, 55180.89638207293, 55656.443435565125, 15847.52457426279, 15961.920190399438, 16083.35666573324, 16428.425005125242, 16443.58891027734, 17406.73889891678, 17895.10524473797, 18141.818581814012, 18244.0, 18423.723644145615, 15972.28124734413, 16125.596860078593, 16609.252583633523, 16720.498385048468, 16721.331146753993, 16021.839781602035, 16050.67898642044, 16400.507984760476, 16532.917335413247, 16646.084465354037, 16430.17849107516, 16716.331346745996, 16774.0, 17150.485485435456, 17155.399561015303, 11683.415829208336, 12021.939890300493, 12086.15396922207, 12202.81695774582, 12209.267443953391, 11447.370394628375, 12780.552680656132, 13390.330483475593, 13790.069830098373, 14029.228392438514], "value": 8144.832755623912, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1465.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 110.0, 112.0, 112.0, 98.0, 139.0, 106.0, 149.0, 163.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 103.0, 107.0, 148.0, 108.0, 134.0, 131.0, 108.0, 132.0, 107.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 199.0, 257.0, 228.0, 186.0, 184.0, 181.0, 184.0, 176.0, 185.0], "value": 196.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 86.0, 59.0, 58.0, 60.0, 60.0, 80.0, 72.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 84.0, 69.0, 68.0, 68.0, 88.0, 69.0, 67.0, 88.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 89.0, 90.0, 90.0, 133.0, 91.0, 92.0, 91.0, 89.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 97.0, 96.0, 98.0, 96.0, 98.0, 142.0], "value": 101.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 128.0, 87.0, 85.0, 86.0, 85.0, 86.0, 85.0, 83.0, 85.0], "value": 90.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 48.0, 47.0, 70.0, 68.0, 70.0, 69.0, 60.0, 48.0, 48.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 61.0, 54.0, 59.0, 62.0, 62.0, 57.0, 57.0, 56.0, 57.0], "value": 57.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 80.0, 83.0, 80.0, 83.0, 81.0, 82.0, 81.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 133.0, 145.0, 150.0, 137.0, 137.0, 133.0, 181.0, 149.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 181.0, 183.0, 210.0, 225.0, 180.0, 182.0, 184.0, 183.0, 178.0], "value": 188.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 64.0, 67.0, 67.0, 67.0, 66.0, 67.0, 66.0], "value": 66.7, "unit": "score"}]}]} 17:20:31 INFO - Return code: 0 17:20:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:20:31 INFO - # TBPL SUCCESS # 17:20:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:20:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:20:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:20:31 INFO - 2017-05-05 17:20:31.964 screenresolution[1650:9768] starting screenresolution argv=screenresolution get 17:20:31 INFO - 2017-05-05 17:20:31.967 screenresolution[1650:9768] Display 0: 1600x1200x32@60 17:20:31 INFO - 2017-05-05 17:20:31.974 screenresolution[1651:9771] starting screenresolution argv=screenresolution list 17:20:31 INFO - Available Modes on Display 0 17:20:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:20:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:20:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:20:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:20:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:20:32 INFO - Intel Iris: 17:20:32 INFO - Chipset Model: Intel Iris 17:20:32 INFO - Type: GPU 17:20:32 INFO - Bus: Built-In 17:20:32 INFO - VRAM (Dynamic, Max): 1536 MB 17:20:32 INFO - Vendor: Intel (0x8086) 17:20:32 INFO - Device ID: 0x0a2e 17:20:32 INFO - Revision ID: 0x0009 17:20:32 INFO - Displays: 17:20:32 INFO - Display: 17:20:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:20:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:20:32 INFO - Display Serial Number: 1600x1200 60 17:20:32 INFO - Main Display: Yes 17:20:32 INFO - Mirror: Off 17:20:32 INFO - Online: Yes 17:20:32 INFO - Rotation: Supported 17:20:32 INFO - Return code: 0 17:20:32 INFO - Running post-action listener: _resource_record_post_action 17:20:32 INFO - [mozharness: 2017-05-06 00:20:32.221039Z] Finished run-tests step (success) 17:20:32 INFO - Running post-run listener: _resource_record_post_run 17:20:32 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 38483456; Write bytes: 510152704; Read time: 248; Write time: 1561 17:20:32 INFO - TinderboxPrint: CPU usage
53.6% 17:20:32 INFO - TinderboxPrint: I/O read bytes / time
38,483,456 / 248 17:20:32 INFO - TinderboxPrint: I/O write bytes / time
510,152,704 / 1,561 17:20:32 INFO - TinderboxPrint: CPU idle
2,225.8 (46.4%) 17:20:32 INFO - TinderboxPrint: CPU system
957.6 (19.9%) 17:20:32 INFO - TinderboxPrint: CPU user
1,618.2 (33.7%) 17:20:32 INFO - TinderboxPrint: Swap in / out
247,095,296 / 0 17:20:32 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205773312; Write bytes: 206280192; Read time: 16080; Write time: 432 17:20:32 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36378112; Write bytes: 296798720; Read time: 227; Write time: 1101 17:20:32 INFO - Running post-run listener: _upload_blobber_files 17:20:32 INFO - Blob upload gear active. 17:20:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:20:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:20:32 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'] 17:20:32 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 17:20:33 INFO - (blobuploader) - INFO - Open directory for files ... 17:20:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:20:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:20:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:33 INFO - (blobuploader) - INFO - Done attempting. 17:20:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:20:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:20:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:34 INFO - (blobuploader) - INFO - Done attempting. 17:20:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:20:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:20:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:36 INFO - (blobuploader) - INFO - Done attempting. 17:20:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:20:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:20:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:38 INFO - (blobuploader) - INFO - Done attempting. 17:20:38 INFO - (blobuploader) - INFO - Iteration through files over. 17:20:38 INFO - Return code: 0 17:20:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:20:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:20:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/944f4ef4c6fbdf9449078eedb3db4da1e8b3f2bc41689a3edd85201e28763272c57be8f8ff2e479db730c53d10500530931947f2706f922d57232cad1a5d8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2970bf52a41933a37ddda0ba28204b4bfc72d5d59d58c10d4c42fb34965763962b27c9f1264c09b5a26b1a175f83d13ed4f233e4b6a94bb14a5d3226c46dfa4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1aa2a4e8a8c545489f71d3f7a8af1920a375831480b5b3459eea6a7d90b3f2c530153b36bbe6de8110ecb7391a66eba0c93005ddcd624947f27c91d823896b08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d75aa97263ab6e9fbfe0add7aa5d8f6739439e7e07bead2645b533255f9abaa16f1c202a58436892e5bddc833b46cb5ba19fe45df65f7c706d5d8ecff0ca131"} 17:20:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:20:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:20:38 INFO - Contents: 17:20:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/944f4ef4c6fbdf9449078eedb3db4da1e8b3f2bc41689a3edd85201e28763272c57be8f8ff2e479db730c53d10500530931947f2706f922d57232cad1a5d8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2970bf52a41933a37ddda0ba28204b4bfc72d5d59d58c10d4c42fb34965763962b27c9f1264c09b5a26b1a175f83d13ed4f233e4b6a94bb14a5d3226c46dfa4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1aa2a4e8a8c545489f71d3f7a8af1920a375831480b5b3459eea6a7d90b3f2c530153b36bbe6de8110ecb7391a66eba0c93005ddcd624947f27c91d823896b08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d75aa97263ab6e9fbfe0add7aa5d8f6739439e7e07bead2645b533255f9abaa16f1c202a58436892e5bddc833b46cb5ba19fe45df65f7c706d5d8ecff0ca131"} 17:20:38 INFO - Running post-run listener: copy_logs_to_upload_dir 17:20:38 INFO - Copying logs to upload dir... 17:20:38 INFO - mkdir: /builds/slave/test/build/upload/logs 17:20:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.991193 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-05 17:20:38.395794) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 17:20:38.399242) ========= 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.9bysca6FWz/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.Yspqbs65xC/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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/944f4ef4c6fbdf9449078eedb3db4da1e8b3f2bc41689a3edd85201e28763272c57be8f8ff2e479db730c53d10500530931947f2706f922d57232cad1a5d8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2970bf52a41933a37ddda0ba28204b4bfc72d5d59d58c10d4c42fb34965763962b27c9f1264c09b5a26b1a175f83d13ed4f233e4b6a94bb14a5d3226c46dfa4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1aa2a4e8a8c545489f71d3f7a8af1920a375831480b5b3459eea6a7d90b3f2c530153b36bbe6de8110ecb7391a66eba0c93005ddcd624947f27c91d823896b08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d75aa97263ab6e9fbfe0add7aa5d8f6739439e7e07bead2645b533255f9abaa16f1c202a58436892e5bddc833b46cb5ba19fe45df65f7c706d5d8ecff0ca131"} build_url:https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011029 build_url: 'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/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/944f4ef4c6fbdf9449078eedb3db4da1e8b3f2bc41689a3edd85201e28763272c57be8f8ff2e479db730c53d10500530931947f2706f922d57232cad1a5d8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2970bf52a41933a37ddda0ba28204b4bfc72d5d59d58c10d4c42fb34965763962b27c9f1264c09b5a26b1a175f83d13ed4f233e4b6a94bb14a5d3226c46dfa4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1aa2a4e8a8c545489f71d3f7a8af1920a375831480b5b3459eea6a7d90b3f2c530153b36bbe6de8110ecb7391a66eba0c93005ddcd624947f27c91d823896b08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d75aa97263ab6e9fbfe0add7aa5d8f6739439e7e07bead2645b533255f9abaa16f1c202a58436892e5bddc833b46cb5ba19fe45df65f7c706d5d8ecff0ca131"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 17:20:38.436242) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 17:20:38.436603) ========= 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.9bysca6FWz/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.Yspqbs65xC/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.005190 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 17:20:38.484960) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 17:20:38.485310) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 17:20:38.485631) ========= ========= Total master_lag: 0.19 =========