builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0066 starttime: 1487156395.51 results: success (0) buildid: 20170209170211 builduid: eadb590b78a6436a8ffabcb183f51b85 revision: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:55.515263) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:55.515702) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:55.515978) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:55.540963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:55.541358) ========= 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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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-02-15 02:59:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 147M=0s 2017-02-15 02:59:55 (147 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153813 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:55.718180) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:55.718491) ========= 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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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.021095 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:55.766917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-15 02:59:55.767270) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 25a94c1047e793ef096d8556fa3c26dd72bd37d7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 25a94c1047e793ef096d8556fa3c26dd72bd37d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pmtEAZDL9J/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.p6z1PlBRTG/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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-02-15 02:59:55,844 truncating revision to first 12 chars 2017-02-15 02:59:55,844 Setting DEBUG logging. 2017-02-15 02:59:55,844 attempt 1/10 2017-02-15 02:59:55,844 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/25a94c1047e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-15 02:59:57,163 unpacking tar archive at: mozilla-central-25a94c1047e7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.585978 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-15 02:59:57.376876) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:57.377230) ========= script_repo_revision: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:57.377685) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:57.377951) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 02:59:57.397799) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-15 02:59:57.398208) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pmtEAZDL9J/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.p6z1PlBRTG/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 02:59:57 INFO - MultiFileLogger online at 20170215 02:59:57 in /builds/slave/test 02:59:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:59:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:59:57 INFO - {'append_to_log': False, 02:59:57 INFO - 'base_work_dir': '/builds/slave/test', 02:59:57 INFO - 'blob_upload_branch': 'Firefox', 02:59:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:59:57 INFO - 'branch': 'Firefox', 02:59:57 INFO - 'buildbot_json_path': 'buildprops.json', 02:59:57 INFO - 'config_files': ('talos/mac_config.py',), 02:59:57 INFO - 'default_actions': ('clobber', 02:59:57 INFO - 'read-buildbot-config', 02:59:57 INFO - 'download-and-extract', 02:59:57 INFO - 'populate-webroot', 02:59:57 INFO - 'create-virtualenv', 02:59:57 INFO - 'install', 02:59:57 INFO - 'run-tests'), 02:59:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:59:57 INFO - 'download_minidump_stackwalk': True, 02:59:57 INFO - 'download_symbols': 'ondemand', 02:59:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:59:57 INFO - 'tooltool.py': '/tools/tooltool.py', 02:59:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:59:57 INFO - '/tools/misc-python/virtualenv.py')}, 02:59:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:59:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:59:57 INFO - 'installer_path': 'installer.exe', 02:59:57 INFO - 'log_level': 'info', 02:59:57 INFO - 'log_name': 'talos', 02:59:57 INFO - 'log_to_console': True, 02:59:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:59:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:59:57 INFO - 'opt_config_files': (), 02:59:57 INFO - 'pip_index': False, 02:59:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:59:57 INFO - 'cmd': ('bash', 02:59:57 INFO - '-c', 02:59:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:59:57 INFO - 'enabled': True, 02:59:57 INFO - 'halt_on_failure': False, 02:59:57 INFO - 'name': 'check_screen_resolution'},), 02:59:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:59:57 INFO - 'cmd': ('bash', 02:59:57 INFO - '-c', 02:59:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:59:57 INFO - 'enabled': True, 02:59:57 INFO - 'halt_on_failure': False, 02:59:57 INFO - 'name': 'check_screen_resolution'},), 02:59:57 INFO - 'run_cmd_checks_enabled': True, 02:59:57 INFO - 'sps_profile': False, 02:59:57 INFO - 'sps_profile_interval': 0, 02:59:57 INFO - 'suite': 'dromaeojs', 02:59:57 INFO - 'system_bits': '32', 02:59:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:59:57 INFO - 'title': 't-yosemite-r7-0066', 02:59:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:59:57 INFO - 'use_talos_json': True, 02:59:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:59:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:59:57 INFO - 'work_dir': 'build'} 02:59:57 INFO - [mozharness: 2017-02-15 10:59:57.594931Z] Running clobber step. 02:59:57 INFO - Running pre-action listener: _resource_record_pre_action 02:59:57 INFO - Running main action method: clobber 02:59:57 INFO - rmtree: /builds/slave/test/build 02:59:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:59:59 INFO - Running post-action listener: _resource_record_post_action 02:59:59 INFO - [mozharness: 2017-02-15 10:59:59.363923Z] Finished clobber step (success) 02:59:59 INFO - [mozharness: 2017-02-15 10:59:59.364103Z] Running read-buildbot-config step. 02:59:59 INFO - Running pre-action listener: _resource_record_pre_action 02:59:59 INFO - Running main action method: read_buildbot_config 02:59:59 INFO - Using buildbot properties: 02:59:59 INFO - { 02:59:59 INFO - "project": "", 02:59:59 INFO - "product": "firefox", 02:59:59 INFO - "script_repo_revision": "production", 02:59:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:59:59 INFO - "repository": "", 02:59:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:59:59 INFO - "buildid": "20170209170211", 02:59:59 INFO - "pgo_build": "False", 02:59:59 INFO - "basedir": "/builds/slave/test", 02:59:59 INFO - "buildnumber": 37, 02:59:59 INFO - "slavename": "t-yosemite-r7-0066", 02:59:59 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:59:59 INFO - "platform": "yosemite_r7", 02:59:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:59:59 INFO - "branch": "mozilla-central", 02:59:59 INFO - "repo_path": "mozilla-central", 02:59:59 INFO - "revision": "25a94c1047e793ef096d8556fa3c26dd72bd37d7", 02:59:59 INFO - "stage_platform": "macosx64", 02:59:59 INFO - "builduid": "eadb590b78a6436a8ffabcb183f51b85", 02:59:59 INFO - "slavebuilddir": "test" 02:59:59 INFO - } 02:59:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 02:59:59 INFO - Running post-action listener: _resource_record_post_action 02:59:59 INFO - [mozharness: 2017-02-15 10:59:59.367510Z] Finished read-buildbot-config step (success) 02:59:59 INFO - [mozharness: 2017-02-15 10:59:59.367631Z] Running download-and-extract step. 02:59:59 INFO - Running pre-action listener: _resource_record_pre_action 02:59:59 INFO - Running main action method: download_and_extract 02:59:59 INFO - mkdir: /builds/slave/test/build/tests 02:59: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')]} 02:59:59 INFO - trying https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 02:59:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 02:59:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 03:00:00 INFO - Downloaded 1199 bytes. 03:00:00 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 03:00:00 INFO - Using the following test package requirements: 03:00:00 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:00:00 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:00:00 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 03:00:00 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:00:00 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 03:00:00 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:00:00 INFO - u'jsshell-mac.zip'], 03:00:00 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:00:00 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 03:00:00 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:00:00 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:00:00 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 03:00:00 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:00:00 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 03:00:00 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:00:00 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 03:00:00 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:00:00 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 03:00:00 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:00:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:00:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:00:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:00:02 INFO - Expected file size: 34949027 03:00:02 INFO - Obtained file size: 34949027 03:00:08 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:00:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:00:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:00:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:00:10 INFO - Expected file size: 34949027 03:00:10 INFO - Obtained file size: 34949027 03:00:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 03:00:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:00:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 03:00:17 INFO - Expected file size: 14403454 03:00:17 INFO - Obtained file size: 14403454 03:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:17 INFO - trying https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:00:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 03:00:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 03:00:19 INFO - Downloaded 70348678 bytes. 03:00:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:00:19 INFO - mkdir: /builds/slave/test/properties 03:00:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:00:19 INFO - Writing to file /builds/slave/test/properties/build_url 03:00:19 INFO - Contents: 03:00:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:00:20 INFO - Running post-action listener: _resource_record_post_action 03:00:20 INFO - Running post-action listener: set_extra_try_arguments 03:00:20 INFO - [mozharness: 2017-02-15 11:00:20.661674Z] Finished download-and-extract step (success) 03:00:20 INFO - [mozharness: 2017-02-15 11:00:20.661827Z] Running populate-webroot step. 03:00:20 INFO - Running pre-action listener: _resource_record_pre_action 03:00:20 INFO - Running main action method: populate_webroot 03:00:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:00:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:00:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:00:20 INFO - '--tppagecycles', 03:00:20 INFO - '7'], 03:00:20 INFO - 'tests': ['tsvgm']}}, 03:00:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:00:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:00:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:00:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:00:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:00:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:00:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:00:20 INFO - 'talos_options': ['--disable-e10s'], 03:00:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:00:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:00:20 INFO - 'talos_options': ['--disable-e10s'], 03:00:20 INFO - 'tests': ['damp', 'tps']}, 03:00:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:00:20 INFO - 'tests': ['damp', 'tps']}, 03:00:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:00:20 INFO - 'tests': ['dromaeo_dom']}, 03:00:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:00:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:00:20 INFO - 'tests': ['basic_compositor_video']}, 03:00:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:00:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:00:20 INFO - 'tests': ['a11yr', 03:00:20 INFO - 'ts_paint', 03:00:20 INFO - 'tpaint', 03:00:20 INFO - 'sessionrestore', 03:00:20 INFO - 'sessionrestore_no_auto_restore', 03:00:20 INFO - 'tabpaint']}, 03:00:20 INFO - 'other-e10s': {'tests': ['a11yr', 03:00:20 INFO - 'ts_paint', 03:00:20 INFO - 'tpaint', 03:00:20 INFO - 'sessionrestore', 03:00:20 INFO - 'sessionrestore_no_auto_restore', 03:00:20 INFO - 'tabpaint']}, 03:00:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:00:20 INFO - 'ts_paint', 03:00:20 INFO - 'tpaint', 03:00:20 INFO - 'sessionrestore', 03:00:20 INFO - 'sessionrestore_no_auto_restore', 03:00:20 INFO - 'tabpaint']}, 03:00:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:00:20 INFO - 'ts_paint', 03:00:20 INFO - 'tpaint', 03:00:20 INFO - 'sessionrestore', 03:00:20 INFO - 'sessionrestore_no_auto_restore', 03:00:20 INFO - 'tabpaint']}, 03:00:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:00:20 INFO - 'tests': ['a11yr', 03:00:20 INFO - 'ts_paint', 03:00:20 INFO - 'tpaint', 03:00:20 INFO - 'sessionrestore', 03:00:20 INFO - 'sessionrestore_no_auto_restore', 03:00:20 INFO - 'tabpaint']}, 03:00:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:00:20 INFO - 'tests': ['a11yr', 03:00:20 INFO - 'ts_paint', 03:00:20 INFO - 'tpaint', 03:00:20 INFO - 'sessionrestore', 03:00:20 INFO - 'sessionrestore_no_auto_restore', 03:00:20 INFO - 'tabpaint']}, 03:00:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:00:20 INFO - 'tests': ['tsvgx', 03:00:20 INFO - 'tsvgr_opacity', 03:00:20 INFO - 'tart', 03:00:20 INFO - 'tscrollx', 03:00:20 INFO - 'cart', 03:00:20 INFO - 'tsvg_static']}, 03:00:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:00:20 INFO - 'tsvgr_opacity', 03:00:20 INFO - 'tart', 03:00:20 INFO - 'tscrollx', 03:00:20 INFO - 'cart', 03:00:20 INFO - 'tsvg_static']}, 03:00:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:00:20 INFO - 'talos_options': ['--disable-e10s'], 03:00:20 INFO - 'tests': ['tp5o']}, 03:00:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:00:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:00:20 INFO - 'talos_options': ['--disable-e10s', 03:00:20 INFO - '--xperf_path', 03:00:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:20 INFO - 'tests': ['tp5n']}, 03:00:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:00:20 INFO - 'talos_options': ['--xperf_path', 03:00:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:20 INFO - 'tests': ['tp5n']}}, 03:00:20 INFO - 'talos.zip': {'path': '', 03:00:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:00:20 INFO - Running post-action listener: _resource_record_post_action 03:00:20 INFO - [mozharness: 2017-02-15 11:00:20.680581Z] Finished populate-webroot step (success) 03:00:20 INFO - [mozharness: 2017-02-15 11:00:20.680717Z] Running create-virtualenv step. 03:00:20 INFO - Running pre-action listener: _resource_record_pre_action 03:00:20 INFO - Running main action method: create_virtualenv 03:00:20 INFO - Creating virtualenv /builds/slave/test/build/venv 03:00:20 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 03:00:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:00:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:00:20 INFO - Using real prefix '/tools/python27' 03:00:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:00:21 INFO - Installing distribute.............................................................................................................................................................................................done. 03:00:24 INFO - Installing pip.................done. 03:00:24 INFO - Return code: 0 03:00:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:00:24 INFO - Reading from file tmpfile_stdout 03:00:24 INFO - Output received: 03:00:24 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) 03:00:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:00:24 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')]} 03:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:24 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')]} 03:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:24 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 0x10f566b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f569768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3bc86880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f344d30>, '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 0x10f26bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f339be8>, '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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 03:00:24 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 03:00:24 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 03:00:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pmtEAZDL9J/Render', 03:00:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:24 INFO - 'HOME': '/Users/cltbld', 03:00:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:24 INFO - 'LOGNAME': 'cltbld', 03:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:24 INFO - 'MOZ_NO_REMOTE': '1', 03:00:24 INFO - 'NO_EM_RESTART': '1', 03:00:24 INFO - 'PAGER': '/bin/cat', 03:00:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:24 INFO - 'PWD': '/builds/slave/test', 03:00:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:24 INFO - 'SHELL': '/bin/bash', 03:00:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6z1PlBRTG/Listeners', 03:00:24 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 03:00:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:24 INFO - 'USER': 'cltbld', 03:00:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:24 INFO - 'XPC_FLAGS': '0x0', 03:00:24 INFO - 'XPC_SERVICE_NAME': '0', 03:00:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:24 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 03:00:24 INFO - Cleaning up... 03:00:24 INFO - Return code: 0 03:00:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:00:24 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')]} 03:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:24 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')]} 03:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:24 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 0x10f566b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f569768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3bc86880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f344d30>, '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 0x10f26bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f339be8>, '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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 03:00:24 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 03:00:24 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 03:00:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pmtEAZDL9J/Render', 03:00:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:24 INFO - 'HOME': '/Users/cltbld', 03:00:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:24 INFO - 'LOGNAME': 'cltbld', 03:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:24 INFO - 'MOZ_NO_REMOTE': '1', 03:00:24 INFO - 'NO_EM_RESTART': '1', 03:00:24 INFO - 'PAGER': '/bin/cat', 03:00:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:24 INFO - 'PWD': '/builds/slave/test', 03:00:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:24 INFO - 'SHELL': '/bin/bash', 03:00:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6z1PlBRTG/Listeners', 03:00:24 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 03:00:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:24 INFO - 'USER': 'cltbld', 03:00:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:24 INFO - 'XPC_FLAGS': '0x0', 03:00:24 INFO - 'XPC_SERVICE_NAME': '0', 03:00:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:25 INFO - Downloading/unpacking psutil>=3.1.1 03:00:25 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 03:00:25 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 03:00:25 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 03:00:25 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 03:00:25 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 03:00:25 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 03:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:00:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:28 INFO - Installing collected packages: psutil 03:00:28 INFO - Running setup.py install for psutil 03:00:28 INFO - building 'psutil._psutil_osx' extension 03:00:28 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 03:00:29 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 03:00:29 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 03:00:29 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 03:00:29 INFO - building 'psutil._psutil_posix' extension 03:00:29 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 03:00:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:00:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:00:29 INFO - ^ 03:00:29 INFO - 1 warning generated. 03:00:29 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 03:00:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:29 INFO - Successfully installed psutil 03:00:29 INFO - Cleaning up... 03:00:29 INFO - Return code: 0 03:00:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:00:29 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')]} 03:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:29 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')]} 03:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:29 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 0x10f566b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f569768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3bc86880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f344d30>, '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 0x10f26bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f339be8>, '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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 03:00:29 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 03:00:29 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 03:00:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pmtEAZDL9J/Render', 03:00:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:29 INFO - 'HOME': '/Users/cltbld', 03:00:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:29 INFO - 'LOGNAME': 'cltbld', 03:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:29 INFO - 'MOZ_NO_REMOTE': '1', 03:00:29 INFO - 'NO_EM_RESTART': '1', 03:00:29 INFO - 'PAGER': '/bin/cat', 03:00:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:29 INFO - 'PWD': '/builds/slave/test', 03:00:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:29 INFO - 'SHELL': '/bin/bash', 03:00:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6z1PlBRTG/Listeners', 03:00:29 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 03:00:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:29 INFO - 'USER': 'cltbld', 03:00:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:29 INFO - 'XPC_FLAGS': '0x0', 03:00:29 INFO - 'XPC_SERVICE_NAME': '0', 03:00:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:00:29 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 03:00:29 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 03:00:29 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 03:00:29 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 03:00:29 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 03:00:29 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 03:00:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:00:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:00:32 INFO - Installing collected packages: mozsystemmonitor 03:00:32 INFO - Running setup.py install for mozsystemmonitor 03:00:32 INFO - Successfully installed mozsystemmonitor 03:00:32 INFO - Cleaning up... 03:00:32 INFO - Return code: 0 03:00:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:00:32 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')]} 03:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:32 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')]} 03:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:32 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 0x10f566b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f569768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3bc86880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f344d30>, '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 0x10f26bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f339be8>, '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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 03:00:32 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 03:00:32 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 03:00:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pmtEAZDL9J/Render', 03:00:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:32 INFO - 'HOME': '/Users/cltbld', 03:00:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:32 INFO - 'LOGNAME': 'cltbld', 03:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:32 INFO - 'MOZ_NO_REMOTE': '1', 03:00:32 INFO - 'NO_EM_RESTART': '1', 03:00:32 INFO - 'PAGER': '/bin/cat', 03:00:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:32 INFO - 'PWD': '/builds/slave/test', 03:00:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:32 INFO - 'SHELL': '/bin/bash', 03:00:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6z1PlBRTG/Listeners', 03:00:32 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 03:00:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:32 INFO - 'USER': 'cltbld', 03:00:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:32 INFO - 'XPC_FLAGS': '0x0', 03:00:32 INFO - 'XPC_SERVICE_NAME': '0', 03:00:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:32 INFO - Downloading/unpacking jsonschema==2.5.1 03:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:00:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:36 INFO - Downloading functools32-3.2.3-2.tar.gz 03:00:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:00:36 INFO - warning: no files found matching '*.txt' 03:00:36 INFO - no previously-included directories found matching 'build' 03:00:36 INFO - no previously-included directories found matching 'dist' 03:00:36 INFO - no previously-included directories found matching '.git*' 03:00:36 INFO - Installing collected packages: jsonschema, functools32 03:00:36 INFO - Running setup.py install for functools32 03:00:36 INFO - warning: no files found matching '*.txt' 03:00:36 INFO - no previously-included directories found matching 'build' 03:00:36 INFO - no previously-included directories found matching 'dist' 03:00:36 INFO - no previously-included directories found matching '.git*' 03:00:36 INFO - Successfully installed jsonschema functools32 03:00:36 INFO - Cleaning up... 03:00:36 INFO - Return code: 0 03:00:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:36 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 0x10f566b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f569768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3bc86880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f344d30>, '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 0x10f26bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f339be8>, '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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 03:00:36 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 03:00:36 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 03:00:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pmtEAZDL9J/Render', 03:00:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:36 INFO - 'HOME': '/Users/cltbld', 03:00:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:36 INFO - 'LOGNAME': 'cltbld', 03:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:36 INFO - 'MOZ_NO_REMOTE': '1', 03:00:36 INFO - 'NO_EM_RESTART': '1', 03:00:36 INFO - 'PAGER': '/bin/cat', 03:00:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:36 INFO - 'PWD': '/builds/slave/test', 03:00:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:36 INFO - 'SHELL': '/bin/bash', 03:00:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6z1PlBRTG/Listeners', 03:00:36 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 03:00:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:36 INFO - 'USER': 'cltbld', 03:00:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:36 INFO - 'XPC_FLAGS': '0x0', 03:00:36 INFO - 'XPC_SERVICE_NAME': '0', 03:00:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:00:36 INFO - Cleaning up... 03:00:36 INFO - Return code: 0 03:00:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:36 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 0x10f566b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f569768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3bc86880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f344d30>, '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 0x10f26bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f339be8>, '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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 03:00:36 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 03:00:36 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 03:00:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pmtEAZDL9J/Render', 03:00:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:36 INFO - 'HOME': '/Users/cltbld', 03:00:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:36 INFO - 'LOGNAME': 'cltbld', 03:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:36 INFO - 'MOZ_NO_REMOTE': '1', 03:00:36 INFO - 'NO_EM_RESTART': '1', 03:00:36 INFO - 'PAGER': '/bin/cat', 03:00:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:36 INFO - 'PWD': '/builds/slave/test', 03:00:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:36 INFO - 'SHELL': '/bin/bash', 03:00:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6z1PlBRTG/Listeners', 03:00:36 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 03:00:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:36 INFO - 'USER': 'cltbld', 03:00:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:36 INFO - 'XPC_FLAGS': '0x0', 03:00:36 INFO - 'XPC_SERVICE_NAME': '0', 03:00:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:36 INFO - Downloading/unpacking blobuploader==1.2.4 03:00:36 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 03:00:36 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 03:00:36 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 03:00:36 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 03:00:36 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 03:00:36 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 03:00:39 INFO - Downloading blobuploader-1.2.4.tar.gz 03:00:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:00:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:00:39 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 03:00:39 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 03:00:39 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 03:00:39 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 03:00:39 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 03:00:39 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 03:00:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:00:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:00:40 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 03:00:40 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 03:00:40 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 03:00:40 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 03:00:40 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 03:00:40 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 03:00:40 INFO - Downloading docopt-0.6.1.tar.gz 03:00:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:00:40 INFO - Installing collected packages: blobuploader, requests, docopt 03:00:40 INFO - Running setup.py install for blobuploader 03:00:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:00:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:00:41 INFO - Running setup.py install for requests 03:00:41 INFO - Running setup.py install for docopt 03:00:41 INFO - Successfully installed blobuploader requests docopt 03:00:41 INFO - Cleaning up... 03:00:41 INFO - Return code: 0 03:00:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00:41 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')]} 03:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:41 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')]} 03:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:41 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 0x10f566b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f569768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3bc86880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f344d30>, '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 0x10f26bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f339be8>, '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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 03:00:41 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 03:00:41 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 03:00:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pmtEAZDL9J/Render', 03:00:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:41 INFO - 'HOME': '/Users/cltbld', 03:00:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:41 INFO - 'LOGNAME': 'cltbld', 03:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:41 INFO - 'MOZ_NO_REMOTE': '1', 03:00:41 INFO - 'NO_EM_RESTART': '1', 03:00:41 INFO - 'PAGER': '/bin/cat', 03:00:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:41 INFO - 'PWD': '/builds/slave/test', 03:00:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:41 INFO - 'SHELL': '/bin/bash', 03:00:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6z1PlBRTG/Listeners', 03:00:41 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 03:00:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:41 INFO - 'USER': 'cltbld', 03:00:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:41 INFO - 'XPC_FLAGS': '0x0', 03:00:41 INFO - 'XPC_SERVICE_NAME': '0', 03:00:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:00:42 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-oMPd80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:00:42 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-XsnslQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:00:42 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-XSCetV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:00:42 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-bRE7YD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:00:42 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-0da7Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:00:42 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-JNiNue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:00:42 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-R2MDJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:00:42 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-AR0S1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:00:42 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-7LaVdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:00:42 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-v8YoGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:00:43 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-RgDLIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:00:43 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-ECcnV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:00:43 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-el1xXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:00:43 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-LwYpW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:43 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-tPQOAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:00:43 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-J6oErY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:00:43 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-ekQxjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:00:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:00:43 INFO - Running setup.py install for manifestparser 03:00:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:00:43 INFO - Running setup.py install for mozcrash 03:00:44 INFO - Running setup.py install for mozdebug 03:00:44 INFO - Running setup.py install for mozdevice 03:00:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:00:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:00:44 INFO - Running setup.py install for mozfile 03:00:44 INFO - Running setup.py install for mozhttpd 03:00:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:00:44 INFO - Running setup.py install for mozinfo 03:00:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:00:44 INFO - Running setup.py install for mozInstall 03:00:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:00:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:00:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:00:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:00:44 INFO - Running setup.py install for mozleak 03:00:45 INFO - Running setup.py install for mozlog 03:00:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:00:45 INFO - Running setup.py install for moznetwork 03:00:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:00:45 INFO - Running setup.py install for mozprocess 03:00:45 INFO - Running setup.py install for mozprofile 03:00:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:00:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:00:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:00:45 INFO - Running setup.py install for mozrunner 03:00:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:00:45 INFO - Running setup.py install for mozscreenshot 03:00:46 INFO - Running setup.py install for moztest 03:00:46 INFO - Running setup.py install for mozversion 03:00:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:00:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:00:46 INFO - Cleaning up... 03:00:46 INFO - Return code: 0 03:00:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00:46 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')]} 03:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:46 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')]} 03:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:46 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 0x10f566b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f569768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3bc86880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f344d30>, '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 0x10f26bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f339be8>, '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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 03:00:46 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 03:00:46 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 03:00:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pmtEAZDL9J/Render', 03:00:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:46 INFO - 'HOME': '/Users/cltbld', 03:00:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:46 INFO - 'LOGNAME': 'cltbld', 03:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:46 INFO - 'MOZ_NO_REMOTE': '1', 03:00:46 INFO - 'NO_EM_RESTART': '1', 03:00:46 INFO - 'PAGER': '/bin/cat', 03:00:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:46 INFO - 'PWD': '/builds/slave/test', 03:00:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:46 INFO - 'SHELL': '/bin/bash', 03:00:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6z1PlBRTG/Listeners', 03:00:46 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 03:00:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:46 INFO - 'USER': 'cltbld', 03:00:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:46 INFO - 'XPC_FLAGS': '0x0', 03:00:46 INFO - 'XPC_SERVICE_NAME': '0', 03:00:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:00:46 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-sePJlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:00:46 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)) 03:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:00:46 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-qofYbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:00:46 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)) 03:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:00:46 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-ysxqui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:00:46 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)) 03:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:00:46 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-ZLv3tY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:00:47 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)) 03:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:00:47 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-UKBRA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:00:47 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)) 03:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:00:47 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-X2uuxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:00:47 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)) 03:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:00:47 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-MtdA0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:00:47 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)) 03:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:00:47 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-oH2OcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:00:47 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)) 03:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:00:47 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-j7i_cR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:00:47 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)) 03:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:00:47 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-P2Guxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:00:47 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)) 03:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:00:47 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-QPnhk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:00:47 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)) 03:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:00:47 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-nCiSqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:00:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 03:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:00:47 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-R_sPXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:00:48 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)) 03:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:00:48 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-5WOXYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:00:48 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)) 03:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:48 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-IJWC8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:48 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)) 03:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:00:48 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-MKE_pd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:00:48 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)) 03:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:00:48 INFO - Running setup.py (path:/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/pip-6LAlff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:00:48 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)) 03:00:48 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)) 03:00:48 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)) 03:00:48 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)) 03:00:48 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)) 03:00:48 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)) 03:00:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:51 INFO - Downloading blessings-1.6.tar.gz 03:00:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:00:51 INFO - Installing collected packages: blessings 03:00:51 INFO - Running setup.py install for blessings 03:00:51 INFO - Successfully installed blessings 03:00:51 INFO - Cleaning up... 03:00:51 INFO - Return code: 0 03:00:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:00:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:00:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:00:51 INFO - Reading from file tmpfile_stdout 03:00:51 INFO - Current package versions: 03:00:51 INFO - blessings == 1.6 03:00:51 INFO - blobuploader == 1.2.4 03:00:51 INFO - docopt == 0.6.1 03:00:51 INFO - functools32 == 3.2.3-2 03:00:51 INFO - jsonschema == 2.5.1 03:00:51 INFO - manifestparser == 1.1 03:00:51 INFO - mozInstall == 1.12 03:00:51 INFO - mozcrash == 1.0 03:00:51 INFO - mozdebug == 0.1 03:00:51 INFO - mozdevice == 0.49 03:00:51 INFO - mozfile == 1.2 03:00:51 INFO - mozhttpd == 0.7 03:00:51 INFO - mozinfo == 0.9 03:00:51 INFO - mozleak == 0.1 03:00:51 INFO - mozlog == 3.4 03:00:51 INFO - moznetwork == 0.27 03:00:51 INFO - mozprocess == 0.24 03:00:51 INFO - mozprofile == 0.28 03:00:51 INFO - mozrunner == 6.13 03:00:51 INFO - mozscreenshot == 0.1 03:00:51 INFO - mozsystemmonitor == 0.3 03:00:51 INFO - moztest == 0.8 03:00:51 INFO - mozversion == 1.4 03:00:51 INFO - psutil == 3.1.1 03:00:51 INFO - requests == 1.2.3 03:00:51 INFO - wsgiref == 0.1.2 03:00:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00: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')]} 03:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:52 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 0x10f566b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f569768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3bc86880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f344d30>, '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 0x10f26bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f339be8>, '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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 03:00:52 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 03:00:52 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 03:00:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pmtEAZDL9J/Render', 03:00:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:52 INFO - 'HOME': '/Users/cltbld', 03:00:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:52 INFO - 'LOGNAME': 'cltbld', 03:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:52 INFO - 'MOZ_NO_REMOTE': '1', 03:00:52 INFO - 'NO_EM_RESTART': '1', 03:00:52 INFO - 'PAGER': '/bin/cat', 03:00:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:52 INFO - 'PWD': '/builds/slave/test', 03:00:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:52 INFO - 'SHELL': '/bin/bash', 03:00:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6z1PlBRTG/Listeners', 03:00:52 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 03:00:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:52 INFO - 'USER': 'cltbld', 03:00:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:52 INFO - 'XPC_FLAGS': '0x0', 03:00:52 INFO - 'XPC_SERVICE_NAME': '0', 03:00:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:52 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)) 03:00:52 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)) 03:00:52 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)) 03:00:52 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)) 03:00:52 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)) 03:00:52 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)) 03:00:52 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)) 03:00:52 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)) 03:00:52 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)) 03:00:52 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)) 03:00:52 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)) 03:00:52 INFO - Cleaning up... 03:00:52 INFO - Return code: 0 03:00:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:00: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')]} 03:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:53 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 0x10f566b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f569768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3bc86880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f344d30>, '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 0x10f26bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f339be8>, '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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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 03:00:53 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 03:00:53 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 03:00:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pmtEAZDL9J/Render', 03:00:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:53 INFO - 'HOME': '/Users/cltbld', 03:00:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:53 INFO - 'LOGNAME': 'cltbld', 03:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:53 INFO - 'MOZ_NO_REMOTE': '1', 03:00:53 INFO - 'NO_EM_RESTART': '1', 03:00:53 INFO - 'PAGER': '/bin/cat', 03:00:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:53 INFO - 'PWD': '/builds/slave/test', 03:00:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:53 INFO - 'SHELL': '/bin/bash', 03:00:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6z1PlBRTG/Listeners', 03:00:53 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 03:00:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:53 INFO - 'USER': 'cltbld', 03:00:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:53 INFO - 'XPC_FLAGS': '0x0', 03:00:53 INFO - 'XPC_SERVICE_NAME': '0', 03:00:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:00:53 INFO - Cleaning up... 03:00:53 INFO - Return code: 0 03:00:53 INFO - Running post-action listener: _resource_record_post_action 03:00:53 INFO - Running post-action listener: _start_resource_monitoring 03:00:53 INFO - Starting resource monitoring. 03:00:53 INFO - [mozharness: 2017-02-15 11:00:53.993799Z] Finished create-virtualenv step (success) 03:00:53 INFO - [mozharness: 2017-02-15 11:00:53.994750Z] Running install step. 03:00:53 INFO - Running pre-action listener: _resource_record_pre_action 03:00:53 INFO - Running main action method: install 03:00:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:00:54 INFO - Reading from file tmpfile_stdout 03:00:54 INFO - Detecting whether we're running mozinstall >=1.0... 03:00:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:00:54 INFO - Reading from file tmpfile_stdout 03:00:54 INFO - Output received: 03:00:54 INFO - Usage: mozinstall [options] installer 03:00:54 INFO - Options: 03:00:54 INFO - -h, --help show this help message and exit 03:00:54 INFO - -d DEST, --destination=DEST 03:00:54 INFO - Directory to install application into. [default: 03:00:54 INFO - "/builds/slave/test"] 03:00:54 INFO - --app=APP Application being installed. [default: firefox] 03:00:54 INFO - mkdir: /builds/slave/test/build/application 03:00:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:01:16 INFO - Reading from file tmpfile_stdout 03:01:16 INFO - Output received: 03:01:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:01:16 INFO - Running post-action listener: _resource_record_post_action 03:01:16 INFO - [mozharness: 2017-02-15 11:01:16.695823Z] Finished install step (success) 03:01:16 INFO - [mozharness: 2017-02-15 11:01:16.695972Z] Running run-tests step. 03:01:16 INFO - Running pre-action listener: _resource_record_pre_action 03:01:16 INFO - Running main action method: run_tests 03:01:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:01:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:01:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:01:16 INFO - 2017-02-15 03:01:16.708 screenresolution[1912:20142] starting screenresolution argv=screenresolution get 03:01:16 INFO - 2017-02-15 03:01:16.711 screenresolution[1912:20142] Display 0: 1600x1200x32@60 03:01:16 INFO - 2017-02-15 03:01:16.716 screenresolution[1913:20145] starting screenresolution argv=screenresolution list 03:01:16 INFO - Available Modes on Display 0 03:01:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:01:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:01:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:01:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:01:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:01:16 INFO - Intel Iris: 03:01:16 INFO - Chipset Model: Intel Iris 03:01:16 INFO - Type: GPU 03:01:16 INFO - Bus: Built-In 03:01:16 INFO - VRAM (Dynamic, Max): 1536 MB 03:01:16 INFO - Vendor: Intel (0x8086) 03:01:16 INFO - Device ID: 0x0a2e 03:01:16 INFO - Revision ID: 0x0009 03:01:16 INFO - Displays: 03:01:16 INFO - Display: 03:01:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:01:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:01:16 INFO - Display Serial Number: 1600x1200 60 03:01:16 INFO - Main Display: Yes 03:01:16 INFO - Mirror: Off 03:01:16 INFO - Online: Yes 03:01:16 INFO - Rotation: Supported 03:01:16 INFO - Return code: 0 03:01:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:01:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:01:16 INFO - Python 2.7.3 03:01:16 INFO - Return code: 0 03:01:16 INFO - grabbing minidump binary from tooltool 03:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:16 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 0x10f344d30>, '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 0x10f26bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f339be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:01:16 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 03:01:16 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 03:01:16 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 03:01:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:01:17 INFO - Return code: 0 03:01:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:01:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:01:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:01:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:01:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:01:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--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 03:01:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0066 --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 03:01:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pmtEAZDL9J/Render', 03:01:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:17 INFO - 'HOME': '/Users/cltbld', 03:01:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:17 INFO - 'LOGNAME': 'cltbld', 03:01:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:01:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:01:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:17 INFO - 'MOZ_NO_REMOTE': '1', 03:01:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:01:17 INFO - 'NO_EM_RESTART': '1', 03:01:17 INFO - 'PAGER': '/bin/cat', 03:01:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:17 INFO - 'PWD': '/builds/slave/test', 03:01:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:01:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:17 INFO - 'SHELL': '/bin/bash', 03:01:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6z1PlBRTG/Listeners', 03:01:17 INFO - 'TMPDIR': '/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/', 03:01:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:17 INFO - 'USER': 'cltbld', 03:01:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:17 INFO - 'XPC_FLAGS': '0x0', 03:01:17 INFO - 'XPC_SERVICE_NAME': '0', 03:01:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--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 03:01:17 INFO - mozversion application_buildid: 20170209170211 03:01:17 INFO - mozversion application_changeset: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 03:01:17 INFO - mozversion application_display_name: Nightly 03:01:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:01:17 INFO - mozversion application_name: Firefox 03:01:17 INFO - mozversion application_remotingname: firefox 03:01:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:01:17 INFO - mozversion application_vendor: Mozilla 03:01:17 INFO - mozversion application_version: 54.0a1 03:01:17 INFO - mozversion platform_buildid: 20170209170211 03:01:17 INFO - mozversion platform_changeset: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 03:01:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:01:17 INFO - mozversion platform_version: 54.0a1 03:01:17 INFO - using testdate: 1487156477 03:01:17 INFO - actual date: 1487156477 03:01:17 INFO - starting webserver on 'localhost:55992' 03:01:17 INFO - SUITE-START | Running 2 tests 03:01:17 INFO - TEST-START | dromaeo_css 03:01:17 INFO - operating with platform_type : mac_ 03:01:17 INFO - Initialising browser for dromaeo_css test... 03:01:17 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/tmpiWQc7I/profile http://localhost:55992/getInfo.html) 03:01:19 INFO - PROCESS | 1923 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 03:01:19 INFO - PROCESS | 1923 | __metrics Screen width/height:1600/1200 03:01:19 INFO - PROCESS | 1923 | colorDepth:24 03:01:19 INFO - PROCESS | 1923 | Browser inner width/height: 1024/689 03:01:19 INFO - PROCESS | 1923 | __metrics 03:01:19 INFO - PROCESS | 1923 | 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" 03:01:19 INFO - PROCESS | 1923 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:01:20 INFO - TEST-INFO | 1923: exit 0 03:01:20 INFO - Browser initialized. 03:01:20 INFO - Running cycle 1/1 for dromaeo_css test... 03:01:20 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/tmpiWQc7I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:32 INFO - PROCESS | 1925 | RSS: Main: 236646400 03:01:32 INFO - PROCESS | 1925 | 03:04:36 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:55992/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55992/tests/dromaeo/cssquery-ext.html) 03:04:37 INFO - PROCESS | 1925 | RSS: Main: 340078592 03:04:37 INFO - PROCESS | 1925 | 03:06:22 INFO - PROCESS | 1925 | ************************* 03:06:22 INFO - PROCESS | 1925 | A coding exception was thrown and uncaught in a Task. 03:06:22 INFO - PROCESS | 1925 | 03:06:22 ERROR - PROCESS | 1925 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:06:22 INFO - PROCESS | 1925 | Full stack: 03:06:22 INFO - PROCESS | 1925 | ************************* 03:07:40 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:55992/tests/dromaeo/cssquery-ext.html (next: http://localhost:55992/tests/dromaeo/cssquery-jquery.html) 03:07:41 INFO - PROCESS | 1925 | RSS: Main: 372232192 03:07:41 INFO - PROCESS | 1925 | 03:10:43 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:55992/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55992/tests/dromaeo/cssquery-mootools.html) 03:10:44 INFO - PROCESS | 1925 | RSS: Main: 343597056 03:10:44 INFO - PROCESS | 1925 | 03:13:46 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:55992/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55992/tests/dromaeo/cssquery-prototype.html) 03:13:47 INFO - PROCESS | 1925 | RSS: Main: 340176896 03:13:47 INFO - PROCESS | 1925 | 03:16:50 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:55992/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55992/tests/dromaeo/cssquery-yui.html) 03:16:51 INFO - PROCESS | 1925 | RSS: Main: 331497472 03:16:51 INFO - PROCESS | 1925 | 03:19:54 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:55992/tests/dromaeo/cssquery-yui.html (next: http://localhost:55992/tests/dromaeo/cssquery-dojo.html) 03:19:54 INFO - PROCESS | 1925 | RSS: Main: 505061376 03:19:54 INFO - PROCESS | 1925 | 03:19:54 INFO - PROCESS | 1925 | __start_tp_report 03:19:54 INFO - PROCESS | 1925 | _x_x_mozilla_page_load 03:19:54 INFO - PROCESS | 1925 | _x_x_mozilla_page_load_details 03:19:54 INFO - PROCESS | 1925 | |i|pagename|runs| 03:19:54 INFO - PROCESS | 1925 | |0;dojo.html;2795.762360199384;3284.7700660953733;3292.1275861896593;3325.0191193597916;3335.199552107493;5209.739513024343;5293.841184764454;5496.670199788011;5572.387037425885;5573.693446860421;5350.117230656937;5388.595855310848;5398.676079435237;5406.729663516819;5429.294191755076;4942.233953737167;4970.080535101018;4992.026554821811;5037.647364684473;5059.392872855263;4987.526185012436;5051.5453609175165;5053.242013697956;5061.898762024757;5070.467600901908;4968.70187788732;5074;5075.593952483792;5085.618578606601;5118.232265160237;5070.340855688755;5104.412992505853;5118.948810511922;5128.897422051576;5157.432682404932;4852.029594081163;5017.422996355429;5042.873928151789;5055.342805435306;5060.1903695408555;5340.54605358545;5409.594280428944;5495.258140151069;5500.58745594082;5516.062269414209;5997.070454079625;6022.18700475439;6022.909656355158;6033.396660333975;6045.546584006217;6114.724837382329;6169.475594574466;6198.349173336825;6222.3466536013975;6225;5399.811006614749;5468.835934921998;5569.858179073281;5570.41510641385;5653.9151912721345;6218.81343559694;6234.937650623527;6236.469900058546;6238.563300569007;6248.843778905472;5917.289925209003;5936.970315148396;5940.940590594125;5947.940520594739;5998.610090344201;5207.427183009866;5703.714814259353;5839.737211825478;5947.316058653224;6005.39946005396;5923.585349025613;5959.374265702126;5976.551758618121;5977.970110149508;5989;5755.59710820247;5766.192733017381;5810.157527158538;5915.556333275022;6010.248718910137;4802.447718512346;4825.638077144228;4841.346418233564;4859.927101093486;4865.95134048662;4244.8514301999285;4262.50981137167;4305.892352691208;4350.978245108754;4384.473863136444;5450.8909821803345;5464.3442786865835;5478.369987451494;5493.862653433696;5548.5838562108;6029.366916473795;6031.276246850406;6032.306284777219;6051.515878729779;6052.848678782978;5323.22813192085;5479.5342395896;5496.642718223303;5504.614676972574;5512;5133.25567792668;5182.818601348935;5207.505286997729;5246.160614301693;5261.684298942076;6248.781292654735;6264;6269.122322874892;6271.153394291803;6284.74861005573;5068.442471328225;6074.453299203093;6109.75560977556;6115.969420152871;6120.785772497941;6079.179310792986;6114.175715401237;6117.553376797925;6130.9791012021615;6280.748770049329;5843.912341314799;5934.910976335272;5942.989691752499;5955.047192449188;5976.013957696931;6021;6033.78881739137;6036.758529658764;6042.063480160582;6050.183225264533;5619.297587801525;5631.63394379364;5647.802326918538;5731.914021289767;5792.82621521355;5789.478946894799;5821.155932389865;5851.414858514115;5865.677387743715;5902.085176797603;5511.3937466879415;5636.577256705681;5713.628614140068;5720.3707592165665;5723.914141287801;4464.732116072916;5668.489835914787;5701.230333904869;5745.3105627324985;5766.538676905921;5481.369642491165;5556.277683901067;5717.428257174416;5736;5743.62666426681 03:19:54 INFO - PROCESS | 1925 | |1;ext.html;439.9450068741408;443.19338803377843;462.52822121436117;473.9431068716759;479.522874739634;29355;38081.80959095204;40104;40137.3979390309;41950.58049419505;40916;41405.37891931624;41416;41501;41571;7766.883496747525;9010.59452324647;9210.49342286174;9221.861672074925;9229.538523073854;7791.298783109519;10699.679009629723;10987.505562249678;11026.393548354838;11093.3898635575;10796.190285728562;10848.566057357697;10863.782724345509;10865.51105200264;10930.56277748893;6632.900506492407;7968.800779980489;10882.347059176474;10893.564257429693;10988.560457581727;10524.526396312185;10855.348679079281;10867.402292873889;10883.564657413774;10883.83674244879;38083.61916380828;38296.617033829585;38564;39009;39247.8037609813;3564.41187204111;3609.3640288629595;3618.583862855779;3619.2037751694584;3739.719521035933;26196.345091372677;27619.86190069057;28844.567331490045;29020.27449313763;29172.99848036477;2883.66837813652;2947.4547208766235;3188.5695431116733;3234.87060517579;3236.2556611979376;420286.89856550825;439799;440145;440773;445411;50281.24578131331;51697.224541631906;51713.86289096863;59580.70209648924;60116.69941650264;62707.37292627106;63349;63925.72148557096;67676.66161669258;67844.6607766958;14704.485343012942;14742.484013059491;14861.256937153315;15080.698386032438;15081.170535620646;11168.720781980515;11170.218084734153;11522.308661480338;11596.536138554364;11635.418229088511;16878.324867005427;16979.405720799714;16980.962284525936;16987.687400956944;16999.74500382495;6373.936260637334;6974.197967233833;6987.196472405739;7048.753293634698;7078.469114816409;701.5545128843221;750.771014840468;820.8932838730926;831.2726364431123;851.8722191671299;27885.16344509668;27971.58042629362;28224.576631350545;28796.712032879404;28930.855345723136;982.7395740128871;991;1036.1348274191134;1037.7198156497855;1141.5148561861176;11796;12479.438425270884;14510.056846304957;14891;14900.105993640416;571468;574474;574855.3771693428;575380;575566;55028.449715503135;55277.44722552723;55315.44684553103;55360.16959745607;55482.61293467615;1285.1967520299813;1327.966800829987;1336.1515437696937;1336.2450215627944;1340.1557019077918;26200.73799261983;27151.59272610952;27909.44181116407;27972.16083517498;28040.719592804628;39610.60389396069;40031.1993760129;40061;40080.39879401753;40284;478858;492804;493114;493435;493817;31383;31620.683793161774;37081.44377834281;38632;38713;44127;44639.77680111579;45407.31889021601;45457.318140228585;45964.77017614891;34833.47749783806;35042.649573503935;36023;36180;36403.817980909924;30580.929667461533;31269.530957036117;34899.302013960085;35922.64077359193;36062.27875442424;34176.82911585426;34788;35303.646963531064;35408.11479713028;35562.28875422425;29354.559681604365;30444;30699.693003070577;30750.53874191844;31083.912063077685;20032.69950950766;21256.893715531325;21392.358229253146;23335.066597335917;23610.52778944377 03:19:54 INFO - PROCESS | 1925 | |2;jquery.html;373.27211936723387;374.59730789401385;390.06968380412707;395.7704531371805;403.8465383154403;15456.665197997994;19126.23495060198;19587.5103122422;20100.09549570269;20343.69484457734;19949.80050199501;20115.295964641242;20715.442602627434;20848.89575552124;21093;5442.374126975409;5505.03661859174;5603.943960560383;5667.319921609413;5760.7695692172265;5477.1510415885605;5563.248961390201;5681.034224181878;5683.431656834325;5693.516051135658;5799.826005219829;5836.5038971687245;5855.268091488564;5856.619319744224;5881.382454842223;5551.611387202897;5804.854878628026;5825.650460972355;5839.795607153729;5871.706414679249;5726.398728133569;5728.968785618587;5731.8567035824435;5741.3110426748635;5747.971260143672;22626.773732262795;24041.91811368475;24137.758622413727;24644.890979905947;24858.875705621354;2112.7253457050483;2456.1649039326535;2564.3717289264064;2582.496413199427;2599.9350016249555;9878.654247101316;9962.701118966443;10025.248106391977;10381;10471.47642617866;1750.5011071844463;1814.3468351393487;1815.4916623345484;1839.5493104189425;1865.4923542553101;124549;124924;125727;127366;128068;9613.567389467491;11406.315621062597;11475.655730328104;11647.59233426826;11660.708482288012;15687.68624627501;15694.9215253923;15719.056856588413;15746.842531574766;15767.369305227892;8100.946876905965;8291.08798032216;8342.207490288412;8392.16078392156;8447.028591711909;7903.486273392211;7935.960320198362;7955.687311593522;7995.920040799634;8124.715634952749;4584.243599806061;4701.811927522861;4712.575868171881;4774.809007639725;4802.183628783116;1945.3288615427655;2003.2587942461382;2011;2013.919443222284;2017.132632967838;963.205355581648;981.9312648114563;983.5180761426992;992.4541502173776;1012.2256473797551;20905.790942090385;21794.91025448721;22447.775522244894;22729.863506824593;23532.29403117909;1006.2201793609864;1011.4588695048261;1031;1047.9685609431729;1052.3790963331671;9517.381370210915;10498.947505262426;11045.779084418267;11070.833937490945;11102.833457498144;707714;710391;731378.106632459;733282;733316;146593;147024.26487867493;150706;151229;153497;966.3187452845727;1011.9645812396531;1035.4615599888016;1037.2998226197287;1042.5048102151266;18356.632867342847;18641.881487110815;18751.906240469256;18933.526661833013;18939.43181704551;48259.03481930272;54963.45036549583;56936.71531642481;57116.714416427654;57806;78771.21228787639;84787;87220.56389718011;87245.56377218073;87329.56335218283;7224.241454647186;7569.848603027798;7935.786006667309;8011.439199255996;8400.83198336042;11830.40847957616;12588.685282868002;12616.49534018629;13003.414846331749;13052.34738263064;10167.796644067224;10853.511591978538;10961.780764384826;11189.776204475702;11325.660230193107;10746.785064298827;10871.67384978452;11051.778964420828;11179.72050698739;11207.66377008691;10982.615608453665;11149.442527873736;11325;11431.771364572829;11516.769664606829;6453.838654033687;7413.962930185315;7501.737439189602;7616.04799400075;7637.198094200029;10229.925857785123;10662.57349706034;10965.725856853642;10992.065674417765;11008.174386920853 03:19:54 INFO - PROCESS | 1925 | |3;mootools.html;1277.9872201277997;1417.787331900215;1433.2403825972235;1446.9493567725126;1452.2012892908901;7342.70629174833;7704.191059938703;8081.070676872161;8360.163983601637;8368.456050356723;8235.835283294331;8282.33741300696;8325.417220794547;8329.541875196861;8345.624446899888;1881.1158755384947;2210.9226177083806;2244.472548950995;2246.4047027537717;2248.7751224877543;2003.8497112716605;2147.2162660628837;2218.090582861027;2224.610693128704;2242.248846636379;2133.264023911748;2159.3090211132444;2161.427221786228;2163.3401812447196;2172.1094351315965;2194.7805219478005;2219.3341997400717;2220.123051394706;2226.7439244486927;2230.609643312414;2171.185805323004;2187.048633844267;2240.831937604686;2250.2799104286637;2251.4483951432003;8332.04181519127;8411.495310281403;8415.28470080044;8416.158384161596;8451.661933522651;4939.431965323962;5062.54437100659;5071.112555302807;5094.566961808214;5099.898002039939;2725.9591106133425;2784.290006048465;3368.292658541709;3446.9655303446893;3454.0501362125365;1128.2722643894724;1195.2469943935266;1226.8773122687749;1233.969150771238;1249.8000319948765;10445.216608754374;10769.892301076967;10781.946090269577;10950.233483656148;10960.397178155199;4325.935110973338;4354.9564504355185;4403.427554417969;4417.757023363746;4435;8771.254443372258;8777.780555486164;8781.121887811169;8798.648054077765;8805.383623146317;5061.342025536656;5065.974670126626;5067.4932506749765;5070.138076527;5077.669951453144;4174.060836311866;4327.242732521796;4652.325412815123;4687.648426368036;4688.835890743808;3294.5222942673176;3519.454484554898;3556.2354093869944;3638.9272214555563;3639.5450568678916;1101.1191047162238;1102.3716481605409;1106.7620461600795;1115.4088333183427;1124.9212555121062;827.2678679368802;856.9442986205877;858.5621333120029;910.0717268386209;934.7569631895756;4846.273059041101;5437.429069947678;5442.210879422461;5512.6968016759465;5851;772.5133166105318;871.1680345270252;873.2315562305258;895.2211575928983;988.2193067476757;3501.8599256029474;3560.4659301104525;3607.2965771674794;3651.525301710814;3699.519062521909;8549.487030778173;9219.893612766511;10002.499875006366;10042.648507302356;10045.648402305884;9325.90674093264;9894.752631184278;9950.80098398028;9954.004599539989;9971.351862129084;7540.773776786705;7809.062912450542;7829.725959591273;7841.725539606086;7850.921490785019;5430.484104010113;5480.287562616834;5564.998300305984;5743.770249189985;5923.644581325134;8656.350773691873;9045.502497362446;9075.13786190311;9157.587908544265;9180.770480738034;8808.603612837309;9113.453192808453;9134.406263592846;9285;9464.290178236797;7204.783856484314;7216.7834964951135;7258.963705181441;7274.709011639475;7345;7870.84258314819;7927.5243485391065;7934.087579928151;7934.404919630935;7954.125046244792;7073.575585464683;7153.3919616833155;7178.99494070841;7254.927450725425;7339.596322202116;6982.68577913985;7009.474289428415;7054.753083642048;7084.220735718964;7130.322619351154;6222.875542489034;6387.712552935036;6389.137466441971;6392;6400;4876.146674332049;4880;4893.657443978887;4951.702897826142;5038.773255203598;4108.650764685035;4896.217689239852;4933.728644924564;5060.038592667382;5139.871503212449 03:19:54 INFO - PROCESS | 1925 | |4;prototype.html;344.77432726656735;352.5593008739076;354.42052244580105;358.5464387549748;361.7558148249929;4309.353596960457;5095.464976177499;5110.591152707784;5123.590112790977;5126.769295381708;4794.3767310249705;4975.12935236334;4996.525330093645;5033.924491132627;5088.211327244274;3808.9428658570146;3844.4814298352135;3844.5386553613607;3879.7196925014678;3883.961160388388;3297.488175093518;3623.8550457981783;3638.744633101576;3887.669548088416;3897.7466464679846;3457.775244609105;3825.234953009395;3827.3876179811095;3827.5981021992707;3847.980760096196;3704.9811301891928;3771.2834561433515;3807.733458657895;3845.1540661054664;3860.7297489175767;3270.280538281574;3286.556314897482;3291.8518666660057;3301.009697090863;3370.1069216657606;4019.879403617867;4187.811548480326;4472.843450479218;4781.330613714083;4830;3731.5149030625844;4310.672236992052;4342.372864417014;4344.978275108636;4452.1763473757455;4999.050180465736;5251.763670634792;5294.823106136326;5315.468453154692;5380.534156649971;3204.4071846708202;3331.5502407175204;3333.883149145039;3652.525171727685;3660.8535658573624;4934.481879402647;5317.012240819386;5526.281583394173;5538.129582642412;5665.915011274834;3169;3531.2760884018717;3572.0891172751303;3581.212133330663;3609.2781443711365;3183.7771356004855;3675.687566556819;3704.2221133561716;3753.5736420160165;3806.1436176859984;3623.221007483404;4343;4355.586219309161;4381;4402;4523.592876641118;4942.283368911488;5368.785248590091;5416.0792665247;5418.02475554404;992.950352482373;1064.8988346107108;1123.8876112388696;1209.359039708938;1240.9018019053203;733.7211859493358;751.545315084377;799.6681377228501;805.8912046873597;825.9215374539409;4112.198121366305;4718.127146477844;4719.858404247878;4773.3245401041695;4835.734034628059;4483.699727079111;4494.6403138352625;5195.2986346843445;5230.92153617696;5336.839894803162;4155.857139286721;4456.598906098467;4547.022390186125;4736.621070314367;4747.501512341223;2379.345679938031;2512.80641695197;2531.46839163774;2802.0858307360118;2805.5931889875856;4901.068796928572;5390.865228369244;5478.233047373371;5526.696031718294;5594.468525490072;5052.5705315048635;5088.821891233789;5175.378954525481;5395.676259424447;5429.511343979061;4838.7338696371335;4958.479359667255;5409.052066623621;5455.918161227505;5474.2609747683555;5372.462753724596;5467.644603100945;5496.450354964471;5516.531094856982;5532.2254884315425;5432.864178395572;5465.426130256425;5472.34331880161;5477.616566840283;5507.834764957058;5211.71335576547;5248.685078895279;5395.622306438511;5719.3136823581435;5727.4845263926445;3141.513065474855;3249.902502924916;3668.3396988542318;3728.4407338898295;3729.079524045173;4412.536683648171;4593.356930029865;5497.862553436036;5522.5858060646415;5551.333839939233;4708.340832283551;4994.225894986283;5000.849974500771;5369.597280204079;5397.923183785361;5304.177852432879;5338.439463856239;5346.625736198429;5353.598480113929;5360.2227676987395;3595.6224596417;4997.900041999213;5012.373453318335;5221.738913054408;5274.89450210986;4901.656884018084;5405.3513578369275;5410.404855465974;5445.168512373622;5494.972525137348;4659.254519276899;5199.66202196856;5331.3739309510975;5342.9198562022375;5380.246765452918 03:19:54 INFO - PROCESS | 1925 | |5;yui.html;728.1044315491941;764.9617519124042;785.1638005524118;792.1167897793962;801.1347744436009;14099;14695.70608587829;14713.852861471381;15887.920560397182;16819.41132060378;14970.251487425612;15182.392704291815;15291.770623440658;15488.225588720547;15524.22378881057;878.3149143667933;897.4391005621486;897.8294124116397;898.3756289378885;900.0819164452255;861.9310455163603;893.9463632182058;897.9236764874961;898.9550522473883;900.1178844732167;924.9075092490764;925.5048549026272;927.9164875161234;928.8513837785955;931.5621657820848;921.8893732752113;922.3128769067021;922.7554698005034;923.1368670293264;924.5608336040394;683.770936736194;685.6194811879404;686.7424715731601;686.8145600687786;686.8317262270775;14654.926725366411;14861.554153375417;14906.254687265593;14924.403023879031;15044.849551504454;13306.933465332611;13434.223283987882;13467;13689.794653080113;13758.243296618683;9382.85925711115;11124.332540047622;11192.720181995515;11406.657800266004;11932;3543.982280088583;3553.9111522211892;3610.873619423334;3612.1692010837655;3624.6012938576737;91144;94273;94855.52572237163;94993;95126.9999999993;10285.22860785444;10338.844917326247;10427.217958653131;10501.002404771534;10631.096356809603;20684.896575517327;21103;21496.462588435414;21525.246616368353;21555.78444215569;7203.927960720431;7288.052553168054;7457.813554661177;7467.925320746832;7499.512531685532;4122.093139509363;4124.670026397881;4135.834566617302;4138.668906487474;4175.352820312795;3489.389356862539;3553.3248682750195;3580.445031020196;3581.4448760442174;3588.102974256436;576.4581293584017;581.6306645280193;658.5061204096927;665.6838001949133;672.3948446398183;642.9196350456193;648.5427773419727;670.6814263224929;675.9932400676029;679.1748026148254;9426.245900327956;9782.315237933277;9867.901320986843;9904;10154.847677284846;641.3522342434175;663.4360793325731;687.1932173137012;687.6252442418894;690.3096903096903;4066.4510291110914;4566.817327306937;4594;4841.862162391835;4851.587615052689;93735.53132234295;94558;95109.5244523773;95742;96986.51506742563;49357.05770846777;64525.354746451936;68846.04442936055;75710.24289757141;85524.57237713771;60089.09866352008;60269;60554;60564.39435605676;60965.39034609686;10263.7434064149;10333.638322658671;10441.269111162146;10463.68608941733;10646.254762166574;41644.58355416407;41828;41991.7900410496;42077.158456831305;42866.14267714566;56199;56551;56894.7155264221;57315.42684573101;57674.711626441596;9783.510824458606;10791.460426978463;12265.938670306949;12472.314022728837;12525.436355363849;13842.584722458343;13850.515231966832;14068.929655351658;14454.132752034911;14615.780763288347;13022.568256615941;13055.412506437424;13301.467941282628;13497.797533037208;13738.450461981809;13053.34733263314;13366.131201471873;13524.93237533806;13605.795913061114;13807.240601766598;12739.61781146567;13196.274204918822;13426.865731342561;13595.592132236048;13644.931775341456;9487.620495180116;9716.028397160228;9905.603775849173;9969.152622027208;9974.251931105342;6741.359570840762;8656.956715216384;8731.738047858575;8740.868886966828;9070.682526111554 03:19:54 INFO - PROCESS | 1925 | __end_tp_report 03:19:54 INFO - PROCESS | 1925 | __start_cc_report 03:19:54 INFO - PROCESS | 1925 | _x_x_mozilla_cycle_collect,1226 03:19:54 INFO - PROCESS | 1925 | __end_cc_report 03:19:54 INFO - PROCESS | 1925 | __startTimestamp1487157594952__endTimestamp 03:19:54 INFO - PROCESS | 1925 | 03:19:54 INFO - PROCESS | 1925 | ------- Summary: start ------- 03:19:54 INFO - PROCESS | 1925 | Number of tests: 6 03:19:54 INFO - PROCESS | 1925 | 03:19:54 INFO - PROCESS | 1925 | [#0] dojo.html Cycles:165 Average:5519.05 Median:5636.58 stddev:627.51 (11.1%) stddev-sans-first:591.95 03:19:54 INFO - PROCESS | 1925 | Values: 2795.8 3284.8 3292.1 3325.0 3335.2 5209.7 5293.8 5496.7 5572.4 5573.7 5350.1 5388.6 5398.7 5406.7 5429.3 4942.2 4970.1 4992.0 5037.6 5059.4 4987.5 5051.5 5053.2 5061.9 5070.5 4968.7 5074.0 5075.6 5085.6 5118.2 5070.3 5104.4 5118.9 5128.9 5157.4 4852.0 5017.4 5042.9 5055.3 5060.2 5340.5 5409.6 5495.3 5500.6 5516.1 5997.1 6022.2 6022.9 6033.4 6045.5 6114.7 6169.5 6198.3 6222.3 6225.0 5399.8 5468.8 5569.9 5570.4 5653.9 6218.8 6234.9 6236.5 6238.6 6248.8 5917.3 5937.0 5940.9 5947.9 5998.6 5207.4 5703.7 5839.7 5947.3 6005.4 5923.6 5959.4 5976.6 5978.0 5989.0 5755.6 5766.2 5810.2 5915.6 6010.2 4802.4 4825.6 4841.3 4859.9 4866.0 4244.9 4262.5 4305.9 4351.0 4384.5 5450.9 5464.3 5478.4 5493.9 5548.6 6029.4 6031.3 6032.3 6051.5 6052.8 5323.2 5479.5 5496.6 5504.6 5512.0 5133.3 5182.8 5207.5 5246.2 5261.7 6248.8 6264.0 6269.1 6271.2 6284.7 5068.4 6074.5 6109.8 6116.0 6120.8 6079.2 6114.2 6117.6 6131.0 6280.7 5843.9 5934.9 5943.0 5955.0 5976.0 6021.0 6033.8 6036.8 6042.1 6050.2 5619.3 5631.6 5647.8 5731.9 5792.8 5789.5 5821.2 5851.4 5865.7 5902.1 5511.4 5636.6 5713.6 5720.4 5723.9 4464.7 5668.5 5701.2 5745.3 5766.5 5481.4 5556.3 5717.4 5736.0 5743.6 03:19:54 INFO - PROCESS | 1925 | 03:19:54 INFO - PROCESS | 1925 | [#1] ext.html Cycles:180 Average:63363.86 Median:36042.64 stddev:134317.31 (372.7%) stddev-sans-first:134611.01 03:19:54 INFO - PROCESS | 1925 | Values: 439.9 443.2 462.5 473.9 479.5 29355.0 38081.8 40104.0 40137.4 41950.6 40916.0 41405.4 41416.0 41501.0 41571.0 7766.9 9010.6 9210.5 9221.9 9229.5 7791.3 10699.7 10987.5 11026.4 11093.4 10796.2 10848.6 10863.8 10865.5 10930.6 6632.9 7968.8 10882.3 10893.6 10988.6 10524.5 10855.3 10867.4 10883.6 10883.8 38083.6 38296.6 38564.0 39009.0 39247.8 3564.4 3609.4 3618.6 3619.2 3739.7 26196.3 27619.9 28844.6 29020.3 29173.0 2883.7 2947.5 3188.6 3234.9 3236.3 420286.9 439799.0 440145.0 440773.0 445411.0 50281.2 51697.2 51713.9 59580.7 60116.7 62707.4 63349.0 63925.7 67676.7 67844.7 14704.5 14742.5 14861.3 15080.7 15081.2 11168.7 11170.2 11522.3 11596.5 11635.4 16878.3 16979.4 16981.0 16987.7 16999.7 6373.9 6974.2 6987.2 7048.8 7078.5 701.6 750.8 820.9 831.3 851.9 27885.2 27971.6 28224.6 28796.7 28930.9 982.7 991.0 1036.1 1037.7 1141.5 11796.0 12479.4 14510.1 14891.0 14900.1 571468.0 574474.0 574855.4 575380.0 575566.0 55028.4 55277.4 55315.4 55360.2 55482.6 1285.2 1328.0 1336.2 1336.2 1340.2 26200.7 27151.6 27909.4 27972.2 28040.7 39610.6 40031.2 40061.0 40080.4 40284.0 478858.0 492804.0 493114.0 493435.0 493817.0 31383.0 31620.7 37081.4 38632.0 38713.0 44127.0 44639.8 45407.3 45457.3 45964.8 34833.5 35042.6 36023.0 36180.0 36403.8 30580.9 31269.5 34899.3 35922.6 36062.3 34176.8 34788.0 35303.6 35408.1 35562.3 29354.6 30444.0 30699.7 30750.5 31083.9 20032.7 21256.9 21392.4 23335.1 23610.5 03:19:54 INFO - PROCESS | 1925 | 03:19:54 INFO - PROCESS | 1925 | [#2] jquery.html Cycles:180 Average:39513.22 Median:22678.32 stddev:120463.54 (531.2%) stddev-sans-first:120765.62 03:19:54 INFO - PROCESS | 1925 | Values: 373.3 374.6 390.1 395.8 403.8 15456.7 19126.2 19587.5 20100.1 20343.7 19949.8 20115.3 20715.4 20848.9 21093.0 5442.4 5505.0 5603.9 5667.3 5760.8 5477.2 5563.2 5681.0 5683.4 5693.5 5799.8 5836.5 5855.3 5856.6 5881.4 5551.6 5804.9 5825.7 5839.8 5871.7 5726.4 5729.0 5731.9 5741.3 5748.0 22626.8 24041.9 24137.8 24644.9 24858.9 2112.7 2456.2 2564.4 2582.5 2599.9 9878.7 9962.7 10025.2 10381.0 10471.5 1750.5 1814.3 1815.5 1839.5 1865.5 124549.0 124924.0 125727.0 127366.0 128068.0 9613.6 11406.3 11475.7 11647.6 11660.7 15687.7 15694.9 15719.1 15746.8 15767.4 8100.9 8291.1 8342.2 8392.2 8447.0 7903.5 7936.0 7955.7 7995.9 8124.7 4584.2 4701.8 4712.6 4774.8 4802.2 1945.3 2003.3 2011.0 2013.9 2017.1 963.2 981.9 983.5 992.5 1012.2 20905.8 21794.9 22447.8 22729.9 23532.3 1006.2 1011.5 1031.0 1048.0 1052.4 9517.4 10498.9 11045.8 11070.8 11102.8 707714.0 710391.0 731378.1 733282.0 733316.0 146593.0 147024.3 150706.0 151229.0 153497.0 966.3 1012.0 1035.5 1037.3 1042.5 18356.6 18641.9 18751.9 18933.5 18939.4 48259.0 54963.5 56936.7 57116.7 57806.0 78771.2 84787.0 87220.6 87245.6 87329.6 7224.2 7569.8 7935.8 8011.4 8400.8 11830.4 12588.7 12616.5 13003.4 13052.3 10167.8 10853.5 10961.8 11189.8 11325.7 10746.8 10871.7 11051.8 11179.7 11207.7 10982.6 11149.4 11325.0 11431.8 11516.8 6453.8 7414.0 7501.7 7616.0 7637.2 10229.9 10662.6 10965.7 10992.1 11008.2 03:19:54 INFO - PROCESS | 1925 | 03:19:54 INFO - PROCESS | 1925 | [#3] mootools.html Cycles:180 Average:5330.87 Median:5086.12 stddev:2947.54 (58.0%) stddev-sans-first:2940.07 03:19:54 INFO - PROCESS | 1925 | Values: 1278.0 1417.8 1433.2 1446.9 1452.2 7342.7 7704.2 8081.1 8360.2 8368.5 8235.8 8282.3 8325.4 8329.5 8345.6 1881.1 2210.9 2244.5 2246.4 2248.8 2003.8 2147.2 2218.1 2224.6 2242.2 2133.3 2159.3 2161.4 2163.3 2172.1 2194.8 2219.3 2220.1 2226.7 2230.6 2171.2 2187.0 2240.8 2250.3 2251.4 8332.0 8411.5 8415.3 8416.2 8451.7 4939.4 5062.5 5071.1 5094.6 5099.9 2726.0 2784.3 3368.3 3447.0 3454.1 1128.3 1195.2 1226.9 1234.0 1249.8 10445.2 10769.9 10781.9 10950.2 10960.4 4325.9 4355.0 4403.4 4417.8 4435.0 8771.3 8777.8 8781.1 8798.6 8805.4 5061.3 5066.0 5067.5 5070.1 5077.7 4174.1 4327.2 4652.3 4687.6 4688.8 3294.5 3519.5 3556.2 3638.9 3639.5 1101.1 1102.4 1106.8 1115.4 1124.9 827.3 856.9 858.6 910.1 934.8 4846.3 5437.4 5442.2 5512.7 5851.0 772.5 871.2 873.2 895.2 988.2 3501.9 3560.5 3607.3 3651.5 3699.5 8549.5 9219.9 10002.5 10042.6 10045.6 9325.9 9894.8 9950.8 9954.0 9971.4 7540.8 7809.1 7829.7 7841.7 7850.9 5430.5 5480.3 5565.0 5743.8 5923.6 8656.4 9045.5 9075.1 9157.6 9180.8 8808.6 9113.5 9134.4 9285.0 9464.3 7204.8 7216.8 7259.0 7274.7 7345.0 7870.8 7927.5 7934.1 7934.4 7954.1 7073.6 7153.4 7179.0 7254.9 7339.6 6982.7 7009.5 7054.8 7084.2 7130.3 6222.9 6387.7 6389.1 6392.0 6400.0 4876.1 4880.0 4893.7 4951.7 5038.8 4108.7 4896.2 4933.7 5060.0 5139.9 03:19:54 INFO - PROCESS | 1925 | 03:19:54 INFO - PROCESS | 1925 | [#4] prototype.html Cycles:180 Average:4254.72 Median:4787.85 stddev:1330.54 (27.8%) stddev-sans-first:1301.51 03:19:54 INFO - PROCESS | 1925 | Values: 344.8 352.6 354.4 358.5 361.8 4309.4 5095.5 5110.6 5123.6 5126.8 4794.4 4975.1 4996.5 5033.9 5088.2 3808.9 3844.5 3844.5 3879.7 3884.0 3297.5 3623.9 3638.7 3887.7 3897.7 3457.8 3825.2 3827.4 3827.6 3848.0 3705.0 3771.3 3807.7 3845.2 3860.7 3270.3 3286.6 3291.9 3301.0 3370.1 4019.9 4187.8 4472.8 4781.3 4830.0 3731.5 4310.7 4342.4 4345.0 4452.2 4999.1 5251.8 5294.8 5315.5 5380.5 3204.4 3331.6 3333.9 3652.5 3660.9 4934.5 5317.0 5526.3 5538.1 5665.9 3169.0 3531.3 3572.1 3581.2 3609.3 3183.8 3675.7 3704.2 3753.6 3806.1 3623.2 4343.0 4355.6 4381.0 4402.0 4523.6 4942.3 5368.8 5416.1 5418.0 993.0 1064.9 1123.9 1209.4 1240.9 733.7 751.5 799.7 805.9 825.9 4112.2 4718.1 4719.9 4773.3 4835.7 4483.7 4494.6 5195.3 5230.9 5336.8 4155.9 4456.6 4547.0 4736.6 4747.5 2379.3 2512.8 2531.5 2802.1 2805.6 4901.1 5390.9 5478.2 5526.7 5594.5 5052.6 5088.8 5175.4 5395.7 5429.5 4838.7 4958.5 5409.1 5455.9 5474.3 5372.5 5467.6 5496.5 5516.5 5532.2 5432.9 5465.4 5472.3 5477.6 5507.8 5211.7 5248.7 5395.6 5719.3 5727.5 3141.5 3249.9 3668.3 3728.4 3729.1 4412.5 4593.4 5497.9 5522.6 5551.3 4708.3 4994.2 5000.8 5369.6 5397.9 5304.2 5338.4 5346.6 5353.6 5360.2 3595.6 4997.9 5012.4 5221.7 5274.9 4901.7 5405.4 5410.4 5445.2 5495.0 4659.3 5199.7 5331.4 5342.9 5380.2 03:19:54 INFO - PROCESS | 1925 | 03:19:54 INFO - PROCESS | 1925 | [#5] yui.html Cycles:180 Average:18163.33 Median:56375.00 stddev:25079.84 (44.5%) stddev-sans-first:25116.02 03:19:54 INFO - PROCESS | 1925 | Values: 728.1 765.0 785.2 792.1 801.1 14099.0 14695.7 14713.9 15887.9 16819.4 14970.3 15182.4 15291.8 15488.2 15524.2 878.3 897.4 897.8 898.4 900.1 861.9 893.9 897.9 899.0 900.1 924.9 925.5 927.9 928.9 931.6 921.9 922.3 922.8 923.1 924.6 683.8 685.6 686.7 686.8 686.8 14654.9 14861.6 14906.3 14924.4 15044.8 13306.9 13434.2 13467.0 13689.8 13758.2 9382.9 11124.3 11192.7 11406.7 11932.0 3544.0 3553.9 3610.9 3612.2 3624.6 91144.0 94273.0 94855.5 94993.0 95127.0 10285.2 10338.8 10427.2 10501.0 10631.1 20684.9 21103.0 21496.5 21525.2 21555.8 7203.9 7288.1 7457.8 7467.9 7499.5 4122.1 4124.7 4135.8 4138.7 4175.4 3489.4 3553.3 3580.4 3581.4 3588.1 576.5 581.6 658.5 665.7 672.4 642.9 648.5 670.7 676.0 679.2 9426.2 9782.3 9867.9 9904.0 10154.8 641.4 663.4 687.2 687.6 690.3 4066.5 4566.8 4594.0 4841.9 4851.6 93735.5 94558.0 95109.5 95742.0 96986.5 49357.1 64525.4 68846.0 75710.2 85524.6 60089.1 60269.0 60554.0 60564.4 60965.4 10263.7 10333.6 10441.3 10463.7 10646.3 41644.6 41828.0 41991.8 42077.2 42866.1 56199.0 56551.0 56894.7 57315.4 57674.7 9783.5 10791.5 12265.9 12472.3 12525.4 13842.6 13850.5 14068.9 14454.1 14615.8 13022.6 13055.4 13301.5 13497.8 13738.5 13053.3 13366.1 13524.9 13605.8 13807.2 12739.6 13196.3 13426.9 13595.6 13644.9 9487.6 9716.0 9905.6 9969.2 9974.3 6741.4 8657.0 8731.7 8740.9 9070.7 03:19:54 INFO - PROCESS | 1925 | -------- Summary: end -------- 03:19:54 INFO - PROCESS | 1925 | 03:19:55 INFO - TEST-INFO | 1925: exit 0 03:19:55 INFO - TEST-OK | dromaeo_css | took 1118319ms 03:19:55 INFO - TEST-START | kraken 03:19:55 INFO - operating with platform_type : mac_ 03:19:55 INFO - Initialising browser for kraken test... 03:19:55 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/tmpxFDmwK/profile http://localhost:55992/getInfo.html) 03:19:57 INFO - PROCESS | 1930 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 03:19:57 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 03:19:57 INFO - PROCESS | 1930 | colorDepth:24 03:19:57 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 03:19:57 INFO - PROCESS | 1930 | __metrics 03:19:57 INFO - PROCESS | 1930 | 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" 03:19:57 INFO - PROCESS | 1930 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:19:58 INFO - TEST-INFO | 1930: exit 0 03:19:58 INFO - Browser initialized. 03:19:58 INFO - Running cycle 1/1 for kraken test... 03:19:58 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/tmpxFDmwK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:10 INFO - PROCESS | 1931 | RSS: Main: 236896256 03:20:10 INFO - PROCESS | 1931 | 03:20:13 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55992/tests/kraken/driver.html?testName=audio-beat-detection) 03:20:14 INFO - PROCESS | 1931 | RSS: Main: 289021952 03:20:14 INFO - PROCESS | 1931 | 03:20:17 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55992/tests/kraken/driver.html?testName=audio-dft) 03:20:17 INFO - PROCESS | 1931 | RSS: Main: 291373056 03:20:17 INFO - PROCESS | 1931 | 03:20:21 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55992/tests/kraken/driver.html?testName=audio-fft) 03:20:21 INFO - PROCESS | 1931 | RSS: Main: 381161472 03:20:21 INFO - PROCESS | 1931 | 03:20:23 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55992/tests/kraken/driver.html?testName=audio-oscillator) 03:20:24 INFO - PROCESS | 1931 | RSS: Main: 284749824 03:20:24 INFO - PROCESS | 1931 | 03:20:26 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55992/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:20:27 INFO - PROCESS | 1931 | RSS: Main: 287854592 03:20:27 INFO - PROCESS | 1931 | 03:20:30 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55992/tests/kraken/driver.html?testName=imaging-darkroom) 03:20:30 INFO - PROCESS | 1931 | RSS: Main: 331702272 03:20:30 INFO - PROCESS | 1931 | 03:20:33 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55992/tests/kraken/driver.html?testName=imaging-desaturate) 03:20:34 INFO - PROCESS | 1931 | RSS: Main: 332750848 03:20:34 INFO - PROCESS | 1931 | 03:20:37 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55992/tests/kraken/driver.html?testName=json-parse-financial) 03:20:37 INFO - PROCESS | 1931 | RSS: Main: 327626752 03:20:37 INFO - PROCESS | 1931 | 03:20:39 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55992/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:20:39 INFO - PROCESS | 1931 | RSS: Main: 305901568 03:20:39 INFO - PROCESS | 1931 | 03:20:41 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55992/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:20:42 INFO - PROCESS | 1931 | RSS: Main: 309727232 03:20:42 INFO - PROCESS | 1931 | 03:20:44 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55992/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:20:45 INFO - PROCESS | 1931 | RSS: Main: 311885824 03:20:45 INFO - PROCESS | 1931 | 03:20:48 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55992/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:20:49 INFO - PROCESS | 1931 | RSS: Main: 328642560 03:20:49 INFO - PROCESS | 1931 | 03:20:52 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55992/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:20:53 INFO - PROCESS | 1931 | RSS: Main: 322781184 03:20:53 INFO - PROCESS | 1931 | 03:20:54 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55992/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55992/tests/kraken/driver.html?testName=ai-astar) 03:20:55 INFO - PROCESS | 1931 | RSS: Main: 367779840 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | __start_tp_report 03:20:55 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 03:20:55 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 03:20:55 INFO - PROCESS | 1931 | |i|pagename|runs| 03:20:55 INFO - PROCESS | 1931 | |0;ai-astar;114;111;111;114;122;168;161;112;113;124 03:20:55 INFO - PROCESS | 1931 | |1;audio-beat-detection;140;112;112;150;115;112;112;111;130;113 03:20:55 INFO - PROCESS | 1931 | |2;audio-dft;188;184;183;187;185;197;263;185;185;184 03:20:55 INFO - PROCESS | 1931 | |3;audio-fft;81;59;60;91;63;60;60;61;76;60 03:20:55 INFO - PROCESS | 1931 | |4;audio-oscillator;85;71;85;70;71;70;85;67;67;85 03:20:55 INFO - PROCESS | 1931 | |5;imaging-gaussian-blur;91;88;89;87;90;88;89;88;88;87 03:20:55 INFO - PROCESS | 1931 | |6;imaging-darkroom;94;96;96;95;94;95;95;95;95;95 03:20:55 INFO - PROCESS | 1931 | |7;imaging-desaturate;88;86;86;85;84;87;86;87;85;86 03:20:55 INFO - PROCESS | 1931 | |8;json-parse-financial;50;59;53;53;60;59;60;59;58;58 03:20:55 INFO - PROCESS | 1931 | |9;json-stringify-tinderbox;65;75;73;76;74;69;95;106;107;73 03:20:55 INFO - PROCESS | 1931 | |10;stanford-crypto-aes;91;91;89;91;88;87;89;90;91;89 03:20:55 INFO - PROCESS | 1931 | |11;stanford-crypto-ccm;146;154;159;143;150;163;146;147;176;160 03:20:55 INFO - PROCESS | 1931 | |12;stanford-crypto-pbkdf2;214;236;179;174;175;173;228;233;226;172 03:20:55 INFO - PROCESS | 1931 | |13;stanford-crypto-sha256-iterative;69;68;68;68;67;67;67;65;79;72 03:20:55 INFO - PROCESS | 1931 | __end_tp_report 03:20:55 INFO - PROCESS | 1931 | __start_cc_report 03:20:55 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,2042 03:20:55 INFO - PROCESS | 1931 | __end_cc_report 03:20:55 INFO - PROCESS | 1931 | __startTimestamp1487157655149__endTimestamp 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | ------- Summary: start ------- 03:20:55 INFO - PROCESS | 1931 | Number of tests: 14 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#0] ai-astar Cycles:10 Average:125.00 Median:118.00 stddev:21.35 (18.1%) stddev-sans-first:22.27 03:20:55 INFO - PROCESS | 1931 | Values: 114.0 111.0 111.0 114.0 122.0 168.0 161.0 112.0 113.0 124.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:114.00 stddev:14.17 (12.4%) stddev-sans-first:13.19 03:20:55 INFO - PROCESS | 1931 | Values: 140.0 112.0 112.0 150.0 115.0 112.0 112.0 111.0 130.0 113.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#2] audio-dft Cycles:10 Average:194.10 Median:186.00 stddev:24.54 (13.2%) stddev-sans-first:25.93 03:20:55 INFO - PROCESS | 1931 | Values: 188.0 184.0 183.0 187.0 185.0 197.0 263.0 185.0 185.0 184.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#3] audio-fft Cycles:10 Average:67.10 Median:62.00 stddev:11.38 (18.3%) stddev-sans-first:10.90 03:20:55 INFO - PROCESS | 1931 | Values: 81.0 59.0 60.0 91.0 63.0 60.0 60.0 61.0 76.0 60.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:78.00 stddev:8.21 (10.5%) stddev-sans-first:7.97 03:20:55 INFO - PROCESS | 1931 | Values: 85.0 71.0 85.0 70.0 71.0 70.0 85.0 67.0 67.0 85.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:88.50 stddev:1.27 (1.4%) stddev-sans-first:0.97 03:20:55 INFO - PROCESS | 1931 | Values: 91.0 88.0 89.0 87.0 90.0 88.0 89.0 88.0 88.0 87.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 03:20:55 INFO - PROCESS | 1931 | Values: 94.0 96.0 96.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.00 stddev:1.15 (1.3%) stddev-sans-first:0.97 03:20:55 INFO - PROCESS | 1931 | Values: 88.0 86.0 86.0 85.0 84.0 87.0 86.0 87.0 85.0 86.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:59.00 stddev:3.54 (6.0%) stddev-sans-first:2.74 03:20:55 INFO - PROCESS | 1931 | Values: 50.0 59.0 53.0 53.0 60.0 59.0 60.0 59.0 58.0 58.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#9] json-stringify-tinderbox Cycles:10 Average:81.30 Median:73.50 stddev:15.40 (21.0%) stddev-sans-first:15.16 03:20:55 INFO - PROCESS | 1931 | Values: 65.0 75.0 73.0 76.0 74.0 69.0 95.0 106.0 107.0 73.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:90.50 stddev:1.43 (1.6%) stddev-sans-first:1.42 03:20:55 INFO - PROCESS | 1931 | Values: 91.0 91.0 89.0 91.0 88.0 87.0 89.0 90.0 91.0 89.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#11] stanford-crypto-ccm Cycles:10 Average:154.40 Median:156.50 stddev:10.21 (6.5%) stddev-sans-first:10.37 03:20:55 INFO - PROCESS | 1931 | Values: 146.0 154.0 159.0 143.0 150.0 163.0 146.0 147.0 176.0 160.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.00 Median:220.00 stddev:28.45 (12.9%) stddev-sans-first:29.79 03:20:55 INFO - PROCESS | 1931 | Values: 214.0 236.0 179.0 174.0 175.0 173.0 228.0 233.0 226.0 172.0 03:20:55 INFO - PROCESS | 1931 | 03:20:55 INFO - PROCESS | 1931 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:68.00 stddev:3.94 (5.8%) stddev-sans-first:4.18 03:20:55 INFO - PROCESS | 1931 | Values: 69.0 68.0 68.0 68.0 67.0 67.0 67.0 65.0 79.0 72.0 03:20:55 INFO - PROCESS | 1931 | -------- Summary: end -------- 03:20:55 INFO - PROCESS | 1931 | 03:20:56 INFO - TEST-INFO | 1931: exit 0 03:20:56 INFO - TEST-OK | kraken | took 60460ms 03:20:56 INFO - SUITE-END | took 1178s 03:20:56 INFO - Completed test suite (00:19:38) 03:20:56 INFO - javascript benchmark 03:20:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7014.281387349036, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2795.762360199384, 3284.7700660953733, 3292.1275861896593, 3325.0191193597916, 3335.199552107493, 5209.739513024343, 5293.841184764454, 5496.670199788011, 5572.387037425885, 5573.693446860421, 5350.117230656937, 5388.595855310848, 5398.676079435237, 5406.729663516819, 5429.294191755076, 4942.233953737167, 4970.080535101018, 4992.026554821811, 5037.647364684473, 5059.392872855263, 4987.526185012436, 5051.5453609175165, 5053.242013697956, 5061.898762024757, 5070.467600901908, 4968.70187788732, 5074.0, 5075.593952483792, 5085.618578606601, 5118.232265160237, 5070.340855688755, 5104.412992505853, 5118.948810511922, 5128.897422051576, 5157.432682404932, 4852.029594081163, 5017.422996355429, 5042.873928151789, 5055.342805435306, 5060.1903695408555, 5340.54605358545, 5409.594280428944, 5495.258140151069, 5500.58745594082, 5516.062269414209, 5997.070454079625, 6022.18700475439, 6022.909656355158, 6033.396660333975, 6045.546584006217, 6114.724837382329, 6169.475594574466, 6198.349173336825, 6222.3466536013975, 6225.0, 5399.811006614749, 5468.835934921998, 5569.858179073281, 5570.41510641385, 5653.9151912721345, 6218.81343559694, 6234.937650623527, 6236.469900058546, 6238.563300569007, 6248.843778905472, 5917.289925209003, 5936.970315148396, 5940.940590594125, 5947.940520594739, 5998.610090344201, 5207.427183009866, 5703.714814259353, 5839.737211825478, 5947.316058653224, 6005.39946005396, 5923.585349025613, 5959.374265702126, 5976.551758618121, 5977.970110149508, 5989.0, 5755.59710820247, 5766.192733017381, 5810.157527158538, 5915.556333275022, 6010.248718910137, 4802.447718512346, 4825.638077144228, 4841.346418233564, 4859.927101093486, 4865.95134048662, 4244.8514301999285, 4262.50981137167, 4305.892352691208, 4350.978245108754, 4384.473863136444, 5450.8909821803345, 5464.3442786865835, 5478.369987451494, 5493.862653433696, 5548.5838562108, 6029.366916473795, 6031.276246850406, 6032.306284777219, 6051.515878729779, 6052.848678782978, 5323.22813192085, 5479.5342395896, 5496.642718223303, 5504.614676972574, 5512.0, 5133.25567792668, 5182.818601348935, 5207.505286997729, 5246.160614301693, 5261.684298942076, 6248.781292654735, 6264.0, 6269.122322874892, 6271.153394291803, 6284.74861005573, 5068.442471328225, 6074.453299203093, 6109.75560977556, 6115.969420152871, 6120.785772497941, 6079.179310792986, 6114.175715401237, 6117.553376797925, 6130.9791012021615, 6280.748770049329, 5843.912341314799, 5934.910976335272, 5942.989691752499, 5955.047192449188, 5976.013957696931, 6021.0, 6033.78881739137, 6036.758529658764, 6042.063480160582, 6050.183225264533, 5619.297587801525, 5631.63394379364, 5647.802326918538, 5731.914021289767, 5792.82621521355, 5789.478946894799, 5821.155932389865, 5851.414858514115, 5865.677387743715, 5902.085176797603, 5511.3937466879415, 5636.577256705681, 5713.628614140068, 5720.3707592165665, 5723.914141287801, 4464.732116072916, 5668.489835914787, 5701.230333904869, 5745.3105627324985, 5766.538676905921, 5481.369642491165, 5556.277683901067, 5717.428257174416, 5736.0, 5743.62666426681], "value": 5479.111036698308, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.9450068741408, 443.19338803377843, 462.52822121436117, 473.9431068716759, 479.522874739634, 29355.0, 38081.80959095204, 40104.0, 40137.3979390309, 41950.58049419505, 40916.0, 41405.37891931624, 41416.0, 41501.0, 41571.0, 7766.883496747525, 9010.59452324647, 9210.49342286174, 9221.861672074925, 9229.538523073854, 7791.298783109519, 10699.679009629723, 10987.505562249678, 11026.393548354838, 11093.3898635575, 10796.190285728562, 10848.566057357697, 10863.782724345509, 10865.51105200264, 10930.56277748893, 6632.900506492407, 7968.800779980489, 10882.347059176474, 10893.564257429693, 10988.560457581727, 10524.526396312185, 10855.348679079281, 10867.402292873889, 10883.564657413774, 10883.83674244879, 38083.61916380828, 38296.617033829585, 38564.0, 39009.0, 39247.8037609813, 3564.41187204111, 3609.3640288629595, 3618.583862855779, 3619.2037751694584, 3739.719521035933, 26196.345091372677, 27619.86190069057, 28844.567331490045, 29020.27449313763, 29172.99848036477, 2883.66837813652, 2947.4547208766235, 3188.5695431116733, 3234.87060517579, 3236.2556611979376, 420286.89856550825, 439799.0, 440145.0, 440773.0, 445411.0, 50281.24578131331, 51697.224541631906, 51713.86289096863, 59580.70209648924, 60116.69941650264, 62707.37292627106, 63349.0, 63925.72148557096, 67676.66161669258, 67844.6607766958, 14704.485343012942, 14742.484013059491, 14861.256937153315, 15080.698386032438, 15081.170535620646, 11168.720781980515, 11170.218084734153, 11522.308661480338, 11596.536138554364, 11635.418229088511, 16878.324867005427, 16979.405720799714, 16980.962284525936, 16987.687400956944, 16999.74500382495, 6373.936260637334, 6974.197967233833, 6987.196472405739, 7048.753293634698, 7078.469114816409, 701.5545128843221, 750.771014840468, 820.8932838730926, 831.2726364431123, 851.8722191671299, 27885.16344509668, 27971.58042629362, 28224.576631350545, 28796.712032879404, 28930.855345723136, 982.7395740128871, 991.0, 1036.1348274191134, 1037.7198156497855, 1141.5148561861176, 11796.0, 12479.438425270884, 14510.056846304957, 14891.0, 14900.105993640416, 571468.0, 574474.0, 574855.3771693428, 575380.0, 575566.0, 55028.449715503135, 55277.44722552723, 55315.44684553103, 55360.16959745607, 55482.61293467615, 1285.1967520299813, 1327.966800829987, 1336.1515437696937, 1336.2450215627944, 1340.1557019077918, 26200.73799261983, 27151.59272610952, 27909.44181116407, 27972.16083517498, 28040.719592804628, 39610.60389396069, 40031.1993760129, 40061.0, 40080.39879401753, 40284.0, 478858.0, 492804.0, 493114.0, 493435.0, 493817.0, 31383.0, 31620.683793161774, 37081.44377834281, 38632.0, 38713.0, 44127.0, 44639.77680111579, 45407.31889021601, 45457.318140228585, 45964.77017614891, 34833.47749783806, 35042.649573503935, 36023.0, 36180.0, 36403.817980909924, 30580.929667461533, 31269.530957036117, 34899.302013960085, 35922.64077359193, 36062.27875442424, 34176.82911585426, 34788.0, 35303.646963531064, 35408.11479713028, 35562.28875422425, 29354.559681604365, 30444.0, 30699.693003070577, 30750.53874191844, 31083.912063077685, 20032.69950950766, 21256.893715531325, 21392.358229253146, 23335.066597335917, 23610.52778944377], "value": 18911.30940121582, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.27211936723387, 374.59730789401385, 390.06968380412707, 395.7704531371805, 403.8465383154403, 15456.665197997994, 19126.23495060198, 19587.5103122422, 20100.09549570269, 20343.69484457734, 19949.80050199501, 20115.295964641242, 20715.442602627434, 20848.89575552124, 21093.0, 5442.374126975409, 5505.03661859174, 5603.943960560383, 5667.319921609413, 5760.7695692172265, 5477.1510415885605, 5563.248961390201, 5681.034224181878, 5683.431656834325, 5693.516051135658, 5799.826005219829, 5836.5038971687245, 5855.268091488564, 5856.619319744224, 5881.382454842223, 5551.611387202897, 5804.854878628026, 5825.650460972355, 5839.795607153729, 5871.706414679249, 5726.398728133569, 5728.968785618587, 5731.8567035824435, 5741.3110426748635, 5747.971260143672, 22626.773732262795, 24041.91811368475, 24137.758622413727, 24644.890979905947, 24858.875705621354, 2112.7253457050483, 2456.1649039326535, 2564.3717289264064, 2582.496413199427, 2599.9350016249555, 9878.654247101316, 9962.701118966443, 10025.248106391977, 10381.0, 10471.47642617866, 1750.5011071844463, 1814.3468351393487, 1815.4916623345484, 1839.5493104189425, 1865.4923542553101, 124549.0, 124924.0, 125727.0, 127366.0, 128068.0, 9613.567389467491, 11406.315621062597, 11475.655730328104, 11647.59233426826, 11660.708482288012, 15687.68624627501, 15694.9215253923, 15719.056856588413, 15746.842531574766, 15767.369305227892, 8100.946876905965, 8291.08798032216, 8342.207490288412, 8392.16078392156, 8447.028591711909, 7903.486273392211, 7935.960320198362, 7955.687311593522, 7995.920040799634, 8124.715634952749, 4584.243599806061, 4701.811927522861, 4712.575868171881, 4774.809007639725, 4802.183628783116, 1945.3288615427655, 2003.2587942461382, 2011.0, 2013.919443222284, 2017.132632967838, 963.205355581648, 981.9312648114563, 983.5180761426992, 992.4541502173776, 1012.2256473797551, 20905.790942090385, 21794.91025448721, 22447.775522244894, 22729.863506824593, 23532.29403117909, 1006.2201793609864, 1011.4588695048261, 1031.0, 1047.9685609431729, 1052.3790963331671, 9517.381370210915, 10498.947505262426, 11045.779084418267, 11070.833937490945, 11102.833457498144, 707714.0, 710391.0, 731378.106632459, 733282.0, 733316.0, 146593.0, 147024.26487867493, 150706.0, 151229.0, 153497.0, 966.3187452845727, 1011.9645812396531, 1035.4615599888016, 1037.2998226197287, 1042.5048102151266, 18356.632867342847, 18641.881487110815, 18751.906240469256, 18933.526661833013, 18939.43181704551, 48259.03481930272, 54963.45036549583, 56936.71531642481, 57116.714416427654, 57806.0, 78771.21228787639, 84787.0, 87220.56389718011, 87245.56377218073, 87329.56335218283, 7224.241454647186, 7569.848603027798, 7935.786006667309, 8011.439199255996, 8400.83198336042, 11830.40847957616, 12588.685282868002, 12616.49534018629, 13003.414846331749, 13052.34738263064, 10167.796644067224, 10853.511591978538, 10961.780764384826, 11189.776204475702, 11325.660230193107, 10746.785064298827, 10871.67384978452, 11051.778964420828, 11179.72050698739, 11207.66377008691, 10982.615608453665, 11149.442527873736, 11325.0, 11431.771364572829, 11516.769664606829, 6453.838654033687, 7413.962930185315, 7501.737439189602, 7616.04799400075, 7637.198094200029, 10229.925857785123, 10662.57349706034, 10965.725856853642, 10992.065674417765, 11008.174386920853], "value": 9707.562818732798, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1277.9872201277997, 1417.787331900215, 1433.2403825972235, 1446.9493567725126, 1452.2012892908901, 7342.70629174833, 7704.191059938703, 8081.070676872161, 8360.163983601637, 8368.456050356723, 8235.835283294331, 8282.33741300696, 8325.417220794547, 8329.541875196861, 8345.624446899888, 1881.1158755384947, 2210.9226177083806, 2244.472548950995, 2246.4047027537717, 2248.7751224877543, 2003.8497112716605, 2147.2162660628837, 2218.090582861027, 2224.610693128704, 2242.248846636379, 2133.264023911748, 2159.3090211132444, 2161.427221786228, 2163.3401812447196, 2172.1094351315965, 2194.7805219478005, 2219.3341997400717, 2220.123051394706, 2226.7439244486927, 2230.609643312414, 2171.185805323004, 2187.048633844267, 2240.831937604686, 2250.2799104286637, 2251.4483951432003, 8332.04181519127, 8411.495310281403, 8415.28470080044, 8416.158384161596, 8451.661933522651, 4939.431965323962, 5062.54437100659, 5071.112555302807, 5094.566961808214, 5099.898002039939, 2725.9591106133425, 2784.290006048465, 3368.292658541709, 3446.9655303446893, 3454.0501362125365, 1128.2722643894724, 1195.2469943935266, 1226.8773122687749, 1233.969150771238, 1249.8000319948765, 10445.216608754374, 10769.892301076967, 10781.946090269577, 10950.233483656148, 10960.397178155199, 4325.935110973338, 4354.9564504355185, 4403.427554417969, 4417.757023363746, 4435.0, 8771.254443372258, 8777.780555486164, 8781.121887811169, 8798.648054077765, 8805.383623146317, 5061.342025536656, 5065.974670126626, 5067.4932506749765, 5070.138076527, 5077.669951453144, 4174.060836311866, 4327.242732521796, 4652.325412815123, 4687.648426368036, 4688.835890743808, 3294.5222942673176, 3519.454484554898, 3556.2354093869944, 3638.9272214555563, 3639.5450568678916, 1101.1191047162238, 1102.3716481605409, 1106.7620461600795, 1115.4088333183427, 1124.9212555121062, 827.2678679368802, 856.9442986205877, 858.5621333120029, 910.0717268386209, 934.7569631895756, 4846.273059041101, 5437.429069947678, 5442.210879422461, 5512.6968016759465, 5851.0, 772.5133166105318, 871.1680345270252, 873.2315562305258, 895.2211575928983, 988.2193067476757, 3501.8599256029474, 3560.4659301104525, 3607.2965771674794, 3651.525301710814, 3699.519062521909, 8549.487030778173, 9219.893612766511, 10002.499875006366, 10042.648507302356, 10045.648402305884, 9325.90674093264, 9894.752631184278, 9950.80098398028, 9954.004599539989, 9971.351862129084, 7540.773776786705, 7809.062912450542, 7829.725959591273, 7841.725539606086, 7850.921490785019, 5430.484104010113, 5480.287562616834, 5564.998300305984, 5743.770249189985, 5923.644581325134, 8656.350773691873, 9045.502497362446, 9075.13786190311, 9157.587908544265, 9180.770480738034, 8808.603612837309, 9113.453192808453, 9134.406263592846, 9285.0, 9464.290178236797, 7204.783856484314, 7216.7834964951135, 7258.963705181441, 7274.709011639475, 7345.0, 7870.84258314819, 7927.5243485391065, 7934.087579928151, 7934.404919630935, 7954.125046244792, 7073.575585464683, 7153.3919616833155, 7178.99494070841, 7254.927450725425, 7339.596322202116, 6982.68577913985, 7009.474289428415, 7054.753083642048, 7084.220735718964, 7130.322619351154, 6222.875542489034, 6387.712552935036, 6389.137466441971, 6392.0, 6400.0, 4876.146674332049, 4880.0, 4893.657443978887, 4951.702897826142, 5038.773255203598, 4108.650764685035, 4896.217689239852, 4933.728644924564, 5060.038592667382, 5139.871503212449], "value": 4299.389829919446, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.77432726656735, 352.5593008739076, 354.42052244580105, 358.5464387549748, 361.7558148249929, 4309.353596960457, 5095.464976177499, 5110.591152707784, 5123.590112790977, 5126.769295381708, 4794.3767310249705, 4975.12935236334, 4996.525330093645, 5033.924491132627, 5088.211327244274, 3808.9428658570146, 3844.4814298352135, 3844.5386553613607, 3879.7196925014678, 3883.961160388388, 3297.488175093518, 3623.8550457981783, 3638.744633101576, 3887.669548088416, 3897.7466464679846, 3457.775244609105, 3825.234953009395, 3827.3876179811095, 3827.5981021992707, 3847.980760096196, 3704.9811301891928, 3771.2834561433515, 3807.733458657895, 3845.1540661054664, 3860.7297489175767, 3270.280538281574, 3286.556314897482, 3291.8518666660057, 3301.009697090863, 3370.1069216657606, 4019.879403617867, 4187.811548480326, 4472.843450479218, 4781.330613714083, 4830.0, 3731.5149030625844, 4310.672236992052, 4342.372864417014, 4344.978275108636, 4452.1763473757455, 4999.050180465736, 5251.763670634792, 5294.823106136326, 5315.468453154692, 5380.534156649971, 3204.4071846708202, 3331.5502407175204, 3333.883149145039, 3652.525171727685, 3660.8535658573624, 4934.481879402647, 5317.012240819386, 5526.281583394173, 5538.129582642412, 5665.915011274834, 3169.0, 3531.2760884018717, 3572.0891172751303, 3581.212133330663, 3609.2781443711365, 3183.7771356004855, 3675.687566556819, 3704.2221133561716, 3753.5736420160165, 3806.1436176859984, 3623.221007483404, 4343.0, 4355.586219309161, 4381.0, 4402.0, 4523.592876641118, 4942.283368911488, 5368.785248590091, 5416.0792665247, 5418.02475554404, 992.950352482373, 1064.8988346107108, 1123.8876112388696, 1209.359039708938, 1240.9018019053203, 733.7211859493358, 751.545315084377, 799.6681377228501, 805.8912046873597, 825.9215374539409, 4112.198121366305, 4718.127146477844, 4719.858404247878, 4773.3245401041695, 4835.734034628059, 4483.699727079111, 4494.6403138352625, 5195.2986346843445, 5230.92153617696, 5336.839894803162, 4155.857139286721, 4456.598906098467, 4547.022390186125, 4736.621070314367, 4747.501512341223, 2379.345679938031, 2512.80641695197, 2531.46839163774, 2802.0858307360118, 2805.5931889875856, 4901.068796928572, 5390.865228369244, 5478.233047373371, 5526.696031718294, 5594.468525490072, 5052.5705315048635, 5088.821891233789, 5175.378954525481, 5395.676259424447, 5429.511343979061, 4838.7338696371335, 4958.479359667255, 5409.052066623621, 5455.918161227505, 5474.2609747683555, 5372.462753724596, 5467.644603100945, 5496.450354964471, 5516.531094856982, 5532.2254884315425, 5432.864178395572, 5465.426130256425, 5472.34331880161, 5477.616566840283, 5507.834764957058, 5211.71335576547, 5248.685078895279, 5395.622306438511, 5719.3136823581435, 5727.4845263926445, 3141.513065474855, 3249.902502924916, 3668.3396988542318, 3728.4407338898295, 3729.079524045173, 4412.536683648171, 4593.356930029865, 5497.862553436036, 5522.5858060646415, 5551.333839939233, 4708.340832283551, 4994.225894986283, 5000.849974500771, 5369.597280204079, 5397.923183785361, 5304.177852432879, 5338.439463856239, 5346.625736198429, 5353.598480113929, 5360.2227676987395, 3595.6224596417, 4997.900041999213, 5012.373453318335, 5221.738913054408, 5274.89450210986, 4901.656884018084, 5405.3513578369275, 5410.404855465974, 5445.168512373622, 5494.972525137348, 4659.254519276899, 5199.66202196856, 5331.3739309510975, 5342.9198562022375, 5380.246765452918], "value": 3841.18085494207, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.1044315491941, 764.9617519124042, 785.1638005524118, 792.1167897793962, 801.1347744436009, 14099.0, 14695.70608587829, 14713.852861471381, 15887.920560397182, 16819.41132060378, 14970.251487425612, 15182.392704291815, 15291.770623440658, 15488.225588720547, 15524.22378881057, 878.3149143667933, 897.4391005621486, 897.8294124116397, 898.3756289378885, 900.0819164452255, 861.9310455163603, 893.9463632182058, 897.9236764874961, 898.9550522473883, 900.1178844732167, 924.9075092490764, 925.5048549026272, 927.9164875161234, 928.8513837785955, 931.5621657820848, 921.8893732752113, 922.3128769067021, 922.7554698005034, 923.1368670293264, 924.5608336040394, 683.770936736194, 685.6194811879404, 686.7424715731601, 686.8145600687786, 686.8317262270775, 14654.926725366411, 14861.554153375417, 14906.254687265593, 14924.403023879031, 15044.849551504454, 13306.933465332611, 13434.223283987882, 13467.0, 13689.794653080113, 13758.243296618683, 9382.85925711115, 11124.332540047622, 11192.720181995515, 11406.657800266004, 11932.0, 3543.982280088583, 3553.9111522211892, 3610.873619423334, 3612.1692010837655, 3624.6012938576737, 91144.0, 94273.0, 94855.52572237163, 94993.0, 95126.9999999993, 10285.22860785444, 10338.844917326247, 10427.217958653131, 10501.002404771534, 10631.096356809603, 20684.896575517327, 21103.0, 21496.462588435414, 21525.246616368353, 21555.78444215569, 7203.927960720431, 7288.052553168054, 7457.813554661177, 7467.925320746832, 7499.512531685532, 4122.093139509363, 4124.670026397881, 4135.834566617302, 4138.668906487474, 4175.352820312795, 3489.389356862539, 3553.3248682750195, 3580.445031020196, 3581.4448760442174, 3588.102974256436, 576.4581293584017, 581.6306645280193, 658.5061204096927, 665.6838001949133, 672.3948446398183, 642.9196350456193, 648.5427773419727, 670.6814263224929, 675.9932400676029, 679.1748026148254, 9426.245900327956, 9782.315237933277, 9867.901320986843, 9904.0, 10154.847677284846, 641.3522342434175, 663.4360793325731, 687.1932173137012, 687.6252442418894, 690.3096903096903, 4066.4510291110914, 4566.817327306937, 4594.0, 4841.862162391835, 4851.587615052689, 93735.53132234295, 94558.0, 95109.5244523773, 95742.0, 96986.51506742563, 49357.05770846777, 64525.354746451936, 68846.04442936055, 75710.24289757141, 85524.57237713771, 60089.09866352008, 60269.0, 60554.0, 60564.39435605676, 60965.39034609686, 10263.7434064149, 10333.638322658671, 10441.269111162146, 10463.68608941733, 10646.254762166574, 41644.58355416407, 41828.0, 41991.7900410496, 42077.158456831305, 42866.14267714566, 56199.0, 56551.0, 56894.7155264221, 57315.42684573101, 57674.711626441596, 9783.510824458606, 10791.460426978463, 12265.938670306949, 12472.314022728837, 12525.436355363849, 13842.584722458343, 13850.515231966832, 14068.929655351658, 14454.132752034911, 14615.780763288347, 13022.568256615941, 13055.412506437424, 13301.467941282628, 13497.797533037208, 13738.450461981809, 13053.34733263314, 13366.131201471873, 13524.93237533806, 13605.795913061114, 13807.240601766598, 12739.61781146567, 13196.274204918822, 13426.865731342561, 13595.592132236048, 13644.931775341456, 9487.620495180116, 9716.028397160228, 9905.603775849173, 9969.152622027208, 9974.251931105342, 6741.359570840762, 8656.956715216384, 8731.738047858575, 8740.868886966828, 9070.682526111554], "value": 7168.618976676324, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1504.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 111.0, 114.0, 122.0, 168.0, 161.0, 112.0, 113.0, 124.0], "value": 125.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 112.0, 112.0, 150.0, 115.0, 112.0, 112.0, 111.0, 130.0, 113.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 184.0, 183.0, 187.0, 185.0, 197.0, 263.0, 185.0, 185.0, 184.0], "value": 194.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 91.0, 63.0, 60.0, 60.0, 61.0, 76.0, 60.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 85.0, 70.0, 71.0, 70.0, 85.0, 67.0, 67.0, 85.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 89.0, 87.0, 90.0, 88.0, 89.0, 88.0, 88.0, 87.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 85.0, 84.0, 87.0, 86.0, 87.0, 85.0, 86.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 59.0, 53.0, 53.0, 60.0, 59.0, 60.0, 59.0, 58.0, 58.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 73.0, 76.0, 74.0, 69.0, 95.0, 106.0, 107.0, 73.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 89.0, 91.0, 88.0, 87.0, 89.0, 90.0, 91.0, 89.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 154.0, 159.0, 143.0, 150.0, 163.0, 146.0, 147.0, 176.0, 160.0], "value": 154.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 236.0, 179.0, 174.0, 175.0, 173.0, 228.0, 233.0, 226.0, 172.0], "value": 201.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 68.0, 67.0, 67.0, 67.0, 65.0, 79.0, 72.0], "value": 69.0, "unit": "score"}]}]} 03:20:56 INFO - Return code: 0 03:20:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:20:56 ERROR - # TBPL SUCCESS # 03:20:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:20:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:20:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:20:56 INFO - 2017-02-15 03:20:56.458 screenresolution[1935:22246] starting screenresolution argv=screenresolution get 03:20:56 INFO - 2017-02-15 03:20:56.461 screenresolution[1935:22246] Display 0: 1600x1200x32@60 03:20:56 INFO - 2017-02-15 03:20:56.467 screenresolution[1936:22249] starting screenresolution argv=screenresolution list 03:20:56 INFO - Available Modes on Display 0 03:20:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:20:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:20:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:20:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:20:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:20:56 INFO - Intel Iris: 03:20:56 INFO - Chipset Model: Intel Iris 03:20:56 INFO - Type: GPU 03:20:56 INFO - Bus: Built-In 03:20:56 INFO - VRAM (Dynamic, Max): 1536 MB 03:20:56 INFO - Vendor: Intel (0x8086) 03:20:56 INFO - Device ID: 0x0a2e 03:20:56 INFO - Revision ID: 0x0009 03:20:56 INFO - Displays: 03:20:56 INFO - Display: 03:20:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:20:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:20:56 INFO - Display Serial Number: 1600x1200 60 03:20:56 INFO - Main Display: Yes 03:20:56 INFO - Mirror: Off 03:20:56 INFO - Online: Yes 03:20:56 INFO - Rotation: Supported 03:20:56 INFO - Return code: 0 03:20:56 INFO - Running post-action listener: _resource_record_post_action 03:20:56 INFO - [mozharness: 2017-02-15 11:20:56.706210Z] Finished run-tests step (success) 03:20:56 INFO - Running post-run listener: _resource_record_post_run 03:20:56 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 512000; Write bytes: 554286592; Read time: 10; Write time: 1586 03:20:56 INFO - TinderboxPrint: CPU usage
53.6% 03:20:56 INFO - TinderboxPrint: I/O read bytes / time
512,000 / 10 03:20:56 INFO - TinderboxPrint: I/O write bytes / time
554,286,592 / 1,586 03:20:56 INFO - TinderboxPrint: CPU idle
2,231.5 (46.4%) 03:20:56 INFO - TinderboxPrint: CPU system
956.1 (19.9%) 03:20:56 INFO - TinderboxPrint: CPU user
1,622.0 (33.7%) 03:20:56 INFO - TinderboxPrint: Swap in / out
252,964,864 / 0 03:20:56 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 237951488; Write bytes: 232138240; Read time: 17400; Write time: 451 03:20:56 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 512000; Write bytes: 299481088; Read time: 10; Write time: 1089 03:20:57 INFO - Running post-run listener: _upload_blobber_files 03:20:57 INFO - Blob upload gear active. 03:20:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:20:57 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 03:20:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:20:57 INFO - (blobuploader) - INFO - Open directory for files ... 03:20:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:20:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:20:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:58 INFO - (blobuploader) - INFO - Done attempting. 03:20:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:20:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:20:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:59 INFO - (blobuploader) - INFO - Done attempting. 03:20:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:20:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:21:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:00 INFO - (blobuploader) - INFO - Done attempting. 03:21:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:21:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:21:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:02 INFO - (blobuploader) - INFO - Done attempting. 03:21:02 INFO - (blobuploader) - INFO - Iteration through files over. 03:21:02 INFO - Return code: 0 03:21:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:21:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:21:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54ff7b492828c62ef9e7d83b536ecc6d72f768416aa85225d2cffa01aaacea379508b98804df5f7a4d20eb30b9c771b62271d2b61a05b83a4ca33930b1d37b7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28f19cad153e7129730b1dd2cd7732cc3c2c1e69f538ca930651bf6fa5df3c9aa7eb51fcfa45b87498d60620551f44c5e301472bd4526e741b54f55645cab94b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24c60e33cdd49ffde38a8ca98b57dec184a9d5a44c9d824e5582017f572e8f2bd897a2e1d5474a1222929a4fce226e468b3cd84d9ccfe8f6ea29eeca0e4cc2cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c487e0c9655cdc80aa59c87788bdb67999b6523fbe36e21f6d9eb8ff27b58851096d225af155dcce0d8c101390275509a90189dd821667c4a4009b8312a9da7"} 03:21:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:21:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:21:02 INFO - Contents: 03:21:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54ff7b492828c62ef9e7d83b536ecc6d72f768416aa85225d2cffa01aaacea379508b98804df5f7a4d20eb30b9c771b62271d2b61a05b83a4ca33930b1d37b7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28f19cad153e7129730b1dd2cd7732cc3c2c1e69f538ca930651bf6fa5df3c9aa7eb51fcfa45b87498d60620551f44c5e301472bd4526e741b54f55645cab94b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24c60e33cdd49ffde38a8ca98b57dec184a9d5a44c9d824e5582017f572e8f2bd897a2e1d5474a1222929a4fce226e468b3cd84d9ccfe8f6ea29eeca0e4cc2cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c487e0c9655cdc80aa59c87788bdb67999b6523fbe36e21f6d9eb8ff27b58851096d225af155dcce0d8c101390275509a90189dd821667c4a4009b8312a9da7"} 03:21:02 INFO - Running post-run listener: copy_logs_to_upload_dir 03:21:02 INFO - Copying logs to upload dir... 03:21:02 INFO - mkdir: /builds/slave/test/build/upload/logs 03:21:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.495256 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-15 03:21:02.931831) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-15 03:21:02.935552) ========= 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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54ff7b492828c62ef9e7d83b536ecc6d72f768416aa85225d2cffa01aaacea379508b98804df5f7a4d20eb30b9c771b62271d2b61a05b83a4ca33930b1d37b7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28f19cad153e7129730b1dd2cd7732cc3c2c1e69f538ca930651bf6fa5df3c9aa7eb51fcfa45b87498d60620551f44c5e301472bd4526e741b54f55645cab94b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24c60e33cdd49ffde38a8ca98b57dec184a9d5a44c9d824e5582017f572e8f2bd897a2e1d5474a1222929a4fce226e468b3cd84d9ccfe8f6ea29eeca0e4cc2cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c487e0c9655cdc80aa59c87788bdb67999b6523fbe36e21f6d9eb8ff27b58851096d225af155dcce0d8c101390275509a90189dd821667c4a4009b8312a9da7"} build_url:https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010970 build_url: 'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54ff7b492828c62ef9e7d83b536ecc6d72f768416aa85225d2cffa01aaacea379508b98804df5f7a4d20eb30b9c771b62271d2b61a05b83a4ca33930b1d37b7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28f19cad153e7129730b1dd2cd7732cc3c2c1e69f538ca930651bf6fa5df3c9aa7eb51fcfa45b87498d60620551f44c5e301472bd4526e741b54f55645cab94b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24c60e33cdd49ffde38a8ca98b57dec184a9d5a44c9d824e5582017f572e8f2bd897a2e1d5474a1222929a4fce226e468b3cd84d9ccfe8f6ea29eeca0e4cc2cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c487e0c9655cdc80aa59c87788bdb67999b6523fbe36e21f6d9eb8ff27b58851096d225af155dcce0d8c101390275509a90189dd821667c4a4009b8312a9da7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-15 03:21:02.976542) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:21:02.976934) ========= 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.pmtEAZDL9J/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.p6z1PlBRTG/Listeners TMPDIR=/var/folders/kd/tq17xhqx0413zl3ljjzhv7wc00000w/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.005101 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:21:03.026926) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 03:21:03.030274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 03:21:03.030614) ========= ========= Total master_lag: 0.19 =========