builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0106 starttime: 1492525332.58 results: success (0) buildid: 20170418064621 builduid: 2a9972d3f52a49939d2b46fafe928014 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:12.578288) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:12.578704) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:12.578978) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:12.603461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:12.603842) ========= 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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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-18 07:22:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.1M=0.001s 2017-04-18 07:22:12 (10.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.192346 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:12.819527) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:12.819840) ========= 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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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.021716 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:12.871955) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:12.872325) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ufIvsNJnKk/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.3bwyWUWs9b/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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-18 07:22:12,964 truncating revision to first 12 chars 2017-04-18 07:22:12,964 Setting DEBUG logging. 2017-04-18 07:22:12,964 attempt 1/10 2017-04-18 07:22:12,964 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-18 07:22:13,611 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.908328 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:13.806595) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:13.806916) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:13.810367) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:13.810645) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 07:22:13.830374) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-18 07:22:13.830736) ========= /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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 07:22:14 INFO - MultiFileLogger online at 20170418 07:22:14 in /builds/slave/test 07:22:14 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 07:22:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:22:14 INFO - {'append_to_log': False, 07:22:14 INFO - 'base_work_dir': '/builds/slave/test', 07:22:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:22:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:22:14 INFO - 'branch': 'Mozilla-Aurora', 07:22:14 INFO - 'buildbot_json_path': 'buildprops.json', 07:22:14 INFO - 'config_files': ('talos/mac_config.py',), 07:22:14 INFO - 'default_actions': ('clobber', 07:22:14 INFO - 'read-buildbot-config', 07:22:14 INFO - 'download-and-extract', 07:22:14 INFO - 'populate-webroot', 07:22:14 INFO - 'create-virtualenv', 07:22:14 INFO - 'install', 07:22:14 INFO - 'run-tests'), 07:22:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:22:14 INFO - 'download_minidump_stackwalk': True, 07:22:14 INFO - 'download_symbols': 'ondemand', 07:22:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:22:14 INFO - 'tooltool.py': '/tools/tooltool.py', 07:22:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:22:14 INFO - '/tools/misc-python/virtualenv.py')}, 07:22:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:22:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:22:14 INFO - 'gecko_profile': False, 07:22:14 INFO - 'gecko_profile_interval': 0, 07:22:14 INFO - 'installer_path': 'installer.exe', 07:22:14 INFO - 'log_level': 'info', 07:22:14 INFO - 'log_name': 'talos', 07:22:14 INFO - 'log_to_console': True, 07:22:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:22:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:22:14 INFO - 'opt_config_files': (), 07:22:14 INFO - 'pip_index': False, 07:22:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:22:14 INFO - 'cmd': ('bash', 07:22:14 INFO - '-c', 07:22:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:22:14 INFO - 'enabled': True, 07:22:14 INFO - 'halt_on_failure': False, 07:22:14 INFO - 'name': 'check_screen_resolution'},), 07:22:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:22:14 INFO - 'cmd': ('bash', 07:22:14 INFO - '-c', 07:22:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:22:14 INFO - 'enabled': True, 07:22:14 INFO - 'halt_on_failure': False, 07:22:14 INFO - 'name': 'check_screen_resolution'},), 07:22:14 INFO - 'run_cmd_checks_enabled': True, 07:22:14 INFO - 'suite': 'dromaeojs', 07:22:14 INFO - 'system_bits': '32', 07:22:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:22:14 INFO - 'title': 't-yosemite-r7-0106', 07:22:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:22:14 INFO - 'use_talos_json': True, 07:22:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:22:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:22:14 INFO - 'work_dir': 'build'} 07:22:14 INFO - [mozharness: 2017-04-18 14:22:14.044294Z] Running clobber step. 07:22:14 INFO - Running pre-action listener: _resource_record_pre_action 07:22:14 INFO - Running main action method: clobber 07:22:14 INFO - rmtree: /builds/slave/test/build 07:22:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:22:15 INFO - Running post-action listener: _resource_record_post_action 07:22:15 INFO - [mozharness: 2017-04-18 14:22:15.786776Z] Finished clobber step (success) 07:22:15 INFO - [mozharness: 2017-04-18 14:22:15.786966Z] Running read-buildbot-config step. 07:22:15 INFO - Running pre-action listener: _resource_record_pre_action 07:22:15 INFO - Running main action method: read_buildbot_config 07:22:15 INFO - Using buildbot properties: 07:22:15 INFO - { 07:22:15 INFO - "project": "", 07:22:15 INFO - "product": "firefox", 07:22:15 INFO - "script_repo_revision": "production", 07:22:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:22:15 INFO - "repository": "", 07:22:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:22:15 INFO - "buildid": "20170418064621", 07:22:15 INFO - "pgo_build": "False", 07:22:15 INFO - "basedir": "/builds/slave/test", 07:22:15 INFO - "buildnumber": 22, 07:22:15 INFO - "slavename": "t-yosemite-r7-0106", 07:22:15 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:22:15 INFO - "platform": "yosemite_r7", 07:22:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:22:15 INFO - "branch": "mozilla-aurora", 07:22:15 INFO - "repo_path": "releases/mozilla-aurora", 07:22:15 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 07:22:15 INFO - "stage_platform": "macosx64", 07:22:15 INFO - "builduid": "2a9972d3f52a49939d2b46fafe928014", 07:22:15 INFO - "slavebuilddir": "test" 07:22:15 INFO - } 07:22:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 07:22:15 INFO - Running post-action listener: _resource_record_post_action 07:22:15 INFO - [mozharness: 2017-04-18 14:22:15.790632Z] Finished read-buildbot-config step (success) 07:22:15 INFO - [mozharness: 2017-04-18 14:22:15.790746Z] Running download-and-extract step. 07:22:15 INFO - Running pre-action listener: _resource_record_pre_action 07:22:15 INFO - Running main action method: download_and_extract 07:22:15 INFO - mkdir: /builds/slave/test/build/tests 07:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:15 INFO - trying https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 07:22:15 INFO - Downloading https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/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 07:22:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/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 07:22:16 INFO - Downloaded 1199 bytes. 07:22:16 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 07:22:16 INFO - Using the following test package requirements: 07:22:16 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 07:22:16 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 07:22:16 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 07:22:16 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 07:22:16 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 07:22:16 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 07:22:16 INFO - u'jsshell-mac.zip'], 07:22:16 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 07:22:16 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 07:22:16 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 07:22:16 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 07:22:16 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 07:22:16 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 07:22:16 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 07:22:16 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 07:22:16 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 07:22:16 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 07:22:16 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 07:22:16 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:22:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 07:22:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:22:16 INFO - Fetch https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 07:22:18 INFO - Expected file size: 51791238 07:22:18 INFO - Obtained file size: 51791238 07:22:34 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 07:22:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 07:22:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:22:34 INFO - Fetch https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 07:22:35 INFO - Expected file size: 51791238 07:22:35 INFO - Obtained file size: 51791238 07:22:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 07:22:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:22:50 INFO - Fetch https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 07:22:52 INFO - Expected file size: 14404424 07:22:52 INFO - Obtained file size: 14404424 07:22:52 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')]} 07:22:52 INFO - trying https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 07:22:52 INFO - Downloading https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 07:22:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/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 07:22:55 INFO - Downloaded 74746774 bytes. 07:22:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 07:22:55 INFO - mkdir: /builds/slave/test/properties 07:22:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:22:55 INFO - Writing to file /builds/slave/test/properties/build_url 07:22:55 INFO - Contents: 07:22:55 INFO - build_url:https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 07:22:55 INFO - Running post-action listener: _resource_record_post_action 07:22:55 INFO - Running post-action listener: set_extra_try_arguments 07:22:55 INFO - [mozharness: 2017-04-18 14:22:55.691821Z] Finished download-and-extract step (success) 07:22:55 INFO - [mozharness: 2017-04-18 14:22:55.691937Z] Running populate-webroot step. 07:22:55 INFO - Running pre-action listener: _resource_record_pre_action 07:22:55 INFO - Running main action method: populate_webroot 07:22:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:22:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:22:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:22:55 INFO - '--tppagecycles', 07:22:55 INFO - '7'], 07:22:55 INFO - 'tests': ['tsvgm']}}, 07:22:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:22:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:22:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:22:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:22:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:22:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:22:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:22:55 INFO - 'talos_options': ['--disable-e10s'], 07:22:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:22:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:22:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:22:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:22:55 INFO - 'talos_options': ['--disable-e10s'], 07:22:55 INFO - 'tests': ['damp', 'tps']}, 07:22:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:22:55 INFO - 'tests': ['damp', 'tps']}, 07:22:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:22:55 INFO - 'tests': ['dromaeo_dom']}, 07:22:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:22:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:22:55 INFO - 'tests': ['basic_compositor_video']}, 07:22:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:22:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:22:55 INFO - 'tests': ['a11yr', 07:22:55 INFO - 'ts_paint', 07:22:55 INFO - 'tpaint', 07:22:55 INFO - 'sessionrestore', 07:22:55 INFO - 'sessionrestore_no_auto_restore', 07:22:55 INFO - 'tabpaint']}, 07:22:55 INFO - 'other-e10s': {'tests': ['a11yr', 07:22:55 INFO - 'ts_paint', 07:22:55 INFO - 'tpaint', 07:22:55 INFO - 'sessionrestore', 07:22:55 INFO - 'sessionrestore_no_auto_restore', 07:22:55 INFO - 'tabpaint']}, 07:22:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:22:55 INFO - 'ts_paint', 07:22:55 INFO - 'tpaint', 07:22:55 INFO - 'sessionrestore', 07:22:55 INFO - 'sessionrestore_no_auto_restore', 07:22:55 INFO - 'tabpaint']}, 07:22:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:22:55 INFO - 'ts_paint', 07:22:55 INFO - 'tpaint', 07:22:55 INFO - 'sessionrestore', 07:22:55 INFO - 'sessionrestore_no_auto_restore', 07:22:55 INFO - 'tabpaint']}, 07:22:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:22:55 INFO - 'tests': ['a11yr', 07:22:55 INFO - 'ts_paint', 07:22:55 INFO - 'tpaint', 07:22:55 INFO - 'sessionrestore', 07:22:55 INFO - 'sessionrestore_no_auto_restore', 07:22:55 INFO - 'tabpaint']}, 07:22:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:22:55 INFO - 'tests': ['a11yr', 07:22:55 INFO - 'ts_paint', 07:22:55 INFO - 'tpaint', 07:22:55 INFO - 'sessionrestore', 07:22:55 INFO - 'sessionrestore_no_auto_restore', 07:22:55 INFO - 'tabpaint']}, 07:22:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:22:55 INFO - 'tests': ['tsvgx', 07:22:55 INFO - 'tsvgr_opacity', 07:22:55 INFO - 'tart', 07:22:55 INFO - 'tscrollx', 07:22:55 INFO - 'cart', 07:22:55 INFO - 'tsvg_static']}, 07:22:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:22:55 INFO - 'tsvgr_opacity', 07:22:55 INFO - 'tart', 07:22:55 INFO - 'tscrollx', 07:22:55 INFO - 'cart', 07:22:55 INFO - 'tsvg_static']}, 07:22:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:22:55 INFO - 'talos_options': ['--disable-e10s'], 07:22:55 INFO - 'tests': ['tp5o']}, 07:22:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:22:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:22:55 INFO - 'talos_options': ['--disable-e10s', 07:22:55 INFO - '--xperf_path', 07:22:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:22:55 INFO - 'tests': ['tp5n']}, 07:22:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:22:55 INFO - 'talos_options': ['--xperf_path', 07:22:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:22:55 INFO - 'tests': ['tp5n']}}, 07:22:55 INFO - 'talos.zip': {'path': '', 07:22:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:22:55 INFO - Running post-action listener: _resource_record_post_action 07:22:55 INFO - [mozharness: 2017-04-18 14:22:55.711196Z] Finished populate-webroot step (success) 07:22:55 INFO - [mozharness: 2017-04-18 14:22:55.711287Z] Running create-virtualenv step. 07:22:55 INFO - Running pre-action listener: _resource_record_pre_action 07:22:55 INFO - Running main action method: create_virtualenv 07:22:55 INFO - Creating virtualenv /builds/slave/test/build/venv 07:22:55 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 07:22:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:22:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:22:55 INFO - Using real prefix '/tools/python27' 07:22:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:22:56 INFO - Installing distribute.............................................................................................................................................................................................done. 07:22:59 INFO - Installing pip.................done. 07:22:59 INFO - Return code: 0 07:22:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:22:59 INFO - Reading from file tmpfile_stdout 07:22:59 INFO - Output received: 07:22:59 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) 07:22:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:22:59 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')]} 07:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:59 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')]} 07:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:59 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 0x10d52cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb9258dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d30ad30>, '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 0x10d231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ffbe8>, '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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 07:22:59 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 07:22:59 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 07:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufIvsNJnKk/Render', 07:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:59 INFO - 'HOME': '/Users/cltbld', 07:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:59 INFO - 'LOGNAME': 'cltbld', 07:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:59 INFO - 'MOZ_NO_REMOTE': '1', 07:22:59 INFO - 'NO_EM_RESTART': '1', 07:22:59 INFO - 'PAGER': '/bin/cat', 07:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:59 INFO - 'PWD': '/builds/slave/test', 07:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:59 INFO - 'SHELL': '/bin/bash', 07:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3bwyWUWs9b/Listeners', 07:22:59 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 07:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:59 INFO - 'USER': 'cltbld', 07:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:59 INFO - 'XPC_FLAGS': '0x0', 07:22:59 INFO - 'XPC_SERVICE_NAME': '0', 07:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:59 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 07:22:59 INFO - Cleaning up... 07:22:59 INFO - Return code: 0 07:22:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:22:59 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')]} 07:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:59 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')]} 07:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:59 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 0x10d52cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb9258dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d30ad30>, '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 0x10d231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ffbe8>, '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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 07:22:59 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 07:22:59 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 07:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufIvsNJnKk/Render', 07:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:59 INFO - 'HOME': '/Users/cltbld', 07:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:59 INFO - 'LOGNAME': 'cltbld', 07:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:59 INFO - 'MOZ_NO_REMOTE': '1', 07:22:59 INFO - 'NO_EM_RESTART': '1', 07:22:59 INFO - 'PAGER': '/bin/cat', 07:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:59 INFO - 'PWD': '/builds/slave/test', 07:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:59 INFO - 'SHELL': '/bin/bash', 07:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3bwyWUWs9b/Listeners', 07:22:59 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 07:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:59 INFO - 'USER': 'cltbld', 07:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:59 INFO - 'XPC_FLAGS': '0x0', 07:22:59 INFO - 'XPC_SERVICE_NAME': '0', 07:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:00 INFO - Downloading/unpacking psutil>=3.1.1 07:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:23:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:23:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:23:03 INFO - Installing collected packages: psutil 07:23:03 INFO - Running setup.py install for psutil 07:23:03 INFO - building 'psutil._psutil_osx' extension 07:23:03 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 07:23:04 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 07:23:04 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 07:23:04 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 07:23:04 INFO - building 'psutil._psutil_posix' extension 07:23:04 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 07:23:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:23:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:23:04 INFO - ^ 07:23:04 INFO - 1 warning generated. 07:23:04 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 07:23:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:23:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:23:04 INFO - Successfully installed psutil 07:23:04 INFO - Cleaning up... 07:23:04 INFO - Return code: 0 07:23:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:23:04 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')]} 07:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:04 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')]} 07:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:04 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 0x10d52cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb9258dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d30ad30>, '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 0x10d231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ffbe8>, '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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 07:23:04 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 07:23:04 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 07:23:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufIvsNJnKk/Render', 07:23:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:04 INFO - 'HOME': '/Users/cltbld', 07:23:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:04 INFO - 'LOGNAME': 'cltbld', 07:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:04 INFO - 'MOZ_NO_REMOTE': '1', 07:23:04 INFO - 'NO_EM_RESTART': '1', 07:23:04 INFO - 'PAGER': '/bin/cat', 07:23:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:04 INFO - 'PWD': '/builds/slave/test', 07:23:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:04 INFO - 'SHELL': '/bin/bash', 07:23:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3bwyWUWs9b/Listeners', 07:23:04 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 07:23:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:04 INFO - 'USER': 'cltbld', 07:23:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:04 INFO - 'XPC_FLAGS': '0x0', 07:23:04 INFO - 'XPC_SERVICE_NAME': '0', 07:23:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:23:04 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 07:23:04 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 07:23:04 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 07:23:04 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 07:23:04 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 07:23:04 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 07:23:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:23:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:23:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:23:07 INFO - Installing collected packages: mozsystemmonitor 07:23:07 INFO - Running setup.py install for mozsystemmonitor 07:23:07 INFO - Successfully installed mozsystemmonitor 07:23:07 INFO - Cleaning up... 07:23:07 INFO - Return code: 0 07:23:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:23: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')]} 07:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23: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')]} 07:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d52cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb9258dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d30ad30>, '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 0x10d231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ffbe8>, '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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 07:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufIvsNJnKk/Render', 07:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:08 INFO - 'HOME': '/Users/cltbld', 07:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:08 INFO - 'LOGNAME': 'cltbld', 07:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:08 INFO - 'MOZ_NO_REMOTE': '1', 07:23:08 INFO - 'NO_EM_RESTART': '1', 07:23:08 INFO - 'PAGER': '/bin/cat', 07:23:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:08 INFO - 'PWD': '/builds/slave/test', 07:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:08 INFO - 'SHELL': '/bin/bash', 07:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3bwyWUWs9b/Listeners', 07:23:08 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 07:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:08 INFO - 'USER': 'cltbld', 07:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:08 INFO - 'XPC_FLAGS': '0x0', 07:23:08 INFO - 'XPC_SERVICE_NAME': '0', 07:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:08 INFO - Downloading/unpacking jsonschema==2.5.1 07:23: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 07:23: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 07:23: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 07:23: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 07:23: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 07:23: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 07:23:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:23:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:23:11 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 07:23:11 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 07:23:11 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 07:23:11 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 07:23:11 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 07:23:11 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 07:23:11 INFO - Downloading functools32-3.2.3-2.tar.gz 07:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:23:11 INFO - warning: no files found matching '*.txt' 07:23:11 INFO - no previously-included directories found matching 'build' 07:23:11 INFO - no previously-included directories found matching 'dist' 07:23:11 INFO - no previously-included directories found matching '.git*' 07:23:11 INFO - Installing collected packages: jsonschema, functools32 07:23:11 INFO - Running setup.py install for functools32 07:23:11 INFO - warning: no files found matching '*.txt' 07:23:11 INFO - no previously-included directories found matching 'build' 07:23:11 INFO - no previously-included directories found matching 'dist' 07:23:11 INFO - no previously-included directories found matching '.git*' 07:23:11 INFO - Successfully installed jsonschema functools32 07:23:11 INFO - Cleaning up... 07:23:12 INFO - Return code: 0 07:23:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:23: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')]} 07:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23: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')]} 07:23:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23: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', '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 0x10d52cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb9258dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d30ad30>, '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 0x10d231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ffbe8>, '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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 07:23: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:23: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 functools32==3.2.3-2 07:23:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufIvsNJnKk/Render', 07:23:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:12 INFO - 'HOME': '/Users/cltbld', 07:23:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:12 INFO - 'LOGNAME': 'cltbld', 07:23:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:12 INFO - 'MOZ_NO_REMOTE': '1', 07:23:12 INFO - 'NO_EM_RESTART': '1', 07:23:12 INFO - 'PAGER': '/bin/cat', 07:23:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:12 INFO - 'PWD': '/builds/slave/test', 07:23:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:12 INFO - 'SHELL': '/bin/bash', 07:23:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3bwyWUWs9b/Listeners', 07:23:12 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 07:23:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:12 INFO - 'USER': 'cltbld', 07:23:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:12 INFO - 'XPC_FLAGS': '0x0', 07:23:12 INFO - 'XPC_SERVICE_NAME': '0', 07:23:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:23:12 INFO - Cleaning up... 07:23:12 INFO - Return code: 0 07:23:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:23: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')]} 07:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23: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')]} 07:23:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23: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', '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 0x10d52cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb9258dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d30ad30>, '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 0x10d231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ffbe8>, '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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 07:23: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:23: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 blobuploader==1.2.4 07:23:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufIvsNJnKk/Render', 07:23:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:12 INFO - 'HOME': '/Users/cltbld', 07:23:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:12 INFO - 'LOGNAME': 'cltbld', 07:23:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:12 INFO - 'MOZ_NO_REMOTE': '1', 07:23:12 INFO - 'NO_EM_RESTART': '1', 07:23:12 INFO - 'PAGER': '/bin/cat', 07:23:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:12 INFO - 'PWD': '/builds/slave/test', 07:23:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:12 INFO - 'SHELL': '/bin/bash', 07:23:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3bwyWUWs9b/Listeners', 07:23:12 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 07:23:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:12 INFO - 'USER': 'cltbld', 07:23:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:12 INFO - 'XPC_FLAGS': '0x0', 07:23:12 INFO - 'XPC_SERVICE_NAME': '0', 07:23:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:12 INFO - Downloading/unpacking blobuploader==1.2.4 07:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:15 INFO - Downloading blobuploader-1.2.4.tar.gz 07:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:23:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:23:15 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 07:23:15 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 07:23:15 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 07:23:15 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 07:23:15 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 07:23:15 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 07:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:23:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:23:15 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 07:23:15 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 07:23:15 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 07:23:15 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 07:23:15 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 07:23:15 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 07:23:16 INFO - Downloading docopt-0.6.1.tar.gz 07:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:23:16 INFO - Installing collected packages: blobuploader, requests, docopt 07:23:16 INFO - Running setup.py install for blobuploader 07:23:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:23:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:23:16 INFO - Running setup.py install for requests 07:23:17 INFO - Running setup.py install for docopt 07:23:17 INFO - Successfully installed blobuploader requests docopt 07:23:17 INFO - Cleaning up... 07:23:17 INFO - Return code: 0 07:23:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:17 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 0x10d52cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb9258dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d30ad30>, '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 0x10d231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ffbe8>, '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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 07:23:17 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 07:23:17 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 07:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufIvsNJnKk/Render', 07:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:17 INFO - 'HOME': '/Users/cltbld', 07:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:17 INFO - 'LOGNAME': 'cltbld', 07:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:17 INFO - 'MOZ_NO_REMOTE': '1', 07:23:17 INFO - 'NO_EM_RESTART': '1', 07:23:17 INFO - 'PAGER': '/bin/cat', 07:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:17 INFO - 'PWD': '/builds/slave/test', 07:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:17 INFO - 'SHELL': '/bin/bash', 07:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3bwyWUWs9b/Listeners', 07:23:17 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 07:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:17 INFO - 'USER': 'cltbld', 07:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:17 INFO - 'XPC_FLAGS': '0x0', 07:23:17 INFO - 'XPC_SERVICE_NAME': '0', 07:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:23:17 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-0cfTbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:23:17 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-mYZe7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:23:17 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-NYCGOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:23:17 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-3G0dzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:23:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-65tdbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:23:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-Tk4rm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:23:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-OVbsR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:23:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-DeygW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:23:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-eO9Cbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:23:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-j2sS4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:23:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-FEdwtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:23:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-gMXPoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:23:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-CNEFfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:23:18 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-qZzeyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-7aPV4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:23:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-__WoHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:23:19 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-ewyy_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:23:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:23:19 INFO - Running setup.py install for manifestparser 07:23:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:23:19 INFO - Running setup.py install for mozcrash 07:23:19 INFO - Running setup.py install for mozdebug 07:23:19 INFO - Running setup.py install for mozdevice 07:23:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Running setup.py install for mozfile 07:23:20 INFO - Running setup.py install for mozhttpd 07:23:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Running setup.py install for mozinfo 07:23:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Running setup.py install for mozInstall 07:23:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Running setup.py install for mozleak 07:23:20 INFO - Running setup.py install for mozlog 07:23:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:23:20 INFO - Running setup.py install for moznetwork 07:23:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:23:21 INFO - Running setup.py install for mozprocess 07:23:21 INFO - Running setup.py install for mozprofile 07:23:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:23:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:23:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:23:21 INFO - Running setup.py install for mozrunner 07:23:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:23:21 INFO - Running setup.py install for mozscreenshot 07:23:21 INFO - Running setup.py install for moztest 07:23:21 INFO - Running setup.py install for mozversion 07:23:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:23:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:23:22 INFO - Cleaning up... 07:23:22 INFO - Return code: 0 07:23:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:23: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')]} 07:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23: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')]} 07:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:22 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 0x10d52cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb9258dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d30ad30>, '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 0x10d231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ffbe8>, '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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 07:23:22 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 07:23:22 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 07:23:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufIvsNJnKk/Render', 07:23:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:22 INFO - 'HOME': '/Users/cltbld', 07:23:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:22 INFO - 'LOGNAME': 'cltbld', 07:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:22 INFO - 'MOZ_NO_REMOTE': '1', 07:23:22 INFO - 'NO_EM_RESTART': '1', 07:23:22 INFO - 'PAGER': '/bin/cat', 07:23:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:22 INFO - 'PWD': '/builds/slave/test', 07:23:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:22 INFO - 'SHELL': '/bin/bash', 07:23:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3bwyWUWs9b/Listeners', 07:23:22 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 07:23:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:22 INFO - 'USER': 'cltbld', 07:23:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:22 INFO - 'XPC_FLAGS': '0x0', 07:23:22 INFO - 'XPC_SERVICE_NAME': '0', 07:23:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:23:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-gnyBBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:23:22 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)) 07:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:23:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-E8ONvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:23:22 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)) 07:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:23:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-mjKHos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:23:22 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)) 07:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:23:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-kZZNiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:23:22 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)) 07:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:23:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-ddCIGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:23:22 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)) 07:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:23:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-tlm45_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:23:22 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)) 07:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:23:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-sQiBzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:23:23 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)) 07:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:23:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-vALgox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:23:23 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)) 07:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:23:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-LGdtF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:23:23 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)) 07:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:23:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-GwF4Va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:23:23 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)) 07:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:23:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-vJxn7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:23:23 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)) 07:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:23:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-uKHXDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:23:23 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)) 07:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:23:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-A434z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:23:23 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)) 07:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:23:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-olzuOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:23:23 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)) 07:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-TcUr3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:23 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)) 07:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:23:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-rLIaBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:23:24 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)) 07:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:23:24 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-aFtqRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:23:24 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)) 07:23:24 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)) 07:23:24 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)) 07:23:24 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)) 07:23:24 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)) 07:23:24 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)) 07:23:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:23: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 07:23: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 07:23: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 07:23: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 07:23: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 07:23: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 07:23:26 INFO - Downloading blessings-1.6.tar.gz 07:23:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:23:26 INFO - Installing collected packages: blessings 07:23:26 INFO - Running setup.py install for blessings 07:23:27 INFO - Successfully installed blessings 07:23:27 INFO - Cleaning up... 07:23:27 INFO - Return code: 0 07:23:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:23:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:23:27 INFO - Reading from file tmpfile_stdout 07:23:27 INFO - Current package versions: 07:23:27 INFO - blessings == 1.6 07:23:27 INFO - blobuploader == 1.2.4 07:23:27 INFO - docopt == 0.6.1 07:23:27 INFO - functools32 == 3.2.3-2 07:23:27 INFO - jsonschema == 2.5.1 07:23:27 INFO - manifestparser == 1.1 07:23:27 INFO - mozInstall == 1.12 07:23:27 INFO - mozcrash == 1.0 07:23:27 INFO - mozdebug == 0.1 07:23:27 INFO - mozdevice == 0.49 07:23:27 INFO - mozfile == 1.2 07:23:27 INFO - mozhttpd == 0.7 07:23:27 INFO - mozinfo == 0.9 07:23:27 INFO - mozleak == 0.1 07:23:27 INFO - mozlog == 3.4 07:23:27 INFO - moznetwork == 0.27 07:23:27 INFO - mozprocess == 0.25 07:23:27 INFO - mozprofile == 0.28 07:23:27 INFO - mozrunner == 6.13 07:23:27 INFO - mozscreenshot == 0.1 07:23:27 INFO - mozsystemmonitor == 0.3 07:23:27 INFO - moztest == 0.8 07:23:27 INFO - mozversion == 1.4 07:23:27 INFO - psutil == 3.1.1 07:23:27 INFO - requests == 1.2.3 07:23:27 INFO - wsgiref == 0.1.2 07:23:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:23:27 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')]} 07:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:27 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')]} 07:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:27 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 0x10d52cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb9258dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d30ad30>, '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 0x10d231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ffbe8>, '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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 07:23:27 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 07:23:27 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 07:23:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufIvsNJnKk/Render', 07:23:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:27 INFO - 'HOME': '/Users/cltbld', 07:23:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:27 INFO - 'LOGNAME': 'cltbld', 07:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:27 INFO - 'MOZ_NO_REMOTE': '1', 07:23:27 INFO - 'NO_EM_RESTART': '1', 07:23:27 INFO - 'PAGER': '/bin/cat', 07:23:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:27 INFO - 'PWD': '/builds/slave/test', 07:23:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:27 INFO - 'SHELL': '/bin/bash', 07:23:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3bwyWUWs9b/Listeners', 07:23:27 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 07:23:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:27 INFO - 'USER': 'cltbld', 07:23:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:27 INFO - 'XPC_FLAGS': '0x0', 07:23:27 INFO - 'XPC_SERVICE_NAME': '0', 07:23:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:27 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)) 07:23:27 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)) 07:23:27 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)) 07:23:27 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)) 07:23:27 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)) 07:23:27 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)) 07:23:27 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)) 07:23:27 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)) 07:23:27 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)) 07:23:27 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)) 07:23:27 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)) 07:23:27 INFO - Cleaning up... 07:23:27 INFO - Return code: 0 07:23:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:23:27 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')]} 07:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:27 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')]} 07:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d52cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb9258dfa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d30ad30>, '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 0x10d231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ffbe8>, '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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 07:23:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:23:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufIvsNJnKk/Render', 07:23:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:27 INFO - 'HOME': '/Users/cltbld', 07:23:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:27 INFO - 'LOGNAME': 'cltbld', 07:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:27 INFO - 'MOZ_NO_REMOTE': '1', 07:23:27 INFO - 'NO_EM_RESTART': '1', 07:23:27 INFO - 'PAGER': '/bin/cat', 07:23:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:27 INFO - 'PWD': '/builds/slave/test', 07:23:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:27 INFO - 'SHELL': '/bin/bash', 07:23:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3bwyWUWs9b/Listeners', 07:23:27 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 07:23:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:27 INFO - 'USER': 'cltbld', 07:23:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:27 INFO - 'XPC_FLAGS': '0x0', 07:23:27 INFO - 'XPC_SERVICE_NAME': '0', 07:23:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:23:27 INFO - Cleaning up... 07:23:27 INFO - Return code: 0 07:23:27 INFO - Running post-action listener: _resource_record_post_action 07:23:27 INFO - Running post-action listener: _start_resource_monitoring 07:23:28 INFO - Starting resource monitoring. 07:23:28 INFO - [mozharness: 2017-04-18 14:23:28.017522Z] Finished create-virtualenv step (success) 07:23:28 INFO - [mozharness: 2017-04-18 14:23:28.018592Z] Running install step. 07:23:28 INFO - Running pre-action listener: _resource_record_pre_action 07:23:28 INFO - Running main action method: install 07:23:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:23:28 INFO - Reading from file tmpfile_stdout 07:23:28 INFO - Detecting whether we're running mozinstall >=1.0... 07:23:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:23:28 INFO - Reading from file tmpfile_stdout 07:23:28 INFO - Output received: 07:23:28 INFO - Usage: mozinstall [options] installer 07:23:28 INFO - Options: 07:23:28 INFO - -h, --help show this help message and exit 07:23:28 INFO - -d DEST, --destination=DEST 07:23:28 INFO - Directory to install application into. [default: 07:23:28 INFO - "/builds/slave/test"] 07:23:28 INFO - --app=APP Application being installed. [default: firefox] 07:23:28 INFO - mkdir: /builds/slave/test/build/application 07:23:28 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'] 07:23:28 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 07:23:48 INFO - Reading from file tmpfile_stdout 07:23:48 INFO - Output received: 07:23:48 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:23:48 INFO - Running post-action listener: _resource_record_post_action 07:23:48 INFO - [mozharness: 2017-04-18 14:23:48.476014Z] Finished install step (success) 07:23:48 INFO - [mozharness: 2017-04-18 14:23:48.476177Z] Running run-tests step. 07:23:48 INFO - Running pre-action listener: _resource_record_pre_action 07:23:48 INFO - Running main action method: run_tests 07:23:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:23:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:23:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:23:48 INFO - 2017-04-18 07:23:48.491 screenresolution[2698:32355] starting screenresolution argv=screenresolution get 07:23:48 INFO - 2017-04-18 07:23:48.493 screenresolution[2698:32355] Display 0: 1600x1200x32@60 07:23:48 INFO - 2017-04-18 07:23:48.500 screenresolution[2699:32359] starting screenresolution argv=screenresolution list 07:23:48 INFO - Available Modes on Display 0 07:23:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:23:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:23:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:23:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:23:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:23:48 INFO - Intel Iris: 07:23:48 INFO - Chipset Model: Intel Iris 07:23:48 INFO - Type: GPU 07:23:48 INFO - Bus: Built-In 07:23:48 INFO - VRAM (Dynamic, Max): 1536 MB 07:23:48 INFO - Vendor: Intel (0x8086) 07:23:48 INFO - Device ID: 0x0a2e 07:23:48 INFO - Revision ID: 0x0009 07:23:48 INFO - Displays: 07:23:48 INFO - Display: 07:23:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:23:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:23:48 INFO - Display Serial Number: 1600x1200 60 07:23:48 INFO - Main Display: Yes 07:23:48 INFO - Mirror: Off 07:23:48 INFO - Online: Yes 07:23:48 INFO - Rotation: Supported 07:23:48 INFO - Return code: 0 07:23:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:23:48 INFO - Python 2.7.3 07:23:48 INFO - Return code: 0 07:23:48 INFO - grabbing minidump binary from tooltool 07:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:48 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 0x10d30ad30>, '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 0x10d231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ffbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:23:48 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 07:23:48 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 07:23:48 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 07:23:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:23:48 INFO - Return code: 0 07:23:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:23:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:23:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:23:48 INFO - ENV: RUST_BACKTRACE is now 1 07:23:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:23:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:23:48 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/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0106', '--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 07:23:48 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/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0106 --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 07:23:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufIvsNJnKk/Render', 07:23:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:48 INFO - 'HOME': '/Users/cltbld', 07:23:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:48 INFO - 'LOGNAME': 'cltbld', 07:23:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:23:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:48 INFO - 'MOZ_NO_REMOTE': '1', 07:23:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:23:48 INFO - 'NO_EM_RESTART': '1', 07:23:48 INFO - 'PAGER': '/bin/cat', 07:23:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:48 INFO - 'PWD': '/builds/slave/test', 07:23:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:23:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:48 INFO - 'RUST_BACKTRACE': '1', 07:23:48 INFO - 'SHELL': '/bin/bash', 07:23:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3bwyWUWs9b/Listeners', 07:23:48 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 07:23:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:48 INFO - 'USER': 'cltbld', 07:23:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:48 INFO - 'XPC_FLAGS': '0x0', 07:23:48 INFO - 'XPC_SERVICE_NAME': '0', 07:23:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:48 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/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0106', '--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 07:23:49 INFO - mozversion application_buildid: 20170418064621 07:23:49 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 07:23:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:23:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:23:49 INFO - mozversion application_name: Firefox 07:23:49 INFO - mozversion application_remotingname: firefox-dev 07:23:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:23:49 INFO - mozversion application_vendor: Mozilla 07:23:49 INFO - mozversion application_version: 54.0a2 07:23:49 INFO - mozversion platform_buildid: 20170418064621 07:23:49 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 07:23:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:23:49 INFO - mozversion platform_version: 54.0a2 07:23:49 INFO - using testdate: 1492525429 07:23:49 INFO - actual date: 1492525429 07:23:49 INFO - starting webserver on 'localhost:57814' 07:23:49 INFO - SUITE-START | Running 2 tests 07:23:49 INFO - TEST-START | dromaeo_css 07:23:49 INFO - operating with platform_type : mac_ 07:23:49 INFO - Initialising browser for dromaeo_css test... 07:23:49 INFO - TEST-INFO | started process 2709 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmp5P3ncO/profile http://localhost:57814/getInfo.html) 07:23:51 INFO - PROCESS | 2709 | __metrics Screen width/height:1600/1200 07:23:51 INFO - PROCESS | 2709 | colorDepth:24 07:23:51 INFO - PROCESS | 2709 | Browser inner width/height: 1024/689 07:23:51 INFO - PROCESS | 2709 | __metrics 07:23:51 INFO - PROCESS | 2709 | 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" 07:23:51 INFO - PROCESS | 2709 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:23:52 INFO - TEST-INFO | 2709: exit 0 07:23:52 INFO - Browser initialized. 07:23:52 INFO - Running cycle 1/1 for dromaeo_css test... 07:23:52 INFO - TEST-INFO | started process 2711 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmp5P3ncO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:24:04 INFO - PROCESS | 2711 | RSS: Main: 210530304 07:24:04 INFO - PROCESS | 2711 | 07:27:08 INFO - PROCESS | 2711 | Cycle 1(1): loaded http://localhost:57814/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57814/tests/dromaeo/cssquery-ext.html) 07:27:10 INFO - PROCESS | 2711 | RSS: Main: 352665600 07:27:10 INFO - PROCESS | 2711 | 07:28:54 INFO - PROCESS | 2711 | ************************* 07:28:54 INFO - PROCESS | 2711 | A coding exception was thrown and uncaught in a Task. 07:28:54 INFO - PROCESS | 2711 | 07:28:54 ERROR - PROCESS | 2711 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:28:54 INFO - PROCESS | 2711 | Full stack: 07:28:54 INFO - PROCESS | 2711 | ************************* 07:28:54 INFO - PROCESS | 2711 | ************************* 07:28:54 INFO - PROCESS | 2711 | A coding exception was thrown and uncaught in a Task. 07:28:54 INFO - PROCESS | 2711 | 07:28:54 ERROR - PROCESS | 2711 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:28:54 INFO - PROCESS | 2711 | Full stack: 07:28:54 INFO - PROCESS | 2711 | ************************* 07:28:54 INFO - PROCESS | 2711 | ************************* 07:28:54 INFO - PROCESS | 2711 | A coding exception was thrown and uncaught in a Task. 07:28:54 INFO - PROCESS | 2711 | 07:28:54 ERROR - PROCESS | 2711 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:28:54 INFO - PROCESS | 2711 | Full stack: 07:28:54 INFO - PROCESS | 2711 | ************************* 07:28:54 INFO - PROCESS | 2711 | ************************* 07:28:54 INFO - PROCESS | 2711 | A coding exception was thrown and uncaught in a Task. 07:28:54 INFO - PROCESS | 2711 | 07:28:54 ERROR - PROCESS | 2711 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:28:54 INFO - PROCESS | 2711 | Full stack: 07:28:54 INFO - PROCESS | 2711 | ************************* 07:30:13 INFO - PROCESS | 2711 | Cycle 1(1): loaded http://localhost:57814/tests/dromaeo/cssquery-ext.html (next: http://localhost:57814/tests/dromaeo/cssquery-jquery.html) 07:30:14 INFO - PROCESS | 2711 | RSS: Main: 351154176 07:30:14 INFO - PROCESS | 2711 | 07:33:16 INFO - PROCESS | 2711 | Cycle 1(1): loaded http://localhost:57814/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57814/tests/dromaeo/cssquery-mootools.html) 07:33:17 INFO - PROCESS | 2711 | RSS: Main: 334946304 07:33:17 INFO - PROCESS | 2711 | 07:36:19 INFO - PROCESS | 2711 | Cycle 1(1): loaded http://localhost:57814/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57814/tests/dromaeo/cssquery-prototype.html) 07:36:20 INFO - PROCESS | 2711 | RSS: Main: 320536576 07:36:20 INFO - PROCESS | 2711 | 07:39:23 INFO - PROCESS | 2711 | Cycle 1(1): loaded http://localhost:57814/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57814/tests/dromaeo/cssquery-yui.html) 07:39:24 INFO - PROCESS | 2711 | RSS: Main: 327340032 07:39:24 INFO - PROCESS | 2711 | 07:42:27 INFO - PROCESS | 2711 | Cycle 1(1): loaded http://localhost:57814/tests/dromaeo/cssquery-yui.html (next: http://localhost:57814/tests/dromaeo/cssquery-dojo.html) 07:42:27 INFO - PROCESS | 2711 | RSS: Main: 538136576 07:42:27 INFO - PROCESS | 2711 | 07:42:27 INFO - PROCESS | 2711 | __start_tp_report 07:42:27 INFO - PROCESS | 2711 | _x_x_mozilla_page_load 07:42:27 INFO - PROCESS | 2711 | _x_x_mozilla_page_load_details 07:42:27 INFO - PROCESS | 2711 | |i|pagename|runs| 07:42:27 INFO - PROCESS | 2711 | |0;dojo.html;3356.630770615232;3372.763906526544;3373.561437013188;3411.761176717631;3439.7764145330552;5934.287885453747;5946.435088666575;5949.643021418707;5956.672383018932;5965.433283838029;5965.46310832025;6008.369121242272;6013.398660133985;6025.246844144482;6030.66831324277;5355.839324820262;5388.841399099193;5430.321209848769;5468.91796623051;5495.835124946258;5309.973450132744;5332.173513105475;5366.302380690505;5498.202760599711;5504.339479262494;5402.324709411324;5419.268398766156;5419.918701219485;5421.647592906468;5425.810096646618;5389.541888939445;5404.945950540523;5406;5412.2152287918425;5424.213489044087;5249.947500525022;5344.438833922421;5347.304850369466;5360.544353729938;5374.435684253175;5919.112133180014;5923.466887980103;5923.674197919113;5930.199423077873;5941.465268125889;6305.68471576424;6309.211348581427;6334.144890439778;6335;6345.714442850082;6321.304656487782;6322.336154703736;6330.873382532323;6338.112664227012;6344.397282258178;5019.29729837823;6079.209702738615;6087.4825639820665;6087.817365479043;6097.146399504073;6244.875102497925;6472.35276472349;6499.480041596661;6521.608703477759;6534.869302613921;5766.625169364062;5848.976429124886;6261.812145635638;6265.185525881606;6291.465225455888;6367.363263673596;6375.808725738235;6390.360963903572;6390.808275751735;6397.008463688136;6289.685515724195;6310.842228944313;6315.810525684236;6318.526110541636;6335.3981371769605;6151.477124444383;6173;6226.40849119333;6273;6283;5050.368703912011;5056.115179843513;5061.5950723942;5065.7720402581235;5116.897662046813;4504.594586487232;4596.7931443085145;4632.166210082218;4650.930236046462;4664;5941.287045554474;5973.462388384979;5981.461668449861;5994.820155395345;5996.610220335751;6255.280642726145;6303.716332765037;6423.907935650951;6427.967860160669;6428.260750013715;5777.277840269966;5896.410358964155;5912.142739302777;5948.226730525004;5959.106134079836;5634.577406694514;5636.267285252891;5641.238432811518;5651.067573850269;5660.716964151776;6473.514486413444;6574.572652777554;6582.901256481061;6586.703598338182;6603.735850565923;6264.279607844974;6311.274203466476;6330.113784070326;6348.936510634834;6371.139896113966;6560.147180866647;6570.5729127608465;6577.6053436792035;6581.769638062645;6587.736490540325;6270.12218289449;6291.905621415774;6340.953742632414;6354.491640668828;6357.872842543031;6218.9378106218355;6278.058291256348;6283.434490895841;6296;6375.9362406377195;6313.43179113882;6323.905141422791;6325.146105275729;6332.715027823667;6337.524685648687;6306.842328941813;6312;6324.525660575568;6328.367163283635;6359.650219237987;6005.129256257906;6067.757289708362;6111.419415155553;6171.537134715004;6236.282827474717;6211.596246243979;6276.9372306276355;6327.050942358683;6335.809925702236;6340.651264180515;6210.658413787285;6218;6218.440340369389;6220.129181914626;6220.7511699533325 07:42:27 INFO - PROCESS | 2711 | |1;ext.html;787.8778789287661;811.2009670474583;862.1895418306791;878.8242351529695;879.2218886285635;40285;41056;42734.57265427345;42866.78566607163;42958.711238662814;41984.58015419845;42090.579094209046;42323.788381058126;42359.15281694364;42458.150836983245;7395.334419902208;9166.404183728037;9344.53277336134;9346.532673366304;9365.438073715564;7467.850642987137;11276.887231127706;11524.769504609903;11528.769424611502;11543.249688770245;11377.089832813355;11419.714507137305;11426.771464570704;11439.19925605208;11439.942800285988;11326.433678316092;11374.37440940748;11379.431028448544;11404.315741055563;11405.31568105912;10845.023947844731;11012.119030477623;11015;11046.889531104667;11054.336739795557;38830.029249268715;38999.22001559953;39028.21943561141;39062.41406378907;39080.60919390798;3810.923781524354;3865.9806700966315;3880.844766209348;3897.4153876918404;3938.3107956107565;26938;28910;29010.27474313138;29052.837886484514;29069;3010.834404107773;3050.100220434977;3067.156531953731;3229.434848901433;3292.1440425489545;334612;335618;335640;336128.63871361216;336702;52665;52670.73664631652;52700.47299527032;52712;52931.470685292654;54328;54518;54642;54687.45312546903;54784.17823732647;14774.704505909822;14898.478553250585;14901.701965960838;14910.701785964437;14913.328900199516;12742.299173545358;12746.04404669635;12760.425780839885;12765.234085954873;12800.423980920881;11180.496877640526;12562.246265223934;12571.120021598583;12571.748565028649;12677.112602117762;5409.594280428905;5454.127339625641;5458.972705136528;5466.781328746805;5479.205515200274;1044.2846650044592;1067.4449286371043;1087.1140020882956;1090.3839330778085;1140.1050175612104;23842.523149537257;26371.47257054848;27338.72661273362;27771.583426248624;29629.703702962695;987.5407935310116;1155.364549497773;1159.5941420502757;1189.6550000499758;1195.7309605338903;14136.15183089018;14202.857971420153;14349.713005739826;14477.638059048608;14676.266186690622;449813;450559;451221;451615;452811;55805.44194558084;55831.72084139553;55873.72063139658;55909.88180236372;56022.71988640031;1401.6075498860334;1469.9632509187143;1470.5735336752223;1471.2129010979288;1471.5658880630453;26261;26486.602700959116;26844.59733103966;27082.187534374;27104.457910842066;45913.852153696425;46065;46097.308540371254;46108.769456152506;46188.30717539307;420089;421624;421717;422688;423565;34122.48816267808;34691.9592412228;34811.129721757155;34855.30289394147;34870.47694284537;42883.571164289206;43075.56924430715;43689;44407.77796110999;44556.55443445524;22468.438289042904;29374;29790.553141703323;29863.701362986092;30522.389552208388;22228.66657000114;29381;29908.102756917327;30342.241443964078;30347.696523034487;35520.46719299264;36310;36482.90551283466;36486;36745.44881826828;28912.367558179274;29469.96855110061;30209;31001.534976974912;31170.532442013842;23178.53642927166;23221.071157153525;23544.29367118989;23580.646290306002;24121.879390602935 07:42:27 INFO - PROCESS | 2711 | |2;jquery.html;387.5078650114353;410.2676722051139;428.2249129076371;429.63695677152805;447.09909532292437;13106.672333191675;14895.851041489595;21271.36185914421;21465.67801482975;21818.672719909213;22381.552368952613;22431.32706018818;23120.537589248204;23131.421714457105;23389.947452364642;6195.349488303731;6672.065910772496;6749.257581666013;6754.2907994660345;6759.0875231843565;6521.217453905538;6601.537892347537;6702.798916032502;6714.429273511757;6721.1598550181225;6734.0572319875255;6734.562253453534;6735.93264067358;6741.764038258638;6743.93256067438;6530.6734663267125;6613.470922326226;6637.137172167611;6694.196696396415;6704.430123439513;6484.708188131498;6516.055172000081;6570.704318305688;6587.011948207759;6593.50548708844;27199.320016999536;27493.725062749316;27703.861480692467;29215.85392073026;29889.70110298891;3321.5898377412746;3395.3718562066097;3480.6345333739846;3509.842057107436;3521.5245941797784;16758.413455528997;16851.83148168515;17341.219645116;17380.826191738048;18406.35577754772;1944.708293755948;2082.8752473664204;2118.7457505099333;2161.200355868323;2187.66091255854;132131.67868321386;133726.3313683435;179197;180039;180529;19086.80913190878;21005.36983890486;22837.429064273525;23143.421414464774;23381;40365;41117.38323925144;41364.586354136074;41439.58560414417;41453.1709365811;8265.586720663943;9803.362781419184;9972.650957216463;10246.948765256126;10409.271351005356;8563.229309362187;8564.571771411405;8564.957175214084;8742.86885696715;8750.95624521886;9129.634814607474;9224.215941645036;9379.108984646447;9393.060693930689;9402.059794020543;3915.7454765440157;4012.9398059029136;4015.357542793139;4016.2849364338454;4035.6569691575955;829.7469271872137;992.1814503035025;1009.4769255298954;1009.5961615353917;1015.081351377005;20476.795232047785;20524.79475205259;20600.690989634866;20631.381058568266;20705.792942070388;992.4938281476354;997.3268044070369;1004.864343313658;1017.9847302290472;1023.6110278094277;8728.214460698568;9997.950010249902;10404.479776011169;10699.83950240747;10736;614104;616238;619596;644917;653942;138147;139854;143069;147429.2628536872;147696;1182.5447202826974;1187.3172925567833;1258.1821815819608;1265.070173422552;1276.0748457368334;18770.530736731693;18994.38547723459;20037.998100094646;20122.597548048663;20185.394438166877;56421;66359.00461493178;66622.33377666162;66753.3324666747;67825.3217467819;71605.28394715986;80728;94904.05095948953;97166.05667886461;98003;7459.104907411145;10599.682009539727;10868.293560918515;10948.890511095105;11285.661430157108;12339.69150771202;12650.177738447339;12771.87228127744;12867.613971580868;13009.154404963647;8721;10145.543450544596;11143.88561143879;11224.719382015515;11299.661010169708;10393.37639741618;10471.109955653856;10487.79024419492;10767;10801.783964320512;10978.286411383235;11082.61210857616;11230.831537527107;11427.200095993201;11514.251573647685;11295.096392288762;11387;11518.308901465938;11754.471048802654;11761.235519691192;10480.58077676915;10859.402732849769;11050.834237486284;11090.500927458444;11206.49570769301 07:42:27 INFO - PROCESS | 2711 | |3;mootools.html;1438.8201474815648;1444.5666300109965;1472.6097584140202;1473.7641977283643;1505.0894209003552;8577.656893724252;10970.780584388307;10985.231033827635;11008.449577521122;11032;10684.412357320345;10763.08513776328;10996.12031037517;11047.226694131414;11074.557017719302;1829.9176537055798;2291.049214575949;2292.083166733303;2292.266474728088;2294.977050229493;2215.014318628211;2263.9434014149615;2273.0453209651987;2286.7598902115287;2298.425393651587;2212.2699509162017;2283.3264187064856;2286.0627142871426;2294.0135741631093;2298.54029194161;2210.635245184543;2272.534130503251;2275.6131457652154;2277.7836105569945;2283.1209984156053;2278.886055697225;2310.90756369745;2316.6061769499224;2322.071171531384;2324.569954558412;11168.776624467466;11242.66272011841;11257.60598379061;11261.436928153642;11322.830157547643;4425;5168.069747445458;5304.655197412156;5316.3620365556;5324.440933701944;2985.6118704568485;3119.953200701991;3255.4791233402543;3292.670732926712;3347.3472672828807;1199.082701733166;1200.6698158006532;1222.8960538354252;1239.4484454417789;1265.5507294910267;15354.846451535564;15382.000169989027;15488.922555387264;15496.690066198613;15499.767503487457;3957.762534247954;4196;4230.682698797603;4253.447051883235;4269.060806622538;10996.560137594406;11002.779944401067;11004.954529319702;11007;11009.504572294267;5202.505761952609;5219.199376215275;5220.791168353223;5220.843374698765;5225.294585230945;4775.140474714515;4864.318995340655;4930.13722598551;4964.801407943714;4994.750262486861;3487.738419618488;3537.6285490023693;3546.7871927683905;3576.2847430514003;3597.5143355647174;878.3412440669498;890.9242714369221;892.2549671024638;892.8437523433373;893.066745251214;686.4336922039338;905.610587447404;936.5146361446953;937.3017102258724;947.5167664491021;6561.770338038145;6599.670016499156;6778.559393639398;6806.421454176351;6836.179658440919;840.9831803363899;963.5258055175592;971.4802580619476;994.8756405449319;1030.4384110659707;4118.217538667644;4138.089620283533;4143.0678097428445;4190.937135942963;4203.663706903501;10832.025117739442;13930.303484825718;14058.156510609397;14101;14162.433502659984;11920;12379.381030948416;13297.335133243492;13879.86120138806;14026.85973140276;8579.528125953133;8905.465672059698;8944.195022448012;8968.730938071869;8973.012968573452;4200.957990420179;6234.657093859882;6601.405873471219;6886.345797149263;6895.965520172568;11741.119416044;11814.350210738492;12088.335141567299;12409.503619855466;12601.18092323996;12697;12745.17156384832;12860.871391285968;12919.870801291867;13023.739525209254;10248.64129755455;10289.382637041801;10301.484925753832;10463.319884207502;10474.68575942723;9420.764480888032;9853.162481188892;10227.43749093807;10237.129843963048;10266.230032747722;8651.351148663749;8668.566571671265;8749.956250218707;8793.296536276954;8909.198172164537;8333.499990000619;8470.32237421017;8564.158705878266;8620.396572239883;8691.739247822576;6710.228323742832;7834.569098699626;7850.6074696266105;7965.4822436541435;7976.082750483537;5899.852503687443;5976.402359763989;5981.521478281814;6003.729832157651;6216.906746398898;5212.322398088224;5932.110183472513;5959.76160953557;6130.172426722514;6229 07:42:27 INFO - PROCESS | 2711 | |4;prototype.html;314.4010659693284;381.8396273565102;383.84262452394523;385.5219527785546;391.09071408974125;4770.7614619269025;5127.589912773989;5150.381954165501;5204.687718736878;5216.843494695156;5005.723540497168;5065.252487891681;5070.619703522232;5087.694738315704;5106.64673861427;3594.892153235394;4051;4108.979455102721;4251.4685664291965;4258.148370325932;3433.6051354094225;3626.4741612466178;4069.470968774055;4238.321868501041;4249.259227798068;4228.619424251817;4233.640140588053;4250.873518517596;4253.851115210969;4269.338252570857;3973.7417067890783;4061.248668996245;4094.651954583864;4272.50866150392;4301.601734629655;2947.778916581265;3330.550375699274;3505.3690335739557;3825.48355971943;3854.036490877281;4164.479440069991;4188.528768026878;4651.37206477128;4848.636352273594;4980.452150263468;4217.894552636178;4553.54464553542;4606.861794146181;4862.6839255448285;4883.388481801033;4627.95372046282;5392.838214853561;5452.754626041838;5463.7814487420455;5476.67139971603;4110.273685052285;4338.392625032498;4345.565443455661;4363;4373.075846627474;4606.470255920612;4701.952980470186;5157.535821776059;5414.401087477993;5441.401445840955;3767.1335592813807;4192.643625291823;4263.802148388709;4354.433923589913;4426.671998400467;4182.330827067654;4265.0830071534765;4455.710378825366;4476.977615111904;4489.842855500107;3976.588311149558;4278.957210427856;4792.424909010941;4844.15227335222;4920.6309526785635;4640.65195110368;4721.898755053968;4736.171170045228;4830.3479030331155;5006.824761133343;1457.5408746244898;1551.6508785523167;1561.2740075864551;1576.929038193284;1584.5721655153275;1044.1959691037857;1092.5356723392686;1108.728361551425;1162.8430161928031;1176.452949378526;4353.69524133314;4612.907741845212;4634.559716826896;4863.586595139453;5055.51972562606;5312.804618960703;5492.450754924555;5598.216249725042;5645.633033852869;5645.689487078168;4346.479042155543;4634.559716826896;4870.172070747982;4878.536539028786;5114.488551144856;2494.064725727852;2842.5167721487396;2908.258394109531;2912.8834846606323;2933.841132752551;5335.519803217729;5514.884090488861;5527.462424048612;5901.668840807857;5916.26046744157;4913.778879950411;5192;5278.498542638443;5505.43095217861;5907.320658124285;5194.714290714047;5469.808556700496;5552.639078459887;5642.150713889541;5767.336756272998;5380.43505431924;5579.776808927598;5632.802851900164;5645.26204790668;5674.971625141848;4417.53615870329;4648.581627653527;5220.843374698765;5318.150034961512;5496.972515137559;5085.415177254663;5143.974280128576;5615.859603509945;5813.79651712188;5819.388964158702;4149.771762552968;4395.516493185811;4433.312836510346;4469.486009108864;4508.63930885535;4404.625606823456;5100.948990510047;5416.404195538409;5571.804986825442;5631.380548139783;4344.978275108604;5370.704611246419;5375.81184658535;5382.877930800205;5421.8041693114355;4669.089527542097;5223.425423203368;5379.821172425174;5384.5192572043;5437.592180586393;4574.5425457453985;4961.801527938842;5192.584593232608;5256.526912577886;5278.261043453843;4605.815767369268;5348.812791552277;5410.10646273801;5435.320584926884;5447.700376479332;4963.362090510195;5068.6430809673675;5158.329417175743;5327.280817089643;5349.251104845247 07:42:27 INFO - PROCESS | 2711 | |5;yui.html;714.5565956767327;721.0698199322874;742.3393180069736;774.7791879314397;801.214809486703;13404.798928016087;14045.15729056256;15150.696986060273;16149.354025838953;16230.350785968576;14754.63113422165;14916;14941.327640256188;15381.846181538182;15816.288267027983;893.7497500699815;932.6549176804558;952.3619175152664;956.3687965942479;961.1974001708581;912.3659056955419;945.678469320431;947.9620815167385;950.6054987180312;950.6482601437477;992.8212921674095;993.2500961773851;1000.5297510170209;1001.0490034467249;1001.3391161833191;993.0913214408807;993.1756641987133;995.1093771074848;996.6212839121124;999.0708640963901;740.1118657610834;740.3891789273871;741.1809950005274;741.3068780690072;743.0080842075836;15962.483564061396;15989.120598367084;16021;16160.191990400433;16195.757063643938;13618.046736728433;13632.591022269347;14006.369713362923;14122.082064665763;14304.213268270241;11822.547563828146;11914.582989595323;11925;12020.459079341363;12032;3577.3918433866306;3608.621094785036;3718.5537735471644;3763.6989040876665;3835.983464381941;108296.45851770691;115483;115849;116710;116819;10575.735606609896;10680.57178818672;10836.187285953585;10875.03997441638;11045.779084418427;22574.435639109153;22766.65850012251;22785.20251791179;22831.20090796814;22871;7701.960235368266;7711;7776.339011184113;7863.882041769378;7869;3761.6614504694708;4292.699511034198;4316.072044510445;4344.23975804233;4345.695801293942;3217.5656286401504;3309.271960168759;3313.337332533503;3316.718078963267;3321.4353559894876;543.3045701502084;611.6788685939918;636.7484843486797;642.6561789442626;643.8165122939939;658.9110470086573;668.3550373889124;691.560858854631;702.2626242445514;730.5434103685196;9893.050798374257;10151.796964060677;10274.589016439259;10410.479476026168;10410.739731506621;623.1400667079402;627.2170587186137;652.2205963873126;656.1207981304979;668.3683918696785;4867.58625516835;4984.127777638899;4997.350344455271;5012.072766538202;5024.171011783015;73985;80472.59763701144;89423;105140;111838.44080779544;56440.717796411576;77477.11858498766;79584.20415795768;79958.6002069986;81460.19689170932;67935.3206467929;68952;69216;71054;71252.64373678098;9632.229421646392;9923.45421001823;9924.40453572788;10067.295289329677;10554.577816887238;35900.461493078146;37759.67841125657;47751.76124119357;49234.753826232074;51460;50529.74735126301;58834;69352.30647693458;69864;69944.65027675033;12347.567835126087;12399.690007749878;12470.688232794253;12479.438425270702;13076.869231307564;14246.857531424554;14288.857111428753;14316.47790716211;14744.115353078849;14747.336369863597;12806.295653739135;12859.614211573667;13185.934070329587;13217.537386191438;13616.795748063589;9948;12328.445219965302;12355.876441235472;12754.170978886343;12907.935460322638;12011.459484323052;12939.870601294244;12958.22250664963;13105.934470327587;13501.729965400833;8942.642294308154;9182;9389.624415023323;9396.201322887358;9417.717468475958;9690.903090969001;9719.780684936248;11019.338839669645;11207.383593902088;11269.154813388865 07:42:27 INFO - PROCESS | 2711 | __end_tp_report 07:42:27 INFO - PROCESS | 2711 | __start_cc_report 07:42:27 INFO - PROCESS | 2711 | _x_x_mozilla_cycle_collect,1903 07:42:27 INFO - PROCESS | 2711 | __end_cc_report 07:42:27 INFO - PROCESS | 2711 | __startTimestamp1492526547832__endTimestamp 07:42:27 INFO - PROCESS | 2711 | 07:42:27 INFO - PROCESS | 2711 | ------- Summary: start ------- 07:42:27 INFO - PROCESS | 2711 | Number of tests: 6 07:42:27 INFO - PROCESS | 2711 | 07:42:27 INFO - PROCESS | 2711 | [#0] dojo.html Cycles:165 Average:5915.01 Median:6173.00 stddev:648.79 (10.5%) stddev-sans-first:618.96 07:42:27 INFO - PROCESS | 2711 | Values: 3356.6 3372.8 3373.6 3411.8 3439.8 5934.3 5946.4 5949.6 5956.7 5965.4 5965.5 6008.4 6013.4 6025.2 6030.7 5355.8 5388.8 5430.3 5468.9 5495.8 5310.0 5332.2 5366.3 5498.2 5504.3 5402.3 5419.3 5419.9 5421.6 5425.8 5389.5 5404.9 5406.0 5412.2 5424.2 5249.9 5344.4 5347.3 5360.5 5374.4 5919.1 5923.5 5923.7 5930.2 5941.5 6305.7 6309.2 6334.1 6335.0 6345.7 6321.3 6322.3 6330.9 6338.1 6344.4 5019.3 6079.2 6087.5 6087.8 6097.1 6244.9 6472.4 6499.5 6521.6 6534.9 5766.6 5849.0 6261.8 6265.2 6291.5 6367.4 6375.8 6390.4 6390.8 6397.0 6289.7 6310.8 6315.8 6318.5 6335.4 6151.5 6173.0 6226.4 6273.0 6283.0 5050.4 5056.1 5061.6 5065.8 5116.9 4504.6 4596.8 4632.2 4650.9 4664.0 5941.3 5973.5 5981.5 5994.8 5996.6 6255.3 6303.7 6423.9 6428.0 6428.3 5777.3 5896.4 5912.1 5948.2 5959.1 5634.6 5636.3 5641.2 5651.1 5660.7 6473.5 6574.6 6582.9 6586.7 6603.7 6264.3 6311.3 6330.1 6348.9 6371.1 6560.1 6570.6 6577.6 6581.8 6587.7 6270.1 6291.9 6341.0 6354.5 6357.9 6218.9 6278.1 6283.4 6296.0 6375.9 6313.4 6323.9 6325.1 6332.7 6337.5 6306.8 6312.0 6324.5 6328.4 6359.7 6005.1 6067.8 6111.4 6171.5 6236.3 6211.6 6276.9 6327.1 6335.8 6340.7 6210.7 6218.0 6218.4 6220.1 6220.8 07:42:27 INFO - PROCESS | 2711 | 07:42:27 INFO - PROCESS | 2711 | [#1] ext.html Cycles:180 Average:54823.96 Median:17199.98 stddev:107457.92 (624.8%) stddev-sans-first:107682.78 07:42:27 INFO - PROCESS | 2711 | Values: 787.9 811.2 862.2 878.8 879.2 40285.0 41056.0 42734.6 42866.8 42958.7 41984.6 42090.6 42323.8 42359.2 42458.2 7395.3 9166.4 9344.5 9346.5 9365.4 7467.9 11276.9 11524.8 11528.8 11543.2 11377.1 11419.7 11426.8 11439.2 11439.9 11326.4 11374.4 11379.4 11404.3 11405.3 10845.0 11012.1 11015.0 11046.9 11054.3 38830.0 38999.2 39028.2 39062.4 39080.6 3810.9 3866.0 3880.8 3897.4 3938.3 26938.0 28910.0 29010.3 29052.8 29069.0 3010.8 3050.1 3067.2 3229.4 3292.1 334612.0 335618.0 335640.0 336128.6 336702.0 52665.0 52670.7 52700.5 52712.0 52931.5 54328.0 54518.0 54642.0 54687.5 54784.2 14774.7 14898.5 14901.7 14910.7 14913.3 12742.3 12746.0 12760.4 12765.2 12800.4 11180.5 12562.2 12571.1 12571.7 12677.1 5409.6 5454.1 5459.0 5466.8 5479.2 1044.3 1067.4 1087.1 1090.4 1140.1 23842.5 26371.5 27338.7 27771.6 29629.7 987.5 1155.4 1159.6 1189.7 1195.7 14136.2 14202.9 14349.7 14477.6 14676.3 449813.0 450559.0 451221.0 451615.0 452811.0 55805.4 55831.7 55873.7 55909.9 56022.7 1401.6 1470.0 1470.6 1471.2 1471.6 26261.0 26486.6 26844.6 27082.2 27104.5 45913.9 46065.0 46097.3 46108.8 46188.3 420089.0 421624.0 421717.0 422688.0 423565.0 34122.5 34692.0 34811.1 34855.3 34870.5 42883.6 43075.6 43689.0 44407.8 44556.6 22468.4 29374.0 29790.6 29863.7 30522.4 22228.7 29381.0 29908.1 30342.2 30347.7 35520.5 36310.0 36482.9 36486.0 36745.4 28912.4 29470.0 30209.0 31001.5 31170.5 23178.5 23221.1 23544.3 23580.6 24121.9 07:42:27 INFO - PROCESS | 2711 | 07:42:27 INFO - PROCESS | 2711 | [#2] jquery.html Cycles:180 Average:39990.39 Median:23137.42 stddev:106352.63 (459.7%) stddev-sans-first:106609.41 07:42:27 INFO - PROCESS | 2711 | Values: 387.5 410.3 428.2 429.6 447.1 13106.7 14895.9 21271.4 21465.7 21818.7 22381.6 22431.3 23120.5 23131.4 23389.9 6195.3 6672.1 6749.3 6754.3 6759.1 6521.2 6601.5 6702.8 6714.4 6721.2 6734.1 6734.6 6735.9 6741.8 6743.9 6530.7 6613.5 6637.1 6694.2 6704.4 6484.7 6516.1 6570.7 6587.0 6593.5 27199.3 27493.7 27703.9 29215.9 29889.7 3321.6 3395.4 3480.6 3509.8 3521.5 16758.4 16851.8 17341.2 17380.8 18406.4 1944.7 2082.9 2118.7 2161.2 2187.7 132131.7 133726.3 179197.0 180039.0 180529.0 19086.8 21005.4 22837.4 23143.4 23381.0 40365.0 41117.4 41364.6 41439.6 41453.2 8265.6 9803.4 9972.7 10246.9 10409.3 8563.2 8564.6 8565.0 8742.9 8751.0 9129.6 9224.2 9379.1 9393.1 9402.1 3915.7 4012.9 4015.4 4016.3 4035.7 829.7 992.2 1009.5 1009.6 1015.1 20476.8 20524.8 20600.7 20631.4 20705.8 992.5 997.3 1004.9 1018.0 1023.6 8728.2 9998.0 10404.5 10699.8 10736.0 614104.0 616238.0 619596.0 644917.0 653942.0 138147.0 139854.0 143069.0 147429.3 147696.0 1182.5 1187.3 1258.2 1265.1 1276.1 18770.5 18994.4 20038.0 20122.6 20185.4 56421.0 66359.0 66622.3 66753.3 67825.3 71605.3 80728.0 94904.1 97166.1 98003.0 7459.1 10599.7 10868.3 10948.9 11285.7 12339.7 12650.2 12771.9 12867.6 13009.2 8721.0 10145.5 11143.9 11224.7 11299.7 10393.4 10471.1 10487.8 10767.0 10801.8 10978.3 11082.6 11230.8 11427.2 11514.3 11295.1 11387.0 11518.3 11754.5 11761.2 10480.6 10859.4 11050.8 11090.5 11206.5 07:42:27 INFO - PROCESS | 2711 | 07:42:27 INFO - PROCESS | 2711 | [#3] mootools.html Cycles:180 Average:6574.02 Median:3274.07 stddev:4242.10 (129.6%) stddev-sans-first:4236.45 07:42:27 INFO - PROCESS | 2711 | Values: 1438.8 1444.6 1472.6 1473.8 1505.1 8577.7 10970.8 10985.2 11008.4 11032.0 10684.4 10763.1 10996.1 11047.2 11074.6 1829.9 2291.0 2292.1 2292.3 2295.0 2215.0 2263.9 2273.0 2286.8 2298.4 2212.3 2283.3 2286.1 2294.0 2298.5 2210.6 2272.5 2275.6 2277.8 2283.1 2278.9 2310.9 2316.6 2322.1 2324.6 11168.8 11242.7 11257.6 11261.4 11322.8 4425.0 5168.1 5304.7 5316.4 5324.4 2985.6 3120.0 3255.5 3292.7 3347.3 1199.1 1200.7 1222.9 1239.4 1265.6 15354.8 15382.0 15488.9 15496.7 15499.8 3957.8 4196.0 4230.7 4253.4 4269.1 10996.6 11002.8 11005.0 11007.0 11009.5 5202.5 5219.2 5220.8 5220.8 5225.3 4775.1 4864.3 4930.1 4964.8 4994.8 3487.7 3537.6 3546.8 3576.3 3597.5 878.3 890.9 892.3 892.8 893.1 686.4 905.6 936.5 937.3 947.5 6561.8 6599.7 6778.6 6806.4 6836.2 841.0 963.5 971.5 994.9 1030.4 4118.2 4138.1 4143.1 4190.9 4203.7 10832.0 13930.3 14058.2 14101.0 14162.4 11920.0 12379.4 13297.3 13879.9 14026.9 8579.5 8905.5 8944.2 8968.7 8973.0 4201.0 6234.7 6601.4 6886.3 6896.0 11741.1 11814.4 12088.3 12409.5 12601.2 12697.0 12745.2 12860.9 12919.9 13023.7 10248.6 10289.4 10301.5 10463.3 10474.7 9420.8 9853.2 10227.4 10237.1 10266.2 8651.4 8668.6 8750.0 8793.3 8909.2 8333.5 8470.3 8564.2 8620.4 8691.7 6710.2 7834.6 7850.6 7965.5 7976.1 5899.9 5976.4 5981.5 6003.7 6216.9 5212.3 5932.1 5959.8 6130.2 6229.0 07:42:27 INFO - PROCESS | 2711 | 07:42:27 INFO - PROCESS | 2711 | [#4] prototype.html Cycles:180 Average:4465.70 Median:4753.47 stddev:1244.40 (26.2%) stddev-sans-first:1208.25 07:42:27 INFO - PROCESS | 2711 | Values: 314.4 381.8 383.8 385.5 391.1 4770.8 5127.6 5150.4 5204.7 5216.8 5005.7 5065.3 5070.6 5087.7 5106.6 3594.9 4051.0 4109.0 4251.5 4258.1 3433.6 3626.5 4069.5 4238.3 4249.3 4228.6 4233.6 4250.9 4253.9 4269.3 3973.7 4061.2 4094.7 4272.5 4301.6 2947.8 3330.6 3505.4 3825.5 3854.0 4164.5 4188.5 4651.4 4848.6 4980.5 4217.9 4553.5 4606.9 4862.7 4883.4 4628.0 5392.8 5452.8 5463.8 5476.7 4110.3 4338.4 4345.6 4363.0 4373.1 4606.5 4702.0 5157.5 5414.4 5441.4 3767.1 4192.6 4263.8 4354.4 4426.7 4182.3 4265.1 4455.7 4477.0 4489.8 3976.6 4279.0 4792.4 4844.2 4920.6 4640.7 4721.9 4736.2 4830.3 5006.8 1457.5 1551.7 1561.3 1576.9 1584.6 1044.2 1092.5 1108.7 1162.8 1176.5 4353.7 4612.9 4634.6 4863.6 5055.5 5312.8 5492.5 5598.2 5645.6 5645.7 4346.5 4634.6 4870.2 4878.5 5114.5 2494.1 2842.5 2908.3 2912.9 2933.8 5335.5 5514.9 5527.5 5901.7 5916.3 4913.8 5192.0 5278.5 5505.4 5907.3 5194.7 5469.8 5552.6 5642.2 5767.3 5380.4 5579.8 5632.8 5645.3 5675.0 4417.5 4648.6 5220.8 5318.2 5497.0 5085.4 5144.0 5615.9 5813.8 5819.4 4149.8 4395.5 4433.3 4469.5 4508.6 4404.6 5100.9 5416.4 5571.8 5631.4 4345.0 5370.7 5375.8 5382.9 5421.8 4669.1 5223.4 5379.8 5384.5 5437.6 4574.5 4961.8 5192.6 5256.5 5278.3 4605.8 5348.8 5410.1 5435.3 5447.7 4963.4 5068.6 5158.3 5327.3 5349.3 07:42:27 INFO - PROCESS | 2711 | 07:42:27 INFO - PROCESS | 2711 | [#5] yui.html Cycles:180 Average:19493.71 Median:4064.34 stddev:27941.12 (687.5%) stddev-sans-first:27983.92 07:42:27 INFO - PROCESS | 2711 | Values: 714.6 721.1 742.3 774.8 801.2 13404.8 14045.2 15150.7 16149.4 16230.4 14754.6 14916.0 14941.3 15381.8 15816.3 893.7 932.7 952.4 956.4 961.2 912.4 945.7 948.0 950.6 950.6 992.8 993.3 1000.5 1001.0 1001.3 993.1 993.2 995.1 996.6 999.1 740.1 740.4 741.2 741.3 743.0 15962.5 15989.1 16021.0 16160.2 16195.8 13618.0 13632.6 14006.4 14122.1 14304.2 11822.5 11914.6 11925.0 12020.5 12032.0 3577.4 3608.6 3718.6 3763.7 3836.0 108296.5 115483.0 115849.0 116710.0 116819.0 10575.7 10680.6 10836.2 10875.0 11045.8 22574.4 22766.7 22785.2 22831.2 22871.0 7702.0 7711.0 7776.3 7863.9 7869.0 3761.7 4292.7 4316.1 4344.2 4345.7 3217.6 3309.3 3313.3 3316.7 3321.4 543.3 611.7 636.7 642.7 643.8 658.9 668.4 691.6 702.3 730.5 9893.1 10151.8 10274.6 10410.5 10410.7 623.1 627.2 652.2 656.1 668.4 4867.6 4984.1 4997.4 5012.1 5024.2 73985.0 80472.6 89423.0 105140.0 111838.4 56440.7 77477.1 79584.2 79958.6 81460.2 67935.3 68952.0 69216.0 71054.0 71252.6 9632.2 9923.5 9924.4 10067.3 10554.6 35900.5 37759.7 47751.8 49234.8 51460.0 50529.7 58834.0 69352.3 69864.0 69944.7 12347.6 12399.7 12470.7 12479.4 13076.9 14246.9 14288.9 14316.5 14744.1 14747.3 12806.3 12859.6 13185.9 13217.5 13616.8 9948.0 12328.4 12355.9 12754.2 12907.9 12011.5 12939.9 12958.2 13105.9 13501.7 8942.6 9182.0 9389.6 9396.2 9417.7 9690.9 9719.8 11019.3 11207.4 11269.2 07:42:27 INFO - PROCESS | 2711 | -------- Summary: end -------- 07:42:27 INFO - PROCESS | 2711 | 07:42:28 INFO - TEST-INFO | 2711: exit 0 07:42:28 INFO - TEST-OK | dromaeo_css | took 1119452ms 07:42:28 INFO - TEST-START | kraken 07:42:28 INFO - operating with platform_type : mac_ 07:42:28 INFO - Initialising browser for kraken test... 07:42:28 INFO - TEST-INFO | started process 2718 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpvyJpgI/profile http://localhost:57814/getInfo.html) 07:42:31 INFO - PROCESS | 2718 | __metrics Screen width/height:1600/1200 07:42:31 INFO - PROCESS | 2718 | colorDepth:24 07:42:31 INFO - PROCESS | 2718 | Browser inner width/height: 1024/689 07:42:31 INFO - PROCESS | 2718 | __metrics 07:42:31 INFO - PROCESS | 2718 | 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" 07:42:31 INFO - PROCESS | 2718 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:42:31 INFO - TEST-INFO | 2718: exit 0 07:42:31 INFO - Browser initialized. 07:42:31 INFO - Running cycle 1/1 for kraken test... 07:42:31 INFO - TEST-INFO | started process 2719 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpvyJpgI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:42:44 INFO - PROCESS | 2719 | RSS: Main: 214999040 07:42:44 INFO - PROCESS | 2719 | 07:42:46 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57814/tests/kraken/driver.html?testName=audio-beat-detection) 07:42:47 INFO - PROCESS | 2719 | RSS: Main: 268816384 07:42:47 INFO - PROCESS | 2719 | 07:42:50 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57814/tests/kraken/driver.html?testName=audio-dft) 07:42:50 INFO - PROCESS | 2719 | RSS: Main: 280547328 07:42:50 INFO - PROCESS | 2719 | 07:42:53 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57814/tests/kraken/driver.html?testName=audio-fft) 07:42:54 INFO - PROCESS | 2719 | RSS: Main: 376492032 07:42:54 INFO - PROCESS | 2719 | 07:42:56 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57814/tests/kraken/driver.html?testName=audio-oscillator) 07:42:57 INFO - PROCESS | 2719 | RSS: Main: 279236608 07:42:57 INFO - PROCESS | 2719 | 07:42:59 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57814/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:42:59 INFO - PROCESS | 2719 | RSS: Main: 280604672 07:42:59 INFO - PROCESS | 2719 | 07:43:02 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57814/tests/kraken/driver.html?testName=imaging-darkroom) 07:43:03 INFO - PROCESS | 2719 | RSS: Main: 321568768 07:43:03 INFO - PROCESS | 2719 | 07:43:06 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57814/tests/kraken/driver.html?testName=imaging-desaturate) 07:43:07 INFO - PROCESS | 2719 | RSS: Main: 321003520 07:43:07 INFO - PROCESS | 2719 | 07:43:10 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57814/tests/kraken/driver.html?testName=json-parse-financial) 07:43:10 INFO - PROCESS | 2719 | RSS: Main: 319328256 07:43:10 INFO - PROCESS | 2719 | 07:43:12 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57814/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:43:13 INFO - PROCESS | 2719 | RSS: Main: 294850560 07:43:13 INFO - PROCESS | 2719 | 07:43:14 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57814/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:43:15 INFO - PROCESS | 2719 | RSS: Main: 297496576 07:43:15 INFO - PROCESS | 2719 | 07:43:17 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57814/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:43:18 INFO - PROCESS | 2719 | RSS: Main: 301035520 07:43:18 INFO - PROCESS | 2719 | 07:43:21 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57814/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:43:22 INFO - PROCESS | 2719 | RSS: Main: 320630784 07:43:22 INFO - PROCESS | 2719 | 07:43:25 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57814/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:43:25 INFO - PROCESS | 2719 | RSS: Main: 314060800 07:43:25 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | Cycle 1(1): loaded http://localhost:57814/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57814/tests/kraken/driver.html?testName=ai-astar) 07:43:27 INFO - PROCESS | 2719 | RSS: Main: 358260736 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | __start_tp_report 07:43:27 INFO - PROCESS | 2719 | _x_x_mozilla_page_load 07:43:27 INFO - PROCESS | 2719 | _x_x_mozilla_page_load_details 07:43:27 INFO - PROCESS | 2719 | |i|pagename|runs| 07:43:27 INFO - PROCESS | 2719 | |0;ai-astar;116;114;114;115;112;115;114;116;114;114 07:43:27 INFO - PROCESS | 2719 | |1;audio-beat-detection;147;110;109;147;112;110;107;109;128;104 07:43:27 INFO - PROCESS | 2719 | |2;audio-dft;185;184;185;185;186;184;182;183;200;162 07:43:27 INFO - PROCESS | 2719 | |3;audio-fft;85;61;62;90;62;62;62;66;67;49 07:43:27 INFO - PROCESS | 2719 | |4;audio-oscillator;88;70;87;68;70;69;83;70;69;83 07:43:27 INFO - PROCESS | 2719 | |5;imaging-gaussian-blur;86;87;86;86;87;86;86;86;86;86 07:43:27 INFO - PROCESS | 2719 | |6;imaging-darkroom;122;94;132;150;94;94;94;93;94;94 07:43:27 INFO - PROCESS | 2719 | |7;imaging-desaturate;83;84;86;84;83;86;86;85;99;129 07:43:27 INFO - PROCESS | 2719 | |8;json-parse-financial;62;59;59;61;63;65;65;64;63;63 07:43:27 INFO - PROCESS | 2719 | |9;json-stringify-tinderbox;54;70;65;62;61;58;58;59;58;59 07:43:27 INFO - PROCESS | 2719 | |10;stanford-crypto-aes;85;83;86;83;83;84;90;102;104;106 07:43:27 INFO - PROCESS | 2719 | |11;stanford-crypto-ccm;138;135;155;138;147;154;157;149;211;183 07:43:27 INFO - PROCESS | 2719 | |12;stanford-crypto-pbkdf2;164;166;171;164;167;164;165;163;163;165 07:43:27 INFO - PROCESS | 2719 | |13;stanford-crypto-sha256-iterative;64;65;66;65;65;67;65;64;64;64 07:43:27 INFO - PROCESS | 2719 | __end_tp_report 07:43:27 INFO - PROCESS | 2719 | __start_cc_report 07:43:27 INFO - PROCESS | 2719 | _x_x_mozilla_cycle_collect,2306 07:43:27 INFO - PROCESS | 2719 | __end_cc_report 07:43:27 INFO - PROCESS | 2719 | __startTimestamp1492526607756__endTimestamp 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | ------- Summary: start ------- 07:43:27 INFO - PROCESS | 2719 | Number of tests: 14 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#0] ai-astar Cycles:10 Average:114.40 Median:114.50 stddev:1.17 (1.0%) stddev-sans-first:1.09 07:43:27 INFO - PROCESS | 2719 | Values: 116.0 114.0 114.0 115.0 112.0 115.0 114.0 116.0 114.0 114.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#1] audio-beat-detection Cycles:10 Average:118.30 Median:111.00 stddev:16.41 (14.8%) stddev-sans-first:13.73 07:43:27 INFO - PROCESS | 2719 | Values: 147.0 110.0 109.0 147.0 112.0 110.0 107.0 109.0 128.0 104.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#2] audio-dft Cycles:10 Average:183.60 Median:185.00 stddev:9.13 (4.9%) stddev-sans-first:9.67 07:43:27 INFO - PROCESS | 2719 | Values: 185.0 184.0 185.0 185.0 186.0 184.0 182.0 183.0 200.0 162.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#3] audio-fft Cycles:10 Average:66.60 Median:64.00 stddev:12.08 (18.9%) stddev-sans-first:10.82 07:43:27 INFO - PROCESS | 2719 | Values: 85.0 61.0 62.0 90.0 62.0 62.0 62.0 66.0 67.0 49.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:76.50 stddev:8.38 (11.0%) stddev-sans-first:7.62 07:43:27 INFO - PROCESS | 2719 | Values: 88.0 70.0 87.0 68.0 70.0 69.0 83.0 70.0 69.0 83.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:86.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 07:43:27 INFO - PROCESS | 2719 | Values: 86.0 87.0 86.0 86.0 87.0 86.0 86.0 86.0 86.0 86.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#6] imaging-darkroom Cycles:10 Average:106.10 Median:94.00 stddev:20.82 (22.1%) stddev-sans-first:21.27 07:43:27 INFO - PROCESS | 2719 | Values: 122.0 94.0 132.0 150.0 94.0 94.0 94.0 93.0 94.0 94.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#7] imaging-desaturate Cycles:10 Average:90.50 Median:85.50 stddev:14.31 (16.7%) stddev-sans-first:14.92 07:43:27 INFO - PROCESS | 2719 | Values: 83.0 84.0 86.0 84.0 83.0 86.0 86.0 85.0 99.0 129.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:63.00 stddev:2.17 (3.4%) stddev-sans-first:2.30 07:43:27 INFO - PROCESS | 2719 | Values: 62.0 59.0 59.0 61.0 63.0 65.0 65.0 64.0 63.0 63.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.00 stddev:4.45 (7.4%) stddev-sans-first:4.08 07:43:27 INFO - PROCESS | 2719 | Values: 54.0 70.0 65.0 62.0 61.0 58.0 58.0 59.0 58.0 59.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:83.50 stddev:9.52 (11.4%) stddev-sans-first:9.88 07:43:27 INFO - PROCESS | 2719 | Values: 85.0 83.0 86.0 83.0 83.0 84.0 90.0 102.0 104.0 106.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#11] stanford-crypto-ccm Cycles:10 Average:156.70 Median:154.50 stddev:23.51 (15.2%) stddev-sans-first:23.94 07:43:27 INFO - PROCESS | 2719 | Values: 138.0 135.0 155.0 138.0 147.0 154.0 157.0 149.0 211.0 183.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:165.00 stddev:2.39 (1.5%) stddev-sans-first:2.50 07:43:27 INFO - PROCESS | 2719 | Values: 164.0 166.0 171.0 164.0 167.0 164.0 165.0 163.0 163.0 165.0 07:43:27 INFO - PROCESS | 2719 | 07:43:27 INFO - PROCESS | 2719 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 07:43:27 INFO - PROCESS | 2719 | Values: 64.0 65.0 66.0 65.0 65.0 67.0 65.0 64.0 64.0 64.0 07:43:27 INFO - PROCESS | 2719 | -------- Summary: end -------- 07:43:27 INFO - PROCESS | 2719 | 07:43:28 INFO - TEST-INFO | 2719: exit 0 07:43:28 INFO - TEST-OK | kraken | took 60121ms 07:43:28 INFO - SUITE-END | took 1179s 07:43:28 INFO - Completed test suite (00:19:39) 07:43:28 INFO - javascript benchmark 07:43:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7612.175248600227, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3356.630770615232, 3372.763906526544, 3373.561437013188, 3411.761176717631, 3439.7764145330552, 5934.287885453747, 5946.435088666575, 5949.643021418707, 5956.672383018932, 5965.433283838029, 5965.46310832025, 6008.369121242272, 6013.398660133985, 6025.246844144482, 6030.66831324277, 5355.839324820262, 5388.841399099193, 5430.321209848769, 5468.91796623051, 5495.835124946258, 5309.973450132744, 5332.173513105475, 5366.302380690505, 5498.202760599711, 5504.339479262494, 5402.324709411324, 5419.268398766156, 5419.918701219485, 5421.647592906468, 5425.810096646618, 5389.541888939445, 5404.945950540523, 5406.0, 5412.2152287918425, 5424.213489044087, 5249.947500525022, 5344.438833922421, 5347.304850369466, 5360.544353729938, 5374.435684253175, 5919.112133180014, 5923.466887980103, 5923.674197919113, 5930.199423077873, 5941.465268125889, 6305.68471576424, 6309.211348581427, 6334.144890439778, 6335.0, 6345.714442850082, 6321.304656487782, 6322.336154703736, 6330.873382532323, 6338.112664227012, 6344.397282258178, 5019.29729837823, 6079.209702738615, 6087.4825639820665, 6087.817365479043, 6097.146399504073, 6244.875102497925, 6472.35276472349, 6499.480041596661, 6521.608703477759, 6534.869302613921, 5766.625169364062, 5848.976429124886, 6261.812145635638, 6265.185525881606, 6291.465225455888, 6367.363263673596, 6375.808725738235, 6390.360963903572, 6390.808275751735, 6397.008463688136, 6289.685515724195, 6310.842228944313, 6315.810525684236, 6318.526110541636, 6335.3981371769605, 6151.477124444383, 6173.0, 6226.40849119333, 6273.0, 6283.0, 5050.368703912011, 5056.115179843513, 5061.5950723942, 5065.7720402581235, 5116.897662046813, 4504.594586487232, 4596.7931443085145, 4632.166210082218, 4650.930236046462, 4664.0, 5941.287045554474, 5973.462388384979, 5981.461668449861, 5994.820155395345, 5996.610220335751, 6255.280642726145, 6303.716332765037, 6423.907935650951, 6427.967860160669, 6428.260750013715, 5777.277840269966, 5896.410358964155, 5912.142739302777, 5948.226730525004, 5959.106134079836, 5634.577406694514, 5636.267285252891, 5641.238432811518, 5651.067573850269, 5660.716964151776, 6473.514486413444, 6574.572652777554, 6582.901256481061, 6586.703598338182, 6603.735850565923, 6264.279607844974, 6311.274203466476, 6330.113784070326, 6348.936510634834, 6371.139896113966, 6560.147180866647, 6570.5729127608465, 6577.6053436792035, 6581.769638062645, 6587.736490540325, 6270.12218289449, 6291.905621415774, 6340.953742632414, 6354.491640668828, 6357.872842543031, 6218.9378106218355, 6278.058291256348, 6283.434490895841, 6296.0, 6375.9362406377195, 6313.43179113882, 6323.905141422791, 6325.146105275729, 6332.715027823667, 6337.524685648687, 6306.842328941813, 6312.0, 6324.525660575568, 6328.367163283635, 6359.650219237987, 6005.129256257906, 6067.757289708362, 6111.419415155553, 6171.537134715004, 6236.282827474717, 6211.596246243979, 6276.9372306276355, 6327.050942358683, 6335.809925702236, 6340.651264180515, 6210.658413787285, 6218.0, 6218.440340369389, 6220.129181914626, 6220.7511699533325], "value": 5872.875676320616, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.8778789287661, 811.2009670474583, 862.1895418306791, 878.8242351529695, 879.2218886285635, 40285.0, 41056.0, 42734.57265427345, 42866.78566607163, 42958.711238662814, 41984.58015419845, 42090.579094209046, 42323.788381058126, 42359.15281694364, 42458.150836983245, 7395.334419902208, 9166.404183728037, 9344.53277336134, 9346.532673366304, 9365.438073715564, 7467.850642987137, 11276.887231127706, 11524.769504609903, 11528.769424611502, 11543.249688770245, 11377.089832813355, 11419.714507137305, 11426.771464570704, 11439.19925605208, 11439.942800285988, 11326.433678316092, 11374.37440940748, 11379.431028448544, 11404.315741055563, 11405.31568105912, 10845.023947844731, 11012.119030477623, 11015.0, 11046.889531104667, 11054.336739795557, 38830.029249268715, 38999.22001559953, 39028.21943561141, 39062.41406378907, 39080.60919390798, 3810.923781524354, 3865.9806700966315, 3880.844766209348, 3897.4153876918404, 3938.3107956107565, 26938.0, 28910.0, 29010.27474313138, 29052.837886484514, 29069.0, 3010.834404107773, 3050.100220434977, 3067.156531953731, 3229.434848901433, 3292.1440425489545, 334612.0, 335618.0, 335640.0, 336128.63871361216, 336702.0, 52665.0, 52670.73664631652, 52700.47299527032, 52712.0, 52931.470685292654, 54328.0, 54518.0, 54642.0, 54687.45312546903, 54784.17823732647, 14774.704505909822, 14898.478553250585, 14901.701965960838, 14910.701785964437, 14913.328900199516, 12742.299173545358, 12746.04404669635, 12760.425780839885, 12765.234085954873, 12800.423980920881, 11180.496877640526, 12562.246265223934, 12571.120021598583, 12571.748565028649, 12677.112602117762, 5409.594280428905, 5454.127339625641, 5458.972705136528, 5466.781328746805, 5479.205515200274, 1044.2846650044592, 1067.4449286371043, 1087.1140020882956, 1090.3839330778085, 1140.1050175612104, 23842.523149537257, 26371.47257054848, 27338.72661273362, 27771.583426248624, 29629.703702962695, 987.5407935310116, 1155.364549497773, 1159.5941420502757, 1189.6550000499758, 1195.7309605338903, 14136.15183089018, 14202.857971420153, 14349.713005739826, 14477.638059048608, 14676.266186690622, 449813.0, 450559.0, 451221.0, 451615.0, 452811.0, 55805.44194558084, 55831.72084139553, 55873.72063139658, 55909.88180236372, 56022.71988640031, 1401.6075498860334, 1469.9632509187143, 1470.5735336752223, 1471.2129010979288, 1471.5658880630453, 26261.0, 26486.602700959116, 26844.59733103966, 27082.187534374, 27104.457910842066, 45913.852153696425, 46065.0, 46097.308540371254, 46108.769456152506, 46188.30717539307, 420089.0, 421624.0, 421717.0, 422688.0, 423565.0, 34122.48816267808, 34691.9592412228, 34811.129721757155, 34855.30289394147, 34870.47694284537, 42883.571164289206, 43075.56924430715, 43689.0, 44407.77796110999, 44556.55443445524, 22468.438289042904, 29374.0, 29790.553141703323, 29863.701362986092, 30522.389552208388, 22228.66657000114, 29381.0, 29908.102756917327, 30342.241443964078, 30347.696523034487, 35520.46719299264, 36310.0, 36482.90551283466, 36486.0, 36745.44881826828, 28912.367558179274, 29469.96855110061, 30209.0, 31001.534976974912, 31170.532442013842, 23178.53642927166, 23221.071157153525, 23544.29367118989, 23580.646290306002, 24121.879390602935], "value": 18949.25178374279, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.5078650114353, 410.2676722051139, 428.2249129076371, 429.63695677152805, 447.09909532292437, 13106.672333191675, 14895.851041489595, 21271.36185914421, 21465.67801482975, 21818.672719909213, 22381.552368952613, 22431.32706018818, 23120.537589248204, 23131.421714457105, 23389.947452364642, 6195.349488303731, 6672.065910772496, 6749.257581666013, 6754.2907994660345, 6759.0875231843565, 6521.217453905538, 6601.537892347537, 6702.798916032502, 6714.429273511757, 6721.1598550181225, 6734.0572319875255, 6734.562253453534, 6735.93264067358, 6741.764038258638, 6743.93256067438, 6530.6734663267125, 6613.470922326226, 6637.137172167611, 6694.196696396415, 6704.430123439513, 6484.708188131498, 6516.055172000081, 6570.704318305688, 6587.011948207759, 6593.50548708844, 27199.320016999536, 27493.725062749316, 27703.861480692467, 29215.85392073026, 29889.70110298891, 3321.5898377412746, 3395.3718562066097, 3480.6345333739846, 3509.842057107436, 3521.5245941797784, 16758.413455528997, 16851.83148168515, 17341.219645116, 17380.826191738048, 18406.35577754772, 1944.708293755948, 2082.8752473664204, 2118.7457505099333, 2161.200355868323, 2187.66091255854, 132131.67868321386, 133726.3313683435, 179197.0, 180039.0, 180529.0, 19086.80913190878, 21005.36983890486, 22837.429064273525, 23143.421414464774, 23381.0, 40365.0, 41117.38323925144, 41364.586354136074, 41439.58560414417, 41453.1709365811, 8265.586720663943, 9803.362781419184, 9972.650957216463, 10246.948765256126, 10409.271351005356, 8563.229309362187, 8564.571771411405, 8564.957175214084, 8742.86885696715, 8750.95624521886, 9129.634814607474, 9224.215941645036, 9379.108984646447, 9393.060693930689, 9402.059794020543, 3915.7454765440157, 4012.9398059029136, 4015.357542793139, 4016.2849364338454, 4035.6569691575955, 829.7469271872137, 992.1814503035025, 1009.4769255298954, 1009.5961615353917, 1015.081351377005, 20476.795232047785, 20524.79475205259, 20600.690989634866, 20631.381058568266, 20705.792942070388, 992.4938281476354, 997.3268044070369, 1004.864343313658, 1017.9847302290472, 1023.6110278094277, 8728.214460698568, 9997.950010249902, 10404.479776011169, 10699.83950240747, 10736.0, 614104.0, 616238.0, 619596.0, 644917.0, 653942.0, 138147.0, 139854.0, 143069.0, 147429.2628536872, 147696.0, 1182.5447202826974, 1187.3172925567833, 1258.1821815819608, 1265.070173422552, 1276.0748457368334, 18770.530736731693, 18994.38547723459, 20037.998100094646, 20122.597548048663, 20185.394438166877, 56421.0, 66359.00461493178, 66622.33377666162, 66753.3324666747, 67825.3217467819, 71605.28394715986, 80728.0, 94904.05095948953, 97166.05667886461, 98003.0, 7459.104907411145, 10599.682009539727, 10868.293560918515, 10948.890511095105, 11285.661430157108, 12339.69150771202, 12650.177738447339, 12771.87228127744, 12867.613971580868, 13009.154404963647, 8721.0, 10145.543450544596, 11143.88561143879, 11224.719382015515, 11299.661010169708, 10393.37639741618, 10471.109955653856, 10487.79024419492, 10767.0, 10801.783964320512, 10978.286411383235, 11082.61210857616, 11230.831537527107, 11427.200095993201, 11514.251573647685, 11295.096392288762, 11387.0, 11518.308901465938, 11754.471048802654, 11761.235519691192, 10480.58077676915, 10859.402732849769, 11050.834237486284, 11090.500927458444, 11206.49570769301], "value": 11578.624454628945, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1438.8201474815648, 1444.5666300109965, 1472.6097584140202, 1473.7641977283643, 1505.0894209003552, 8577.656893724252, 10970.780584388307, 10985.231033827635, 11008.449577521122, 11032.0, 10684.412357320345, 10763.08513776328, 10996.12031037517, 11047.226694131414, 11074.557017719302, 1829.9176537055798, 2291.049214575949, 2292.083166733303, 2292.266474728088, 2294.977050229493, 2215.014318628211, 2263.9434014149615, 2273.0453209651987, 2286.7598902115287, 2298.425393651587, 2212.2699509162017, 2283.3264187064856, 2286.0627142871426, 2294.0135741631093, 2298.54029194161, 2210.635245184543, 2272.534130503251, 2275.6131457652154, 2277.7836105569945, 2283.1209984156053, 2278.886055697225, 2310.90756369745, 2316.6061769499224, 2322.071171531384, 2324.569954558412, 11168.776624467466, 11242.66272011841, 11257.60598379061, 11261.436928153642, 11322.830157547643, 4425.0, 5168.069747445458, 5304.655197412156, 5316.3620365556, 5324.440933701944, 2985.6118704568485, 3119.953200701991, 3255.4791233402543, 3292.670732926712, 3347.3472672828807, 1199.082701733166, 1200.6698158006532, 1222.8960538354252, 1239.4484454417789, 1265.5507294910267, 15354.846451535564, 15382.000169989027, 15488.922555387264, 15496.690066198613, 15499.767503487457, 3957.762534247954, 4196.0, 4230.682698797603, 4253.447051883235, 4269.060806622538, 10996.560137594406, 11002.779944401067, 11004.954529319702, 11007.0, 11009.504572294267, 5202.505761952609, 5219.199376215275, 5220.791168353223, 5220.843374698765, 5225.294585230945, 4775.140474714515, 4864.318995340655, 4930.13722598551, 4964.801407943714, 4994.750262486861, 3487.738419618488, 3537.6285490023693, 3546.7871927683905, 3576.2847430514003, 3597.5143355647174, 878.3412440669498, 890.9242714369221, 892.2549671024638, 892.8437523433373, 893.066745251214, 686.4336922039338, 905.610587447404, 936.5146361446953, 937.3017102258724, 947.5167664491021, 6561.770338038145, 6599.670016499156, 6778.559393639398, 6806.421454176351, 6836.179658440919, 840.9831803363899, 963.5258055175592, 971.4802580619476, 994.8756405449319, 1030.4384110659707, 4118.217538667644, 4138.089620283533, 4143.0678097428445, 4190.937135942963, 4203.663706903501, 10832.025117739442, 13930.303484825718, 14058.156510609397, 14101.0, 14162.433502659984, 11920.0, 12379.381030948416, 13297.335133243492, 13879.86120138806, 14026.85973140276, 8579.528125953133, 8905.465672059698, 8944.195022448012, 8968.730938071869, 8973.012968573452, 4200.957990420179, 6234.657093859882, 6601.405873471219, 6886.345797149263, 6895.965520172568, 11741.119416044, 11814.350210738492, 12088.335141567299, 12409.503619855466, 12601.18092323996, 12697.0, 12745.17156384832, 12860.871391285968, 12919.870801291867, 13023.739525209254, 10248.64129755455, 10289.382637041801, 10301.484925753832, 10463.319884207502, 10474.68575942723, 9420.764480888032, 9853.162481188892, 10227.43749093807, 10237.129843963048, 10266.230032747722, 8651.351148663749, 8668.566571671265, 8749.956250218707, 8793.296536276954, 8909.198172164537, 8333.499990000619, 8470.32237421017, 8564.158705878266, 8620.396572239883, 8691.739247822576, 6710.228323742832, 7834.569098699626, 7850.6074696266105, 7965.4822436541435, 7976.082750483537, 5899.852503687443, 5976.402359763989, 5981.521478281814, 6003.729832157651, 6216.906746398898, 5212.322398088224, 5932.110183472513, 5959.76160953557, 6130.172426722514, 6229.0], "value": 4943.78379142803, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.4010659693284, 381.8396273565102, 383.84262452394523, 385.5219527785546, 391.09071408974125, 4770.7614619269025, 5127.589912773989, 5150.381954165501, 5204.687718736878, 5216.843494695156, 5005.723540497168, 5065.252487891681, 5070.619703522232, 5087.694738315704, 5106.64673861427, 3594.892153235394, 4051.0, 4108.979455102721, 4251.4685664291965, 4258.148370325932, 3433.6051354094225, 3626.4741612466178, 4069.470968774055, 4238.321868501041, 4249.259227798068, 4228.619424251817, 4233.640140588053, 4250.873518517596, 4253.851115210969, 4269.338252570857, 3973.7417067890783, 4061.248668996245, 4094.651954583864, 4272.50866150392, 4301.601734629655, 2947.778916581265, 3330.550375699274, 3505.3690335739557, 3825.48355971943, 3854.036490877281, 4164.479440069991, 4188.528768026878, 4651.37206477128, 4848.636352273594, 4980.452150263468, 4217.894552636178, 4553.54464553542, 4606.861794146181, 4862.6839255448285, 4883.388481801033, 4627.95372046282, 5392.838214853561, 5452.754626041838, 5463.7814487420455, 5476.67139971603, 4110.273685052285, 4338.392625032498, 4345.565443455661, 4363.0, 4373.075846627474, 4606.470255920612, 4701.952980470186, 5157.535821776059, 5414.401087477993, 5441.401445840955, 3767.1335592813807, 4192.643625291823, 4263.802148388709, 4354.433923589913, 4426.671998400467, 4182.330827067654, 4265.0830071534765, 4455.710378825366, 4476.977615111904, 4489.842855500107, 3976.588311149558, 4278.957210427856, 4792.424909010941, 4844.15227335222, 4920.6309526785635, 4640.65195110368, 4721.898755053968, 4736.171170045228, 4830.3479030331155, 5006.824761133343, 1457.5408746244898, 1551.6508785523167, 1561.2740075864551, 1576.929038193284, 1584.5721655153275, 1044.1959691037857, 1092.5356723392686, 1108.728361551425, 1162.8430161928031, 1176.452949378526, 4353.69524133314, 4612.907741845212, 4634.559716826896, 4863.586595139453, 5055.51972562606, 5312.804618960703, 5492.450754924555, 5598.216249725042, 5645.633033852869, 5645.689487078168, 4346.479042155543, 4634.559716826896, 4870.172070747982, 4878.536539028786, 5114.488551144856, 2494.064725727852, 2842.5167721487396, 2908.258394109531, 2912.8834846606323, 2933.841132752551, 5335.519803217729, 5514.884090488861, 5527.462424048612, 5901.668840807857, 5916.26046744157, 4913.778879950411, 5192.0, 5278.498542638443, 5505.43095217861, 5907.320658124285, 5194.714290714047, 5469.808556700496, 5552.639078459887, 5642.150713889541, 5767.336756272998, 5380.43505431924, 5579.776808927598, 5632.802851900164, 5645.26204790668, 5674.971625141848, 4417.53615870329, 4648.581627653527, 5220.843374698765, 5318.150034961512, 5496.972515137559, 5085.415177254663, 5143.974280128576, 5615.859603509945, 5813.79651712188, 5819.388964158702, 4149.771762552968, 4395.516493185811, 4433.312836510346, 4469.486009108864, 4508.63930885535, 4404.625606823456, 5100.948990510047, 5416.404195538409, 5571.804986825442, 5631.380548139783, 4344.978275108604, 5370.704611246419, 5375.81184658535, 5382.877930800205, 5421.8041693114355, 4669.089527542097, 5223.425423203368, 5379.821172425174, 5384.5192572043, 5437.592180586393, 4574.5425457453985, 4961.801527938842, 5192.584593232608, 5256.526912577886, 5278.261043453843, 4605.815767369268, 5348.812791552277, 5410.10646273801, 5435.320584926884, 5447.700376479332, 4963.362090510195, 5068.6430809673675, 5158.329417175743, 5327.280817089643, 5349.251104845247], "value": 4106.2549532096455, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.5565956767327, 721.0698199322874, 742.3393180069736, 774.7791879314397, 801.214809486703, 13404.798928016087, 14045.15729056256, 15150.696986060273, 16149.354025838953, 16230.350785968576, 14754.63113422165, 14916.0, 14941.327640256188, 15381.846181538182, 15816.288267027983, 893.7497500699815, 932.6549176804558, 952.3619175152664, 956.3687965942479, 961.1974001708581, 912.3659056955419, 945.678469320431, 947.9620815167385, 950.6054987180312, 950.6482601437477, 992.8212921674095, 993.2500961773851, 1000.5297510170209, 1001.0490034467249, 1001.3391161833191, 993.0913214408807, 993.1756641987133, 995.1093771074848, 996.6212839121124, 999.0708640963901, 740.1118657610834, 740.3891789273871, 741.1809950005274, 741.3068780690072, 743.0080842075836, 15962.483564061396, 15989.120598367084, 16021.0, 16160.191990400433, 16195.757063643938, 13618.046736728433, 13632.591022269347, 14006.369713362923, 14122.082064665763, 14304.213268270241, 11822.547563828146, 11914.582989595323, 11925.0, 12020.459079341363, 12032.0, 3577.3918433866306, 3608.621094785036, 3718.5537735471644, 3763.6989040876665, 3835.983464381941, 108296.45851770691, 115483.0, 115849.0, 116710.0, 116819.0, 10575.735606609896, 10680.57178818672, 10836.187285953585, 10875.03997441638, 11045.779084418427, 22574.435639109153, 22766.65850012251, 22785.20251791179, 22831.20090796814, 22871.0, 7701.960235368266, 7711.0, 7776.339011184113, 7863.882041769378, 7869.0, 3761.6614504694708, 4292.699511034198, 4316.072044510445, 4344.23975804233, 4345.695801293942, 3217.5656286401504, 3309.271960168759, 3313.337332533503, 3316.718078963267, 3321.4353559894876, 543.3045701502084, 611.6788685939918, 636.7484843486797, 642.6561789442626, 643.8165122939939, 658.9110470086573, 668.3550373889124, 691.560858854631, 702.2626242445514, 730.5434103685196, 9893.050798374257, 10151.796964060677, 10274.589016439259, 10410.479476026168, 10410.739731506621, 623.1400667079402, 627.2170587186137, 652.2205963873126, 656.1207981304979, 668.3683918696785, 4867.58625516835, 4984.127777638899, 4997.350344455271, 5012.072766538202, 5024.171011783015, 73985.0, 80472.59763701144, 89423.0, 105140.0, 111838.44080779544, 56440.717796411576, 77477.11858498766, 79584.20415795768, 79958.6002069986, 81460.19689170932, 67935.3206467929, 68952.0, 69216.0, 71054.0, 71252.64373678098, 9632.229421646392, 9923.45421001823, 9924.40453572788, 10067.295289329677, 10554.577816887238, 35900.461493078146, 37759.67841125657, 47751.76124119357, 49234.753826232074, 51460.0, 50529.74735126301, 58834.0, 69352.30647693458, 69864.0, 69944.65027675033, 12347.567835126087, 12399.690007749878, 12470.688232794253, 12479.438425270702, 13076.869231307564, 14246.857531424554, 14288.857111428753, 14316.47790716211, 14744.115353078849, 14747.336369863597, 12806.295653739135, 12859.614211573667, 13185.934070329587, 13217.537386191438, 13616.795748063589, 9948.0, 12328.445219965302, 12355.876441235472, 12754.170978886343, 12907.935460322638, 12011.459484323052, 12939.870601294244, 12958.22250664963, 13105.934470327587, 13501.729965400833, 8942.642294308154, 9182.0, 9389.624415023323, 9396.201322887358, 9417.717468475958, 9690.903090969001, 9719.780684936248, 11019.338839669645, 11207.383593902088, 11269.154813388865], "value": 7437.09606813598, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1441.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 114.0, 115.0, 112.0, 115.0, 114.0, 116.0, 114.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 109.0, 147.0, 112.0, 110.0, 107.0, 109.0, 128.0, 104.0], "value": 118.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 185.0, 185.0, 186.0, 184.0, 182.0, 183.0, 200.0, 162.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 61.0, 62.0, 90.0, 62.0, 62.0, 62.0, 66.0, 67.0, 49.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 87.0, 68.0, 70.0, 69.0, 83.0, 70.0, 69.0, 83.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 86.0, 87.0, 86.0, 86.0, 86.0, 86.0, 86.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 94.0, 132.0, 150.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0], "value": 106.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 86.0, 84.0, 83.0, 86.0, 86.0, 85.0, 99.0, 129.0], "value": 90.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 59.0, 59.0, 61.0, 63.0, 65.0, 65.0, 64.0, 63.0, 63.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 70.0, 65.0, 62.0, 61.0, 58.0, 58.0, 59.0, 58.0, 59.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 83.0, 83.0, 84.0, 90.0, 102.0, 104.0, 106.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 155.0, 138.0, 147.0, 154.0, 157.0, 149.0, 211.0, 183.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 171.0, 164.0, 167.0, 164.0, 165.0, 163.0, 163.0, 165.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 65.0, 65.0, 67.0, 65.0, 64.0, 64.0, 64.0], "value": 64.9, "unit": "score"}]}]} 07:43:28 INFO - Return code: 0 07:43:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:43:29 ERROR - # TBPL SUCCESS # 07:43:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:43:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:43:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:43:29 INFO - 2017-04-18 07:43:29.086 screenresolution[2723:34604] starting screenresolution argv=screenresolution get 07:43:29 INFO - 2017-04-18 07:43:29.088 screenresolution[2723:34604] Display 0: 1600x1200x32@60 07:43:29 INFO - 2017-04-18 07:43:29.095 screenresolution[2724:34606] starting screenresolution argv=screenresolution list 07:43:29 INFO - Available Modes on Display 0 07:43:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:43:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:43:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:43:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:43:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:43:29 INFO - Intel Iris: 07:43:29 INFO - Chipset Model: Intel Iris 07:43:29 INFO - Type: GPU 07:43:29 INFO - Bus: Built-In 07:43:29 INFO - VRAM (Dynamic, Max): 1536 MB 07:43:29 INFO - Vendor: Intel (0x8086) 07:43:29 INFO - Device ID: 0x0a2e 07:43:29 INFO - Revision ID: 0x0009 07:43:29 INFO - Displays: 07:43:29 INFO - Display: 07:43:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:43:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:43:29 INFO - Display Serial Number: 1600x1200 60 07:43:29 INFO - Main Display: Yes 07:43:29 INFO - Mirror: Off 07:43:29 INFO - Online: Yes 07:43:29 INFO - Rotation: Supported 07:43:29 INFO - Return code: 0 07:43:29 INFO - Running post-action listener: _resource_record_post_action 07:43:29 INFO - [mozharness: 2017-04-18 14:43:29.334006Z] Finished run-tests step (success) 07:43:29 INFO - Running post-run listener: _resource_record_post_run 07:43:29 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 11662848; Write bytes: 517666304; Read time: 175; Write time: 1717 07:43:29 INFO - TinderboxPrint: CPU usage
53.8% 07:43:29 INFO - TinderboxPrint: I/O read bytes / time
11,662,848 / 175 07:43:29 INFO - TinderboxPrint: I/O write bytes / time
517,666,304 / 1,717 07:43:29 INFO - TinderboxPrint: CPU idle
2,215.9 (46.1%) 07:43:29 INFO - TinderboxPrint: CPU system
959.4 (20.0%) 07:43:29 INFO - TinderboxPrint: CPU user
1,626.3 (33.9%) 07:43:29 INFO - TinderboxPrint: Swap in / out
212,697,088 / 0 07:43:29 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203657728; Write bytes: 199376896; Read time: 16036; Write time: 387 07:43:29 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 11662336; Write bytes: 307447296; Read time: 173; Write time: 1299 07:43:29 INFO - Running post-run listener: _upload_blobber_files 07:43:29 INFO - Blob upload gear active. 07:43:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:43:29 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 07:43:29 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'] 07:43:29 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 07:43:30 INFO - (blobuploader) - INFO - Open directory for files ... 07:43:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:43:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:43:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:43:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:43:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:43:30 INFO - (blobuploader) - INFO - Done attempting. 07:43:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:43:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:43:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:43:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:43:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:43:31 INFO - (blobuploader) - INFO - Done attempting. 07:43:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:43:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:43:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:43:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:43:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:43:32 INFO - (blobuploader) - INFO - Done attempting. 07:43:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:43:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:43:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:43:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:43:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:43:35 INFO - (blobuploader) - INFO - Done attempting. 07:43:35 INFO - (blobuploader) - INFO - Iteration through files over. 07:43:35 INFO - Return code: 0 07:43:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:43:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:43:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8099b858f8624e5bd4d21a4e60d422f51330f53302ea31f5043466e736682a3485d3a453233f3c475059a3fc2d4b16b500a937edbbffd25d3b0c224f1094c41c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e33107ff1c0a469bb4c3ad6dc9f0d229c393d48ecd396cbbf5f8fa687ee0610a365aed7fb306c3404b5f89eff8046d9a321eafe1a3df879813247bdc8471c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12c96155d83355b647c428def6f1d2554597af43e6b4eabd74125c8216b666425aa99345daa11ce65065ee962ec04c60c87b784bcffa876a2857b094ac745d80", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b523b884a8db5f72aa3acf3afbebc3f9098ebc85b2a6b306873e05b8f0905e261a786e51a6f757c0002bebe05cfd5504529066a56869e376de4cfc17e9e254f4"} 07:43:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:43:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:43:35 INFO - Contents: 07:43:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8099b858f8624e5bd4d21a4e60d422f51330f53302ea31f5043466e736682a3485d3a453233f3c475059a3fc2d4b16b500a937edbbffd25d3b0c224f1094c41c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e33107ff1c0a469bb4c3ad6dc9f0d229c393d48ecd396cbbf5f8fa687ee0610a365aed7fb306c3404b5f89eff8046d9a321eafe1a3df879813247bdc8471c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12c96155d83355b647c428def6f1d2554597af43e6b4eabd74125c8216b666425aa99345daa11ce65065ee962ec04c60c87b784bcffa876a2857b094ac745d80", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b523b884a8db5f72aa3acf3afbebc3f9098ebc85b2a6b306873e05b8f0905e261a786e51a6f757c0002bebe05cfd5504529066a56869e376de4cfc17e9e254f4"} 07:43:35 INFO - Running post-run listener: copy_logs_to_upload_dir 07:43:35 INFO - Copying logs to upload dir... 07:43:35 INFO - mkdir: /builds/slave/test/build/upload/logs 07:43:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.232712 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-18 07:43:35.101784) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 07:43:35.105637) ========= 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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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/8099b858f8624e5bd4d21a4e60d422f51330f53302ea31f5043466e736682a3485d3a453233f3c475059a3fc2d4b16b500a937edbbffd25d3b0c224f1094c41c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e33107ff1c0a469bb4c3ad6dc9f0d229c393d48ecd396cbbf5f8fa687ee0610a365aed7fb306c3404b5f89eff8046d9a321eafe1a3df879813247bdc8471c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12c96155d83355b647c428def6f1d2554597af43e6b4eabd74125c8216b666425aa99345daa11ce65065ee962ec04c60c87b784bcffa876a2857b094ac745d80", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b523b884a8db5f72aa3acf3afbebc3f9098ebc85b2a6b306873e05b8f0905e261a786e51a6f757c0002bebe05cfd5504529066a56869e376de4cfc17e9e254f4"} build_url:https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011207 build_url: 'https://queue.taskcluster.net/v1/task/fSwzWM53TSO1CLbh41rPbQ/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/8099b858f8624e5bd4d21a4e60d422f51330f53302ea31f5043466e736682a3485d3a453233f3c475059a3fc2d4b16b500a937edbbffd25d3b0c224f1094c41c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e33107ff1c0a469bb4c3ad6dc9f0d229c393d48ecd396cbbf5f8fa687ee0610a365aed7fb306c3404b5f89eff8046d9a321eafe1a3df879813247bdc8471c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12c96155d83355b647c428def6f1d2554597af43e6b4eabd74125c8216b666425aa99345daa11ce65065ee962ec04c60c87b784bcffa876a2857b094ac745d80", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b523b884a8db5f72aa3acf3afbebc3f9098ebc85b2a6b306873e05b8f0905e261a786e51a6f757c0002bebe05cfd5504529066a56869e376de4cfc17e9e254f4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 07:43:35.140290) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 07:43:35.140673) ========= 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.ufIvsNJnKk/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.3bwyWUWs9b/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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.005102 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 07:43:35.188917) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 07:43:35.189238) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 07:43:35.189556) ========= ========= Total master_lag: 0.18 =========