builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0030 starttime: 1491997397.57 results: success (0) buildid: 20170412024510 builduid: 73ec3e6ce1724308b27af787bba100d2 revision: 65875bd4b7df1e5e29f1b07927d88d37b9ddded0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:17.574021) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:17.574539) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:17.575213) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:17.604002) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:17.604767) ========= 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.zNhhyCaaal/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.EAPU4waAyN/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-04-12 04:43:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 140M=0s 2017-04-12 04:43:17 (140 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131197 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:17.761614) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:17.761974) ========= 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.zNhhyCaaal/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.EAPU4waAyN/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.021935 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:17.811082) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-12 04:43:17.811486) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 65875bd4b7df1e5e29f1b07927d88d37b9ddded0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 65875bd4b7df1e5e29f1b07927d88d37b9ddded0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zNhhyCaaal/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.EAPU4waAyN/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-04-12 04:43:17,971 truncating revision to first 12 chars 2017-04-12 04:43:17,971 Setting DEBUG logging. 2017-04-12 04:43:17,971 attempt 1/10 2017-04-12 04:43:17,971 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/65875bd4b7df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-12 04:43:19,946 unpacking tar archive at: mozilla-aurora-65875bd4b7df/testing/mozharness/ program finished with exit code 0 elapsedTime=2.541756 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-12 04:43:20.378022) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:20.378419) ========= script_repo_revision: 65875bd4b7df1e5e29f1b07927d88d37b9ddded0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:20.378873) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:20.379176) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 04:43:20.400264) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-12 04:43:20.401003) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zNhhyCaaal/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.EAPU4waAyN/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 04:43:20 INFO - MultiFileLogger online at 20170412 04:43:20 in /builds/slave/test 04:43:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:43:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:43:20 INFO - {'append_to_log': False, 04:43:20 INFO - 'base_work_dir': '/builds/slave/test', 04:43:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:43:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:43:20 INFO - 'branch': 'Mozilla-Aurora', 04:43:20 INFO - 'buildbot_json_path': 'buildprops.json', 04:43:20 INFO - 'config_files': ('talos/mac_config.py',), 04:43:20 INFO - 'default_actions': ('clobber', 04:43:20 INFO - 'read-buildbot-config', 04:43:20 INFO - 'download-and-extract', 04:43:20 INFO - 'populate-webroot', 04:43:20 INFO - 'create-virtualenv', 04:43:20 INFO - 'install', 04:43:20 INFO - 'run-tests'), 04:43:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:43:20 INFO - 'download_minidump_stackwalk': True, 04:43:20 INFO - 'download_symbols': 'ondemand', 04:43:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:43:20 INFO - 'tooltool.py': '/tools/tooltool.py', 04:43:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:43:20 INFO - '/tools/misc-python/virtualenv.py')}, 04:43:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:43:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:43:20 INFO - 'gecko_profile': False, 04:43:20 INFO - 'gecko_profile_interval': 0, 04:43:20 INFO - 'installer_path': 'installer.exe', 04:43:20 INFO - 'log_level': 'info', 04:43:20 INFO - 'log_name': 'talos', 04:43:20 INFO - 'log_to_console': True, 04:43:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:43:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:43:20 INFO - 'opt_config_files': (), 04:43:20 INFO - 'pip_index': False, 04:43:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:43:20 INFO - 'cmd': ('bash', 04:43:20 INFO - '-c', 04:43:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:43:20 INFO - 'enabled': True, 04:43:20 INFO - 'halt_on_failure': False, 04:43:20 INFO - 'name': 'check_screen_resolution'},), 04:43:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:43:20 INFO - 'cmd': ('bash', 04:43:20 INFO - '-c', 04:43:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:43:20 INFO - 'enabled': True, 04:43:20 INFO - 'halt_on_failure': False, 04:43:20 INFO - 'name': 'check_screen_resolution'},), 04:43:20 INFO - 'run_cmd_checks_enabled': True, 04:43:20 INFO - 'suite': 'dromaeojs', 04:43:20 INFO - 'system_bits': '32', 04:43:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:43:20 INFO - 'title': 't-yosemite-r7-0030', 04:43:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:43:20 INFO - 'use_talos_json': True, 04:43:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:43:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:43:20 INFO - 'work_dir': 'build'} 04:43:20 INFO - [mozharness: 2017-04-12 11:43:20.689272Z] Running clobber step. 04:43:20 INFO - Running pre-action listener: _resource_record_pre_action 04:43:20 INFO - Running main action method: clobber 04:43:20 INFO - rmtree: /builds/slave/test/build 04:43:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:43:22 INFO - Running post-action listener: _resource_record_post_action 04:43:22 INFO - [mozharness: 2017-04-12 11:43:22.537925Z] Finished clobber step (success) 04:43:22 INFO - [mozharness: 2017-04-12 11:43:22.538109Z] Running read-buildbot-config step. 04:43:22 INFO - Running pre-action listener: _resource_record_pre_action 04:43:22 INFO - Running main action method: read_buildbot_config 04:43:22 INFO - Using buildbot properties: 04:43:22 INFO - { 04:43:22 INFO - "project": "", 04:43:22 INFO - "product": "firefox", 04:43:22 INFO - "script_repo_revision": "production", 04:43:22 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 04:43:22 INFO - "repository": "", 04:43:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 04:43:22 INFO - "buildid": "20170412024510", 04:43:22 INFO - "pgo_build": "False", 04:43:22 INFO - "basedir": "/builds/slave/test", 04:43:22 INFO - "buildnumber": 17, 04:43:22 INFO - "slavename": "t-yosemite-r7-0030", 04:43:22 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:43:22 INFO - "platform": "yosemite_r7", 04:43:22 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:43:22 INFO - "branch": "mozilla-aurora", 04:43:22 INFO - "repo_path": "releases/mozilla-aurora", 04:43:22 INFO - "revision": "65875bd4b7df1e5e29f1b07927d88d37b9ddded0", 04:43:22 INFO - "stage_platform": "macosx64", 04:43:22 INFO - "builduid": "73ec3e6ce1724308b27af787bba100d2", 04:43:22 INFO - "slavebuilddir": "test" 04:43:22 INFO - } 04:43:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 04:43:22 INFO - Running post-action listener: _resource_record_post_action 04:43:22 INFO - [mozharness: 2017-04-12 11:43:22.541581Z] Finished read-buildbot-config step (success) 04:43:22 INFO - [mozharness: 2017-04-12 11:43:22.541690Z] Running download-and-extract step. 04:43:22 INFO - Running pre-action listener: _resource_record_pre_action 04:43:22 INFO - Running main action method: download_and_extract 04:43:22 INFO - mkdir: /builds/slave/test/build/tests 04:43: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')]} 04:43:22 INFO - trying https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 04:43:22 INFO - Downloading https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 04:43:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 04:43:23 INFO - Downloaded 1199 bytes. 04:43:23 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 04:43:23 INFO - Using the following test package requirements: 04:43:23 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 04:43:23 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:43:23 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 04:43:23 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:43:23 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 04:43:23 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:43:23 INFO - u'jsshell-mac.zip'], 04:43:23 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:43:23 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 04:43:23 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 04:43:23 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:43:23 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 04:43:23 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:43:23 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 04:43:23 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:43:23 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 04:43:23 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:43:23 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 04:43:23 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 04:43:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 04:43:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:43:23 INFO - Fetch https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 04:43:25 INFO - Expected file size: 51790296 04:43:25 INFO - Obtained file size: 51790296 04:43:41 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 04:43:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 04:43:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:43:41 INFO - Fetch https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 04:43:42 INFO - Expected file size: 51790296 04:43:42 INFO - Obtained file size: 51790296 04:43:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 04:43:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 04:43:58 INFO - Fetch https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 04:43:59 INFO - Expected file size: 14404424 04:43:59 INFO - Obtained file size: 14404424 04:44:00 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')]} 04:44:00 INFO - trying https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 04:44:00 INFO - Downloading https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 04:44:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 04:44:02 INFO - Downloaded 74726214 bytes. 04:44:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 04:44:02 INFO - mkdir: /builds/slave/test/properties 04:44:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:44:02 INFO - Writing to file /builds/slave/test/properties/build_url 04:44:02 INFO - Contents: 04:44:02 INFO - build_url:https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 04:44:03 INFO - Running post-action listener: _resource_record_post_action 04:44:03 INFO - Running post-action listener: set_extra_try_arguments 04:44:03 INFO - [mozharness: 2017-04-12 11:44:03.694194Z] Finished download-and-extract step (success) 04:44:03 INFO - [mozharness: 2017-04-12 11:44:03.694297Z] Running populate-webroot step. 04:44:03 INFO - Running pre-action listener: _resource_record_pre_action 04:44:03 INFO - Running main action method: populate_webroot 04:44:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:44:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:44:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:44:03 INFO - '--tppagecycles', 04:44:03 INFO - '7'], 04:44:03 INFO - 'tests': ['tsvgm']}}, 04:44:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:44:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:44:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:44:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:44:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:44:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:44:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:44:03 INFO - 'talos_options': ['--disable-e10s'], 04:44:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:44:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:44:03 INFO - 'talos_options': ['--disable-e10s'], 04:44:03 INFO - 'tests': ['damp', 'tps']}, 04:44:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:44:03 INFO - 'tests': ['damp', 'tps']}, 04:44:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:44:03 INFO - 'tests': ['dromaeo_dom']}, 04:44:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:44:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:44:03 INFO - 'tests': ['basic_compositor_video']}, 04:44:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:44:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:44:03 INFO - 'tests': ['a11yr', 04:44:03 INFO - 'ts_paint', 04:44:03 INFO - 'tpaint', 04:44:03 INFO - 'sessionrestore', 04:44:03 INFO - 'sessionrestore_no_auto_restore', 04:44:03 INFO - 'tabpaint']}, 04:44:03 INFO - 'other-e10s': {'tests': ['a11yr', 04:44:03 INFO - 'ts_paint', 04:44:03 INFO - 'tpaint', 04:44:03 INFO - 'sessionrestore', 04:44:03 INFO - 'sessionrestore_no_auto_restore', 04:44:03 INFO - 'tabpaint']}, 04:44:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:44:03 INFO - 'ts_paint', 04:44:03 INFO - 'tpaint', 04:44:03 INFO - 'sessionrestore', 04:44:03 INFO - 'sessionrestore_no_auto_restore', 04:44:03 INFO - 'tabpaint']}, 04:44:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:44:03 INFO - 'ts_paint', 04:44:03 INFO - 'tpaint', 04:44:03 INFO - 'sessionrestore', 04:44:03 INFO - 'sessionrestore_no_auto_restore', 04:44:03 INFO - 'tabpaint']}, 04:44:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:44:03 INFO - 'tests': ['a11yr', 04:44:03 INFO - 'ts_paint', 04:44:03 INFO - 'tpaint', 04:44:03 INFO - 'sessionrestore', 04:44:03 INFO - 'sessionrestore_no_auto_restore', 04:44:03 INFO - 'tabpaint']}, 04:44:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:44:03 INFO - 'tests': ['a11yr', 04:44:03 INFO - 'ts_paint', 04:44:03 INFO - 'tpaint', 04:44:03 INFO - 'sessionrestore', 04:44:03 INFO - 'sessionrestore_no_auto_restore', 04:44:03 INFO - 'tabpaint']}, 04:44:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:44:03 INFO - 'tests': ['tsvgx', 04:44:03 INFO - 'tsvgr_opacity', 04:44:03 INFO - 'tart', 04:44:03 INFO - 'tscrollx', 04:44:03 INFO - 'cart', 04:44:03 INFO - 'tsvg_static']}, 04:44:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:44:03 INFO - 'tsvgr_opacity', 04:44:03 INFO - 'tart', 04:44:03 INFO - 'tscrollx', 04:44:03 INFO - 'cart', 04:44:03 INFO - 'tsvg_static']}, 04:44:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:44:03 INFO - 'talos_options': ['--disable-e10s'], 04:44:03 INFO - 'tests': ['tp5o']}, 04:44:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:44:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:44:03 INFO - 'talos_options': ['--disable-e10s', 04:44:03 INFO - '--xperf_path', 04:44:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:03 INFO - 'tests': ['tp5n']}, 04:44:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:44:03 INFO - 'talos_options': ['--xperf_path', 04:44:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:03 INFO - 'tests': ['tp5n']}}, 04:44:03 INFO - 'talos.zip': {'path': '', 04:44:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:44:03 INFO - Running post-action listener: _resource_record_post_action 04:44:03 INFO - [mozharness: 2017-04-12 11:44:03.712956Z] Finished populate-webroot step (success) 04:44:03 INFO - [mozharness: 2017-04-12 11:44:03.713087Z] Running create-virtualenv step. 04:44:03 INFO - Running pre-action listener: _resource_record_pre_action 04:44:03 INFO - Running main action method: create_virtualenv 04:44:03 INFO - Creating virtualenv /builds/slave/test/build/venv 04:44:03 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 04:44:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:44:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:44:03 INFO - Using real prefix '/tools/python27' 04:44:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:44:04 INFO - Installing distribute.............................................................................................................................................................................................done. 04:44:07 INFO - Installing pip.................done. 04:44:07 INFO - Return code: 0 04:44:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:44:07 INFO - Reading from file tmpfile_stdout 04:44:07 INFO - Output received: 04:44:07 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) 04:44:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:44:07 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')]} 04:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:07 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')]} 04:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:07 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 0x102abeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe12bd63c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10289cd30>, '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 0x1027c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102891be8>, '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.zNhhyCaaal/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.EAPU4waAyN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 04:44:07 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 04:44:07 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 04:44:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNhhyCaaal/Render', 04:44:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:07 INFO - 'HOME': '/Users/cltbld', 04:44:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:07 INFO - 'LOGNAME': 'cltbld', 04:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:07 INFO - 'MOZ_NO_REMOTE': '1', 04:44:07 INFO - 'NO_EM_RESTART': '1', 04:44:07 INFO - 'PAGER': '/bin/cat', 04:44:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:07 INFO - 'PWD': '/builds/slave/test', 04:44:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:07 INFO - 'SHELL': '/bin/bash', 04:44:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EAPU4waAyN/Listeners', 04:44:07 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 04:44:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:07 INFO - 'USER': 'cltbld', 04:44:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:07 INFO - 'XPC_FLAGS': '0x0', 04:44:07 INFO - 'XPC_SERVICE_NAME': '0', 04:44:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:07 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 04:44:07 INFO - Cleaning up... 04:44:07 INFO - Return code: 0 04:44:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:44:07 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')]} 04:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:07 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')]} 04:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:07 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 0x102abeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe12bd63c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10289cd30>, '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 0x1027c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102891be8>, '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.zNhhyCaaal/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.EAPU4waAyN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 04:44:07 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 04:44:07 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 04:44:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNhhyCaaal/Render', 04:44:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:07 INFO - 'HOME': '/Users/cltbld', 04:44:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:07 INFO - 'LOGNAME': 'cltbld', 04:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:07 INFO - 'MOZ_NO_REMOTE': '1', 04:44:07 INFO - 'NO_EM_RESTART': '1', 04:44:07 INFO - 'PAGER': '/bin/cat', 04:44:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:07 INFO - 'PWD': '/builds/slave/test', 04:44:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:07 INFO - 'SHELL': '/bin/bash', 04:44:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EAPU4waAyN/Listeners', 04:44:07 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 04:44:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:07 INFO - 'USER': 'cltbld', 04:44:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:07 INFO - 'XPC_FLAGS': '0x0', 04:44:07 INFO - 'XPC_SERVICE_NAME': '0', 04:44:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:08 INFO - Downloading/unpacking psutil>=3.1.1 04:44:08 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 04:44:08 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 04:44:08 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 04:44:08 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 04:44:08 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 04:44:08 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 04:44:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:44:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:44:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:44:11 INFO - Installing collected packages: psutil 04:44:11 INFO - Running setup.py install for psutil 04:44:12 INFO - building 'psutil._psutil_osx' extension 04:44: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/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:44: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/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:44: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 04:44: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 04:44:12 INFO - building 'psutil._psutil_posix' extension 04:44: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 04:44:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:44:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:44:12 INFO - ^ 04:44:12 INFO - 1 warning generated. 04:44: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 04:44:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:44:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:44:12 INFO - Successfully installed psutil 04:44:12 INFO - Cleaning up... 04:44:12 INFO - Return code: 0 04:44:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:44: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')]} 04:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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')]} 04:44:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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 0x102abeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe12bd63c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10289cd30>, '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 0x1027c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102891be8>, '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.zNhhyCaaal/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.EAPU4waAyN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 04:44: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 04:44: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 04:44:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNhhyCaaal/Render', 04:44:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:12 INFO - 'HOME': '/Users/cltbld', 04:44:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:12 INFO - 'LOGNAME': 'cltbld', 04:44:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:12 INFO - 'MOZ_NO_REMOTE': '1', 04:44:12 INFO - 'NO_EM_RESTART': '1', 04:44:12 INFO - 'PAGER': '/bin/cat', 04:44:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:12 INFO - 'PWD': '/builds/slave/test', 04:44:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:12 INFO - 'SHELL': '/bin/bash', 04:44:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EAPU4waAyN/Listeners', 04:44:12 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 04:44:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:12 INFO - 'USER': 'cltbld', 04:44:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:12 INFO - 'XPC_FLAGS': '0x0', 04:44:12 INFO - 'XPC_SERVICE_NAME': '0', 04:44:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:44:13 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 04:44:13 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 04:44:13 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 04:44:13 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 04:44:13 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 04:44:13 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 04:44:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:44:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:44:15 INFO - Installing collected packages: mozsystemmonitor 04:44:15 INFO - Running setup.py install for mozsystemmonitor 04:44:16 INFO - Successfully installed mozsystemmonitor 04:44:16 INFO - Cleaning up... 04:44:16 INFO - Return code: 0 04:44:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:44: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')]} 04:44:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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')]} 04:44:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:16 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 0x102abeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe12bd63c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10289cd30>, '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 0x1027c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102891be8>, '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.zNhhyCaaal/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.EAPU4waAyN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 04:44:16 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 04:44:16 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 04:44:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNhhyCaaal/Render', 04:44:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:16 INFO - 'HOME': '/Users/cltbld', 04:44:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:16 INFO - 'LOGNAME': 'cltbld', 04:44:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:16 INFO - 'MOZ_NO_REMOTE': '1', 04:44:16 INFO - 'NO_EM_RESTART': '1', 04:44:16 INFO - 'PAGER': '/bin/cat', 04:44:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:16 INFO - 'PWD': '/builds/slave/test', 04:44:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:16 INFO - 'SHELL': '/bin/bash', 04:44:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EAPU4waAyN/Listeners', 04:44:16 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 04:44:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:16 INFO - 'USER': 'cltbld', 04:44:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:16 INFO - 'XPC_FLAGS': '0x0', 04:44:16 INFO - 'XPC_SERVICE_NAME': '0', 04:44:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:16 INFO - Downloading/unpacking jsonschema==2.5.1 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:44:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:44:19 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 04:44:19 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 04:44:19 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 04:44:19 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 04:44:19 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 04:44:19 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 04:44:19 INFO - Downloading functools32-3.2.3-2.tar.gz 04:44:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:44:19 INFO - warning: no files found matching '*.txt' 04:44:19 INFO - no previously-included directories found matching 'build' 04:44:19 INFO - no previously-included directories found matching 'dist' 04:44:19 INFO - no previously-included directories found matching '.git*' 04:44:19 INFO - Installing collected packages: jsonschema, functools32 04:44:19 INFO - Running setup.py install for functools32 04:44:20 INFO - warning: no files found matching '*.txt' 04:44:20 INFO - no previously-included directories found matching 'build' 04:44:20 INFO - no previously-included directories found matching 'dist' 04:44:20 INFO - no previously-included directories found matching '.git*' 04:44:20 INFO - Successfully installed jsonschema functools32 04:44:20 INFO - Cleaning up... 04:44:20 INFO - Return code: 0 04:44:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:44:20 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')]} 04:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:20 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')]} 04:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:20 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 0x102abeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe12bd63c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10289cd30>, '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 0x1027c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102891be8>, '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.zNhhyCaaal/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.EAPU4waAyN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 04:44:20 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 04:44:20 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 04:44:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNhhyCaaal/Render', 04:44:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:20 INFO - 'HOME': '/Users/cltbld', 04:44:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:20 INFO - 'LOGNAME': 'cltbld', 04:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:20 INFO - 'MOZ_NO_REMOTE': '1', 04:44:20 INFO - 'NO_EM_RESTART': '1', 04:44:20 INFO - 'PAGER': '/bin/cat', 04:44:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:20 INFO - 'PWD': '/builds/slave/test', 04:44:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:20 INFO - 'SHELL': '/bin/bash', 04:44:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EAPU4waAyN/Listeners', 04:44:20 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 04:44:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:20 INFO - 'USER': 'cltbld', 04:44:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:20 INFO - 'XPC_FLAGS': '0x0', 04:44:20 INFO - 'XPC_SERVICE_NAME': '0', 04:44:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:44:20 INFO - Cleaning up... 04:44:20 INFO - Return code: 0 04:44:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:44:20 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')]} 04:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:20 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')]} 04:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:20 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 0x102abeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe12bd63c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10289cd30>, '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 0x1027c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102891be8>, '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.zNhhyCaaal/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.EAPU4waAyN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 04:44:20 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 04:44:20 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 04:44:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNhhyCaaal/Render', 04:44:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:20 INFO - 'HOME': '/Users/cltbld', 04:44:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:20 INFO - 'LOGNAME': 'cltbld', 04:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:20 INFO - 'MOZ_NO_REMOTE': '1', 04:44:20 INFO - 'NO_EM_RESTART': '1', 04:44:20 INFO - 'PAGER': '/bin/cat', 04:44:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:20 INFO - 'PWD': '/builds/slave/test', 04:44:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:20 INFO - 'SHELL': '/bin/bash', 04:44:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EAPU4waAyN/Listeners', 04:44:20 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 04:44:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:20 INFO - 'USER': 'cltbld', 04:44:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:20 INFO - 'XPC_FLAGS': '0x0', 04:44:20 INFO - 'XPC_SERVICE_NAME': '0', 04:44:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:20 INFO - Downloading/unpacking blobuploader==1.2.4 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44:23 INFO - Downloading blobuploader-1.2.4.tar.gz 04:44:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:44:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:44:23 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 04:44:23 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 04:44:23 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 04:44:23 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 04:44:23 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 04:44:23 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 04:44:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:44:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:24 INFO - Downloading docopt-0.6.1.tar.gz 04:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:44:24 INFO - Installing collected packages: blobuploader, requests, docopt 04:44:24 INFO - Running setup.py install for blobuploader 04:44:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:44:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:44:24 INFO - Running setup.py install for requests 04:44:25 INFO - Running setup.py install for docopt 04:44:25 INFO - Successfully installed blobuploader requests docopt 04:44:25 INFO - Cleaning up... 04:44:25 INFO - Return code: 0 04:44:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:44: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')]} 04:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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')]} 04:44:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:25 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 0x102abeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe12bd63c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10289cd30>, '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 0x1027c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102891be8>, '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.zNhhyCaaal/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.EAPU4waAyN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 04:44:25 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 04:44:25 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 04:44:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNhhyCaaal/Render', 04:44:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:25 INFO - 'HOME': '/Users/cltbld', 04:44:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:25 INFO - 'LOGNAME': 'cltbld', 04:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:25 INFO - 'MOZ_NO_REMOTE': '1', 04:44:25 INFO - 'NO_EM_RESTART': '1', 04:44:25 INFO - 'PAGER': '/bin/cat', 04:44:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:25 INFO - 'PWD': '/builds/slave/test', 04:44:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:25 INFO - 'SHELL': '/bin/bash', 04:44:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EAPU4waAyN/Listeners', 04:44:25 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 04:44:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:25 INFO - 'USER': 'cltbld', 04:44:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:25 INFO - 'XPC_FLAGS': '0x0', 04:44:25 INFO - 'XPC_SERVICE_NAME': '0', 04:44:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:44:25 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-KDghWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:44:25 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-JpRgl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:44:25 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-23Mgus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:44:26 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-v4Ohy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:44:26 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-cKvelg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:44:26 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-7CkpB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:44:26 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-SKLOYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:44:26 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-psPNYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:44:26 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-1V0T2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:44:26 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-bADT9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:44:26 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-WCQRHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:44:26 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-AIn1LK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:44:27 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-HijLtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:44:27 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ldRR0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:27 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-IXsl6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:44:27 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-RWAakH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:44:27 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-jvp9hd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:44:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:44:27 INFO - Running setup.py install for manifestparser 04:44:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:44:27 INFO - Running setup.py install for mozcrash 04:44:27 INFO - Running setup.py install for mozdebug 04:44:27 INFO - Running setup.py install for mozdevice 04:44:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:44:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:44:28 INFO - Running setup.py install for mozfile 04:44:28 INFO - Running setup.py install for mozhttpd 04:44:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:44:28 INFO - Running setup.py install for mozinfo 04:44:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:44:28 INFO - Running setup.py install for mozInstall 04:44:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:44:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:44:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:44:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:44:28 INFO - Running setup.py install for mozleak 04:44:28 INFO - Running setup.py install for mozlog 04:44:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:44:29 INFO - Running setup.py install for moznetwork 04:44:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:44:29 INFO - Running setup.py install for mozprocess 04:44:29 INFO - Running setup.py install for mozprofile 04:44:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:44:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:44:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:44:29 INFO - Running setup.py install for mozrunner 04:44:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:44:29 INFO - Running setup.py install for mozscreenshot 04:44:29 INFO - Running setup.py install for moztest 04:44:30 INFO - Running setup.py install for mozversion 04:44:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:44:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:44:30 INFO - Cleaning up... 04:44:30 INFO - Return code: 0 04:44:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:44: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')]} 04:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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')]} 04:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:30 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 0x102abeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe12bd63c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10289cd30>, '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 0x1027c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102891be8>, '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.zNhhyCaaal/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.EAPU4waAyN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 04:44:30 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 04:44:30 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 04:44:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNhhyCaaal/Render', 04:44:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:30 INFO - 'HOME': '/Users/cltbld', 04:44:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:30 INFO - 'LOGNAME': 'cltbld', 04:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:30 INFO - 'MOZ_NO_REMOTE': '1', 04:44:30 INFO - 'NO_EM_RESTART': '1', 04:44:30 INFO - 'PAGER': '/bin/cat', 04:44:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:30 INFO - 'PWD': '/builds/slave/test', 04:44:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:30 INFO - 'SHELL': '/bin/bash', 04:44:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EAPU4waAyN/Listeners', 04:44:30 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 04:44:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:30 INFO - 'USER': 'cltbld', 04:44:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:30 INFO - 'XPC_FLAGS': '0x0', 04:44:30 INFO - 'XPC_SERVICE_NAME': '0', 04:44:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:44:30 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-SuH9fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:44:30 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)) 04:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:44:30 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-YkxXmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:44:30 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)) 04:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:44:30 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-DmwXg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:44:30 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)) 04:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:44:30 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-qZGyTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:44:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:44:30 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-5iShvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:44:31 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)) 04:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:44:31 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-VDhFt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:44:31 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)) 04:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:44:31 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-3ikgu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:44:31 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)) 04:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:44:31 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-VpaVaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:44:31 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)) 04:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:44:31 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-VxtwBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:44:31 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)) 04:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:44:31 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-hJC3QV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:44:31 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)) 04:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:44:31 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-5eaN4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:44:31 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)) 04:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:44:31 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ZfU7cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:44:31 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)) 04:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:44:31 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-scDTfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:44:31 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)) 04:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:44:31 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-S1BMKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:44:31 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)) 04:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:31 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-FakaEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:32 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)) 04:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:44:32 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-rCHWht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:44:32 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)) 04:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:44:32 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-DVD0oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:44:32 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)) 04:44:32 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)) 04:44:32 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)) 04:44:32 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)) 04:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:44:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:34 INFO - Downloading blessings-1.6.tar.gz 04:44:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:44:35 INFO - Installing collected packages: blessings 04:44:35 INFO - Running setup.py install for blessings 04:44:35 INFO - Successfully installed blessings 04:44:35 INFO - Cleaning up... 04:44:35 INFO - Return code: 0 04:44:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:44:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:44:35 INFO - Reading from file tmpfile_stdout 04:44:35 INFO - Current package versions: 04:44:35 INFO - blessings == 1.6 04:44:35 INFO - blobuploader == 1.2.4 04:44:35 INFO - docopt == 0.6.1 04:44:35 INFO - functools32 == 3.2.3-2 04:44:35 INFO - jsonschema == 2.5.1 04:44:35 INFO - manifestparser == 1.1 04:44:35 INFO - mozInstall == 1.12 04:44:35 INFO - mozcrash == 1.0 04:44:35 INFO - mozdebug == 0.1 04:44:35 INFO - mozdevice == 0.49 04:44:35 INFO - mozfile == 1.2 04:44:35 INFO - mozhttpd == 0.7 04:44:35 INFO - mozinfo == 0.9 04:44:35 INFO - mozleak == 0.1 04:44:35 INFO - mozlog == 3.4 04:44:35 INFO - moznetwork == 0.27 04:44:35 INFO - mozprocess == 0.25 04:44:35 INFO - mozprofile == 0.28 04:44:35 INFO - mozrunner == 6.13 04:44:35 INFO - mozscreenshot == 0.1 04:44:35 INFO - mozsystemmonitor == 0.3 04:44:35 INFO - moztest == 0.8 04:44:35 INFO - mozversion == 1.4 04:44:35 INFO - psutil == 3.1.1 04:44:35 INFO - requests == 1.2.3 04:44:35 INFO - wsgiref == 0.1.2 04:44:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:35 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 0x102abeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe12bd63c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10289cd30>, '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 0x1027c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102891be8>, '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.zNhhyCaaal/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.EAPU4waAyN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 04:44:35 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 04:44:35 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 04:44:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNhhyCaaal/Render', 04:44:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:35 INFO - 'HOME': '/Users/cltbld', 04:44:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:35 INFO - 'LOGNAME': 'cltbld', 04:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:35 INFO - 'MOZ_NO_REMOTE': '1', 04:44:35 INFO - 'NO_EM_RESTART': '1', 04:44:35 INFO - 'PAGER': '/bin/cat', 04:44:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:35 INFO - 'PWD': '/builds/slave/test', 04:44:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:35 INFO - 'SHELL': '/bin/bash', 04:44:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EAPU4waAyN/Listeners', 04:44:35 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 04:44:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:35 INFO - 'USER': 'cltbld', 04:44:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:35 INFO - 'XPC_FLAGS': '0x0', 04:44:35 INFO - 'XPC_SERVICE_NAME': '0', 04:44:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:35 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)) 04:44:35 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)) 04:44:35 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)) 04:44:35 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)) 04:44:35 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)) 04:44:35 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)) 04:44:35 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)) 04:44:35 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)) 04:44:35 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)) 04:44:35 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)) 04:44:35 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)) 04:44:35 INFO - Cleaning up... 04:44:35 INFO - Return code: 0 04:44:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102abeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ac1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe12bd63c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10289cd30>, '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 0x1027c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102891be8>, '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.zNhhyCaaal/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.EAPU4waAyN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 04:44:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:44:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNhhyCaaal/Render', 04:44:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:35 INFO - 'HOME': '/Users/cltbld', 04:44:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:35 INFO - 'LOGNAME': 'cltbld', 04:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:35 INFO - 'MOZ_NO_REMOTE': '1', 04:44:35 INFO - 'NO_EM_RESTART': '1', 04:44:35 INFO - 'PAGER': '/bin/cat', 04:44:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:35 INFO - 'PWD': '/builds/slave/test', 04:44:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:35 INFO - 'SHELL': '/bin/bash', 04:44:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EAPU4waAyN/Listeners', 04:44:35 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 04:44:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:35 INFO - 'USER': 'cltbld', 04:44:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:35 INFO - 'XPC_FLAGS': '0x0', 04:44:35 INFO - 'XPC_SERVICE_NAME': '0', 04:44:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:44:36 INFO - Cleaning up... 04:44:36 INFO - Return code: 0 04:44:36 INFO - Running post-action listener: _resource_record_post_action 04:44:36 INFO - Running post-action listener: _start_resource_monitoring 04:44:36 INFO - Starting resource monitoring. 04:44:36 INFO - [mozharness: 2017-04-12 11:44:36.171068Z] Finished create-virtualenv step (success) 04:44:36 INFO - [mozharness: 2017-04-12 11:44:36.172060Z] Running install step. 04:44:36 INFO - Running pre-action listener: _resource_record_pre_action 04:44:36 INFO - Running main action method: install 04:44:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:44:36 INFO - Reading from file tmpfile_stdout 04:44:36 INFO - Detecting whether we're running mozinstall >=1.0... 04:44:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:44:36 INFO - Reading from file tmpfile_stdout 04:44:36 INFO - Output received: 04:44:36 INFO - Usage: mozinstall [options] installer 04:44:36 INFO - Options: 04:44:36 INFO - -h, --help show this help message and exit 04:44:36 INFO - -d DEST, --destination=DEST 04:44:36 INFO - Directory to install application into. [default: 04:44:36 INFO - "/builds/slave/test"] 04:44:36 INFO - --app=APP Application being installed. [default: firefox] 04:44:36 INFO - mkdir: /builds/slave/test/build/application 04:44:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:44:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 04:44:56 INFO - Reading from file tmpfile_stdout 04:44:56 INFO - Output received: 04:44:56 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:44:56 INFO - Running post-action listener: _resource_record_post_action 04:44:56 INFO - [mozharness: 2017-04-12 11:44:56.751677Z] Finished install step (success) 04:44:56 INFO - [mozharness: 2017-04-12 11:44:56.751826Z] Running run-tests step. 04:44:56 INFO - Running pre-action listener: _resource_record_pre_action 04:44:56 INFO - Running main action method: run_tests 04:44:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:44:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:44:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:44:56 INFO - 2017-04-12 04:44:56.765 screenresolution[2538:33914] starting screenresolution argv=screenresolution get 04:44:56 INFO - 2017-04-12 04:44:56.768 screenresolution[2538:33914] Display 0: 1600x1200x32@60 04:44:56 INFO - 2017-04-12 04:44:56.773 screenresolution[2539:33918] starting screenresolution argv=screenresolution list 04:44:56 INFO - Available Modes on Display 0 04:44:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:44:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:44:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:44:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:44:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:44:57 INFO - Intel Iris: 04:44:57 INFO - Chipset Model: Intel Iris 04:44:57 INFO - Type: GPU 04:44:57 INFO - Bus: Built-In 04:44:57 INFO - VRAM (Dynamic, Max): 1536 MB 04:44:57 INFO - Vendor: Intel (0x8086) 04:44:57 INFO - Device ID: 0x0a2e 04:44:57 INFO - Revision ID: 0x0009 04:44:57 INFO - Displays: 04:44:57 INFO - Display: 04:44:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:44:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:44:57 INFO - Display Serial Number: 1600x1200 60 04:44:57 INFO - Main Display: Yes 04:44:57 INFO - Mirror: Off 04:44:57 INFO - Online: Yes 04:44:57 INFO - Rotation: Supported 04:44:57 INFO - Return code: 0 04:44:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:44:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:44:57 INFO - Python 2.7.3 04:44:57 INFO - Return code: 0 04:44:57 INFO - grabbing minidump binary from tooltool 04:44:57 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')]} 04:44:57 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 0x10289cd30>, '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 0x1027c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102891be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:44:57 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 04:44:57 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 04:44:57 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 04:44:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:44:57 INFO - Return code: 0 04:44:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:44:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:44:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:44:57 INFO - ENV: RUST_BACKTRACE is now 1 04:44:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:44:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:44:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 04:44:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0030 --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 04:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zNhhyCaaal/Render', 04:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:57 INFO - 'HOME': '/Users/cltbld', 04:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:57 INFO - 'LOGNAME': 'cltbld', 04:44:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:44:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:57 INFO - 'MOZ_NO_REMOTE': '1', 04:44:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:44:57 INFO - 'NO_EM_RESTART': '1', 04:44:57 INFO - 'PAGER': '/bin/cat', 04:44:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:57 INFO - 'PWD': '/builds/slave/test', 04:44:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:57 INFO - 'RUST_BACKTRACE': '1', 04:44:57 INFO - 'SHELL': '/bin/bash', 04:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EAPU4waAyN/Listeners', 04:44:57 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 04:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:57 INFO - 'USER': 'cltbld', 04:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:57 INFO - 'XPC_FLAGS': '0x0', 04:44:57 INFO - 'XPC_SERVICE_NAME': '0', 04:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 04:44:57 INFO - mozversion application_buildid: 20170412024510 04:44:57 INFO - mozversion application_changeset: 65875bd4b7df1e5e29f1b07927d88d37b9ddded0 04:44:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:44:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:44:57 INFO - mozversion application_name: Firefox 04:44:57 INFO - mozversion application_remotingname: firefox-dev 04:44:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:44:57 INFO - mozversion application_vendor: Mozilla 04:44:57 INFO - mozversion application_version: 54.0a2 04:44:57 INFO - mozversion platform_buildid: 20170412024510 04:44:57 INFO - mozversion platform_changeset: 65875bd4b7df1e5e29f1b07927d88d37b9ddded0 04:44:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:44:57 INFO - mozversion platform_version: 54.0a2 04:44:57 INFO - using testdate: 1491997497 04:44:57 INFO - actual date: 1491997497 04:44:57 INFO - starting webserver on 'localhost:57604' 04:44:57 INFO - SUITE-START | Running 2 tests 04:44:57 INFO - TEST-START | dromaeo_css 04:44:57 INFO - operating with platform_type : mac_ 04:44:57 INFO - Initialising browser for dromaeo_css test... 04:44:57 INFO - TEST-INFO | started process 2549 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpP1_2C8/profile http://localhost:57604/getInfo.html) 04:44:59 INFO - PROCESS | 2549 | __metrics Screen width/height:1600/1200 04:44:59 INFO - PROCESS | 2549 | colorDepth:24 04:44:59 INFO - PROCESS | 2549 | Browser inner width/height: 1024/689 04:44:59 INFO - PROCESS | 2549 | __metrics 04:44:59 INFO - PROCESS | 2549 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:44:59 INFO - PROCESS | 2549 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:45:00 INFO - TEST-INFO | 2549: exit 0 04:45:00 INFO - Browser initialized. 04:45:00 INFO - Running cycle 1/1 for dromaeo_css test... 04:45:00 INFO - TEST-INFO | started process 2551 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpP1_2C8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:45:12 INFO - PROCESS | 2551 | RSS: Main: 210333696 04:45:12 INFO - PROCESS | 2551 | 04:48:17 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:57604/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57604/tests/dromaeo/cssquery-ext.html) 04:48:19 INFO - PROCESS | 2551 | RSS: Main: 398360576 04:48:19 INFO - PROCESS | 2551 | 04:50:02 INFO - PROCESS | 2551 | ************************* 04:50:02 INFO - PROCESS | 2551 | A coding exception was thrown and uncaught in a Task. 04:50:02 INFO - PROCESS | 2551 | 04:50:02 ERROR - PROCESS | 2551 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:50:02 INFO - PROCESS | 2551 | Full stack: 04:50:02 INFO - PROCESS | 2551 | ************************* 04:50:02 INFO - PROCESS | 2551 | ************************* 04:50:02 INFO - PROCESS | 2551 | A coding exception was thrown and uncaught in a Task. 04:50:02 INFO - PROCESS | 2551 | 04:50:02 ERROR - PROCESS | 2551 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:50:02 INFO - PROCESS | 2551 | Full stack: 04:50:02 INFO - PROCESS | 2551 | ************************* 04:50:02 INFO - PROCESS | 2551 | ************************* 04:50:02 INFO - PROCESS | 2551 | A coding exception was thrown and uncaught in a Task. 04:50:02 INFO - PROCESS | 2551 | 04:50:02 ERROR - PROCESS | 2551 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:50:02 INFO - PROCESS | 2551 | Full stack: 04:50:02 INFO - PROCESS | 2551 | ************************* 04:50:02 INFO - PROCESS | 2551 | ************************* 04:50:02 INFO - PROCESS | 2551 | A coding exception was thrown and uncaught in a Task. 04:50:02 INFO - PROCESS | 2551 | 04:50:02 ERROR - PROCESS | 2551 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:50:02 INFO - PROCESS | 2551 | Full stack: 04:50:02 INFO - PROCESS | 2551 | ************************* 04:51:22 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:57604/tests/dromaeo/cssquery-ext.html (next: http://localhost:57604/tests/dromaeo/cssquery-jquery.html) 04:51:23 INFO - PROCESS | 2551 | RSS: Main: 351772672 04:51:23 INFO - PROCESS | 2551 | 04:54:25 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:57604/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57604/tests/dromaeo/cssquery-mootools.html) 04:54:26 INFO - PROCESS | 2551 | RSS: Main: 331235328 04:54:26 INFO - PROCESS | 2551 | 04:57:28 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:57604/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57604/tests/dromaeo/cssquery-prototype.html) 04:57:29 INFO - PROCESS | 2551 | RSS: Main: 332931072 04:57:29 INFO - PROCESS | 2551 | 05:00:33 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:57604/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57604/tests/dromaeo/cssquery-yui.html) 05:00:33 INFO - PROCESS | 2551 | RSS: Main: 323563520 05:00:33 INFO - PROCESS | 2551 | 05:03:36 INFO - PROCESS | 2551 | Cycle 1(1): loaded http://localhost:57604/tests/dromaeo/cssquery-yui.html (next: http://localhost:57604/tests/dromaeo/cssquery-dojo.html) 05:03:37 INFO - PROCESS | 2551 | RSS: Main: 539590656 05:03:37 INFO - PROCESS | 2551 | 05:03:37 INFO - PROCESS | 2551 | __start_tp_report 05:03:37 INFO - PROCESS | 2551 | _x_x_mozilla_page_load 05:03:37 INFO - PROCESS | 2551 | _x_x_mozilla_page_load_details 05:03:37 INFO - PROCESS | 2551 | |i|pagename|runs| 05:03:37 INFO - PROCESS | 2551 | |0;dojo.html;3400.4729266963623;3428.7942723436595;3432.6395728448497;3442.74339865949;3456.498807672886;6065.969670151654;6107.114468402079;6114.816555503331;6126.356732543086;6127.63234205948;6045.486133678643;6115.113308570255;6118.694065296729;6131.202943617323;6150.538709596775;5409.377921539034;5418.485243901823;5472.534834539069;5479.205515200293;5544.251526043973;5336.146216605344;5338.813141540047;5533.675458487491;5538.501534861882;5589.217509548666;5380.435054319299;5440.483154100354;5460.31746031746;5513.531349835269;5541.362743284533;5465.152901300305;5482;5493.670379777226;5501.944980550184;5508.256385387961;5340.652857564246;5434.592405569598;5463.7814487420455;5479.3424789025175;5508.586855985777;5798.565107616945;5833.912491312633;5898.734556944905;5927.64434133949;6053.970824959767;6437.485001199892;6445.2265728112925;6460.709268082947;6464.74141034358;6472.093906853044;6536.444402225817;6536.54244202906;6538.411542961157;6545.705443255065;6573.244076931119;5934.732937017845;5965.045592705146;6037.63774173551;6181.072839074175;6209.565330426872;6447.161868957006;6578.835529111715;6618.0403841443685;6630.1049358336;6630.403263706289;6368.745250189941;6394.9680251599375;6397.296297407282;6402.647854368054;6412.3267056959285;6373.426391624684;6490.772822951269;6499.09012738217;6500.317466666026;6564.277929427759;5367.221752845893;6269.153664255358;6275.654838983808;6322.873542529123;6332.081848131995;6303.779367722108;6328.145700330489;6329.240491141092;6342.968285158545;6366.84082897922;5074.847754567369;5172.948270517322;5178.585713142939;5200.349956255468;5212.947870521322;4609.861704148881;4617.722936623813;4652.906941861143;4662;4682.6253899687945;5850.678212698343;5897.970510147422;5920.052791553331;6066.969665151646;6119.908201376983;6488.805335839932;6490.221173459215;6512.381323774329;6513.739450422025;6522.934770652328;6117.877642447126;6139.140520327158;6149.446549810538;6150.692465376803;6155.569110162246;5445.53712934405;5554.333479982347;5697.430256974352;5698.943010569924;5752.137179423036;6518.706658200297;6547.312532184147;6562.671866406565;6579.309172536815;6585.5719378240265;6332.873342533216;6346.07981842621;6433.806985790434;6492.74029038852;6509.349065093453;6720.831979200363;6782.966085169543;6783.830404239933;6786.4910131739325;6798.8640227196165;6429.485641148791;6433;6442.291347951816;6450.8064758057335;6452.193475815523;6277.937220627918;6281.3404592518955;6304.369563043659;6329.5252856036905;6495.317991610813;5505.9724701376235;6293.55945083858;6335.90496142549;6336.112944187726;6336.429721324918;6277.435030847063;6354.809355719336;6356;6365;6368.076628888878;6072.635641861502;6135.601185922901;6226.066090086523;6306.085617585332;6328.335524770017;6166.136740856194;6239.78160764371;6240.500759939285;6280.27776805655;6353.078803573549;6204.255489341308;6249.687515624109;6265.874682506415;6280.057991301342;6290.119383286252 05:03:37 INFO - PROCESS | 2551 | |1;ext.html;779.8128449172199;806.4395245304514;868.118859357752;878.0648609231172;878.7100256915219;39274.607253927454;40360.19279614406;42511.574884251146;42525.787371063176;42591.93520161998;41547.79226103865;41736.165276694446;41752.79123604386;41862.162756744925;41872.790636046775;7338.86247631618;9198.862017069732;9330.953345233263;9358.157765801077;9363.859542106873;7248.710051597956;7316.414686825068;7320.414366850666;7321.816954576151;7322.450816188807;11079.889201107966;11338.979491845732;11346.149038822081;11357;11471.024962878166;11228.15788815838;11258.211925165242;11276.943615281953;11281.661550153509;11305.547778088867;10887.183461240438;10944.945275273572;10947.124230061576;10973.561057557688;10979.725506862393;38739.41890871639;38948;38973;39005.02487437808;39035;3792.677622402099;3793.753406028627;3839.731218814684;3839.8464061437508;3886.3198940185353;26857.46285074307;27222;28540.857295713387;29108.854455727796;29292.414151716846;3125.749940004794;3135.9835021291638;3185.9522107168177;3194.0577529628818;3297.4559197732347;335308;336376;337395.62604374136;339439;344201;42640.78679606582;42823.7858810704;43532;52527.47472525302;52592.474075259524;44810.77594612006;44983;45113.32330015052;45313.54686453093;45323;13172.802407963889;13890.374933128034;14301.57095287143;14612.342444590018;14681.339339729524;10862.511186996604;11033.668989930315;11181.105511559055;11213.551457941754;12694.682632934066;10926.071283940953;12564.183328083645;12732.363381830872;12995;13023.609291721263;5710.710934358143;5725.942740572541;5737.483626473554;5742.196092547047;5760.0783874579865;988.17981075707;1018.843612499818;1125.1280257800106;1129.9435028248554;1145.3814939932342;26668.59997100045;27099;27172.72827271702;27259.727402726116;27286.181414557595;1258.6664533898524;1261.2054405724334;1265.6329664397222;1291.5285920639087;1310.4496111633136;13869.514566990309;14199.85800142006;14212.786808197885;14227.932905032165;14289.785653215211;424318.8784056122;603173;610339.9483002557;610619.8938010563;611055;49168.77078073077;49593.25610115851;49607.007859842604;49631.50368496341;49745.502544974086;1406.5499040307209;1440.553428437188;1445.7903603977575;1454.5563603100943;1455.6651970046948;23302.65046024345;25721.456712597304;26940;27308;27386.5892011616;40815;40860.1827963445;40863.59136408598;40901.38647920224;40987.1802563953;455864;456554;457539;457594;457962;27719.168424947286;28191;28275.72759225881;28278.85860570684;28301.43397132087;37238.44142337921;38070.61929380782;38151.236975260894;38163.236735265695;38171.23657526779;26435.735642644097;26541.7345826547;27192.456150877268;27453.862730686218;27682;26553.203403897915;27027.72972270331;27511.86244068767;27563;27976.24695970048;31358.686413135576;31532.684673152973;31551.053468395985;31949.20126996844;32223.838880806383;27078.72921270762;27253.86373068122;27646.585301220097;27897.302567435978;28094.43811123725;21369.465763356042;21429.464263392918;22186.66719999169;22592;22625.321240362817 05:03:37 INFO - PROCESS | 2551 | |2;jquery.html;376.1705439505889;382.7703377973218;404.90687141957346;417.3947775725199;442.25700822618006;11050;16023.599410014756;17519.386821461252;20734.48163795906;21407.892960535195;17130.914345428286;21251.831149286783;21365.67951480729;21429.928503574836;21443.785562144374;5619.381867994517;6655.367740064698;6823.55646882951;6842.9315706843045;6906.75826346078;6683.632400217986;6763.695633696471;6779.220389655204;6786.626735529544;6803.55776874504;6484.383983521581;6657.300983396746;6734.764283250087;6740.932590674104;6802.591844489347;6569.211694596631;6750.594964302158;6763.729450821961;6859.142607174103;6864.141982252218;5722.19889215506;6637.73449062037;6675.198976122848;6676.732930682767;6682.097916781221;26601.93592256327;27347.86326068357;27368.315792105157;27379.04173353943;27763;2901.4922388581913;2910.4761142994253;2917.28526511004;2921.5909772631635;2954.748846348063;15917.761233581505;16465.094419807025;16540.255688494046;16585.917070414693;17086.914565427218;1997.3608445297511;2002.4493264352275;2053.117159621377;2055.075216152737;2064.1743302679047;118149.40925295405;131979;135885.32057339748;136908.63091369058;137562.31218843942;21429.78570214278;21672.34982950514;21923.67114493284;22137.335879923627;22775.544489110125;35789.284214315565;36002.63997360045;36544.63455365413;36670.08324791848;36695.449568256496;10572.947135264274;10717.196210284259;10953.671389858317;11009.61466348674;11221.326720396803;8823.250023747925;8844.55777211137;8874.201321880933;8992.280617550581;9037.457752534869;8847.424917380478;10358.844617330746;10367.533460994122;10471.18325570231;10506.632267870587;3518.185451638679;4138.461999939988;4140.896477588024;4167.06241095751;4180.268453020709;880.9295256379474;1036.4144258493927;1040.2770074797943;1055.0820785916303;1065.280935368623;20862.895685521475;21949.561008779736;21992.900354982186;21995;22065.558688826135;909.4679612426672;1026.861373714539;1042.8800687920834;1045.7699306152633;1060.2631171336002;8976.730698079067;11285.322880627189;11513.942430287794;11555.942220288845;11620.186586938833;743975;749507;751917;754576;755819;138093;156954;157932;158554;159446.01384965237;1210.6791700199453;1299.928503932274;1352.5063351876495;1367.4940272099586;1370.2737549098993;18524.259029638662;19670.21319147218;19694.8030519693;19815.801841981396;19832;61909.44490801489;63043;63226.36773632205;64438.35561644324;64683;87821.1217887813;87823;89139.55430222808;90202.88174064366;90740.18519629439;9926.057024582653;11231.26996745209;11429.428528573371;11506.194566380273;11875.762484750085;12508.499660013498;12636.810447843474;12655.93672031634;12734.808977865525;13009.739805204032;10876.891231087904;10927.890721092688;10963.342199468058;11303.717407064889;11316.20786544934;10311.123554497952;10346.482675866328;10380.792384152124;10438.739031523968;10569.577216911237;11153.55385784592;11155.44222788841;11159.38623375722;11199.60801371948;11261.268017578832;10070.69787906364;10838.67483975482;11513;11846.052315814542;11868.703282417664;9307.953460232926;9881.357711748713;10310.381377117397;10690.82400935912;11003.284786488852 05:03:37 INFO - PROCESS | 2551 | |3;mootools.html;1528.5261568913631;1531.6936612677466;1558.7973563436751;1562.97655535167;1571.6306667933034;10484.31851929625;11050.010998020352;11051.557937682503;11053.668389948294;11061.115110791137;10614.57541698331;11047.392393418359;11173.888261117387;11183.552657893675;11200.887991120107;2366.2191476812695;2394.3774618599114;2405.230326295586;2405.5068710914306;2406.975930240693;2296.0930432479163;2375.6911601491784;2388.988055059731;2389.82076344274;2392.437777122375;2320.8839558022114;2386.2721869829693;2386.474975505386;2396.8681722505257;2401.3070785096465;2294.7934685878186;2355.316958082154;2367.621180611103;2373.667686523888;2373.8338316317863;2299.356180269527;2323.5236776460783;2327.080803082773;2327.441414060613;2332.848364856296;10695.839562406492;10923.126149907988;10937.671869843838;10938.234323597351;10941.12471002318;5555.777768889239;5803.767849286023;5819.53443724501;5824.271966004249;5832;3431.759776815624;3492.738044646662;3506.3162683276773;3527.2592755521373;3583.982080089609;1164.7554013657143;1168.8538932633421;1193.379442689805;1205.5659962413524;1216.3492531495608;13455.529056483074;13885.166889986633;14155.858441415558;15162;15394.846051539453;4568.954310456853;4718.0328032753205;4757.405324334458;4761.35721677576;4762.976185119052;10249.538770755335;11738.530458781725;11743.530258789724;11749.88250117505;11763.705907352385;4911.852644420673;5519.31008623922;5685.1756495307945;5735.397783232784;5745.741441635137;4973.776180071905;5106.565941894906;5140.974295128575;5228.451012643618;5232.03207406631;3247.2693643930397;3768.5854555998817;3797.9430308545393;3826.9234615307537;3899;903.3179949138358;1002.2282842211456;1003.7540802503433;1007.561710655867;1014.761531040205;924.1451657217048;934.2572654739498;951.7192428233549;955.2358113509304;1009.1876039787894;5707.729528845451;6652.7338906444165;6735.225449073321;6828.863422731518;6932.944955409748;948.5826236455937;955.7706150523824;966.8936416994125;1051.6109039655223;1054.4991129213563;3698.2788356270808;4149.962509372657;4245.214635292481;4382.715123516961;4410.603045725836;13462.86537134616;14114.364853581614;14119.85880141206;14125.85874141266;14205.289735513183;12575.87424125747;13060.085793994329;13980.091294066056;14041.368138433794;14065.367058482392;9900.702978910644;10188.388696678223;10188.643397481053;10207.132393746466;10216.284860059724;6172.105044768573;6518.73925043012;6576.44100251484;6927.302810811383;7082.362587367162;11791.76416471683;12199.634010979686;12238.44926978306;12356.949659279062;12363.567275145328;11977.700557485785;12134.211276267015;12276;12333.814992774936;12348.197367171106;9226.07739226072;9271.721848344561;9468.100530449596;9486.193673537826;9499.38254013487;10263.435511046964;10335.121514671337;10382.065614094769;10401.115905148146;10405.739856503647;8601.698940537051;8726.956365218133;8821.2060914518;8831.60257788414;8841.823163536821;7834.647440865288;8545.52999585004;8602.526861022703;8671.21959023691;8716;7467.178610352966;7932.762017139495;7973.441859070042;7976.800579985546;7982.042154941444;5822.970885145716;5827.24245848037;5887.8233652990475;6006.939930600638;6057.969710151421;5410.242566040679;5870.7945221917025;6044.274687037584;6156.969215153896;6179.876402472015 05:03:37 INFO - PROCESS | 2551 | |4;prototype.html;337.48196518344344;363.4493741980899;381.31934496923;381.81291167328;382.3939056595298;4869.58781953234;5004.5218573091715;5062.645614806965;5173.870653233666;5186.585073194145;4331.7691388934645;4495.9659278366;4856.785803549113;4864.805407783684;5031;3666.9633303666956;4148.94201978495;4323.329883868006;4368.514705000299;4368.628666563346;3795.335816232162;3892.6181205672005;3965.722399432041;4090.6931980101463;4095;4116.814743336557;4124.13393187431;4175.081482073939;4329.328954012118;4391.516933137353;3657.7687610557405;4320.82716691332;4321.567843215685;4362.956370436318;4392.538783427726;4054.56063097602;4116.58834116589;4178.874633760992;4224.239636865363;4255.616994470482;4288.549702281247;4467.7319360838455;4625.930611040837;4945.36802855057;4963.528464795839;4322.471354257763;4330.761808100553;4860.4896485869185;4866.416030076413;4894.448163208215;4991.825286115005;5221.6083793715625;5364.275822763916;5440.340696087692;5463.562914966793;3995.1210733638845;4197.286461301586;4267.613025766638;4276.2894842063115;4326.394304797331;5135.2810606515495;5623.7469313880565;5667.631603945731;5689.175069614924;5749.9712501437225;3774.9807551960816;3926.4306675532457;4401.6698747594055;4426.424564806554;4451;4180.561041090703;4205.2640787862;4227.773945555754;4260.126674031878;4294.677899157576;4097.783240156523;4148.585141485888;4648.628109751212;4806.759662016885;4850.53919877611;5213.3838510062005;5444.91832622511;5463.836084917459;5508.7521061552325;5530.917036244459;1151.4530597966066;1407.1627381707867;1413.4982081361175;1426.7788492783636;1544.7605621128744;1102.8456016157745;1119.9048080913215;1141.668916014363;1145.639123676056;1157.2651366382408;4151.543330233671;4229.577042295746;4805.8077676892535;5001;5030.84907452777;4599.816007359735;4993.575546078551;5295.325860440453;5505.6696598204235;5505.752241149158;4273.850415235514;4387.298032314814;4706.858794236178;4826.401374405673;5090.567301779316;2446.620773780067;2719.510488112119;2777.666679998413;2833.880617156253;2874.870630821618;4995.750212489361;5239.502247286502;5561.277033985637;5720.627049508088;5743.24830926563;4699.036697477009;5221.582273418194;5283.392409872914;5509.228707980886;5743.827685169452;5498.340199176125;5740.512056475246;5751.124437781109;5768.596198266081;5799.999999999915;4698.177818881613;4748.548887855648;5295.3116094908955;5525.955396503319;5566.91649625264;4240.978795106004;4879.974415862046;5290.418054013979;5504.339479262514;5572.01896985111;5497.532709719719;5499.562773266708;5837.357890631942;5867.32525759526;5868.911966320423;3935.4883865097354;4146.730462519927;4454.309582014793;4461.509233984324;4471.776411179493;5090.109230884506;5165.741712914414;5286.735663216747;5289.57683385336;5415.48552887475;5236.031334203185;5245.21662634706;5246.685198888079;5392.847507419344;5481.561475081904;4797.688150270221;4821.600771456045;5254.973725131503;5370.677759334452;5416.8104116355735;5054.0325844213585;5306.8407947761625;5314.707691077027;5359.3386050324525;5375.596830237826;5401.491203100333;5412.9174165167915;5445.700486473123;5451.264079349396;5454.836354909359;4718.669693121448;4722.622190224705;5064.5819170632285;5344.919826202532;5366.672662948997 05:03:37 INFO - PROCESS | 2551 | |5;yui.html;690.8618276344731;698.6332175607802;724.7753196509079;776.2819392062341;815.6207363575938;14853.628659283524;15677.764833527477;15693.921530392361;15728.977616454937;15746.055236685806;14189.503367382144;14223.431062757503;14227.71544569108;14323.57029289123;14399.568012959602;908.4685459006489;912.6760000199934;930.6370515498927;933.5099072986668;936.4568550240843;925.319889880937;942.5711301357866;943.6980166346772;944.2917811641269;945.1588086602894;996.9700908972742;997.9870431511985;998.6005597760881;1000.3797645459825;1001.1390204424189;992.270681049428;994.3834822410151;995.2485873165786;995.8157740817971;998.116666749928;738.3723834740427;738.8780851159552;739.1093732052864;739.2902813299239;739.3087463221851;14831.555053348417;15443.841711871655;15791.921040394724;16093.839061609351;16324.673506529803;13064.934675326562;13190.802137967843;13228.140170888957;13319.26744029078;13336.19982801035;11218.495167717472;11556.711082222928;11601.535938562447;11970.58102966392;12052.517899284017;3547.875824346161;3569.803660798655;3664.7617904836366;3690.852365905361;3805.162864169878;109174.5315960648;112437;113171.3024304636;115466;115847.99999999916;10427.895721042843;10545.050945414796;10897.564097436172;11034.227604067639;11068;18528.351507697436;22299.21952731679;22737.545249095256;22761.772382276296;22901.31296061121;7636.923630763621;7666.118396384487;7699.076110866732;7710.036245469317;7723.033302658719;4221.852235171754;4237;4259.97870010648;4260.467441569804;4326.913461730748;2882.6684931233176;3400.1329660936303;3489.284696637183;3494.5107684924133;3542.557180352456;634.1755717567145;639.2105749399487;641.4483544152025;652.419346781365;654.4208375587544;668.7525615522288;677.4884961853868;693.5769180799708;698.5703792167781;703.3318347569829;9792.951035244778;10303.948480257552;10379.377237365632;10554.683359499228;10692.572297108185;654.456800855289;683.3371629519359;696.3523922751788;708.2598684374738;725.3037084399036;4618.445786505641;4988.27669987857;5013.1227035268685;5021.648484606116;5023.422306434807;101874;103058;103176;104113;105168;59898.101528477106;68256.6587167061;91543.62684559736;93519;94982.57526137114;80474.47621137078;81543.59228203821;81875;81883;83770.58114709388;10374.170066394821;10761.085307748928;11083.113350932068;11216.327020378803;11231.21381503287;40673.59326406698;46642;47379.763101184275;48077.03845922992;48427.78930526624;59300;59443;60469.69765151146;61008.389916102045;61917.69041154766;11984.820227696766;12011.279323240633;12136.150469467406;12245.755084898074;12457.501699931901;13817.861821381657;14236.430542778173;14312.57062288133;14427.855721443071;15024.6995060096;12645.620631381073;12765.914957139137;13595.320233988063;13638.727225455237;13918.234497102352;12885.871141288842;13467.12463689857;13607.455701771818;13628.182309061489;14075.577732668036;12175.573854915036;13397.397117129947;13402;13482.662933426744;13692.931535342259;9003.54982250898;9248.445093294424;9602.279829012992;9693.273004524548;9962.402255864672;7305.379042781211;10030.598776049168;10690.011526690398;10847.403392813156;11072.557097716 05:03:37 INFO - PROCESS | 2551 | __end_tp_report 05:03:37 INFO - PROCESS | 2551 | __start_cc_report 05:03:37 INFO - PROCESS | 2551 | _x_x_mozilla_cycle_collect,2210 05:03:37 INFO - PROCESS | 2551 | __end_cc_report 05:03:37 INFO - PROCESS | 2551 | __startTimestamp1491998617080__endTimestamp 05:03:37 INFO - PROCESS | 2551 | 05:03:37 INFO - PROCESS | 2551 | ------- Summary: start ------- 05:03:37 INFO - PROCESS | 2551 | Number of tests: 6 05:03:37 INFO - PROCESS | 2551 | 05:03:37 INFO - PROCESS | 2551 | [#0] dojo.html Cycles:165 Average:5990.86 Median:6239.78 stddev:663.31 (10.6%) stddev-sans-first:633.45 05:03:37 INFO - PROCESS | 2551 | Values: 3400.5 3428.8 3432.6 3442.7 3456.5 6066.0 6107.1 6114.8 6126.4 6127.6 6045.5 6115.1 6118.7 6131.2 6150.5 5409.4 5418.5 5472.5 5479.2 5544.3 5336.1 5338.8 5533.7 5538.5 5589.2 5380.4 5440.5 5460.3 5513.5 5541.4 5465.2 5482.0 5493.7 5501.9 5508.3 5340.7 5434.6 5463.8 5479.3 5508.6 5798.6 5833.9 5898.7 5927.6 6054.0 6437.5 6445.2 6460.7 6464.7 6472.1 6536.4 6536.5 6538.4 6545.7 6573.2 5934.7 5965.0 6037.6 6181.1 6209.6 6447.2 6578.8 6618.0 6630.1 6630.4 6368.7 6395.0 6397.3 6402.6 6412.3 6373.4 6490.8 6499.1 6500.3 6564.3 5367.2 6269.2 6275.7 6322.9 6332.1 6303.8 6328.1 6329.2 6343.0 6366.8 5074.8 5172.9 5178.6 5200.3 5212.9 4609.9 4617.7 4652.9 4662.0 4682.6 5850.7 5898.0 5920.1 6067.0 6119.9 6488.8 6490.2 6512.4 6513.7 6522.9 6117.9 6139.1 6149.4 6150.7 6155.6 5445.5 5554.3 5697.4 5698.9 5752.1 6518.7 6547.3 6562.7 6579.3 6585.6 6332.9 6346.1 6433.8 6492.7 6509.3 6720.8 6783.0 6783.8 6786.5 6798.9 6429.5 6433.0 6442.3 6450.8 6452.2 6277.9 6281.3 6304.4 6329.5 6495.3 5506.0 6293.6 6335.9 6336.1 6336.4 6277.4 6354.8 6356.0 6365.0 6368.1 6072.6 6135.6 6226.1 6306.1 6328.3 6166.1 6239.8 6240.5 6280.3 6353.1 6204.3 6249.7 6265.9 6280.1 6290.1 05:03:37 INFO - PROCESS | 2551 | 05:03:37 INFO - PROCESS | 2551 | [#1] ext.html Cycles:180 Average:57629.01 Median:17247.33 stddev:124994.75 (724.7%) stddev-sans-first:125272.51 05:03:37 INFO - PROCESS | 2551 | Values: 779.8 806.4 868.1 878.1 878.7 39274.6 40360.2 42511.6 42525.8 42591.9 41547.8 41736.2 41752.8 41862.2 41872.8 7338.9 9198.9 9331.0 9358.2 9363.9 7248.7 7316.4 7320.4 7321.8 7322.5 11079.9 11339.0 11346.1 11357.0 11471.0 11228.2 11258.2 11276.9 11281.7 11305.5 10887.2 10944.9 10947.1 10973.6 10979.7 38739.4 38948.0 38973.0 39005.0 39035.0 3792.7 3793.8 3839.7 3839.8 3886.3 26857.5 27222.0 28540.9 29108.9 29292.4 3125.7 3136.0 3186.0 3194.1 3297.5 335308.0 336376.0 337395.6 339439.0 344201.0 42640.8 42823.8 43532.0 52527.5 52592.5 44810.8 44983.0 45113.3 45313.5 45323.0 13172.8 13890.4 14301.6 14612.3 14681.3 10862.5 11033.7 11181.1 11213.6 12694.7 10926.1 12564.2 12732.4 12995.0 13023.6 5710.7 5725.9 5737.5 5742.2 5760.1 988.2 1018.8 1125.1 1129.9 1145.4 26668.6 27099.0 27172.7 27259.7 27286.2 1258.7 1261.2 1265.6 1291.5 1310.4 13869.5 14199.9 14212.8 14227.9 14289.8 424318.9 603173.0 610339.9 610619.9 611055.0 49168.8 49593.3 49607.0 49631.5 49745.5 1406.5 1440.6 1445.8 1454.6 1455.7 23302.7 25721.5 26940.0 27308.0 27386.6 40815.0 40860.2 40863.6 40901.4 40987.2 455864.0 456554.0 457539.0 457594.0 457962.0 27719.2 28191.0 28275.7 28278.9 28301.4 37238.4 38070.6 38151.2 38163.2 38171.2 26435.7 26541.7 27192.5 27453.9 27682.0 26553.2 27027.7 27511.9 27563.0 27976.2 31358.7 31532.7 31551.1 31949.2 32223.8 27078.7 27253.9 27646.6 27897.3 28094.4 21369.5 21429.5 22186.7 22592.0 22625.3 05:03:37 INFO - PROCESS | 2551 | 05:03:37 INFO - PROCESS | 2551 | [#2] jquery.html Cycles:180 Average:42674.99 Median:21386.79 stddev:124844.59 (583.7%) stddev-sans-first:125154.41 05:03:37 INFO - PROCESS | 2551 | Values: 376.2 382.8 404.9 417.4 442.3 11050.0 16023.6 17519.4 20734.5 21407.9 17130.9 21251.8 21365.7 21429.9 21443.8 5619.4 6655.4 6823.6 6842.9 6906.8 6683.6 6763.7 6779.2 6786.6 6803.6 6484.4 6657.3 6734.8 6740.9 6802.6 6569.2 6750.6 6763.7 6859.1 6864.1 5722.2 6637.7 6675.2 6676.7 6682.1 26601.9 27347.9 27368.3 27379.0 27763.0 2901.5 2910.5 2917.3 2921.6 2954.7 15917.8 16465.1 16540.3 16585.9 17086.9 1997.4 2002.4 2053.1 2055.1 2064.2 118149.4 131979.0 135885.3 136908.6 137562.3 21429.8 21672.3 21923.7 22137.3 22775.5 35789.3 36002.6 36544.6 36670.1 36695.4 10572.9 10717.2 10953.7 11009.6 11221.3 8823.3 8844.6 8874.2 8992.3 9037.5 8847.4 10358.8 10367.5 10471.2 10506.6 3518.2 4138.5 4140.9 4167.1 4180.3 880.9 1036.4 1040.3 1055.1 1065.3 20862.9 21949.6 21992.9 21995.0 22065.6 909.5 1026.9 1042.9 1045.8 1060.3 8976.7 11285.3 11513.9 11555.9 11620.2 743975.0 749507.0 751917.0 754576.0 755819.0 138093.0 156954.0 157932.0 158554.0 159446.0 1210.7 1299.9 1352.5 1367.5 1370.3 18524.3 19670.2 19694.8 19815.8 19832.0 61909.4 63043.0 63226.4 64438.4 64683.0 87821.1 87823.0 89139.6 90202.9 90740.2 9926.1 11231.3 11429.4 11506.2 11875.8 12508.5 12636.8 12655.9 12734.8 13009.7 10876.9 10927.9 10963.3 11303.7 11316.2 10311.1 10346.5 10380.8 10438.7 10569.6 11153.6 11155.4 11159.4 11199.6 11261.3 10070.7 10838.7 11513.0 11846.1 11868.7 9308.0 9881.4 10310.4 10690.8 11003.3 05:03:37 INFO - PROCESS | 2551 | 05:03:37 INFO - PROCESS | 2551 | [#3] mootools.html Cycles:180 Average:6688.44 Median:2395.62 stddev:4179.00 (174.4%) stddev-sans-first:4172.74 05:03:37 INFO - PROCESS | 2551 | Values: 1528.5 1531.7 1558.8 1563.0 1571.6 10484.3 11050.0 11051.6 11053.7 11061.1 10614.6 11047.4 11173.9 11183.6 11200.9 2366.2 2394.4 2405.2 2405.5 2407.0 2296.1 2375.7 2389.0 2389.8 2392.4 2320.9 2386.3 2386.5 2396.9 2401.3 2294.8 2355.3 2367.6 2373.7 2373.8 2299.4 2323.5 2327.1 2327.4 2332.8 10695.8 10923.1 10937.7 10938.2 10941.1 5555.8 5803.8 5819.5 5824.3 5832.0 3431.8 3492.7 3506.3 3527.3 3584.0 1164.8 1168.9 1193.4 1205.6 1216.3 13455.5 13885.2 14155.9 15162.0 15394.8 4569.0 4718.0 4757.4 4761.4 4763.0 10249.5 11738.5 11743.5 11749.9 11763.7 4911.9 5519.3 5685.2 5735.4 5745.7 4973.8 5106.6 5141.0 5228.5 5232.0 3247.3 3768.6 3797.9 3826.9 3899.0 903.3 1002.2 1003.8 1007.6 1014.8 924.1 934.3 951.7 955.2 1009.2 5707.7 6652.7 6735.2 6828.9 6932.9 948.6 955.8 966.9 1051.6 1054.5 3698.3 4150.0 4245.2 4382.7 4410.6 13462.9 14114.4 14119.9 14125.9 14205.3 12575.9 13060.1 13980.1 14041.4 14065.4 9900.7 10188.4 10188.6 10207.1 10216.3 6172.1 6518.7 6576.4 6927.3 7082.4 11791.8 12199.6 12238.4 12356.9 12363.6 11977.7 12134.2 12276.0 12333.8 12348.2 9226.1 9271.7 9468.1 9486.2 9499.4 10263.4 10335.1 10382.1 10401.1 10405.7 8601.7 8727.0 8821.2 8831.6 8841.8 7834.6 8545.5 8602.5 8671.2 8716.0 7467.2 7932.8 7973.4 7976.8 7982.0 5823.0 5827.2 5887.8 6006.9 6058.0 5410.2 5870.8 6044.3 6157.0 6179.9 05:03:37 INFO - PROCESS | 2551 | 05:03:37 INFO - PROCESS | 2551 | [#4] prototype.html Cycles:180 Average:4488.41 Median:4824.00 stddev:1254.78 (26.0%) stddev-sans-first:1219.01 05:03:37 INFO - PROCESS | 2551 | Values: 337.5 363.4 381.3 381.8 382.4 4869.6 5004.5 5062.6 5173.9 5186.6 4331.8 4496.0 4856.8 4864.8 5031.0 3667.0 4148.9 4323.3 4368.5 4368.6 3795.3 3892.6 3965.7 4090.7 4095.0 4116.8 4124.1 4175.1 4329.3 4391.5 3657.8 4320.8 4321.6 4363.0 4392.5 4054.6 4116.6 4178.9 4224.2 4255.6 4288.5 4467.7 4625.9 4945.4 4963.5 4322.5 4330.8 4860.5 4866.4 4894.4 4991.8 5221.6 5364.3 5440.3 5463.6 3995.1 4197.3 4267.6 4276.3 4326.4 5135.3 5623.7 5667.6 5689.2 5750.0 3775.0 3926.4 4401.7 4426.4 4451.0 4180.6 4205.3 4227.8 4260.1 4294.7 4097.8 4148.6 4648.6 4806.8 4850.5 5213.4 5444.9 5463.8 5508.8 5530.9 1151.5 1407.2 1413.5 1426.8 1544.8 1102.8 1119.9 1141.7 1145.6 1157.3 4151.5 4229.6 4805.8 5001.0 5030.8 4599.8 4993.6 5295.3 5505.7 5505.8 4273.9 4387.3 4706.9 4826.4 5090.6 2446.6 2719.5 2777.7 2833.9 2874.9 4995.8 5239.5 5561.3 5720.6 5743.2 4699.0 5221.6 5283.4 5509.2 5743.8 5498.3 5740.5 5751.1 5768.6 5800.0 4698.2 4748.5 5295.3 5526.0 5566.9 4241.0 4880.0 5290.4 5504.3 5572.0 5497.5 5499.6 5837.4 5867.3 5868.9 3935.5 4146.7 4454.3 4461.5 4471.8 5090.1 5165.7 5286.7 5289.6 5415.5 5236.0 5245.2 5246.7 5392.8 5481.6 4797.7 4821.6 5255.0 5370.7 5416.8 5054.0 5306.8 5314.7 5359.3 5375.6 5401.5 5412.9 5445.7 5451.3 5454.8 4718.7 4722.6 5064.6 5344.9 5366.7 05:03:37 INFO - PROCESS | 2551 | 05:03:37 INFO - PROCESS | 2551 | [#5] yui.html Cycles:180 Average:20239.22 Median:3558.84 stddev:29529.27 (829.7%) stddev-sans-first:29575.63 05:03:37 INFO - PROCESS | 2551 | Values: 690.9 698.6 724.8 776.3 815.6 14853.6 15677.8 15693.9 15729.0 15746.1 14189.5 14223.4 14227.7 14323.6 14399.6 908.5 912.7 930.6 933.5 936.5 925.3 942.6 943.7 944.3 945.2 997.0 998.0 998.6 1000.4 1001.1 992.3 994.4 995.2 995.8 998.1 738.4 738.9 739.1 739.3 739.3 14831.6 15443.8 15791.9 16093.8 16324.7 13064.9 13190.8 13228.1 13319.3 13336.2 11218.5 11556.7 11601.5 11970.6 12052.5 3547.9 3569.8 3664.8 3690.9 3805.2 109174.5 112437.0 113171.3 115466.0 115848.0 10427.9 10545.1 10897.6 11034.2 11068.0 18528.4 22299.2 22737.5 22761.8 22901.3 7636.9 7666.1 7699.1 7710.0 7723.0 4221.9 4237.0 4260.0 4260.5 4326.9 2882.7 3400.1 3489.3 3494.5 3542.6 634.2 639.2 641.4 652.4 654.4 668.8 677.5 693.6 698.6 703.3 9793.0 10303.9 10379.4 10554.7 10692.6 654.5 683.3 696.4 708.3 725.3 4618.4 4988.3 5013.1 5021.6 5023.4 101874.0 103058.0 103176.0 104113.0 105168.0 59898.1 68256.7 91543.6 93519.0 94982.6 80474.5 81543.6 81875.0 81883.0 83770.6 10374.2 10761.1 11083.1 11216.3 11231.2 40673.6 46642.0 47379.8 48077.0 48427.8 59300.0 59443.0 60469.7 61008.4 61917.7 11984.8 12011.3 12136.2 12245.8 12457.5 13817.9 14236.4 14312.6 14427.9 15024.7 12645.6 12765.9 13595.3 13638.7 13918.2 12885.9 13467.1 13607.5 13628.2 14075.6 12175.6 13397.4 13402.0 13482.7 13692.9 9003.5 9248.4 9602.3 9693.3 9962.4 7305.4 10030.6 10690.0 10847.4 11072.6 05:03:37 INFO - PROCESS | 2551 | -------- Summary: end -------- 05:03:37 INFO - PROCESS | 2551 | 05:03:37 INFO - TEST-INFO | 2551: exit 0 05:03:37 INFO - TEST-OK | dromaeo_css | took 1120406ms 05:03:37 INFO - TEST-START | kraken 05:03:37 INFO - operating with platform_type : mac_ 05:03:37 INFO - Initialising browser for kraken test... 05:03:37 INFO - TEST-INFO | started process 2554 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpOuinrJ/profile http://localhost:57604/getInfo.html) 05:03:40 INFO - PROCESS | 2554 | __metrics Screen width/height:1600/1200 05:03:40 INFO - PROCESS | 2554 | colorDepth:24 05:03:40 INFO - PROCESS | 2554 | Browser inner width/height: 1024/689 05:03:40 INFO - PROCESS | 2554 | __metrics 05:03:40 INFO - PROCESS | 2554 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:03:40 INFO - PROCESS | 2554 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:03:41 INFO - TEST-INFO | 2554: exit 0 05:03:41 INFO - Browser initialized. 05:03:41 INFO - Running cycle 1/1 for kraken test... 05:03:41 INFO - TEST-INFO | started process 2555 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpOuinrJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:03:53 INFO - PROCESS | 2555 | RSS: Main: 210792448 05:03:53 INFO - PROCESS | 2555 | 05:03:56 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57604/tests/kraken/driver.html?testName=audio-beat-detection) 05:03:57 INFO - PROCESS | 2555 | RSS: Main: 264462336 05:03:57 INFO - PROCESS | 2555 | 05:03:59 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57604/tests/kraken/driver.html?testName=audio-dft) 05:04:00 INFO - PROCESS | 2555 | RSS: Main: 277778432 05:04:00 INFO - PROCESS | 2555 | 05:04:03 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57604/tests/kraken/driver.html?testName=audio-fft) 05:04:03 INFO - PROCESS | 2555 | RSS: Main: 374702080 05:04:03 INFO - PROCESS | 2555 | 05:04:05 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57604/tests/kraken/driver.html?testName=audio-oscillator) 05:04:06 INFO - PROCESS | 2555 | RSS: Main: 277897216 05:04:06 INFO - PROCESS | 2555 | 05:04:08 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57604/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:04:09 INFO - PROCESS | 2555 | RSS: Main: 279699456 05:04:09 INFO - PROCESS | 2555 | 05:04:12 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57604/tests/kraken/driver.html?testName=imaging-darkroom) 05:04:12 INFO - PROCESS | 2555 | RSS: Main: 319938560 05:04:12 INFO - PROCESS | 2555 | 05:04:15 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57604/tests/kraken/driver.html?testName=imaging-desaturate) 05:04:16 INFO - PROCESS | 2555 | RSS: Main: 321978368 05:04:16 INFO - PROCESS | 2555 | 05:04:19 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57604/tests/kraken/driver.html?testName=json-parse-financial) 05:04:19 INFO - PROCESS | 2555 | RSS: Main: 316784640 05:04:19 INFO - PROCESS | 2555 | 05:04:21 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57604/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:04:22 INFO - PROCESS | 2555 | RSS: Main: 295972864 05:04:22 INFO - PROCESS | 2555 | 05:04:24 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57604/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:04:24 INFO - PROCESS | 2555 | RSS: Main: 298471424 05:04:24 INFO - PROCESS | 2555 | 05:04:26 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57604/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:04:27 INFO - PROCESS | 2555 | RSS: Main: 304877568 05:04:27 INFO - PROCESS | 2555 | 05:04:30 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57604/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:04:31 INFO - PROCESS | 2555 | RSS: Main: 319987712 05:04:31 INFO - PROCESS | 2555 | 05:04:33 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57604/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:04:34 INFO - PROCESS | 2555 | RSS: Main: 314032128 05:04:34 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | Cycle 1(1): loaded http://localhost:57604/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57604/tests/kraken/driver.html?testName=ai-astar) 05:04:36 INFO - PROCESS | 2555 | RSS: Main: 358551552 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | __start_tp_report 05:04:36 INFO - PROCESS | 2555 | _x_x_mozilla_page_load 05:04:36 INFO - PROCESS | 2555 | _x_x_mozilla_page_load_details 05:04:36 INFO - PROCESS | 2555 | |i|pagename|runs| 05:04:36 INFO - PROCESS | 2555 | |0;ai-astar;106;116;116;116;116;116;115;117;115;116 05:04:36 INFO - PROCESS | 2555 | |1;audio-beat-detection;140;102;114;140;110;109;109;108;128;107 05:04:36 INFO - PROCESS | 2555 | |2;audio-dft;185;185;186;186;183;185;185;184;186;185 05:04:36 INFO - PROCESS | 2555 | |3;audio-fft;84;61;61;92;64;59;60;63;75;59 05:04:36 INFO - PROCESS | 2555 | |4;audio-oscillator;86;69;88;70;70;68;83;70;70;84 05:04:36 INFO - PROCESS | 2555 | |5;imaging-gaussian-blur;88;104;107;88;89;89;89;88;88;88 05:04:36 INFO - PROCESS | 2555 | |6;imaging-darkroom;95;103;95;97;95;95;95;97;95;94 05:04:36 INFO - PROCESS | 2555 | |7;imaging-desaturate;83;86;84;86;84;86;84;84;87;85 05:04:36 INFO - PROCESS | 2555 | |8;json-parse-financial;61;61;61;61;65;66;65;63;64;63 05:04:36 INFO - PROCESS | 2555 | |9;json-stringify-tinderbox;55;74;66;71;56;57;59;59;58;58 05:04:36 INFO - PROCESS | 2555 | |10;stanford-crypto-aes;83;83;83;84;85;84;82;84;85;84 05:04:36 INFO - PROCESS | 2555 | |11;stanford-crypto-ccm;137;137;161;142;132;123;143;156;179;147 05:04:36 INFO - PROCESS | 2555 | |12;stanford-crypto-pbkdf2;165;167;165;166;165;167;167;165;163;165 05:04:36 INFO - PROCESS | 2555 | |13;stanford-crypto-sha256-iterative;65;65;67;69;81;81;80;80;63;66 05:04:36 INFO - PROCESS | 2555 | __end_tp_report 05:04:36 INFO - PROCESS | 2555 | __start_cc_report 05:04:36 INFO - PROCESS | 2555 | _x_x_mozilla_cycle_collect,2134 05:04:36 INFO - PROCESS | 2555 | __end_cc_report 05:04:36 INFO - PROCESS | 2555 | __startTimestamp1491998676672__endTimestamp 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | ------- Summary: start ------- 05:04:36 INFO - PROCESS | 2555 | Number of tests: 14 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#0] ai-astar Cycles:10 Average:114.90 Median:116.00 stddev:3.18 (2.7%) stddev-sans-first:0.60 05:04:36 INFO - PROCESS | 2555 | Values: 106.0 116.0 116.0 116.0 116.0 116.0 115.0 117.0 115.0 116.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#1] audio-beat-detection Cycles:10 Average:116.70 Median:112.00 stddev:14.02 (12.5%) stddev-sans-first:12.08 05:04:36 INFO - PROCESS | 2555 | Values: 140.0 102.0 114.0 140.0 110.0 109.0 109.0 108.0 128.0 107.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.00 stddev:0.94 (0.5%) stddev-sans-first:1.00 05:04:36 INFO - PROCESS | 2555 | Values: 185.0 185.0 186.0 186.0 183.0 185.0 185.0 184.0 186.0 185.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#3] audio-fft Cycles:10 Average:67.80 Median:63.50 stddev:11.76 (18.5%) stddev-sans-first:10.92 05:04:36 INFO - PROCESS | 2555 | Values: 84.0 61.0 61.0 92.0 64.0 59.0 60.0 63.0 75.0 59.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:76.50 stddev:8.26 (10.8%) stddev-sans-first:7.89 05:04:36 INFO - PROCESS | 2555 | Values: 86.0 69.0 88.0 70.0 70.0 68.0 83.0 70.0 70.0 84.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:88.00 stddev:7.27 (8.3%) stddev-sans-first:7.58 05:04:36 INFO - PROCESS | 2555 | Values: 88.0 104.0 107.0 88.0 89.0 89.0 89.0 88.0 88.0 88.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:95.00 stddev:2.60 (2.7%) stddev-sans-first:2.73 05:04:36 INFO - PROCESS | 2555 | Values: 95.0 103.0 95.0 97.0 95.0 95.0 95.0 97.0 95.0 94.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.50 stddev:1.29 (1.5%) stddev-sans-first:1.17 05:04:36 INFO - PROCESS | 2555 | Values: 83.0 86.0 84.0 86.0 84.0 86.0 84.0 84.0 87.0 85.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:63.50 stddev:1.94 (3.1%) stddev-sans-first:1.92 05:04:36 INFO - PROCESS | 2555 | Values: 61.0 61.0 61.0 61.0 65.0 66.0 65.0 63.0 64.0 63.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:59.00 stddev:6.63 (11.2%) stddev-sans-first:6.63 05:04:36 INFO - PROCESS | 2555 | Values: 55.0 74.0 66.0 71.0 56.0 57.0 59.0 59.0 58.0 58.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:0.95 (1.1%) stddev-sans-first:0.97 05:04:36 INFO - PROCESS | 2555 | Values: 83.0 83.0 83.0 84.0 85.0 84.0 82.0 84.0 85.0 84.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#11] stanford-crypto-ccm Cycles:10 Average:145.70 Median:145.00 stddev:16.08 (11.1%) stddev-sans-first:16.74 05:04:36 INFO - PROCESS | 2555 | Values: 137.0 137.0 161.0 142.0 132.0 123.0 143.0 156.0 179.0 147.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:165.50 stddev:1.27 (0.8%) stddev-sans-first:1.33 05:04:36 INFO - PROCESS | 2555 | Values: 165.0 167.0 165.0 166.0 165.0 167.0 167.0 165.0 163.0 165.0 05:04:36 INFO - PROCESS | 2555 | 05:04:36 INFO - PROCESS | 2555 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:74.50 stddev:7.73 (10.4%) stddev-sans-first:7.81 05:04:36 INFO - PROCESS | 2555 | Values: 65.0 65.0 67.0 69.0 81.0 81.0 80.0 80.0 63.0 66.0 05:04:36 INFO - PROCESS | 2555 | -------- Summary: end -------- 05:04:36 INFO - PROCESS | 2555 | 05:04:37 INFO - TEST-INFO | 2555: exit 0 05:04:37 INFO - TEST-OK | kraken | took 59908ms 05:04:37 INFO - SUITE-END | took 1180s 05:04:37 INFO - Completed test suite (00:19:40) 05:04:37 INFO - javascript benchmark 05:04:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7634.024841993567, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3400.4729266963623, 3428.7942723436595, 3432.6395728448497, 3442.74339865949, 3456.498807672886, 6065.969670151654, 6107.114468402079, 6114.816555503331, 6126.356732543086, 6127.63234205948, 6045.486133678643, 6115.113308570255, 6118.694065296729, 6131.202943617323, 6150.538709596775, 5409.377921539034, 5418.485243901823, 5472.534834539069, 5479.205515200293, 5544.251526043973, 5336.146216605344, 5338.813141540047, 5533.675458487491, 5538.501534861882, 5589.217509548666, 5380.435054319299, 5440.483154100354, 5460.31746031746, 5513.531349835269, 5541.362743284533, 5465.152901300305, 5482.0, 5493.670379777226, 5501.944980550184, 5508.256385387961, 5340.652857564246, 5434.592405569598, 5463.7814487420455, 5479.3424789025175, 5508.586855985777, 5798.565107616945, 5833.912491312633, 5898.734556944905, 5927.64434133949, 6053.970824959767, 6437.485001199892, 6445.2265728112925, 6460.709268082947, 6464.74141034358, 6472.093906853044, 6536.444402225817, 6536.54244202906, 6538.411542961157, 6545.705443255065, 6573.244076931119, 5934.732937017845, 5965.045592705146, 6037.63774173551, 6181.072839074175, 6209.565330426872, 6447.161868957006, 6578.835529111715, 6618.0403841443685, 6630.1049358336, 6630.403263706289, 6368.745250189941, 6394.9680251599375, 6397.296297407282, 6402.647854368054, 6412.3267056959285, 6373.426391624684, 6490.772822951269, 6499.09012738217, 6500.317466666026, 6564.277929427759, 5367.221752845893, 6269.153664255358, 6275.654838983808, 6322.873542529123, 6332.081848131995, 6303.779367722108, 6328.145700330489, 6329.240491141092, 6342.968285158545, 6366.84082897922, 5074.847754567369, 5172.948270517322, 5178.585713142939, 5200.349956255468, 5212.947870521322, 4609.861704148881, 4617.722936623813, 4652.906941861143, 4662.0, 4682.6253899687945, 5850.678212698343, 5897.970510147422, 5920.052791553331, 6066.969665151646, 6119.908201376983, 6488.805335839932, 6490.221173459215, 6512.381323774329, 6513.739450422025, 6522.934770652328, 6117.877642447126, 6139.140520327158, 6149.446549810538, 6150.692465376803, 6155.569110162246, 5445.53712934405, 5554.333479982347, 5697.430256974352, 5698.943010569924, 5752.137179423036, 6518.706658200297, 6547.312532184147, 6562.671866406565, 6579.309172536815, 6585.5719378240265, 6332.873342533216, 6346.07981842621, 6433.806985790434, 6492.74029038852, 6509.349065093453, 6720.831979200363, 6782.966085169543, 6783.830404239933, 6786.4910131739325, 6798.8640227196165, 6429.485641148791, 6433.0, 6442.291347951816, 6450.8064758057335, 6452.193475815523, 6277.937220627918, 6281.3404592518955, 6304.369563043659, 6329.5252856036905, 6495.317991610813, 5505.9724701376235, 6293.55945083858, 6335.90496142549, 6336.112944187726, 6336.429721324918, 6277.435030847063, 6354.809355719336, 6356.0, 6365.0, 6368.076628888878, 6072.635641861502, 6135.601185922901, 6226.066090086523, 6306.085617585332, 6328.335524770017, 6166.136740856194, 6239.78160764371, 6240.500759939285, 6280.27776805655, 6353.078803573549, 6204.255489341308, 6249.687515624109, 6265.874682506415, 6280.057991301342, 6290.119383286252], "value": 5947.639466730184, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.8128449172199, 806.4395245304514, 868.118859357752, 878.0648609231172, 878.7100256915219, 39274.607253927454, 40360.19279614406, 42511.574884251146, 42525.787371063176, 42591.93520161998, 41547.79226103865, 41736.165276694446, 41752.79123604386, 41862.162756744925, 41872.790636046775, 7338.86247631618, 9198.862017069732, 9330.953345233263, 9358.157765801077, 9363.859542106873, 7248.710051597956, 7316.414686825068, 7320.414366850666, 7321.816954576151, 7322.450816188807, 11079.889201107966, 11338.979491845732, 11346.149038822081, 11357.0, 11471.024962878166, 11228.15788815838, 11258.211925165242, 11276.943615281953, 11281.661550153509, 11305.547778088867, 10887.183461240438, 10944.945275273572, 10947.124230061576, 10973.561057557688, 10979.725506862393, 38739.41890871639, 38948.0, 38973.0, 39005.02487437808, 39035.0, 3792.677622402099, 3793.753406028627, 3839.731218814684, 3839.8464061437508, 3886.3198940185353, 26857.46285074307, 27222.0, 28540.857295713387, 29108.854455727796, 29292.414151716846, 3125.749940004794, 3135.9835021291638, 3185.9522107168177, 3194.0577529628818, 3297.4559197732347, 335308.0, 336376.0, 337395.62604374136, 339439.0, 344201.0, 42640.78679606582, 42823.7858810704, 43532.0, 52527.47472525302, 52592.474075259524, 44810.77594612006, 44983.0, 45113.32330015052, 45313.54686453093, 45323.0, 13172.802407963889, 13890.374933128034, 14301.57095287143, 14612.342444590018, 14681.339339729524, 10862.511186996604, 11033.668989930315, 11181.105511559055, 11213.551457941754, 12694.682632934066, 10926.071283940953, 12564.183328083645, 12732.363381830872, 12995.0, 13023.609291721263, 5710.710934358143, 5725.942740572541, 5737.483626473554, 5742.196092547047, 5760.0783874579865, 988.17981075707, 1018.843612499818, 1125.1280257800106, 1129.9435028248554, 1145.3814939932342, 26668.59997100045, 27099.0, 27172.72827271702, 27259.727402726116, 27286.181414557595, 1258.6664533898524, 1261.2054405724334, 1265.6329664397222, 1291.5285920639087, 1310.4496111633136, 13869.514566990309, 14199.85800142006, 14212.786808197885, 14227.932905032165, 14289.785653215211, 424318.8784056122, 603173.0, 610339.9483002557, 610619.8938010563, 611055.0, 49168.77078073077, 49593.25610115851, 49607.007859842604, 49631.50368496341, 49745.502544974086, 1406.5499040307209, 1440.553428437188, 1445.7903603977575, 1454.5563603100943, 1455.6651970046948, 23302.65046024345, 25721.456712597304, 26940.0, 27308.0, 27386.5892011616, 40815.0, 40860.1827963445, 40863.59136408598, 40901.38647920224, 40987.1802563953, 455864.0, 456554.0, 457539.0, 457594.0, 457962.0, 27719.168424947286, 28191.0, 28275.72759225881, 28278.85860570684, 28301.43397132087, 37238.44142337921, 38070.61929380782, 38151.236975260894, 38163.236735265695, 38171.23657526779, 26435.735642644097, 26541.7345826547, 27192.456150877268, 27453.862730686218, 27682.0, 26553.203403897915, 27027.72972270331, 27511.86244068767, 27563.0, 27976.24695970048, 31358.686413135576, 31532.684673152973, 31551.053468395985, 31949.20126996844, 32223.838880806383, 27078.72921270762, 27253.86373068122, 27646.585301220097, 27897.302567435978, 28094.43811123725, 21369.465763356042, 21429.464263392918, 22186.66719999169, 22592.0, 22625.321240362817], "value": 18213.212605494853, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.1705439505889, 382.7703377973218, 404.90687141957346, 417.3947775725199, 442.25700822618006, 11050.0, 16023.599410014756, 17519.386821461252, 20734.48163795906, 21407.892960535195, 17130.914345428286, 21251.831149286783, 21365.67951480729, 21429.928503574836, 21443.785562144374, 5619.381867994517, 6655.367740064698, 6823.55646882951, 6842.9315706843045, 6906.75826346078, 6683.632400217986, 6763.695633696471, 6779.220389655204, 6786.626735529544, 6803.55776874504, 6484.383983521581, 6657.300983396746, 6734.764283250087, 6740.932590674104, 6802.591844489347, 6569.211694596631, 6750.594964302158, 6763.729450821961, 6859.142607174103, 6864.141982252218, 5722.19889215506, 6637.73449062037, 6675.198976122848, 6676.732930682767, 6682.097916781221, 26601.93592256327, 27347.86326068357, 27368.315792105157, 27379.04173353943, 27763.0, 2901.4922388581913, 2910.4761142994253, 2917.28526511004, 2921.5909772631635, 2954.748846348063, 15917.761233581505, 16465.094419807025, 16540.255688494046, 16585.917070414693, 17086.914565427218, 1997.3608445297511, 2002.4493264352275, 2053.117159621377, 2055.075216152737, 2064.1743302679047, 118149.40925295405, 131979.0, 135885.32057339748, 136908.63091369058, 137562.31218843942, 21429.78570214278, 21672.34982950514, 21923.67114493284, 22137.335879923627, 22775.544489110125, 35789.284214315565, 36002.63997360045, 36544.63455365413, 36670.08324791848, 36695.449568256496, 10572.947135264274, 10717.196210284259, 10953.671389858317, 11009.61466348674, 11221.326720396803, 8823.250023747925, 8844.55777211137, 8874.201321880933, 8992.280617550581, 9037.457752534869, 8847.424917380478, 10358.844617330746, 10367.533460994122, 10471.18325570231, 10506.632267870587, 3518.185451638679, 4138.461999939988, 4140.896477588024, 4167.06241095751, 4180.268453020709, 880.9295256379474, 1036.4144258493927, 1040.2770074797943, 1055.0820785916303, 1065.280935368623, 20862.895685521475, 21949.561008779736, 21992.900354982186, 21995.0, 22065.558688826135, 909.4679612426672, 1026.861373714539, 1042.8800687920834, 1045.7699306152633, 1060.2631171336002, 8976.730698079067, 11285.322880627189, 11513.942430287794, 11555.942220288845, 11620.186586938833, 743975.0, 749507.0, 751917.0, 754576.0, 755819.0, 138093.0, 156954.0, 157932.0, 158554.0, 159446.01384965237, 1210.6791700199453, 1299.928503932274, 1352.5063351876495, 1367.4940272099586, 1370.2737549098993, 18524.259029638662, 19670.21319147218, 19694.8030519693, 19815.801841981396, 19832.0, 61909.44490801489, 63043.0, 63226.36773632205, 64438.35561644324, 64683.0, 87821.1217887813, 87823.0, 89139.55430222808, 90202.88174064366, 90740.18519629439, 9926.057024582653, 11231.26996745209, 11429.428528573371, 11506.194566380273, 11875.762484750085, 12508.499660013498, 12636.810447843474, 12655.93672031634, 12734.808977865525, 13009.739805204032, 10876.891231087904, 10927.890721092688, 10963.342199468058, 11303.717407064889, 11316.20786544934, 10311.123554497952, 10346.482675866328, 10380.792384152124, 10438.739031523968, 10569.577216911237, 11153.55385784592, 11155.44222788841, 11159.38623375722, 11199.60801371948, 11261.268017578832, 10070.69787906364, 10838.67483975482, 11513.0, 11846.052315814542, 11868.703282417664, 9307.953460232926, 9881.357711748713, 10310.381377117397, 10690.82400935912, 11003.284786488852], "value": 11561.394459234894, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1528.5261568913631, 1531.6936612677466, 1558.7973563436751, 1562.97655535167, 1571.6306667933034, 10484.31851929625, 11050.010998020352, 11051.557937682503, 11053.668389948294, 11061.115110791137, 10614.57541698331, 11047.392393418359, 11173.888261117387, 11183.552657893675, 11200.887991120107, 2366.2191476812695, 2394.3774618599114, 2405.230326295586, 2405.5068710914306, 2406.975930240693, 2296.0930432479163, 2375.6911601491784, 2388.988055059731, 2389.82076344274, 2392.437777122375, 2320.8839558022114, 2386.2721869829693, 2386.474975505386, 2396.8681722505257, 2401.3070785096465, 2294.7934685878186, 2355.316958082154, 2367.621180611103, 2373.667686523888, 2373.8338316317863, 2299.356180269527, 2323.5236776460783, 2327.080803082773, 2327.441414060613, 2332.848364856296, 10695.839562406492, 10923.126149907988, 10937.671869843838, 10938.234323597351, 10941.12471002318, 5555.777768889239, 5803.767849286023, 5819.53443724501, 5824.271966004249, 5832.0, 3431.759776815624, 3492.738044646662, 3506.3162683276773, 3527.2592755521373, 3583.982080089609, 1164.7554013657143, 1168.8538932633421, 1193.379442689805, 1205.5659962413524, 1216.3492531495608, 13455.529056483074, 13885.166889986633, 14155.858441415558, 15162.0, 15394.846051539453, 4568.954310456853, 4718.0328032753205, 4757.405324334458, 4761.35721677576, 4762.976185119052, 10249.538770755335, 11738.530458781725, 11743.530258789724, 11749.88250117505, 11763.705907352385, 4911.852644420673, 5519.31008623922, 5685.1756495307945, 5735.397783232784, 5745.741441635137, 4973.776180071905, 5106.565941894906, 5140.974295128575, 5228.451012643618, 5232.03207406631, 3247.2693643930397, 3768.5854555998817, 3797.9430308545393, 3826.9234615307537, 3899.0, 903.3179949138358, 1002.2282842211456, 1003.7540802503433, 1007.561710655867, 1014.761531040205, 924.1451657217048, 934.2572654739498, 951.7192428233549, 955.2358113509304, 1009.1876039787894, 5707.729528845451, 6652.7338906444165, 6735.225449073321, 6828.863422731518, 6932.944955409748, 948.5826236455937, 955.7706150523824, 966.8936416994125, 1051.6109039655223, 1054.4991129213563, 3698.2788356270808, 4149.962509372657, 4245.214635292481, 4382.715123516961, 4410.603045725836, 13462.86537134616, 14114.364853581614, 14119.85880141206, 14125.85874141266, 14205.289735513183, 12575.87424125747, 13060.085793994329, 13980.091294066056, 14041.368138433794, 14065.367058482392, 9900.702978910644, 10188.388696678223, 10188.643397481053, 10207.132393746466, 10216.284860059724, 6172.105044768573, 6518.73925043012, 6576.44100251484, 6927.302810811383, 7082.362587367162, 11791.76416471683, 12199.634010979686, 12238.44926978306, 12356.949659279062, 12363.567275145328, 11977.700557485785, 12134.211276267015, 12276.0, 12333.814992774936, 12348.197367171106, 9226.07739226072, 9271.721848344561, 9468.100530449596, 9486.193673537826, 9499.38254013487, 10263.435511046964, 10335.121514671337, 10382.065614094769, 10401.115905148146, 10405.739856503647, 8601.698940537051, 8726.956365218133, 8821.2060914518, 8831.60257788414, 8841.823163536821, 7834.647440865288, 8545.52999585004, 8602.526861022703, 8671.21959023691, 8716.0, 7467.178610352966, 7932.762017139495, 7973.441859070042, 7976.800579985546, 7982.042154941444, 5822.970885145716, 5827.24245848037, 5887.8233652990475, 6006.939930600638, 6057.969710151421, 5410.242566040679, 5870.7945221917025, 6044.274687037584, 6156.969215153896, 6179.876402472015], "value": 5097.086452364968, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.48196518344344, 363.4493741980899, 381.31934496923, 381.81291167328, 382.3939056595298, 4869.58781953234, 5004.5218573091715, 5062.645614806965, 5173.870653233666, 5186.585073194145, 4331.7691388934645, 4495.9659278366, 4856.785803549113, 4864.805407783684, 5031.0, 3666.9633303666956, 4148.94201978495, 4323.329883868006, 4368.514705000299, 4368.628666563346, 3795.335816232162, 3892.6181205672005, 3965.722399432041, 4090.6931980101463, 4095.0, 4116.814743336557, 4124.13393187431, 4175.081482073939, 4329.328954012118, 4391.516933137353, 3657.7687610557405, 4320.82716691332, 4321.567843215685, 4362.956370436318, 4392.538783427726, 4054.56063097602, 4116.58834116589, 4178.874633760992, 4224.239636865363, 4255.616994470482, 4288.549702281247, 4467.7319360838455, 4625.930611040837, 4945.36802855057, 4963.528464795839, 4322.471354257763, 4330.761808100553, 4860.4896485869185, 4866.416030076413, 4894.448163208215, 4991.825286115005, 5221.6083793715625, 5364.275822763916, 5440.340696087692, 5463.562914966793, 3995.1210733638845, 4197.286461301586, 4267.613025766638, 4276.2894842063115, 4326.394304797331, 5135.2810606515495, 5623.7469313880565, 5667.631603945731, 5689.175069614924, 5749.9712501437225, 3774.9807551960816, 3926.4306675532457, 4401.6698747594055, 4426.424564806554, 4451.0, 4180.561041090703, 4205.2640787862, 4227.773945555754, 4260.126674031878, 4294.677899157576, 4097.783240156523, 4148.585141485888, 4648.628109751212, 4806.759662016885, 4850.53919877611, 5213.3838510062005, 5444.91832622511, 5463.836084917459, 5508.7521061552325, 5530.917036244459, 1151.4530597966066, 1407.1627381707867, 1413.4982081361175, 1426.7788492783636, 1544.7605621128744, 1102.8456016157745, 1119.9048080913215, 1141.668916014363, 1145.639123676056, 1157.2651366382408, 4151.543330233671, 4229.577042295746, 4805.8077676892535, 5001.0, 5030.84907452777, 4599.816007359735, 4993.575546078551, 5295.325860440453, 5505.6696598204235, 5505.752241149158, 4273.850415235514, 4387.298032314814, 4706.858794236178, 4826.401374405673, 5090.567301779316, 2446.620773780067, 2719.510488112119, 2777.666679998413, 2833.880617156253, 2874.870630821618, 4995.750212489361, 5239.502247286502, 5561.277033985637, 5720.627049508088, 5743.24830926563, 4699.036697477009, 5221.582273418194, 5283.392409872914, 5509.228707980886, 5743.827685169452, 5498.340199176125, 5740.512056475246, 5751.124437781109, 5768.596198266081, 5799.999999999915, 4698.177818881613, 4748.548887855648, 5295.3116094908955, 5525.955396503319, 5566.91649625264, 4240.978795106004, 4879.974415862046, 5290.418054013979, 5504.339479262514, 5572.01896985111, 5497.532709719719, 5499.562773266708, 5837.357890631942, 5867.32525759526, 5868.911966320423, 3935.4883865097354, 4146.730462519927, 4454.309582014793, 4461.509233984324, 4471.776411179493, 5090.109230884506, 5165.741712914414, 5286.735663216747, 5289.57683385336, 5415.48552887475, 5236.031334203185, 5245.21662634706, 5246.685198888079, 5392.847507419344, 5481.561475081904, 4797.688150270221, 4821.600771456045, 5254.973725131503, 5370.677759334452, 5416.8104116355735, 5054.0325844213585, 5306.8407947761625, 5314.707691077027, 5359.3386050324525, 5375.596830237826, 5401.491203100333, 5412.9174165167915, 5445.700486473123, 5451.264079349396, 5454.836354909359, 4718.669693121448, 4722.622190224705, 5064.5819170632285, 5344.919826202532, 5366.672662948997], "value": 4118.8655703718305, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.8618276344731, 698.6332175607802, 724.7753196509079, 776.2819392062341, 815.6207363575938, 14853.628659283524, 15677.764833527477, 15693.921530392361, 15728.977616454937, 15746.055236685806, 14189.503367382144, 14223.431062757503, 14227.71544569108, 14323.57029289123, 14399.568012959602, 908.4685459006489, 912.6760000199934, 930.6370515498927, 933.5099072986668, 936.4568550240843, 925.319889880937, 942.5711301357866, 943.6980166346772, 944.2917811641269, 945.1588086602894, 996.9700908972742, 997.9870431511985, 998.6005597760881, 1000.3797645459825, 1001.1390204424189, 992.270681049428, 994.3834822410151, 995.2485873165786, 995.8157740817971, 998.116666749928, 738.3723834740427, 738.8780851159552, 739.1093732052864, 739.2902813299239, 739.3087463221851, 14831.555053348417, 15443.841711871655, 15791.921040394724, 16093.839061609351, 16324.673506529803, 13064.934675326562, 13190.802137967843, 13228.140170888957, 13319.26744029078, 13336.19982801035, 11218.495167717472, 11556.711082222928, 11601.535938562447, 11970.58102966392, 12052.517899284017, 3547.875824346161, 3569.803660798655, 3664.7617904836366, 3690.852365905361, 3805.162864169878, 109174.5315960648, 112437.0, 113171.3024304636, 115466.0, 115847.99999999916, 10427.895721042843, 10545.050945414796, 10897.564097436172, 11034.227604067639, 11068.0, 18528.351507697436, 22299.21952731679, 22737.545249095256, 22761.772382276296, 22901.31296061121, 7636.923630763621, 7666.118396384487, 7699.076110866732, 7710.036245469317, 7723.033302658719, 4221.852235171754, 4237.0, 4259.97870010648, 4260.467441569804, 4326.913461730748, 2882.6684931233176, 3400.1329660936303, 3489.284696637183, 3494.5107684924133, 3542.557180352456, 634.1755717567145, 639.2105749399487, 641.4483544152025, 652.419346781365, 654.4208375587544, 668.7525615522288, 677.4884961853868, 693.5769180799708, 698.5703792167781, 703.3318347569829, 9792.951035244778, 10303.948480257552, 10379.377237365632, 10554.683359499228, 10692.572297108185, 654.456800855289, 683.3371629519359, 696.3523922751788, 708.2598684374738, 725.3037084399036, 4618.445786505641, 4988.27669987857, 5013.1227035268685, 5021.648484606116, 5023.422306434807, 101874.0, 103058.0, 103176.0, 104113.0, 105168.0, 59898.101528477106, 68256.6587167061, 91543.62684559736, 93519.0, 94982.57526137114, 80474.47621137078, 81543.59228203821, 81875.0, 81883.0, 83770.58114709388, 10374.170066394821, 10761.085307748928, 11083.113350932068, 11216.327020378803, 11231.21381503287, 40673.59326406698, 46642.0, 47379.763101184275, 48077.03845922992, 48427.78930526624, 59300.0, 59443.0, 60469.69765151146, 61008.389916102045, 61917.69041154766, 11984.820227696766, 12011.279323240633, 12136.150469467406, 12245.755084898074, 12457.501699931901, 13817.861821381657, 14236.430542778173, 14312.57062288133, 14427.855721443071, 15024.6995060096, 12645.620631381073, 12765.914957139137, 13595.320233988063, 13638.727225455237, 13918.234497102352, 12885.871141288842, 13467.12463689857, 13607.455701771818, 13628.182309061489, 14075.577732668036, 12175.573854915036, 13397.397117129947, 13402.0, 13482.662933426744, 13692.931535342259, 9003.54982250898, 9248.445093294424, 9602.279829012992, 9693.273004524548, 9962.402255864672, 7305.379042781211, 10030.598776049168, 10690.011526690398, 10847.403392813156, 11072.557097716], "value": 7527.295439884317, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1423.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 116.0, 116.0, 116.0, 116.0, 115.0, 117.0, 115.0, 116.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 102.0, 114.0, 140.0, 110.0, 109.0, 109.0, 108.0, 128.0, 107.0], "value": 116.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 186.0, 186.0, 183.0, 185.0, 185.0, 184.0, 186.0, 185.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 61.0, 61.0, 92.0, 64.0, 59.0, 60.0, 63.0, 75.0, 59.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 88.0, 70.0, 70.0, 68.0, 83.0, 70.0, 70.0, 84.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 104.0, 107.0, 88.0, 89.0, 89.0, 89.0, 88.0, 88.0, 88.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 103.0, 95.0, 97.0, 95.0, 95.0, 95.0, 97.0, 95.0, 94.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 84.0, 86.0, 84.0, 86.0, 84.0, 84.0, 87.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 65.0, 66.0, 65.0, 63.0, 64.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 74.0, 66.0, 71.0, 56.0, 57.0, 59.0, 59.0, 58.0, 58.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 84.0, 85.0, 84.0, 82.0, 84.0, 85.0, 84.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 161.0, 142.0, 132.0, 123.0, 143.0, 156.0, 179.0, 147.0], "value": 145.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 165.0, 166.0, 165.0, 167.0, 167.0, 165.0, 163.0, 165.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 69.0, 81.0, 81.0, 80.0, 80.0, 63.0, 66.0], "value": 71.7, "unit": "score"}]}]} 05:04:37 INFO - Return code: 0 05:04:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:04:37 ERROR - # TBPL SUCCESS # 05:04:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:04:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:04:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:04:37 INFO - 2017-04-12 05:04:37.976 screenresolution[2559:35944] starting screenresolution argv=screenresolution get 05:04:37 INFO - 2017-04-12 05:04:37.979 screenresolution[2559:35944] Display 0: 1600x1200x32@60 05:04:37 INFO - 2017-04-12 05:04:37.985 screenresolution[2560:35947] starting screenresolution argv=screenresolution list 05:04:37 INFO - Available Modes on Display 0 05:04:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:04:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:04:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:04:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:04:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:04:38 INFO - Intel Iris: 05:04:38 INFO - Chipset Model: Intel Iris 05:04:38 INFO - Type: GPU 05:04:38 INFO - Bus: Built-In 05:04:38 INFO - VRAM (Dynamic, Max): 1536 MB 05:04:38 INFO - Vendor: Intel (0x8086) 05:04:38 INFO - Device ID: 0x0a2e 05:04:38 INFO - Revision ID: 0x0009 05:04:38 INFO - Displays: 05:04:38 INFO - Display: 05:04:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:04:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:04:38 INFO - Display Serial Number: 1600x1200 60 05:04:38 INFO - Main Display: Yes 05:04:38 INFO - Mirror: Off 05:04:38 INFO - Online: Yes 05:04:38 INFO - Rotation: Supported 05:04:38 INFO - Return code: 0 05:04:38 INFO - Running post-action listener: _resource_record_post_action 05:04:38 INFO - [mozharness: 2017-04-12 12:04:38.228372Z] Finished run-tests step (success) 05:04:38 INFO - Running post-run listener: _resource_record_post_run 05:04:38 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 348160; Write bytes: 507179008; Read time: 4; Write time: 1828 05:04:38 INFO - TinderboxPrint: CPU usage
53.8% 05:04:38 INFO - TinderboxPrint: I/O read bytes / time
348,160 / 4 05:04:38 INFO - TinderboxPrint: I/O write bytes / time
507,179,008 / 1,828 05:04:38 INFO - TinderboxPrint: CPU idle
2,219.9 (46.2%) 05:04:38 INFO - TinderboxPrint: CPU system
958.8 (20.0%) 05:04:38 INFO - TinderboxPrint: CPU user
1,627.1 (33.9%) 05:04:38 INFO - TinderboxPrint: Swap in / out
208,207,872 / 0 05:04:38 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201548288; Write bytes: 201127424; Read time: 15668; Write time: 430 05:04:38 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 348160; Write bytes: 295266816; Read time: 4; Write time: 1364 05:04:38 INFO - Running post-run listener: _upload_blobber_files 05:04:38 INFO - Blob upload gear active. 05:04:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:04:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:04:38 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:04:38 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:04:39 INFO - (blobuploader) - INFO - Open directory for files ... 05:04:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:04:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:04:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:04:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:04:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:04:40 INFO - (blobuploader) - INFO - Done attempting. 05:04:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:04:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:04:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:04:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:04:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:04:41 INFO - (blobuploader) - INFO - Done attempting. 05:04:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:04:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:04:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:04:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:04:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:04:41 INFO - (blobuploader) - INFO - Done attempting. 05:04:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:04:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:04:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:04:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:04:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:04:44 INFO - (blobuploader) - INFO - Done attempting. 05:04:44 INFO - (blobuploader) - INFO - Iteration through files over. 05:04:44 INFO - Return code: 0 05:04:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:04:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:04:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c4b8e8cdf9d6d50197d07bebaf1f2ab3060c5e3f543f2585b177bb5f94c0f076238f6ebcc4e62b61da9ce97a1aaca125f68e9edda50afbaa88450725bf3b9f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20efadc30b9108ed586fb71bafd025e74a2d82c9d9384c56adfb3dcb169ab197388b1d6d550eec0807df0b9b1f63582ca109d0195ff1e18b08c3be6572d3f6a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9864d02eb4bba404dfad6b82c01d77321edba9dbb3884f0821687d445fb6db47026347d4b63f2ed8788868732171186c3decc296910fb9053dee2d4864a6a20d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22968c770f70cc87ae16819cd9f3a5bbc0ea03a8745952ae9037b1827c7dba4bf90cb4df9852a3d0e112d2a62a1e24851bae1474d5d0ebc12e49390c6f613705"} 05:04:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:04:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:04:44 INFO - Contents: 05:04:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c4b8e8cdf9d6d50197d07bebaf1f2ab3060c5e3f543f2585b177bb5f94c0f076238f6ebcc4e62b61da9ce97a1aaca125f68e9edda50afbaa88450725bf3b9f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20efadc30b9108ed586fb71bafd025e74a2d82c9d9384c56adfb3dcb169ab197388b1d6d550eec0807df0b9b1f63582ca109d0195ff1e18b08c3be6572d3f6a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9864d02eb4bba404dfad6b82c01d77321edba9dbb3884f0821687d445fb6db47026347d4b63f2ed8788868732171186c3decc296910fb9053dee2d4864a6a20d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22968c770f70cc87ae16819cd9f3a5bbc0ea03a8745952ae9037b1827c7dba4bf90cb4df9852a3d0e112d2a62a1e24851bae1474d5d0ebc12e49390c6f613705"} 05:04:44 INFO - Running post-run listener: copy_logs_to_upload_dir 05:04:44 INFO - Copying logs to upload dir... 05:04:44 INFO - mkdir: /builds/slave/test/build/upload/logs 05:04:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.798318 ========= master_lag: -0.17 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-12 05:04:44.028548) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 05:04:44.031909) ========= 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.zNhhyCaaal/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.EAPU4waAyN/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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/Mozilla-Aurora/sha512/4c4b8e8cdf9d6d50197d07bebaf1f2ab3060c5e3f543f2585b177bb5f94c0f076238f6ebcc4e62b61da9ce97a1aaca125f68e9edda50afbaa88450725bf3b9f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20efadc30b9108ed586fb71bafd025e74a2d82c9d9384c56adfb3dcb169ab197388b1d6d550eec0807df0b9b1f63582ca109d0195ff1e18b08c3be6572d3f6a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9864d02eb4bba404dfad6b82c01d77321edba9dbb3884f0821687d445fb6db47026347d4b63f2ed8788868732171186c3decc296910fb9053dee2d4864a6a20d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22968c770f70cc87ae16819cd9f3a5bbc0ea03a8745952ae9037b1827c7dba4bf90cb4df9852a3d0e112d2a62a1e24851bae1474d5d0ebc12e49390c6f613705"} build_url:https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011420 build_url: 'https://queue.taskcluster.net/v1/task/a_3f6jyFTMyk9hDUQyFzVw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c4b8e8cdf9d6d50197d07bebaf1f2ab3060c5e3f543f2585b177bb5f94c0f076238f6ebcc4e62b61da9ce97a1aaca125f68e9edda50afbaa88450725bf3b9f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20efadc30b9108ed586fb71bafd025e74a2d82c9d9384c56adfb3dcb169ab197388b1d6d550eec0807df0b9b1f63582ca109d0195ff1e18b08c3be6572d3f6a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9864d02eb4bba404dfad6b82c01d77321edba9dbb3884f0821687d445fb6db47026347d4b63f2ed8788868732171186c3decc296910fb9053dee2d4864a6a20d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22968c770f70cc87ae16819cd9f3a5bbc0ea03a8745952ae9037b1827c7dba4bf90cb4df9852a3d0e112d2a62a1e24851bae1474d5d0ebc12e49390c6f613705"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 05:04:44.091524) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 05:04:44.091847) ========= 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.zNhhyCaaal/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.EAPU4waAyN/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.005210 ========= master_lag: 0.70 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 05:04:44.796247) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 05:04:44.796570) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 05:04:45.427080) ========= ========= Total master_lag: 0.65 =========