builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0171 starttime: 1492075619.43 results: success (0) buildid: 20170413004025 builduid: 5cc30a4fa32b4eb9b7a70620589e82a8 revision: 1f1c921f172cfb6f299e9ed810b748de6bede180 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:59.433519) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:59.434003) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:59.434281) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:59.458813) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:59.459090) ========= 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.5pIh4zL2yO/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.skwzHjheYZ/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-13 02:26:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2017-04-13 02:26:59 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.097620 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:59.579501) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:59.579860) ========= 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.5pIh4zL2yO/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.skwzHjheYZ/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.022909 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:59.628182) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-13 02:26:59.628540) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1f1c921f172cfb6f299e9ed810b748de6bede180 --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 1f1c921f172cfb6f299e9ed810b748de6bede180 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5pIh4zL2yO/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.skwzHjheYZ/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-13 02:26:59,717 truncating revision to first 12 chars 2017-04-13 02:26:59,717 Setting DEBUG logging. 2017-04-13 02:26:59,717 attempt 1/10 2017-04-13 02:26:59,718 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1f1c921f172c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 02:27:01,299 unpacking tar archive at: mozilla-central-1f1c921f172c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.842808 ========= master_lag: 2.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-13 02:27:03.744663) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:03.744990) ========= script_repo_revision: 1f1c921f172cfb6f299e9ed810b748de6bede180 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:03.745383) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:03.745667) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 02:27:03.765275) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-13 02:27:03.765574) ========= /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.5pIh4zL2yO/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.skwzHjheYZ/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:27:03 INFO - MultiFileLogger online at 20170413 02:27:03 in /builds/slave/test 02:27:03 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:27:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:27:03 INFO - {'append_to_log': False, 02:27:03 INFO - 'base_work_dir': '/builds/slave/test', 02:27:03 INFO - 'blob_upload_branch': 'Firefox', 02:27:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:27:03 INFO - 'branch': 'Firefox', 02:27:03 INFO - 'buildbot_json_path': 'buildprops.json', 02:27:03 INFO - 'config_files': ('talos/mac_config.py',), 02:27:03 INFO - 'default_actions': ('clobber', 02:27:03 INFO - 'read-buildbot-config', 02:27:03 INFO - 'download-and-extract', 02:27:03 INFO - 'populate-webroot', 02:27:03 INFO - 'create-virtualenv', 02:27:03 INFO - 'install', 02:27:03 INFO - 'run-tests'), 02:27:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:27:03 INFO - 'download_minidump_stackwalk': True, 02:27:03 INFO - 'download_symbols': 'ondemand', 02:27:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:27:03 INFO - 'tooltool.py': '/tools/tooltool.py', 02:27:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:27:03 INFO - '/tools/misc-python/virtualenv.py')}, 02:27:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:27:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:27:03 INFO - 'gecko_profile': False, 02:27:03 INFO - 'gecko_profile_interval': 0, 02:27:03 INFO - 'installer_path': 'installer.exe', 02:27:03 INFO - 'log_level': 'info', 02:27:03 INFO - 'log_name': 'talos', 02:27:03 INFO - 'log_to_console': True, 02:27:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:27:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:27:03 INFO - 'opt_config_files': (), 02:27:03 INFO - 'pip_index': False, 02:27:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:03 INFO - 'cmd': ('bash', 02:27:03 INFO - '-c', 02:27:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:03 INFO - 'enabled': True, 02:27:03 INFO - 'halt_on_failure': False, 02:27:03 INFO - 'name': 'check_screen_resolution'},), 02:27:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:03 INFO - 'cmd': ('bash', 02:27:03 INFO - '-c', 02:27:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:03 INFO - 'enabled': True, 02:27:03 INFO - 'halt_on_failure': False, 02:27:03 INFO - 'name': 'check_screen_resolution'},), 02:27:03 INFO - 'run_cmd_checks_enabled': True, 02:27:03 INFO - 'suite': 'dromaeojs', 02:27:03 INFO - 'system_bits': '32', 02:27:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:27:03 INFO - 'title': 't-yosemite-r7-0171', 02:27:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:27:03 INFO - 'use_talos_json': True, 02:27:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:27:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:27:03 INFO - 'work_dir': 'build'} 02:27:03 INFO - [mozharness: 2017-04-13 09:27:03.979169Z] Running clobber step. 02:27:03 INFO - Running pre-action listener: _resource_record_pre_action 02:27:03 INFO - Running main action method: clobber 02:27:03 INFO - rmtree: /builds/slave/test/build 02:27:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:27:06 INFO - Running post-action listener: _resource_record_post_action 02:27:06 INFO - [mozharness: 2017-04-13 09:27:06.234637Z] Finished clobber step (success) 02:27:06 INFO - [mozharness: 2017-04-13 09:27:06.234773Z] Running read-buildbot-config step. 02:27:06 INFO - Running pre-action listener: _resource_record_pre_action 02:27:06 INFO - Running main action method: read_buildbot_config 02:27:06 INFO - Using buildbot properties: 02:27:06 INFO - { 02:27:06 INFO - "project": "", 02:27:06 INFO - "product": "firefox", 02:27:06 INFO - "script_repo_revision": "production", 02:27:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:27:06 INFO - "repository": "", 02:27:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:27:06 INFO - "buildid": "20170413004025", 02:27:06 INFO - "pgo_build": "False", 02:27:06 INFO - "basedir": "/builds/slave/test", 02:27:06 INFO - "buildnumber": 10, 02:27:06 INFO - "slavename": "t-yosemite-r7-0171", 02:27:06 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:27:06 INFO - "platform": "yosemite_r7", 02:27:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:27:06 INFO - "branch": "mozilla-central", 02:27:06 INFO - "repo_path": "mozilla-central", 02:27:06 INFO - "revision": "1f1c921f172cfb6f299e9ed810b748de6bede180", 02:27:06 INFO - "stage_platform": "macosx64", 02:27:06 INFO - "builduid": "5cc30a4fa32b4eb9b7a70620589e82a8", 02:27:06 INFO - "slavebuilddir": "test" 02:27:06 INFO - } 02:27:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 02:27:06 INFO - Running post-action listener: _resource_record_post_action 02:27:06 INFO - [mozharness: 2017-04-13 09:27:06.238080Z] Finished read-buildbot-config step (success) 02:27:06 INFO - [mozharness: 2017-04-13 09:27:06.238230Z] Running download-and-extract step. 02:27:06 INFO - Running pre-action listener: _resource_record_pre_action 02:27:06 INFO - Running main action method: download_and_extract 02:27:06 INFO - mkdir: /builds/slave/test/build/tests 02:27:06 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:27:06 INFO - trying https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:27:06 INFO - Downloading https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:27:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 02:27:07 INFO - Downloaded 1325 bytes. 02:27:07 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:27:07 INFO - Using the following test package requirements: 02:27:07 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:27:07 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:27:07 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:27:07 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:27:07 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:27:07 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:27:07 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:27:07 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:27:07 INFO - u'jsshell-mac.zip'], 02:27:07 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:27:07 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:27:07 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:27:07 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:27:07 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:27:07 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:27:07 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:27:07 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:27:07 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 02:27:07 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:27:07 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:27:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:27:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:27:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:27:07 INFO - Fetch https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:27:09 INFO - Expected file size: 52596488 02:27:09 INFO - Obtained file size: 52596488 02:27:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:27:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:27:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:27:25 INFO - Fetch https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:27:27 INFO - Expected file size: 52596488 02:27:27 INFO - Obtained file size: 52596488 02:27:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:27:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:27:43 INFO - Fetch https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:27:44 INFO - Expected file size: 14407739 02:27:44 INFO - Obtained file size: 14407739 02:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:44 INFO - trying https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:27:44 INFO - Downloading https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:27:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 02:27:46 INFO - Downloaded 71068722 bytes. 02:27:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:27:46 INFO - mkdir: /builds/slave/test/properties 02:27:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:27:46 INFO - Writing to file /builds/slave/test/properties/build_url 02:27:46 INFO - Contents: 02:27:46 INFO - build_url:https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:27:47 INFO - Running post-action listener: _resource_record_post_action 02:27:47 INFO - Running post-action listener: set_extra_try_arguments 02:27:47 INFO - [mozharness: 2017-04-13 09:27:47.537567Z] Finished download-and-extract step (success) 02:27:47 INFO - [mozharness: 2017-04-13 09:27:47.537727Z] Running populate-webroot step. 02:27:47 INFO - Running pre-action listener: _resource_record_pre_action 02:27:47 INFO - Running main action method: populate_webroot 02:27:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:27:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:27:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:27:47 INFO - '--tppagecycles', 02:27:47 INFO - '7'], 02:27:47 INFO - 'tests': ['tsvgm']}}, 02:27:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:27:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:27:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:27:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:27:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:27:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:27:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:27:47 INFO - 'talos_options': ['--disable-e10s'], 02:27:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:27:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:27:47 INFO - 'talos_options': ['--disable-e10s'], 02:27:47 INFO - 'tests': ['damp', 'tps']}, 02:27:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:27:47 INFO - 'tests': ['damp', 'tps']}, 02:27:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:27:47 INFO - 'tests': ['dromaeo_dom']}, 02:27:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:27:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:27:47 INFO - 'tests': ['basic_compositor_video']}, 02:27:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:27:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:27:47 INFO - 'tests': ['a11yr', 02:27:47 INFO - 'ts_paint', 02:27:47 INFO - 'tpaint', 02:27:47 INFO - 'sessionrestore', 02:27:47 INFO - 'sessionrestore_no_auto_restore', 02:27:47 INFO - 'tabpaint']}, 02:27:47 INFO - 'other-e10s': {'tests': ['a11yr', 02:27:47 INFO - 'ts_paint', 02:27:47 INFO - 'tpaint', 02:27:47 INFO - 'sessionrestore', 02:27:47 INFO - 'sessionrestore_no_auto_restore', 02:27:47 INFO - 'tabpaint']}, 02:27:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:27:47 INFO - 'ts_paint', 02:27:47 INFO - 'tpaint', 02:27:47 INFO - 'sessionrestore', 02:27:47 INFO - 'sessionrestore_no_auto_restore', 02:27:47 INFO - 'tabpaint']}, 02:27:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:27:47 INFO - 'ts_paint', 02:27:47 INFO - 'tpaint', 02:27:47 INFO - 'sessionrestore', 02:27:47 INFO - 'sessionrestore_no_auto_restore', 02:27:47 INFO - 'tabpaint']}, 02:27:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:27:47 INFO - 'tests': ['a11yr', 02:27:47 INFO - 'ts_paint', 02:27:47 INFO - 'tpaint', 02:27:47 INFO - 'sessionrestore', 02:27:47 INFO - 'sessionrestore_no_auto_restore', 02:27:47 INFO - 'tabpaint']}, 02:27:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:27:47 INFO - 'tests': ['a11yr', 02:27:47 INFO - 'ts_paint', 02:27:47 INFO - 'tpaint', 02:27:47 INFO - 'sessionrestore', 02:27:47 INFO - 'sessionrestore_no_auto_restore', 02:27:47 INFO - 'tabpaint']}, 02:27:47 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:27:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:27:47 INFO - 'bloom_basic_ref']}, 02:27:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:27:47 INFO - 'tests': ['tsvgx', 02:27:47 INFO - 'tsvgr_opacity', 02:27:47 INFO - 'tart', 02:27:47 INFO - 'tscrollx', 02:27:47 INFO - 'cart', 02:27:47 INFO - 'tsvg_static']}, 02:27:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:27:47 INFO - 'tsvgr_opacity', 02:27:47 INFO - 'tart', 02:27:47 INFO - 'tscrollx', 02:27:47 INFO - 'cart', 02:27:47 INFO - 'tsvg_static']}, 02:27:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:27:47 INFO - 'talos_options': ['--disable-e10s'], 02:27:47 INFO - 'tests': ['tp5o']}, 02:27:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:27:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:27:47 INFO - 'talos_options': ['--disable-e10s', 02:27:47 INFO - '--xperf_path', 02:27:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:47 INFO - 'tests': ['tp5n']}, 02:27:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:27:47 INFO - 'talos_options': ['--xperf_path', 02:27:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:47 INFO - 'tests': ['tp5n']}}, 02:27:47 INFO - 'talos.zip': {'path': '', 02:27:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:27:47 INFO - Running post-action listener: _resource_record_post_action 02:27:47 INFO - [mozharness: 2017-04-13 09:27:47.556886Z] Finished populate-webroot step (success) 02:27:47 INFO - [mozharness: 2017-04-13 09:27:47.556999Z] Running create-virtualenv step. 02:27:47 INFO - Running pre-action listener: _resource_record_pre_action 02:27:47 INFO - Running main action method: create_virtualenv 02:27:47 INFO - Creating virtualenv /builds/slave/test/build/venv 02:27:47 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 02:27:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:27:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:27:47 INFO - Using real prefix '/tools/python27' 02:27:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:27:48 INFO - Installing distribute.............................................................................................................................................................................................done. 02:27:51 INFO - Installing pip.................done. 02:27:51 INFO - Return code: 0 02:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:27:51 INFO - Reading from file tmpfile_stdout 02:27:51 INFO - Output received: 02:27:51 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) 02:27:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:27: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')]} 02:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27: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')]} 02:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:51 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 0x10be0ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf98c8da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbe8d30>, '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 0x10bb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbddbe8>, '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.5pIh4zL2yO/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.skwzHjheYZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 02:27:51 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 02:27:51 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 02:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5pIh4zL2yO/Render', 02:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:51 INFO - 'HOME': '/Users/cltbld', 02:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:51 INFO - 'LOGNAME': 'cltbld', 02:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:51 INFO - 'MOZ_NO_REMOTE': '1', 02:27:51 INFO - 'NO_EM_RESTART': '1', 02:27:51 INFO - 'PAGER': '/bin/cat', 02:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:51 INFO - 'PWD': '/builds/slave/test', 02:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:51 INFO - 'SHELL': '/bin/bash', 02:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.skwzHjheYZ/Listeners', 02:27:51 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 02:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:51 INFO - 'USER': 'cltbld', 02:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:51 INFO - 'XPC_FLAGS': '0x0', 02:27:51 INFO - 'XPC_SERVICE_NAME': '0', 02:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:51 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 02:27:51 INFO - Cleaning up... 02:27:51 INFO - Return code: 0 02:27:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:27: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')]} 02:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27: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')]} 02:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:51 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 0x10be0ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf98c8da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbe8d30>, '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 0x10bb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbddbe8>, '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.5pIh4zL2yO/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.skwzHjheYZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 02:27:51 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 02:27:51 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 02:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5pIh4zL2yO/Render', 02:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:51 INFO - 'HOME': '/Users/cltbld', 02:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:51 INFO - 'LOGNAME': 'cltbld', 02:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:51 INFO - 'MOZ_NO_REMOTE': '1', 02:27:51 INFO - 'NO_EM_RESTART': '1', 02:27:51 INFO - 'PAGER': '/bin/cat', 02:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:51 INFO - 'PWD': '/builds/slave/test', 02:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:51 INFO - 'SHELL': '/bin/bash', 02:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.skwzHjheYZ/Listeners', 02:27:51 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 02:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:51 INFO - 'USER': 'cltbld', 02:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:51 INFO - 'XPC_FLAGS': '0x0', 02:27:51 INFO - 'XPC_SERVICE_NAME': '0', 02:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:52 INFO - Downloading/unpacking psutil>=3.1.1 02:27:52 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 02:27:52 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 02:27:52 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 02:27:52 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 02:27:52 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 02:27:52 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 02:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:27:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:27:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:27:55 INFO - Installing collected packages: psutil 02:27:55 INFO - Running setup.py install for psutil 02:27:55 INFO - building 'psutil._psutil_osx' extension 02:27:55 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 02:27:56 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 02:27:56 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 02:27:56 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 02:27:56 INFO - building 'psutil._psutil_posix' extension 02:27:56 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 02:27:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:27:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:27:56 INFO - ^ 02:27:56 INFO - 1 warning generated. 02:27:56 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 02:27:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:27:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:27:56 INFO - Successfully installed psutil 02:27:56 INFO - Cleaning up... 02:27:56 INFO - Return code: 0 02:27:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:27:56 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:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:56 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:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:57 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 0x10be0ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf98c8da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbe8d30>, '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 0x10bb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbddbe8>, '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.5pIh4zL2yO/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.skwzHjheYZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 02:27:57 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 02:27:57 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 02:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5pIh4zL2yO/Render', 02:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:57 INFO - 'HOME': '/Users/cltbld', 02:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:57 INFO - 'LOGNAME': 'cltbld', 02:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:57 INFO - 'MOZ_NO_REMOTE': '1', 02:27:57 INFO - 'NO_EM_RESTART': '1', 02:27:57 INFO - 'PAGER': '/bin/cat', 02:27:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:57 INFO - 'PWD': '/builds/slave/test', 02:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:57 INFO - 'SHELL': '/bin/bash', 02:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.skwzHjheYZ/Listeners', 02:27:57 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 02:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:57 INFO - 'USER': 'cltbld', 02:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:57 INFO - 'XPC_FLAGS': '0x0', 02:27:57 INFO - 'XPC_SERVICE_NAME': '0', 02:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:27:57 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 02:27:57 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 02:27:57 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 02:27:57 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 02:27:57 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 02:27:57 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 02:28:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:28:00 INFO - Installing collected packages: mozsystemmonitor 02:28:00 INFO - Running setup.py install for mozsystemmonitor 02:28:00 INFO - Successfully installed mozsystemmonitor 02:28:00 INFO - Cleaning up... 02:28:00 INFO - Return code: 0 02:28:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:00 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 0x10be0ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf98c8da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbe8d30>, '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 0x10bb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbddbe8>, '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.5pIh4zL2yO/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.skwzHjheYZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 02:28:00 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 02:28:00 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 02:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5pIh4zL2yO/Render', 02:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:00 INFO - 'HOME': '/Users/cltbld', 02:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:00 INFO - 'LOGNAME': 'cltbld', 02:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:00 INFO - 'MOZ_NO_REMOTE': '1', 02:28:00 INFO - 'NO_EM_RESTART': '1', 02:28:00 INFO - 'PAGER': '/bin/cat', 02:28:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:00 INFO - 'PWD': '/builds/slave/test', 02:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:00 INFO - 'SHELL': '/bin/bash', 02:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.skwzHjheYZ/Listeners', 02:28:00 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 02:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:00 INFO - 'USER': 'cltbld', 02:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:00 INFO - 'XPC_FLAGS': '0x0', 02:28:00 INFO - 'XPC_SERVICE_NAME': '0', 02:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:01 INFO - Downloading/unpacking jsonschema==2.5.1 02:28:01 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 02:28:01 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 02:28:01 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 02:28:01 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 02:28:01 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 02:28:01 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 02:28:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:28:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:28:03 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 02:28:03 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 02:28:03 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 02:28:03 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 02:28:03 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 02:28:03 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 02:28:03 INFO - Downloading functools32-3.2.3-2.tar.gz 02:28:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:28:04 INFO - warning: no files found matching '*.txt' 02:28:04 INFO - no previously-included directories found matching 'build' 02:28:04 INFO - no previously-included directories found matching 'dist' 02:28:04 INFO - no previously-included directories found matching '.git*' 02:28:04 INFO - Installing collected packages: jsonschema, functools32 02:28:04 INFO - Running setup.py install for functools32 02:28:04 INFO - warning: no files found matching '*.txt' 02:28:04 INFO - no previously-included directories found matching 'build' 02:28:04 INFO - no previously-included directories found matching 'dist' 02:28:04 INFO - no previously-included directories found matching '.git*' 02:28:04 INFO - Successfully installed jsonschema functools32 02:28:04 INFO - Cleaning up... 02:28:04 INFO - Return code: 0 02:28:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10be0ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf98c8da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbe8d30>, '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 0x10bb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbddbe8>, '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.5pIh4zL2yO/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.skwzHjheYZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 02:28:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:28:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5pIh4zL2yO/Render', 02:28:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:04 INFO - 'HOME': '/Users/cltbld', 02:28:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:04 INFO - 'LOGNAME': 'cltbld', 02:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:04 INFO - 'MOZ_NO_REMOTE': '1', 02:28:04 INFO - 'NO_EM_RESTART': '1', 02:28:04 INFO - 'PAGER': '/bin/cat', 02:28:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:04 INFO - 'PWD': '/builds/slave/test', 02:28:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:04 INFO - 'SHELL': '/bin/bash', 02:28:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.skwzHjheYZ/Listeners', 02:28:04 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 02:28:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:04 INFO - 'USER': 'cltbld', 02:28:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:04 INFO - 'XPC_FLAGS': '0x0', 02:28:04 INFO - 'XPC_SERVICE_NAME': '0', 02:28:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:28:04 INFO - Cleaning up... 02:28:04 INFO - Return code: 0 02:28:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10be0ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf98c8da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbe8d30>, '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 0x10bb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbddbe8>, '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.5pIh4zL2yO/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.skwzHjheYZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 02:28:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:28:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5pIh4zL2yO/Render', 02:28:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:04 INFO - 'HOME': '/Users/cltbld', 02:28:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:04 INFO - 'LOGNAME': 'cltbld', 02:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:04 INFO - 'MOZ_NO_REMOTE': '1', 02:28:04 INFO - 'NO_EM_RESTART': '1', 02:28:04 INFO - 'PAGER': '/bin/cat', 02:28:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:04 INFO - 'PWD': '/builds/slave/test', 02:28:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:04 INFO - 'SHELL': '/bin/bash', 02:28:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.skwzHjheYZ/Listeners', 02:28:04 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 02:28:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:04 INFO - 'USER': 'cltbld', 02:28:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:04 INFO - 'XPC_FLAGS': '0x0', 02:28:04 INFO - 'XPC_SERVICE_NAME': '0', 02:28:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:04 INFO - Downloading/unpacking blobuploader==1.2.4 02:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:07 INFO - Downloading blobuploader-1.2.4.tar.gz 02:28:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:28:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:28:07 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 02:28:07 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 02:28:07 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 02:28:07 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 02:28:07 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 02:28:07 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 02:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:28:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:08 INFO - Downloading docopt-0.6.1.tar.gz 02:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:28:08 INFO - Installing collected packages: blobuploader, requests, docopt 02:28:08 INFO - Running setup.py install for blobuploader 02:28:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:28:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:28:09 INFO - Running setup.py install for requests 02:28:09 INFO - Running setup.py install for docopt 02:28:09 INFO - Successfully installed blobuploader requests docopt 02:28:09 INFO - Cleaning up... 02:28:09 INFO - Return code: 0 02:28:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:09 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:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:09 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:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:09 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 0x10be0ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf98c8da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbe8d30>, '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 0x10bb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbddbe8>, '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.5pIh4zL2yO/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.skwzHjheYZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 02:28:09 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 02:28:09 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 02:28:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5pIh4zL2yO/Render', 02:28:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:09 INFO - 'HOME': '/Users/cltbld', 02:28:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:09 INFO - 'LOGNAME': 'cltbld', 02:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:09 INFO - 'MOZ_NO_REMOTE': '1', 02:28:09 INFO - 'NO_EM_RESTART': '1', 02:28:09 INFO - 'PAGER': '/bin/cat', 02:28:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:09 INFO - 'PWD': '/builds/slave/test', 02:28:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:09 INFO - 'SHELL': '/bin/bash', 02:28:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.skwzHjheYZ/Listeners', 02:28:09 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 02:28:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:09 INFO - 'USER': 'cltbld', 02:28:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:09 INFO - 'XPC_FLAGS': '0x0', 02:28:09 INFO - 'XPC_SERVICE_NAME': '0', 02:28:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-nXqKc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-fxv8eh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-HRzFfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-_ZGHVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-6vMJxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-IkG44f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-k66RCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-OcqFHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Lnrqxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:10 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Ngq8Ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Oi_H7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-8VWLYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-2sgP0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-roO0vb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-p05dRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-8K2d8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:11 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-JkEmtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:28:11 INFO - Running setup.py install for manifestparser 02:28:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:28:11 INFO - Running setup.py install for mozcrash 02:28:12 INFO - Running setup.py install for mozdebug 02:28:12 INFO - Running setup.py install for mozdevice 02:28:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:28:12 INFO - Running setup.py install for mozfile 02:28:12 INFO - Running setup.py install for mozhttpd 02:28:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:28:12 INFO - Running setup.py install for mozinfo 02:28:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:28:12 INFO - Running setup.py install for mozInstall 02:28:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:28:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:28:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:28:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:28:12 INFO - Running setup.py install for mozleak 02:28:13 INFO - Running setup.py install for mozlog 02:28:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:28:13 INFO - Running setup.py install for moznetwork 02:28:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:28:13 INFO - Running setup.py install for mozprocess 02:28:13 INFO - Running setup.py install for mozprofile 02:28:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:28:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:28:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:28:13 INFO - Running setup.py install for mozrunner 02:28:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:28:13 INFO - Running setup.py install for mozscreenshot 02:28:14 INFO - Running setup.py install for moztest 02:28:14 INFO - Running setup.py install for mozversion 02:28:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:28:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:28:14 INFO - Cleaning up... 02:28:14 INFO - Return code: 0 02:28:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:14 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 0x10be0ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf98c8da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbe8d30>, '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 0x10bb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbddbe8>, '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.5pIh4zL2yO/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.skwzHjheYZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 02:28:14 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 02:28:14 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 02:28:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5pIh4zL2yO/Render', 02:28:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:14 INFO - 'HOME': '/Users/cltbld', 02:28:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:14 INFO - 'LOGNAME': 'cltbld', 02:28:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:14 INFO - 'MOZ_NO_REMOTE': '1', 02:28:14 INFO - 'NO_EM_RESTART': '1', 02:28:14 INFO - 'PAGER': '/bin/cat', 02:28:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:14 INFO - 'PWD': '/builds/slave/test', 02:28:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:14 INFO - 'SHELL': '/bin/bash', 02:28:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.skwzHjheYZ/Listeners', 02:28:14 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 02:28:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:14 INFO - 'USER': 'cltbld', 02:28:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:14 INFO - 'XPC_FLAGS': '0x0', 02:28:14 INFO - 'XPC_SERVICE_NAME': '0', 02:28:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:14 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-WT7gzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:14 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)) 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:14 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-IvD6x8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:14 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)) 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:14 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-peWyhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:14 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)) 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:14 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-wSv4aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-RaEew3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:15 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)) 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-vFUPzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:15 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)) 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-0OIm4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:15 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)) 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-nRFNR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:15 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)) 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-_Z3DDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:15 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)) 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-jCITk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:15 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)) 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-WzSLKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:15 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)) 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-I8AFnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:15 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-llYvOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:16 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)) 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:16 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-O1o1NW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:16 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)) 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:16 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-VKTYXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:16 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)) 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:16 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-nTwFOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:16 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)) 02:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:16 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-iBzRBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:16 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)) 02:28:16 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)) 02:28:16 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)) 02:28:16 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)) 02:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:28:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:19 INFO - Downloading blessings-1.6.tar.gz 02:28:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:28:19 INFO - Installing collected packages: blessings 02:28:19 INFO - Running setup.py install for blessings 02:28:19 INFO - Successfully installed blessings 02:28:19 INFO - Cleaning up... 02:28:19 INFO - Return code: 0 02:28:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:28:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:19 INFO - Reading from file tmpfile_stdout 02:28:19 INFO - Current package versions: 02:28:19 INFO - blessings == 1.6 02:28:19 INFO - blobuploader == 1.2.4 02:28:19 INFO - docopt == 0.6.1 02:28:19 INFO - functools32 == 3.2.3-2 02:28:19 INFO - jsonschema == 2.5.1 02:28:19 INFO - manifestparser == 1.1 02:28:19 INFO - mozInstall == 1.12 02:28:19 INFO - mozcrash == 1.0 02:28:19 INFO - mozdebug == 0.1 02:28:19 INFO - mozdevice == 0.50 02:28:19 INFO - mozfile == 1.2 02:28:19 INFO - mozhttpd == 0.7 02:28:19 INFO - mozinfo == 0.9 02:28:19 INFO - mozleak == 0.1 02:28:19 INFO - mozlog == 3.4 02:28:19 INFO - moznetwork == 0.27 02:28:19 INFO - mozprocess == 0.25 02:28:19 INFO - mozprofile == 0.28 02:28:19 INFO - mozrunner == 6.13 02:28:19 INFO - mozscreenshot == 0.1 02:28:19 INFO - mozsystemmonitor == 0.3 02:28:19 INFO - moztest == 0.8 02:28:19 INFO - mozversion == 1.4 02:28:19 INFO - psutil == 3.1.1 02:28:19 INFO - requests == 1.2.3 02:28:19 INFO - wsgiref == 0.1.2 02:28:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:19 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:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:19 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:28:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:19 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 0x10be0ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf98c8da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbe8d30>, '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 0x10bb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbddbe8>, '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.5pIh4zL2yO/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.skwzHjheYZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 02:28:19 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 02:28:19 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 02:28:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5pIh4zL2yO/Render', 02:28:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:19 INFO - 'HOME': '/Users/cltbld', 02:28:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:19 INFO - 'LOGNAME': 'cltbld', 02:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:19 INFO - 'MOZ_NO_REMOTE': '1', 02:28:19 INFO - 'NO_EM_RESTART': '1', 02:28:19 INFO - 'PAGER': '/bin/cat', 02:28:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:19 INFO - 'PWD': '/builds/slave/test', 02:28:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:19 INFO - 'SHELL': '/bin/bash', 02:28:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.skwzHjheYZ/Listeners', 02:28:19 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 02:28:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:19 INFO - 'USER': 'cltbld', 02:28:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:19 INFO - 'XPC_FLAGS': '0x0', 02:28:19 INFO - 'XPC_SERVICE_NAME': '0', 02:28:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:19 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)) 02:28:19 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)) 02:28:19 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)) 02:28:19 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)) 02:28:20 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)) 02:28:20 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)) 02:28:20 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)) 02:28:20 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)) 02:28:20 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)) 02:28:20 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)) 02:28:20 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)) 02:28:20 INFO - Cleaning up... 02:28:20 INFO - Return code: 0 02:28:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:28:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be0ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf98c8da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbe8d30>, '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 0x10bb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbddbe8>, '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.5pIh4zL2yO/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.skwzHjheYZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 02:28:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:28:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:28:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5pIh4zL2yO/Render', 02:28:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:20 INFO - 'HOME': '/Users/cltbld', 02:28:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:20 INFO - 'LOGNAME': 'cltbld', 02:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:20 INFO - 'MOZ_NO_REMOTE': '1', 02:28:20 INFO - 'NO_EM_RESTART': '1', 02:28:20 INFO - 'PAGER': '/bin/cat', 02:28:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:20 INFO - 'PWD': '/builds/slave/test', 02:28:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:20 INFO - 'SHELL': '/bin/bash', 02:28:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.skwzHjheYZ/Listeners', 02:28:20 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 02:28:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:20 INFO - 'USER': 'cltbld', 02:28:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:20 INFO - 'XPC_FLAGS': '0x0', 02:28:20 INFO - 'XPC_SERVICE_NAME': '0', 02:28:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:28:20 INFO - Cleaning up... 02:28:20 INFO - Return code: 0 02:28:20 INFO - Running post-action listener: _resource_record_post_action 02:28:20 INFO - Running post-action listener: _start_resource_monitoring 02:28:20 INFO - Starting resource monitoring. 02:28:20 INFO - [mozharness: 2017-04-13 09:28:20.308494Z] Finished create-virtualenv step (success) 02:28:20 INFO - [mozharness: 2017-04-13 09:28:20.309584Z] Running install step. 02:28:20 INFO - Running pre-action listener: _resource_record_pre_action 02:28:20 INFO - Running main action method: install 02:28:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:20 INFO - Reading from file tmpfile_stdout 02:28:20 INFO - Detecting whether we're running mozinstall >=1.0... 02:28:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:28:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:28:20 INFO - Reading from file tmpfile_stdout 02:28:20 INFO - Output received: 02:28:20 INFO - Usage: mozinstall [options] installer 02:28:20 INFO - Options: 02:28:20 INFO - -h, --help show this help message and exit 02:28:20 INFO - -d DEST, --destination=DEST 02:28:20 INFO - Directory to install application into. [default: 02:28:20 INFO - "/builds/slave/test"] 02:28:20 INFO - --app=APP Application being installed. [default: firefox] 02:28:20 INFO - mkdir: /builds/slave/test/build/application 02:28:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:28:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:28:40 INFO - Reading from file tmpfile_stdout 02:28:40 INFO - Output received: 02:28:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:28:40 INFO - Running post-action listener: _resource_record_post_action 02:28:40 INFO - [mozharness: 2017-04-13 09:28:40.665492Z] Finished install step (success) 02:28:40 INFO - [mozharness: 2017-04-13 09:28:40.665623Z] Running run-tests step. 02:28:40 INFO - Running pre-action listener: _resource_record_pre_action 02:28:40 INFO - Running main action method: run_tests 02:28:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:28:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:28:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:28:40 INFO - 2017-04-13 02:28:40.680 screenresolution[3240:50837] starting screenresolution argv=screenresolution get 02:28:40 INFO - 2017-04-13 02:28:40.682 screenresolution[3240:50837] Display 0: 1600x1200x32@60 02:28:40 INFO - 2017-04-13 02:28:40.688 screenresolution[3241:50840] starting screenresolution argv=screenresolution list 02:28:40 INFO - Available Modes on Display 0 02:28:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:28:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:28:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:28:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:28:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:28:40 INFO - Intel Iris: 02:28:40 INFO - Chipset Model: Intel Iris 02:28:40 INFO - Type: GPU 02:28:40 INFO - Bus: Built-In 02:28:40 INFO - VRAM (Dynamic, Max): 1536 MB 02:28:40 INFO - Vendor: Intel (0x8086) 02:28:40 INFO - Device ID: 0x0a2e 02:28:40 INFO - Revision ID: 0x0009 02:28:40 INFO - Displays: 02:28:40 INFO - Display: 02:28:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:28:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:28:40 INFO - Display Serial Number: 1600x1200 60 02:28:40 INFO - Main Display: Yes 02:28:40 INFO - Mirror: Off 02:28:40 INFO - Online: Yes 02:28:40 INFO - Rotation: Supported 02:28:40 INFO - Return code: 0 02:28:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:28:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:28:40 INFO - Python 2.7.3 02:28:40 INFO - Return code: 0 02:28:40 INFO - grabbing minidump binary from tooltool 02:28:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:40 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 0x10bbe8d30>, '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 0x10bb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbddbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:28:40 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 02:28:40 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 02:28:40 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 02:28:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:28:41 INFO - Return code: 0 02:28:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:28:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:28:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:28:41 INFO - ENV: RUST_BACKTRACE is now 1 02:28:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:28:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:28:41 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/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 02:28:41 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/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0171 --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 02:28:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5pIh4zL2yO/Render', 02:28:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:41 INFO - 'HOME': '/Users/cltbld', 02:28:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:41 INFO - 'LOGNAME': 'cltbld', 02:28:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:28:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:41 INFO - 'MOZ_NO_REMOTE': '1', 02:28:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:28:41 INFO - 'NO_EM_RESTART': '1', 02:28:41 INFO - 'PAGER': '/bin/cat', 02:28:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:41 INFO - 'PWD': '/builds/slave/test', 02:28:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:28:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:41 INFO - 'RUST_BACKTRACE': '1', 02:28:41 INFO - 'SHELL': '/bin/bash', 02:28:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.skwzHjheYZ/Listeners', 02:28:41 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 02:28:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:41 INFO - 'USER': 'cltbld', 02:28:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:41 INFO - 'XPC_FLAGS': '0x0', 02:28:41 INFO - 'XPC_SERVICE_NAME': '0', 02:28:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:41 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/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 02:28:41 INFO - mozversion application_buildid: 20170413004025 02:28:41 INFO - mozversion application_changeset: 1f1c921f172cfb6f299e9ed810b748de6bede180 02:28:41 INFO - mozversion application_display_name: Nightly 02:28:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:28:41 INFO - mozversion application_name: Firefox 02:28:41 INFO - mozversion application_remotingname: firefox 02:28:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:28:41 INFO - mozversion application_vendor: Mozilla 02:28:41 INFO - mozversion application_version: 55.0a1 02:28:41 INFO - mozversion platform_buildid: 20170413004025 02:28:41 INFO - mozversion platform_changeset: 1f1c921f172cfb6f299e9ed810b748de6bede180 02:28:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:28:41 INFO - mozversion platform_version: 55.0a1 02:28:41 INFO - using testdate: 1492075721 02:28:41 INFO - actual date: 1492075721 02:28:41 INFO - starting webserver on 'localhost:65435' 02:28:41 INFO - SUITE-START | Running 2 tests 02:28:41 INFO - TEST-START | dromaeo_css 02:28:41 INFO - operating with platform_type : mac_ 02:28:41 INFO - Initialising browser for dromaeo_css test... 02:28:41 INFO - TEST-INFO | started process 3251 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpeNChrc/profile http://localhost:65435/getInfo.html) 02:28:43 INFO - PID 3251 | __metrics Screen width/height:1600/1200 02:28:43 INFO - PID 3251 | colorDepth:24 02:28:43 INFO - PID 3251 | Browser inner width/height: 1024/689 02:28:43 INFO - PID 3251 | __metrics 02:28:43 INFO - PID 3251 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:28:43 INFO - PID 3251 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:28:44 INFO - TEST-INFO | 3251: exit 0 02:28:44 INFO - Browser initialized. 02:28:44 INFO - Running cycle 1/1 for dromaeo_css test... 02:28:44 INFO - TEST-INFO | started process 3253 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpeNChrc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:57 INFO - PID 3253 | RSS: Main: 240467968 02:28:57 INFO - PID 3253 | 02:32:01 INFO - PID 3253 | Cycle 1(1): loaded http://localhost:65435/tests/dromaeo/cssquery-dojo.html (next: http://localhost:65435/tests/dromaeo/cssquery-ext.html) 02:32:04 INFO - PID 3253 | RSS: Main: 456032256 02:32:04 INFO - PID 3253 | 02:33:47 INFO - PID 3253 | ************************* 02:33:47 INFO - PID 3253 | A coding exception was thrown and uncaught in a Task. 02:33:47 INFO - PID 3253 | 02:33:47 ERROR - PID 3253 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:33:47 INFO - PID 3253 | Full stack: 02:33:47 INFO - PID 3253 | ************************* 02:33:47 INFO - PID 3253 | ************************* 02:33:47 INFO - PID 3253 | A coding exception was thrown and uncaught in a Task. 02:33:47 INFO - PID 3253 | 02:33:47 ERROR - PID 3253 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:33:47 INFO - PID 3253 | Full stack: 02:33:47 INFO - PID 3253 | ************************* 02:33:47 INFO - PID 3253 | ************************* 02:33:47 INFO - PID 3253 | A coding exception was thrown and uncaught in a Task. 02:33:47 INFO - PID 3253 | 02:33:47 ERROR - PID 3253 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:33:47 INFO - PID 3253 | Full stack: 02:33:47 INFO - PID 3253 | ************************* 02:33:47 INFO - PID 3253 | ************************* 02:33:47 INFO - PID 3253 | A coding exception was thrown and uncaught in a Task. 02:33:47 INFO - PID 3253 | 02:33:47 ERROR - PID 3253 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:33:47 INFO - PID 3253 | Full stack: 02:33:47 INFO - PID 3253 | ************************* 02:35:07 INFO - PID 3253 | Cycle 1(1): loaded http://localhost:65435/tests/dromaeo/cssquery-ext.html (next: http://localhost:65435/tests/dromaeo/cssquery-jquery.html) 02:35:07 INFO - PID 3253 | RSS: Main: 370020352 02:35:07 INFO - PID 3253 | 02:38:10 INFO - PID 3253 | Cycle 1(1): loaded http://localhost:65435/tests/dromaeo/cssquery-jquery.html (next: http://localhost:65435/tests/dromaeo/cssquery-mootools.html) 02:38:10 INFO - PID 3253 | RSS: Main: 356810752 02:38:10 INFO - PID 3253 | 02:41:12 INFO - PID 3253 | Cycle 1(1): loaded http://localhost:65435/tests/dromaeo/cssquery-mootools.html (next: http://localhost:65435/tests/dromaeo/cssquery-prototype.html) 02:41:13 INFO - PID 3253 | RSS: Main: 343420928 02:41:13 INFO - PID 3253 | 02:44:17 INFO - PID 3253 | Cycle 1(1): loaded http://localhost:65435/tests/dromaeo/cssquery-prototype.html (next: http://localhost:65435/tests/dromaeo/cssquery-yui.html) 02:44:17 INFO - PID 3253 | RSS: Main: 335912960 02:44:17 INFO - PID 3253 | 02:47:20 INFO - PID 3253 | Cycle 1(1): loaded http://localhost:65435/tests/dromaeo/cssquery-yui.html (next: http://localhost:65435/tests/dromaeo/cssquery-dojo.html) 02:47:20 INFO - PID 3253 | RSS: Main: 379510784 02:47:20 INFO - PID 3253 | 02:47:20 INFO - PID 3253 | __start_tp_report 02:47:20 INFO - PID 3253 | _x_x_mozilla_page_load 02:47:20 INFO - PID 3253 | _x_x_mozilla_page_load_details 02:47:20 INFO - PID 3253 | |i|pagename|runs| 02:47:20 INFO - PID 3253 | |0;dojo.html;3130.311331507068;3389.2374215801447;3417.572803399575;3436.7766095203824;3468.1329667583104;5888.087346461295;5978.790742324025;5991.101334799777;5992.221011268529;6043.788467403641;5905.409459054093;5973.910391344133;5984.730687119079;6005.069214271794;6011.188489553909;5139.15203991341;5173.249878767577;5189.922151167735;5215.660982036175;5236.7905283788605;5285.497877701631;5296.417394086648;5297.523222909937;5303.787848486056;5304.92042619361;5290.1800220965815;5294.52349288564;5295.894082118355;5298.0993231150605;5298.682079075248;5287.286216360781;5291.941611677641;5295.841124766263;5299.49654782795;5301.973490132544;5112.923306150411;5229.37247530295;5236.895262094775;5240.475952404767;5243.947560524384;6060;6070.301915279755;6076.848078798022;6095.329513753483;6102.176206212149;6212.409821066992;6316.74733010679;6348.238211414614;6360.9045864312075;6367.554271201017;5754.74103665336;6259.186305780265;6275.152854364648;6286.49708023357;6301.4328710416285;6156.661383623944;6166.506679465677;6200.627962322275;6206.751729930797;6207.286162091417;6640.601563906181;6643.50173736962;6653.833654158588;6663.766768163091;6673.666316684146;6192.225971753531;6301.8109456716375;6308.558400911892;6309.905351419732;6310.968445157745;6263.4362907338555;6343.302236754045;6412.839679007969;6460.903086453707;6475;6369.649669268142;6374.776882809079;6374.872502549923;6378.936210637834;6396.392342727433;6312.779052733133;6367.554271200971;6370.936290637127;6386.07401926718;6397.296297407282;4582.541745825458;4927.211646136601;5214.6610470320065;5227.111391063529;5250;4628.051249493846;4633.9536604634195;4636.374089497875;4638.165130276515;4677.18149323867;6075.33171351151;6100.877982440326;6125.295591007002;6134.4172303631085;6139.079138129317;4695.577398034194;5993.790217342459;6348.079528468346;6382.393672601188;6393.264774550893;6139.369480960552;6141.109539116803;6144.631322120597;6148.354422785633;6150.753969841246;5703.486686198179;5745.396733343023;5750.942490575125;5765.538756899438;5767.509761670305;6404.935950640434;6466.385693359123;6601.075849380994;6663.700133493908;6673.666316684049;6436.324185960407;6492.902606461002;6498.122753428227;6501.707423166064;6513.5114866384265;6597.208334999831;6667.233268174222;6669.833254168685;6672.733090676318;6677.499187560855;6172.4136207060255;6232.844178895564;6397.424231818973;6419.614823110628;6435.83910402225;6420.6468644225015;6432.09950606906;6517.608943463218;6528.804135875931;6529.281779004401;6278.340774218824;6291.150694656346;6294.1188233648245;6299.118123462627;6308.52686048539;4741.857744267678;6085.665288409003;6175.104609831459;6210.844728881633;6243.719032643451;6114.3277820888225;6114.449440885012;6122.174448092536;6131.68436510395;6141.700809433504;5211.791528338824;5550.861228469159;6031.215941927521;6186.19579454686;6279.183706118175;6241.21984751906;6252.124702541739;6255.874882502416;6267.96866015667;6271.43557079865 02:47:20 INFO - PID 3253 | |1;ext.html;795.0856515007742;811.7848770075931;1122.640754958413;1142.7371704507968;1144.902683271922;35948.460773088394;36542.26915461692;36629.81685091578;36799.08002299944;36826.4476032859;38414.03964900879;38821.61178388215;38860.61139388605;38871.41692874609;38964.8310550684;6037.849053773647;8449.070602233765;8598.957005214965;8606.225439710424;8609.354298427612;6127.38726127386;8403.579821008925;8609;8619.698310559132;8624.18070283322;8632.741017769446;8688.652453901836;8689.826203475926;8695.739127826144;8696.695615653454;8609.39734218605;8650.005249396338;8665.956670216608;8666.263367613761;8694.347923905732;8384.077751447336;8435.95782021092;8437.662493500253;8438.240558349717;8442.873356899652;35566.11084722877;35864.924052278475;35908.281834363166;35940.64059359399;35942.46086308707;3545.53907991962;3571.1250743567994;3579.910502237439;3582.695470884978;3583.910402239939;27904.88380464779;30071.248218794488;30188.94338698135;30218.54672179897;30267.545986810215;2872.3250036241466;3084.8457577121053;3112.4242015227255;3142.2301536123796;3149.4173577888164;187304.0634796831;226582;233629.83185084135;233690;233770;38634;39698.80150599228;45680.3147952781;45767;45851.541484585396;33930.32139357199;41456;41478;41546;48944.510554894;11889.405529723652;14026.368813403422;14087.647808804863;14116.294185290695;14130.222837744022;11590.072794176445;11617.825732614017;11625.59310424148;11626.244294120856;11633.825492617449;10316;10541.631042913461;11899.821502677467;11926.46330915111;12033.278003319829;4690.377806830872;4883.243097319921;5449.782008719607;5593.328800543879;5604.299462567179;949.7435692363023;1006.4112494190834;1007.9848802267971;1052.4211683574003;1064.5794911010107;20884.746915185136;23200.839958002034;23363.41591460193;23695.28914132579;23768;1023.0281232828843;1053.1574740207802;1137.4938152522213;1140.1334985411147;1169.3802284789076;11733.823992640117;11874.971882169992;11962.58130965412;11968.940155299342;11989.520419183309;365614;366189;366240;366783;366902;52540.94918101616;52630.47369526256;52643.73678131585;52760.47239527556;52947;1500.144917397095;1502.1212590634602;1572.5911263071685;1575.4564675187041;1585.080653221129;23006;23063.769362306834;23166.07335706553;23174.652380214648;23233.65149522725;37218.81390593138;37334.813325933195;37389.81305093457;37415;37507.06232344213;327733;327860;328229;328270.51053213025;329474;34244.97265082051;34264.45809938509;34385.65614343925;34524.65475345315;34532.65467345395;36103;36745.816270919546;43557;44540.77729611331;44608.55391446044;26747.19758407251;27853.16440506788;29111.563326549694;30420.087397378113;30546.694533055273;28985.855070724512;29693.703062969093;29842.955496557515;29845.403091937605;29956.400871982;34038;34647;35224;35277.470837937966;35391;29560;29574;30034.69965300319;30049.549256761602;30177.547336790405;23177;23204.767952320937;23436.76563234414;23632.881835590713;23645 02:47:20 INFO - PID 3253 | |2;jquery.html;348.6426412926748;428.84739850693916;435.7821089455272;439.613140436416;459.402776390692;15475.226238688063;21810.89094554529;22608.208712695057;23537.529249415;23579.82100894957;17954.730679039825;22540.09839606414;22734.090636374567;22782.2026229082;23824;6131.356207598194;6264.749410023594;6291.559590828644;6302.46429053531;6354.269259035201;3694.3719567673425;6006.279246490405;6260.248770147566;6260.686965651722;6291.1192433059405;3833.5974722654137;3851.84592616295;5595.552355811526;6282.026285925689;6286.811395658137;6275.341089185638;6296.055591661242;6304.968475157641;6305.9369406305805;6307.117003619497;5476.7535460903955;5631.549476041907;5687.544996400278;5707.800226992035;5710.314762228518;20804.5839083219;26559.07043253496;27854.771618270694;28033.57949630757;28215.012474563293;2181.520065585569;2228.0419419649543;2231.3752149398197;2232.3749350181974;2234.4190510467233;16304.940178888453;17265;17775;18492.47931375921;18587.535311617183;2120.8833514156713;2123.1719629344566;2178;2179.1719146724304;2179.4333473296897;143241.13517729586;143462;149668;150002.24998875044;151537;11255.606053788078;11597.88402115968;11952.282863028246;12482.687932801753;12532.185407948455;15420.46028389001;15443.227838608025;16381;16853.241604127845;16894.66210675804;8803.867941980747;8854.64581416749;8944.329175311877;9053.456792592466;9097.49963752;8416.537090460084;8426.452280601863;8433.367497437594;8608.440451370641;8632.223099920911;7628.427867909929;7774.805629859299;7795.337396321376;7822.569758663214;7844.215578442224;2126.744790625104;2128.180650449557;2129.8828564428795;2132.072548441433;2253.2226381898217;907.5416914458157;924.3252425729255;948.7153853843819;989.9653512127041;991.6628346362271;19777.40667779969;20539.28112516055;20561;20563.794362056487;20658.896705516378;889.9555022248861;903.1735961595198;999.1806718490768;999.9051039112161;1015.1067060986433;10089.09198172168;10576.99518545737;10586.835448100703;10704.625338113128;10719.571217151382;604687;693017;700689.4069300682;702900;722769;145403;149549;152406;154675.49284789443;157832;1128.6783266768932;1179.8407215026034;1221.248931906871;1234.2717796500108;1237.8576463706609;20331.695024574346;21404;21540.676889846978;22146.003429845372;22170.44573885666;62841;63366.73266534551;64115.35884641094;64520;66373.66813165903;85335.71996420182;90805.54597226971;90992.5450372744;91998;92179.15641686994;8568.828623427622;8720.127987201482;8736.650533978569;8905.73282801517;9074;13590.86409135896;13749.175049497062;13786.65533361674;14084.577462676136;14115.576532704035;10645.201609879136;11244.26912250701;11646.301221926711;11674.94162529182;11826.46780894879;10767.3539587625;10944.507497162487;10960.177986651193;11136.49885755159;11244.887551124384;10795.676129716121;11135.886411358799;11564.132690048113;11908.940455297668;11972.760544788882;7556.093268807778;8625.568721563772;8794.076621954602;8912.019677835304;8972.596233169654;11541.884581154416;11744.29534227949;11886.643400697993;11990.700232494257;12159.81760273579 02:47:20 INFO - PID 3253 | |3;mootools.html;1320.3728229091182;1391.4781956766205;1404.2346920928094;1407.1908652524799;1411.6329754263888;11338.376389298595;11867.465964031619;11878.94060529696;11890.286582805038;11896.940515297421;9460.81078378432;11727.598996291086;11878.52485900565;11918.225315354495;11918.523259069649;2464.1006032798036;2513.6229565565127;2515.220281712666;2517.7356377580363;2519.4583164619603;2089.7910208979133;2367.775061369176;2440.9023639054417;2492.7756501914823;2493.4514406830467;2150.7419109706857;2175.3365223606875;2412.1195763546357;2504.9248522544262;2508.9874550627223;2421.1041914491575;2455.656208130863;2465.60550311951;2467.210492642364;2467.296820406176;2420.298113547087;2424.066734307287;2426.34488688055;2426.8543887366814;2428.9514209715708;11593.362365069917;11836.704082397922;11839.644810655607;11843.230190037706;11843.348615826126;6431.839204019937;6670.666466676696;6679.833004174886;6682.732690692366;6695.531312808105;3941.625545573166;4004.539477960043;4318.373835793793;4325.653947684178;4327.285997810358;1237.3689418396593;1240.640214337841;1265.2155663488604;1265.2218885385514;1287.4335292471376;16250.674986500322;16342.182890855529;16391.91804040984;16394.508164755076;16410.671786564322;5354.54486368663;5421.566274698014;5483.945160548344;5567.47109024642;5650.378458369576;12380.81428778569;12402;12408.937955310166;12418.937905310597;12419.006479481619;6542.705578248895;6544.7382104716235;6547.312532184147;6556.311587283362;6557.737690492327;5964.940350596526;6085.847853803691;6159.907601385983;6184.165137706442;6213.906791398133;4210.705250632487;4332.783360831946;4345.326474396474;4371.169477799208;4373.016071383913;1252.5616034387892;1256.4032084759665;1258.874112588752;1266.4617537546505;1273.9681507962375;665.351282499559;685.9142607174103;771.1209221487509;786.6263524825777;801.6352559585374;6763.7632682855865;6888.104546408935;6962.860742785116;7026.191987921352;7043.964780176067;586.8943590153727;594.5095296380504;597.0924195223323;598.8023952095808;713.4506430048833;3237.012711123083;3602.945955810665;4069.89825254371;4229.809658565372;4360.345948107809;14539.34572944199;15385.999910005814;15392.153431561372;15431.92284038595;15455.459058932884;15153.848461515243;15205.695886082216;15218.315175817115;15309.15799631031;15323.846761532242;10499.790004199873;10835;11110.61112861062;11119.55521779136;11130.387828669502;6610.4711623071;6710.16122984627;6782.559133656297;6814.454843612598;6822.727090916506;12329;12878.162919410208;13483.393247304091;13582.524611638546;13710.862891370958;14036;14145.292735363397;14173.220472873678;14269.357878895682;14359.641008974859;8762.167594078552;8887.866681999905;8908.064653211319;8917.420367676339;8924.107589241024;8792;10947.56209751633;11019.614313498989;11071.391073491035;11085.944570277097;9137.177654011171;9155.679551215675;9266.814663706822;9292.117248861347;9466.810663786548;8623.094575069528;8669.21977022071;8767.824643507221;8768.210861022539;8881.467111973303;8088.595570221348;8192.566300897439;8291.295239904435;8306.252437280673;8315.875261870955;6028.09578563219;6086.969565152146;6172.876542469216;6225.782097626561;6335.936640633535;5458.563314934875;5954.880902381841;6276.623402595676;6279.937200627935;6359.491240700825 02:47:20 INFO - PID 3253 | |4;prototype.html;378.52684144818977;388.0531503132357;402.07923854373485;407.23439932926095;409.1775531182324;4024.766254728632;5109.591232701384;5733.9713301433485;5882.735276912538;5953.987822070248;4626;4673.252279635259;5014.974925125379;5754.827355179351;5759.510441612468;4284.6786491013145;4291.184674911772;4316.000379736085;4320.567943205686;4321.503027151886;4154.646855017327;4310.741355518663;4315.223259813232;4321.178975994551;4323.502797178317;3767.0017445377;3851;4223.070924396643;4301.311790113583;4319.444999800069;3469.3928562501715;4213.052063285767;4244.8514301999285;4252.192083504125;4252.213340531996;3703.7777733335815;3724.9627503724887;3728.2543491301576;3730.608286129944;3736.065983504124;4095.9590404095875;4853.781579828917;5627.170522102556;5641.464060914206;5696.515796157331;3947.5788716062193;4338.8047537860875;5013.298138260647;5084.237364395333;5105.33630628016;5361.383440904268;6061.848453788646;6077.665728384937;6092.45167934888;6092.939070609282;4009.3785463252952;4228.090960443519;4256.44666193396;4269.338252570857;4270.444842170529;5898.528117750569;6318.210223722035;6491.675416229217;6526.216853977506;6666.100076489661;3508.386032444312;4308.116836048603;4311.849085282063;4350.347447882779;4359.738415695068;4190.832366705297;4275.5724427557;4396.559909250333;4402.625776808943;4413.646908247332;5040.722760248222;5115.693058416507;5122.9754049190315;5146.562542183956;5173.379194496684;6168.753249870045;6176.907346389808;6191.876162476725;6194.5973511721595;6206.72069756862;1451.2816156002634;1463.232842319984;1481.8221813382247;1484.2059498168428;1503.5263891874024;855.7774978505508;964.609333220047;974.4263015230493;1002.1910451634869;1002.7944271424341;4948.505149485023;5193.2989046479;5931.1992880960515;5937.821865344046;5954.928112939626;5603.971980140154;6401.1678481798;6401.935980640134;6431.196100487439;6457.515686323544;5588.742532930059;5880.088586269135;5911.576253735948;5961.195238642727;5979.6412215266355;2755.6910467528164;2923.5906973023793;3133.38898914714;3381.2899291149133;3390.5253264542985;5334.333208348957;6648.734050638017;6654.467642588678;6682.465402767767;6690.029945657852;5518.006758783377;6053.273607167168;6067.817965461043;6599.8020059398295;6666.566673166229;5641.210230567724;5962.582619216613;6239.313675495782;6382;6574.737010519621;6039.78860739872;6385.776497822554;6386.840328991812;6409.7436102555375;6437.742490300336;5896.439838215363;5913.793017244376;6291.370862913672;6298.559100862895;6299.748010079546;5201.833121998578;6183.072539119168;6351.079093531504;6392.648404337806;6494.7077381517;3932.2725295820364;4301.096769678403;4304.74171549708;4316.352547117957;4323.394724738476;4946.950530494793;5696.715164241854;6387.8722425550295;6426.3573642635365;6499.707513161825;4701.694389864648;4944.950550494449;5631.014572449887;6192.164057852132;6193.597416167935;5525.778968841201;5614.07367784311;6176.166217560572;6209.627422354673;6224.346443623535;5062.392512898476;5143.434222235476;5977.625146216482;6000.069989151689;6008.639481631116;6114.419130182803;6121.204852990996;6151.815445536641;6195.659238741912;6239.844003899939;5711.7429715662065;5896.435050752936;5994.9808532548695;6018.3981601841215;6029.8191054268445 02:47:20 INFO - PID 3253 | |5;yui.html;716.1442076718324;859.3683642522747;869.4739682492092;870.0472982084615;886.9955650221748;13039.217646941179;13381.7323653527;13385.39765710544;13401.99485038621;13504.662383440418;11797.646070617873;14872.810175185987;17076.743848842245;17405.98526841075;17566.385176518823;978.8238117138894;991.0634450444333;1007.0433088565856;1009.3741880034389;1009.9141572966307;1004.9196064314874;1005.1004351105756;1005.1807776662033;1006.436395618456;1011.4133802394593;709.779968209854;724.7390939261888;999.7902537929115;1049.5014867937707;1068.754186537097;1044.5560636729397;1045.2526443592867;1045.6653870761322;1046.3878631000873;1049.1921220660124;773.872311068673;774.082711986298;774.5855967057654;775.2325198053886;776.2741836383;16169;16490.42283520083;16622.92475891964;16695.415660451825;16798.244079016473;16236.51290461288;16292.83707162925;16669.666606667797;16830.410935617194;17069.146542672817;13211.801822972662;13367.532136375277;13474.12418192824;13608.523701670394;13623.114497557626;3372.15696075981;3373.139849338403;3406.7104296134858;3407.369636617234;3410.4031794435873;115497;117721;117810;118231.40884295524;119480.80519194697;10966.780664386828;11938.403079845973;11996.280223186463;12166;12192;16928.40750573724;19660.70508942367;24736;25016.4996700065;25064.87467562687;7249.710011599583;8591.613377398033;8896.955515222382;8903.821923561622;8903.955480222687;4427.424434823452;4642.2340313847835;4713.08094921487;4821.831235906797;4822.348982887335;4638;4726.1020406122725;4815.398075240595;4819.927701084486;4830.806767729322;862.5169904853302;939.6523286384082;975.7414285214423;987.8370068938689;988.3081842710131;645.7029766307458;660.8645227728305;662.8177251255943;672.0725398949419;681.3969636871404;10984.340939543654;11123.332600044023;11580.826287605692;11679.532818687327;11957.880421195676;634.2484156274829;642.1459458919626;653.6764301670703;654.6693919570681;659.9109120268798;4456.68803183774;5352.330958630171;5369.677819330853;5427.6743395395615;5615.803446879339;104440;105792.47103764433;107228.46385768021;107630;110685.89314106917;61301;91965;94605.053949461;96269;96952;74452.62773686097;74560.88158677625;74765;76622;76773;11896.167268291483;11943.283402995849;12207.877921220674;12552.937235313764;12607.495700172258;37896.29466673951;38517.422238667;39100.80449597734;39553.033522485355;41080;51594;51775.74112129415;52182.47817521928;52294.215586765466;53296.73351633217;13991.650208744863;15645;15939.043657380595;16027.118508482145;16353;17951.820481795014;18011.919284842952;18139.45581632553;18256.817431826043;18468;16288.185590720654;16351.918240408722;16485.91757041207;16501.33994640201;16637.918535295168;15902.602434938757;16304.347826086823;16498.505044848673;16644.667106657558;16651.583710407336;13448.26034568069;15563.377464901278;15997.920010399874;16146.19269036567;16296.348146074024;10045.69862904114;11586.826197607212;11825.113116516124;11964.46159922823;11991.22057066288;12459.190152640049;12528;12539.24764514132;12542.811857822322;12597.685057873627 02:47:20 INFO - PID 3253 | __end_tp_report 02:47:20 INFO - PID 3253 | __start_cc_report 02:47:20 INFO - PID 3253 | _x_x_mozilla_cycle_collect,2532 02:47:20 INFO - PID 3253 | __end_cc_report 02:47:20 INFO - PID 3253 | __startTimestamp1492076840614__endTimestamp 02:47:20 INFO - PID 3253 | 02:47:20 INFO - PID 3253 | ------- Summary: start ------- 02:47:20 INFO - PID 3253 | Number of tests: 6 02:47:20 INFO - PID 3253 | 02:47:20 INFO - PID 3253 | [#0] dojo.html Cycles:165 Average:5910.73 Median:6150.75 stddev:699.21 (11.4%) stddev-sans-first:666.46 02:47:20 INFO - PID 3253 | Values: 3130.3 3389.2 3417.6 3436.8 3468.1 5888.1 5978.8 5991.1 5992.2 6043.8 5905.4 5973.9 5984.7 6005.1 6011.2 5139.2 5173.2 5189.9 5215.7 5236.8 5285.5 5296.4 5297.5 5303.8 5304.9 5290.2 5294.5 5295.9 5298.1 5298.7 5287.3 5291.9 5295.8 5299.5 5302.0 5112.9 5229.4 5236.9 5240.5 5243.9 6060.0 6070.3 6076.8 6095.3 6102.2 6212.4 6316.7 6348.2 6360.9 6367.6 5754.7 6259.2 6275.2 6286.5 6301.4 6156.7 6166.5 6200.6 6206.8 6207.3 6640.6 6643.5 6653.8 6663.8 6673.7 6192.2 6301.8 6308.6 6309.9 6311.0 6263.4 6343.3 6412.8 6460.9 6475.0 6369.6 6374.8 6374.9 6378.9 6396.4 6312.8 6367.6 6370.9 6386.1 6397.3 4582.5 4927.2 5214.7 5227.1 5250.0 4628.1 4634.0 4636.4 4638.2 4677.2 6075.3 6100.9 6125.3 6134.4 6139.1 4695.6 5993.8 6348.1 6382.4 6393.3 6139.4 6141.1 6144.6 6148.4 6150.8 5703.5 5745.4 5750.9 5765.5 5767.5 6404.9 6466.4 6601.1 6663.7 6673.7 6436.3 6492.9 6498.1 6501.7 6513.5 6597.2 6667.2 6669.8 6672.7 6677.5 6172.4 6232.8 6397.4 6419.6 6435.8 6420.6 6432.1 6517.6 6528.8 6529.3 6278.3 6291.2 6294.1 6299.1 6308.5 4741.9 6085.7 6175.1 6210.8 6243.7 6114.3 6114.4 6122.2 6131.7 6141.7 5211.8 5550.9 6031.2 6186.2 6279.2 6241.2 6252.1 6255.9 6268.0 6271.4 02:47:20 INFO - PID 3253 | 02:47:20 INFO - PID 3253 | [#1] ext.html Cycles:180 Average:44684.67 Median:34325.06 stddev:82269.14 (239.7%) stddev-sans-first:82433.93 02:47:20 INFO - PID 3253 | Values: 795.1 811.8 1122.6 1142.7 1144.9 35948.5 36542.3 36629.8 36799.1 36826.4 38414.0 38821.6 38860.6 38871.4 38964.8 6037.8 8449.1 8599.0 8606.2 8609.4 6127.4 8403.6 8609.0 8619.7 8624.2 8632.7 8688.7 8689.8 8695.7 8696.7 8609.4 8650.0 8666.0 8666.3 8694.3 8384.1 8436.0 8437.7 8438.2 8442.9 35566.1 35864.9 35908.3 35940.6 35942.5 3545.5 3571.1 3579.9 3582.7 3583.9 27904.9 30071.2 30188.9 30218.5 30267.5 2872.3 3084.8 3112.4 3142.2 3149.4 187304.1 226582.0 233629.8 233690.0 233770.0 38634.0 39698.8 45680.3 45767.0 45851.5 33930.3 41456.0 41478.0 41546.0 48944.5 11889.4 14026.4 14087.6 14116.3 14130.2 11590.1 11617.8 11625.6 11626.2 11633.8 10316.0 10541.6 11899.8 11926.5 12033.3 4690.4 4883.2 5449.8 5593.3 5604.3 949.7 1006.4 1008.0 1052.4 1064.6 20884.7 23200.8 23363.4 23695.3 23768.0 1023.0 1053.2 1137.5 1140.1 1169.4 11733.8 11875.0 11962.6 11968.9 11989.5 365614.0 366189.0 366240.0 366783.0 366902.0 52540.9 52630.5 52643.7 52760.5 52947.0 1500.1 1502.1 1572.6 1575.5 1585.1 23006.0 23063.8 23166.1 23174.7 23233.7 37218.8 37334.8 37389.8 37415.0 37507.1 327733.0 327860.0 328229.0 328270.5 329474.0 34245.0 34264.5 34385.7 34524.7 34532.7 36103.0 36745.8 43557.0 44540.8 44608.6 26747.2 27853.2 29111.6 30420.1 30546.7 28985.9 29693.7 29843.0 29845.4 29956.4 34038.0 34647.0 35224.0 35277.5 35391.0 29560.0 29574.0 30034.7 30049.5 30177.5 23177.0 23204.8 23436.8 23632.9 23645.0 02:47:20 INFO - PID 3253 | 02:47:20 INFO - PID 3253 | [#2] jquery.html Cycles:180 Average:40228.67 Median:22574.15 stddev:115094.51 (509.9%) stddev-sans-first:115378.42 02:47:20 INFO - PID 3253 | Values: 348.6 428.8 435.8 439.6 459.4 15475.2 21810.9 22608.2 23537.5 23579.8 17954.7 22540.1 22734.1 22782.2 23824.0 6131.4 6264.7 6291.6 6302.5 6354.3 3694.4 6006.3 6260.2 6260.7 6291.1 3833.6 3851.8 5595.6 6282.0 6286.8 6275.3 6296.1 6305.0 6305.9 6307.1 5476.8 5631.5 5687.5 5707.8 5710.3 20804.6 26559.1 27854.8 28033.6 28215.0 2181.5 2228.0 2231.4 2232.4 2234.4 16304.9 17265.0 17775.0 18492.5 18587.5 2120.9 2123.2 2178.0 2179.2 2179.4 143241.1 143462.0 149668.0 150002.2 151537.0 11255.6 11597.9 11952.3 12482.7 12532.2 15420.5 15443.2 16381.0 16853.2 16894.7 8803.9 8854.6 8944.3 9053.5 9097.5 8416.5 8426.5 8433.4 8608.4 8632.2 7628.4 7774.8 7795.3 7822.6 7844.2 2126.7 2128.2 2129.9 2132.1 2253.2 907.5 924.3 948.7 990.0 991.7 19777.4 20539.3 20561.0 20563.8 20658.9 890.0 903.2 999.2 999.9 1015.1 10089.1 10577.0 10586.8 10704.6 10719.6 604687.0 693017.0 700689.4 702900.0 722769.0 145403.0 149549.0 152406.0 154675.5 157832.0 1128.7 1179.8 1221.2 1234.3 1237.9 20331.7 21404.0 21540.7 22146.0 22170.4 62841.0 63366.7 64115.4 64520.0 66373.7 85335.7 90805.5 90992.5 91998.0 92179.2 8568.8 8720.1 8736.7 8905.7 9074.0 13590.9 13749.2 13786.7 14084.6 14115.6 10645.2 11244.3 11646.3 11674.9 11826.5 10767.4 10944.5 10960.2 11136.5 11244.9 10795.7 11135.9 11564.1 11908.9 11972.8 7556.1 8625.6 8794.1 8912.0 8972.6 11541.9 11744.3 11886.6 11990.7 12159.8 02:47:20 INFO - PID 3253 | 02:47:20 INFO - PID 3253 | [#3] mootools.html Cycles:180 Average:7187.39 Median:2516.48 stddev:4615.27 (183.4%) stddev-sans-first:4607.16 02:47:20 INFO - PID 3253 | Values: 1320.4 1391.5 1404.2 1407.2 1411.6 11338.4 11867.5 11878.9 11890.3 11896.9 9460.8 11727.6 11878.5 11918.2 11918.5 2464.1 2513.6 2515.2 2517.7 2519.5 2089.8 2367.8 2440.9 2492.8 2493.5 2150.7 2175.3 2412.1 2504.9 2509.0 2421.1 2455.7 2465.6 2467.2 2467.3 2420.3 2424.1 2426.3 2426.9 2429.0 11593.4 11836.7 11839.6 11843.2 11843.3 6431.8 6670.7 6679.8 6682.7 6695.5 3941.6 4004.5 4318.4 4325.7 4327.3 1237.4 1240.6 1265.2 1265.2 1287.4 16250.7 16342.2 16391.9 16394.5 16410.7 5354.5 5421.6 5483.9 5567.5 5650.4 12380.8 12402.0 12408.9 12418.9 12419.0 6542.7 6544.7 6547.3 6556.3 6557.7 5964.9 6085.8 6159.9 6184.2 6213.9 4210.7 4332.8 4345.3 4371.2 4373.0 1252.6 1256.4 1258.9 1266.5 1274.0 665.4 685.9 771.1 786.6 801.6 6763.8 6888.1 6962.9 7026.2 7044.0 586.9 594.5 597.1 598.8 713.5 3237.0 3602.9 4069.9 4229.8 4360.3 14539.3 15386.0 15392.2 15431.9 15455.5 15153.8 15205.7 15218.3 15309.2 15323.8 10499.8 10835.0 11110.6 11119.6 11130.4 6610.5 6710.2 6782.6 6814.5 6822.7 12329.0 12878.2 13483.4 13582.5 13710.9 14036.0 14145.3 14173.2 14269.4 14359.6 8762.2 8887.9 8908.1 8917.4 8924.1 8792.0 10947.6 11019.6 11071.4 11085.9 9137.2 9155.7 9266.8 9292.1 9466.8 8623.1 8669.2 8767.8 8768.2 8881.5 8088.6 8192.6 8291.3 8306.3 8315.9 6028.1 6087.0 6172.9 6225.8 6335.9 5458.6 5954.9 6276.6 6279.9 6359.5 02:47:20 INFO - PID 3253 | 02:47:20 INFO - PID 3253 | [#4] prototype.html Cycles:180 Average:4935.23 Median:5557.26 stddev:1541.52 (27.7%) stddev-sans-first:1507.42 02:47:20 INFO - PID 3253 | Values: 378.5 388.1 402.1 407.2 409.2 4024.8 5109.6 5734.0 5882.7 5954.0 4626.0 4673.3 5015.0 5754.8 5759.5 4284.7 4291.2 4316.0 4320.6 4321.5 4154.6 4310.7 4315.2 4321.2 4323.5 3767.0 3851.0 4223.1 4301.3 4319.4 3469.4 4213.1 4244.9 4252.2 4252.2 3703.8 3725.0 3728.3 3730.6 3736.1 4096.0 4853.8 5627.2 5641.5 5696.5 3947.6 4338.8 5013.3 5084.2 5105.3 5361.4 6061.8 6077.7 6092.5 6092.9 4009.4 4228.1 4256.4 4269.3 4270.4 5898.5 6318.2 6491.7 6526.2 6666.1 3508.4 4308.1 4311.8 4350.3 4359.7 4190.8 4275.6 4396.6 4402.6 4413.6 5040.7 5115.7 5123.0 5146.6 5173.4 6168.8 6176.9 6191.9 6194.6 6206.7 1451.3 1463.2 1481.8 1484.2 1503.5 855.8 964.6 974.4 1002.2 1002.8 4948.5 5193.3 5931.2 5937.8 5954.9 5604.0 6401.2 6401.9 6431.2 6457.5 5588.7 5880.1 5911.6 5961.2 5979.6 2755.7 2923.6 3133.4 3381.3 3390.5 5334.3 6648.7 6654.5 6682.5 6690.0 5518.0 6053.3 6067.8 6599.8 6666.6 5641.2 5962.6 6239.3 6382.0 6574.7 6039.8 6385.8 6386.8 6409.7 6437.7 5896.4 5913.8 6291.4 6298.6 6299.7 5201.8 6183.1 6351.1 6392.6 6494.7 3932.3 4301.1 4304.7 4316.4 4323.4 4947.0 5696.7 6387.9 6426.4 6499.7 4701.7 4945.0 5631.0 6192.2 6193.6 5525.8 5614.1 6176.2 6209.6 6224.3 5062.4 5143.4 5977.6 6000.1 6008.6 6114.4 6121.2 6151.8 6195.7 6239.8 5711.7 5896.4 5995.0 6018.4 6029.8 02:47:20 INFO - PID 3253 | 02:47:20 INFO - PID 3253 | [#5] yui.html Cycles:180 Average:20948.08 Median:16879.41 stddev:29607.22 (175.4%) stddev-sans-first:29651.30 02:47:20 INFO - PID 3253 | Values: 716.1 859.4 869.5 870.0 887.0 13039.2 13381.7 13385.4 13402.0 13504.7 11797.6 14872.8 17076.7 17406.0 17566.4 978.8 991.1 1007.0 1009.4 1009.9 1004.9 1005.1 1005.2 1006.4 1011.4 709.8 724.7 999.8 1049.5 1068.8 1044.6 1045.3 1045.7 1046.4 1049.2 773.9 774.1 774.6 775.2 776.3 16169.0 16490.4 16622.9 16695.4 16798.2 16236.5 16292.8 16669.7 16830.4 17069.1 13211.8 13367.5 13474.1 13608.5 13623.1 3372.2 3373.1 3406.7 3407.4 3410.4 115497.0 117721.0 117810.0 118231.4 119480.8 10966.8 11938.4 11996.3 12166.0 12192.0 16928.4 19660.7 24736.0 25016.5 25064.9 7249.7 8591.6 8897.0 8903.8 8904.0 4427.4 4642.2 4713.1 4821.8 4822.3 4638.0 4726.1 4815.4 4819.9 4830.8 862.5 939.7 975.7 987.8 988.3 645.7 660.9 662.8 672.1 681.4 10984.3 11123.3 11580.8 11679.5 11957.9 634.2 642.1 653.7 654.7 659.9 4456.7 5352.3 5369.7 5427.7 5615.8 104440.0 105792.5 107228.5 107630.0 110685.9 61301.0 91965.0 94605.1 96269.0 96952.0 74452.6 74560.9 74765.0 76622.0 76773.0 11896.2 11943.3 12207.9 12552.9 12607.5 37896.3 38517.4 39100.8 39553.0 41080.0 51594.0 51775.7 52182.5 52294.2 53296.7 13991.7 15645.0 15939.0 16027.1 16353.0 17951.8 18011.9 18139.5 18256.8 18468.0 16288.2 16351.9 16485.9 16501.3 16637.9 15902.6 16304.3 16498.5 16644.7 16651.6 13448.3 15563.4 15997.9 16146.2 16296.3 10045.7 11586.8 11825.1 11964.5 11991.2 12459.2 12528.0 12539.2 12542.8 12597.7 02:47:20 INFO - PID 3253 | -------- Summary: end -------- 02:47:20 INFO - PID 3253 | 02:47:21 INFO - TEST-INFO | 3253: exit 0 02:47:21 INFO - TEST-OK | dromaeo_css | took 1120155ms 02:47:21 INFO - TEST-START | kraken 02:47:21 INFO - operating with platform_type : mac_ 02:47:21 INFO - Initialising browser for kraken test... 02:47:21 INFO - TEST-INFO | started process 3257 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpV79dPr/profile http://localhost:65435/getInfo.html) 02:47:24 INFO - PID 3257 | __metrics Screen width/height:1600/1200 02:47:24 INFO - PID 3257 | colorDepth:24 02:47:24 INFO - PID 3257 | Browser inner width/height: 1024/689 02:47:24 INFO - PID 3257 | __metrics 02:47:24 INFO - PID 3257 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:47:24 INFO - PID 3257 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:47:25 INFO - TEST-INFO | 3257: exit 0 02:47:25 INFO - Browser initialized. 02:47:25 INFO - Running cycle 1/1 for kraken test... 02:47:25 INFO - TEST-INFO | started process 3258 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpV79dPr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:47:37 INFO - PID 3258 | RSS: Main: 240459776 02:47:37 INFO - PID 3258 | 02:47:40 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:65435/tests/kraken/driver.html?testName=audio-beat-detection) 02:47:41 INFO - PID 3258 | RSS: Main: 291164160 02:47:41 INFO - PID 3258 | 02:47:44 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:65435/tests/kraken/driver.html?testName=audio-dft) 02:47:44 INFO - PID 3258 | RSS: Main: 290979840 02:47:44 INFO - PID 3258 | 02:47:48 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:65435/tests/kraken/driver.html?testName=audio-fft) 02:47:48 INFO - PID 3258 | RSS: Main: 385626112 02:47:48 INFO - PID 3258 | 02:47:50 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:65435/tests/kraken/driver.html?testName=audio-oscillator) 02:47:51 INFO - PID 3258 | RSS: Main: 288636928 02:47:51 INFO - PID 3258 | 02:47:53 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:65435/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:47:53 INFO - PID 3258 | RSS: Main: 291549184 02:47:53 INFO - PID 3258 | 02:47:56 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:65435/tests/kraken/driver.html?testName=imaging-darkroom) 02:47:57 INFO - PID 3258 | RSS: Main: 333012992 02:47:57 INFO - PID 3258 | 02:48:00 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:65435/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:01 INFO - PID 3258 | RSS: Main: 340574208 02:48:01 INFO - PID 3258 | 02:48:04 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:65435/tests/kraken/driver.html?testName=json-parse-financial) 02:48:04 INFO - PID 3258 | RSS: Main: 344461312 02:48:04 INFO - PID 3258 | 02:48:06 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:65435/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:48:06 INFO - PID 3258 | RSS: Main: 310329344 02:48:06 INFO - PID 3258 | 02:48:08 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:65435/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:48:09 INFO - PID 3258 | RSS: Main: 314351616 02:48:09 INFO - PID 3258 | 02:48:11 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:65435/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:48:12 INFO - PID 3258 | RSS: Main: 316887040 02:48:12 INFO - PID 3258 | 02:48:15 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:65435/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:48:16 INFO - PID 3258 | RSS: Main: 331563008 02:48:16 INFO - PID 3258 | 02:48:19 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:65435/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:48:19 INFO - PID 3258 | RSS: Main: 325672960 02:48:19 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | Cycle 1(1): loaded http://localhost:65435/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:65435/tests/kraken/driver.html?testName=ai-astar) 02:48:21 INFO - PID 3258 | RSS: Main: 387125248 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | __start_tp_report 02:48:21 INFO - PID 3258 | _x_x_mozilla_page_load 02:48:21 INFO - PID 3258 | _x_x_mozilla_page_load_details 02:48:21 INFO - PID 3258 | |i|pagename|runs| 02:48:21 INFO - PID 3258 | |0;ai-astar;112;110;110;112;111;116;163;164;110;111 02:48:21 INFO - PID 3258 | |1;audio-beat-detection;132;110;107;145;110;109;118;107;127;115 02:48:21 INFO - PID 3258 | |2;audio-dft;186;186;199;186;184;180;183;186;186;185 02:48:21 INFO - PID 3258 | |3;audio-fft;81;60;61;93;65;61;58;59;77;62 02:48:21 INFO - PID 3258 | |4;audio-oscillator;91;70;89;69;69;70;87;69;69;84 02:48:21 INFO - PID 3258 | |5;imaging-gaussian-blur;93;92;94;92;93;93;94;92;93;88 02:48:21 INFO - PID 3258 | |6;imaging-darkroom;95;96;96;96;95;96;96;97;96;97 02:48:21 INFO - PID 3258 | |7;imaging-desaturate;87;86;84;86;87;87;86;94;86;86 02:48:21 INFO - PID 3258 | |8;json-parse-financial;52;53;49;50;50;51;50;52;51;50 02:48:21 INFO - PID 3258 | |9;json-stringify-tinderbox;56;60;63;66;60;60;60;60;60;61 02:48:21 INFO - PID 3258 | |10;stanford-crypto-aes;85;83;82;84;82;101;102;103;104;83 02:48:21 INFO - PID 3258 | |11;stanford-crypto-ccm;141;140;138;137;144;133;129;141;192;175 02:48:21 INFO - PID 3258 | |12;stanford-crypto-pbkdf2;188;188;184;188;185;201;184;188;185;182 02:48:21 INFO - PID 3258 | |13;stanford-crypto-sha256-iterative;68;69;68;68;67;65;88;69;68;67 02:48:21 INFO - PID 3258 | __end_tp_report 02:48:21 INFO - PID 3258 | __start_cc_report 02:48:21 INFO - PID 3258 | _x_x_mozilla_cycle_collect,2326 02:48:21 INFO - PID 3258 | __end_cc_report 02:48:21 INFO - PID 3258 | __startTimestamp1492076901820__endTimestamp 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | ------- Summary: start ------- 02:48:21 INFO - PID 3258 | Number of tests: 14 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#0] ai-astar Cycles:10 Average:121.90 Median:112.00 stddev:22.00 (19.6%) stddev-sans-first:23.04 02:48:21 INFO - PID 3258 | Values: 112.0 110.0 110.0 112.0 111.0 116.0 163.0 164.0 110.0 111.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#1] audio-beat-detection Cycles:10 Average:118.00 Median:116.50 stddev:12.76 (11.0%) stddev-sans-first:12.49 02:48:21 INFO - PID 3258 | Values: 132.0 110.0 107.0 145.0 110.0 109.0 118.0 107.0 127.0 115.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#2] audio-dft Cycles:10 Average:186.10 Median:186.00 stddev:4.93 (2.7%) stddev-sans-first:5.23 02:48:21 INFO - PID 3258 | Values: 186.0 186.0 199.0 186.0 184.0 180.0 183.0 186.0 186.0 185.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#3] audio-fft Cycles:10 Average:67.70 Median:63.50 stddev:11.84 (18.6%) stddev-sans-first:11.54 02:48:21 INFO - PID 3258 | Values: 81.0 60.0 61.0 93.0 65.0 61.0 58.0 59.0 77.0 62.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:77.00 stddev:9.67 (12.6%) stddev-sans-first:8.77 02:48:21 INFO - PID 3258 | Values: 91.0 70.0 89.0 69.0 69.0 70.0 87.0 69.0 69.0 84.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:93.00 stddev:1.71 (1.8%) stddev-sans-first:1.80 02:48:21 INFO - PID 3258 | Values: 93.0 92.0 94.0 92.0 93.0 93.0 94.0 92.0 93.0 88.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 02:48:21 INFO - PID 3258 | Values: 95.0 96.0 96.0 96.0 95.0 96.0 96.0 97.0 96.0 97.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#7] imaging-desaturate Cycles:10 Average:86.90 Median:86.50 stddev:2.64 (3.1%) stddev-sans-first:2.80 02:48:21 INFO - PID 3258 | Values: 87.0 86.0 84.0 86.0 87.0 87.0 86.0 94.0 86.0 86.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:1.23 (2.4%) stddev-sans-first:1.22 02:48:21 INFO - PID 3258 | Values: 52.0 53.0 49.0 50.0 50.0 51.0 50.0 52.0 51.0 50.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:60.00 stddev:2.55 (4.2%) stddev-sans-first:2.09 02:48:21 INFO - PID 3258 | Values: 56.0 60.0 63.0 66.0 60.0 60.0 60.0 60.0 60.0 61.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:82.50 stddev:10.05 (12.2%) stddev-sans-first:10.43 02:48:21 INFO - PID 3258 | Values: 85.0 83.0 82.0 84.0 82.0 101.0 102.0 103.0 104.0 83.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:141.00 stddev:20.11 (14.3%) stddev-sans-first:21.21 02:48:21 INFO - PID 3258 | Values: 141.0 140.0 138.0 137.0 144.0 133.0 129.0 141.0 192.0 175.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:188.00 stddev:5.27 (2.8%) stddev-sans-first:5.59 02:48:21 INFO - PID 3258 | Values: 188.0 188.0 184.0 188.0 185.0 201.0 184.0 188.0 185.0 182.0 02:48:21 INFO - PID 3258 | 02:48:21 INFO - PID 3258 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:68.00 stddev:6.53 (9.6%) stddev-sans-first:6.90 02:48:21 INFO - PID 3258 | Values: 68.0 69.0 68.0 68.0 67.0 65.0 88.0 69.0 68.0 67.0 02:48:21 INFO - PID 3258 | -------- Summary: end -------- 02:48:21 INFO - PID 3258 | 02:48:23 INFO - TEST-INFO | 3258: exit 0 02:48:23 INFO - TEST-OK | kraken | took 61692ms 02:48:23 INFO - SUITE-END | took 1181s 02:48:23 INFO - Completed test suite (00:19:42) 02:48:23 INFO - javascript benchmark 02:48:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7674.013156928826, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3130.311331507068, 3389.2374215801447, 3417.572803399575, 3436.7766095203824, 3468.1329667583104, 5888.087346461295, 5978.790742324025, 5991.101334799777, 5992.221011268529, 6043.788467403641, 5905.409459054093, 5973.910391344133, 5984.730687119079, 6005.069214271794, 6011.188489553909, 5139.15203991341, 5173.249878767577, 5189.922151167735, 5215.660982036175, 5236.7905283788605, 5285.497877701631, 5296.417394086648, 5297.523222909937, 5303.787848486056, 5304.92042619361, 5290.1800220965815, 5294.52349288564, 5295.894082118355, 5298.0993231150605, 5298.682079075248, 5287.286216360781, 5291.941611677641, 5295.841124766263, 5299.49654782795, 5301.973490132544, 5112.923306150411, 5229.37247530295, 5236.895262094775, 5240.475952404767, 5243.947560524384, 6060.0, 6070.301915279755, 6076.848078798022, 6095.329513753483, 6102.176206212149, 6212.409821066992, 6316.74733010679, 6348.238211414614, 6360.9045864312075, 6367.554271201017, 5754.74103665336, 6259.186305780265, 6275.152854364648, 6286.49708023357, 6301.4328710416285, 6156.661383623944, 6166.506679465677, 6200.627962322275, 6206.751729930797, 6207.286162091417, 6640.601563906181, 6643.50173736962, 6653.833654158588, 6663.766768163091, 6673.666316684146, 6192.225971753531, 6301.8109456716375, 6308.558400911892, 6309.905351419732, 6310.968445157745, 6263.4362907338555, 6343.302236754045, 6412.839679007969, 6460.903086453707, 6475.0, 6369.649669268142, 6374.776882809079, 6374.872502549923, 6378.936210637834, 6396.392342727433, 6312.779052733133, 6367.554271200971, 6370.936290637127, 6386.07401926718, 6397.296297407282, 4582.541745825458, 4927.211646136601, 5214.6610470320065, 5227.111391063529, 5250.0, 4628.051249493846, 4633.9536604634195, 4636.374089497875, 4638.165130276515, 4677.18149323867, 6075.33171351151, 6100.877982440326, 6125.295591007002, 6134.4172303631085, 6139.079138129317, 4695.577398034194, 5993.790217342459, 6348.079528468346, 6382.393672601188, 6393.264774550893, 6139.369480960552, 6141.109539116803, 6144.631322120597, 6148.354422785633, 6150.753969841246, 5703.486686198179, 5745.396733343023, 5750.942490575125, 5765.538756899438, 5767.509761670305, 6404.935950640434, 6466.385693359123, 6601.075849380994, 6663.700133493908, 6673.666316684049, 6436.324185960407, 6492.902606461002, 6498.122753428227, 6501.707423166064, 6513.5114866384265, 6597.208334999831, 6667.233268174222, 6669.833254168685, 6672.733090676318, 6677.499187560855, 6172.4136207060255, 6232.844178895564, 6397.424231818973, 6419.614823110628, 6435.83910402225, 6420.6468644225015, 6432.09950606906, 6517.608943463218, 6528.804135875931, 6529.281779004401, 6278.340774218824, 6291.150694656346, 6294.1188233648245, 6299.118123462627, 6308.52686048539, 4741.857744267678, 6085.665288409003, 6175.104609831459, 6210.844728881633, 6243.719032643451, 6114.3277820888225, 6114.449440885012, 6122.174448092536, 6131.68436510395, 6141.700809433504, 5211.791528338824, 5550.861228469159, 6031.215941927521, 6186.19579454686, 6279.183706118175, 6241.21984751906, 6252.124702541739, 6255.874882502416, 6267.96866015667, 6271.43557079865], "value": 5864.731419074107, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.0856515007742, 811.7848770075931, 1122.640754958413, 1142.7371704507968, 1144.902683271922, 35948.460773088394, 36542.26915461692, 36629.81685091578, 36799.08002299944, 36826.4476032859, 38414.03964900879, 38821.61178388215, 38860.61139388605, 38871.41692874609, 38964.8310550684, 6037.849053773647, 8449.070602233765, 8598.957005214965, 8606.225439710424, 8609.354298427612, 6127.38726127386, 8403.579821008925, 8609.0, 8619.698310559132, 8624.18070283322, 8632.741017769446, 8688.652453901836, 8689.826203475926, 8695.739127826144, 8696.695615653454, 8609.39734218605, 8650.005249396338, 8665.956670216608, 8666.263367613761, 8694.347923905732, 8384.077751447336, 8435.95782021092, 8437.662493500253, 8438.240558349717, 8442.873356899652, 35566.11084722877, 35864.924052278475, 35908.281834363166, 35940.64059359399, 35942.46086308707, 3545.53907991962, 3571.1250743567994, 3579.910502237439, 3582.695470884978, 3583.910402239939, 27904.88380464779, 30071.248218794488, 30188.94338698135, 30218.54672179897, 30267.545986810215, 2872.3250036241466, 3084.8457577121053, 3112.4242015227255, 3142.2301536123796, 3149.4173577888164, 187304.0634796831, 226582.0, 233629.83185084135, 233690.0, 233770.0, 38634.0, 39698.80150599228, 45680.3147952781, 45767.0, 45851.541484585396, 33930.32139357199, 41456.0, 41478.0, 41546.0, 48944.510554894, 11889.405529723652, 14026.368813403422, 14087.647808804863, 14116.294185290695, 14130.222837744022, 11590.072794176445, 11617.825732614017, 11625.59310424148, 11626.244294120856, 11633.825492617449, 10316.0, 10541.631042913461, 11899.821502677467, 11926.46330915111, 12033.278003319829, 4690.377806830872, 4883.243097319921, 5449.782008719607, 5593.328800543879, 5604.299462567179, 949.7435692363023, 1006.4112494190834, 1007.9848802267971, 1052.4211683574003, 1064.5794911010107, 20884.746915185136, 23200.839958002034, 23363.41591460193, 23695.28914132579, 23768.0, 1023.0281232828843, 1053.1574740207802, 1137.4938152522213, 1140.1334985411147, 1169.3802284789076, 11733.823992640117, 11874.971882169992, 11962.58130965412, 11968.940155299342, 11989.520419183309, 365614.0, 366189.0, 366240.0, 366783.0, 366902.0, 52540.94918101616, 52630.47369526256, 52643.73678131585, 52760.47239527556, 52947.0, 1500.144917397095, 1502.1212590634602, 1572.5911263071685, 1575.4564675187041, 1585.080653221129, 23006.0, 23063.769362306834, 23166.07335706553, 23174.652380214648, 23233.65149522725, 37218.81390593138, 37334.813325933195, 37389.81305093457, 37415.0, 37507.06232344213, 327733.0, 327860.0, 328229.0, 328270.51053213025, 329474.0, 34244.97265082051, 34264.45809938509, 34385.65614343925, 34524.65475345315, 34532.65467345395, 36103.0, 36745.816270919546, 43557.0, 44540.77729611331, 44608.55391446044, 26747.19758407251, 27853.16440506788, 29111.563326549694, 30420.087397378113, 30546.694533055273, 28985.855070724512, 29693.703062969093, 29842.955496557515, 29845.403091937605, 29956.400871982, 34038.0, 34647.0, 35224.0, 35277.470837937966, 35391.0, 29560.0, 29574.0, 30034.69965300319, 30049.549256761602, 30177.547336790405, 23177.0, 23204.767952320937, 23436.76563234414, 23632.881835590713, 23645.0], "value": 17005.365841020543, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.6426412926748, 428.84739850693916, 435.7821089455272, 439.613140436416, 459.402776390692, 15475.226238688063, 21810.89094554529, 22608.208712695057, 23537.529249415, 23579.82100894957, 17954.730679039825, 22540.09839606414, 22734.090636374567, 22782.2026229082, 23824.0, 6131.356207598194, 6264.749410023594, 6291.559590828644, 6302.46429053531, 6354.269259035201, 3694.3719567673425, 6006.279246490405, 6260.248770147566, 6260.686965651722, 6291.1192433059405, 3833.5974722654137, 3851.84592616295, 5595.552355811526, 6282.026285925689, 6286.811395658137, 6275.341089185638, 6296.055591661242, 6304.968475157641, 6305.9369406305805, 6307.117003619497, 5476.7535460903955, 5631.549476041907, 5687.544996400278, 5707.800226992035, 5710.314762228518, 20804.5839083219, 26559.07043253496, 27854.771618270694, 28033.57949630757, 28215.012474563293, 2181.520065585569, 2228.0419419649543, 2231.3752149398197, 2232.3749350181974, 2234.4190510467233, 16304.940178888453, 17265.0, 17775.0, 18492.47931375921, 18587.535311617183, 2120.8833514156713, 2123.1719629344566, 2178.0, 2179.1719146724304, 2179.4333473296897, 143241.13517729586, 143462.0, 149668.0, 150002.24998875044, 151537.0, 11255.606053788078, 11597.88402115968, 11952.282863028246, 12482.687932801753, 12532.185407948455, 15420.46028389001, 15443.227838608025, 16381.0, 16853.241604127845, 16894.66210675804, 8803.867941980747, 8854.64581416749, 8944.329175311877, 9053.456792592466, 9097.49963752, 8416.537090460084, 8426.452280601863, 8433.367497437594, 8608.440451370641, 8632.223099920911, 7628.427867909929, 7774.805629859299, 7795.337396321376, 7822.569758663214, 7844.215578442224, 2126.744790625104, 2128.180650449557, 2129.8828564428795, 2132.072548441433, 2253.2226381898217, 907.5416914458157, 924.3252425729255, 948.7153853843819, 989.9653512127041, 991.6628346362271, 19777.40667779969, 20539.28112516055, 20561.0, 20563.794362056487, 20658.896705516378, 889.9555022248861, 903.1735961595198, 999.1806718490768, 999.9051039112161, 1015.1067060986433, 10089.09198172168, 10576.99518545737, 10586.835448100703, 10704.625338113128, 10719.571217151382, 604687.0, 693017.0, 700689.4069300682, 702900.0, 722769.0, 145403.0, 149549.0, 152406.0, 154675.49284789443, 157832.0, 1128.6783266768932, 1179.8407215026034, 1221.248931906871, 1234.2717796500108, 1237.8576463706609, 20331.695024574346, 21404.0, 21540.676889846978, 22146.003429845372, 22170.44573885666, 62841.0, 63366.73266534551, 64115.35884641094, 64520.0, 66373.66813165903, 85335.71996420182, 90805.54597226971, 90992.5450372744, 91998.0, 92179.15641686994, 8568.828623427622, 8720.127987201482, 8736.650533978569, 8905.73282801517, 9074.0, 13590.86409135896, 13749.175049497062, 13786.65533361674, 14084.577462676136, 14115.576532704035, 10645.201609879136, 11244.26912250701, 11646.301221926711, 11674.94162529182, 11826.46780894879, 10767.3539587625, 10944.507497162487, 10960.177986651193, 11136.49885755159, 11244.887551124384, 10795.676129716121, 11135.886411358799, 11564.132690048113, 11908.940455297668, 11972.760544788882, 7556.093268807778, 8625.568721563772, 8794.076621954602, 8912.019677835304, 8972.596233169654, 11541.884581154416, 11744.29534227949, 11886.643400697993, 11990.700232494257, 12159.81760273579], "value": 10502.978654903498, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.3728229091182, 1391.4781956766205, 1404.2346920928094, 1407.1908652524799, 1411.6329754263888, 11338.376389298595, 11867.465964031619, 11878.94060529696, 11890.286582805038, 11896.940515297421, 9460.81078378432, 11727.598996291086, 11878.52485900565, 11918.225315354495, 11918.523259069649, 2464.1006032798036, 2513.6229565565127, 2515.220281712666, 2517.7356377580363, 2519.4583164619603, 2089.7910208979133, 2367.775061369176, 2440.9023639054417, 2492.7756501914823, 2493.4514406830467, 2150.7419109706857, 2175.3365223606875, 2412.1195763546357, 2504.9248522544262, 2508.9874550627223, 2421.1041914491575, 2455.656208130863, 2465.60550311951, 2467.210492642364, 2467.296820406176, 2420.298113547087, 2424.066734307287, 2426.34488688055, 2426.8543887366814, 2428.9514209715708, 11593.362365069917, 11836.704082397922, 11839.644810655607, 11843.230190037706, 11843.348615826126, 6431.839204019937, 6670.666466676696, 6679.833004174886, 6682.732690692366, 6695.531312808105, 3941.625545573166, 4004.539477960043, 4318.373835793793, 4325.653947684178, 4327.285997810358, 1237.3689418396593, 1240.640214337841, 1265.2155663488604, 1265.2218885385514, 1287.4335292471376, 16250.674986500322, 16342.182890855529, 16391.91804040984, 16394.508164755076, 16410.671786564322, 5354.54486368663, 5421.566274698014, 5483.945160548344, 5567.47109024642, 5650.378458369576, 12380.81428778569, 12402.0, 12408.937955310166, 12418.937905310597, 12419.006479481619, 6542.705578248895, 6544.7382104716235, 6547.312532184147, 6556.311587283362, 6557.737690492327, 5964.940350596526, 6085.847853803691, 6159.907601385983, 6184.165137706442, 6213.906791398133, 4210.705250632487, 4332.783360831946, 4345.326474396474, 4371.169477799208, 4373.016071383913, 1252.5616034387892, 1256.4032084759665, 1258.874112588752, 1266.4617537546505, 1273.9681507962375, 665.351282499559, 685.9142607174103, 771.1209221487509, 786.6263524825777, 801.6352559585374, 6763.7632682855865, 6888.104546408935, 6962.860742785116, 7026.191987921352, 7043.964780176067, 586.8943590153727, 594.5095296380504, 597.0924195223323, 598.8023952095808, 713.4506430048833, 3237.012711123083, 3602.945955810665, 4069.89825254371, 4229.809658565372, 4360.345948107809, 14539.34572944199, 15385.999910005814, 15392.153431561372, 15431.92284038595, 15455.459058932884, 15153.848461515243, 15205.695886082216, 15218.315175817115, 15309.15799631031, 15323.846761532242, 10499.790004199873, 10835.0, 11110.61112861062, 11119.55521779136, 11130.387828669502, 6610.4711623071, 6710.16122984627, 6782.559133656297, 6814.454843612598, 6822.727090916506, 12329.0, 12878.162919410208, 13483.393247304091, 13582.524611638546, 13710.862891370958, 14036.0, 14145.292735363397, 14173.220472873678, 14269.357878895682, 14359.641008974859, 8762.167594078552, 8887.866681999905, 8908.064653211319, 8917.420367676339, 8924.107589241024, 8792.0, 10947.56209751633, 11019.614313498989, 11071.391073491035, 11085.944570277097, 9137.177654011171, 9155.679551215675, 9266.814663706822, 9292.117248861347, 9466.810663786548, 8623.094575069528, 8669.21977022071, 8767.824643507221, 8768.210861022539, 8881.467111973303, 8088.595570221348, 8192.566300897439, 8291.295239904435, 8306.252437280673, 8315.875261870955, 6028.09578563219, 6086.969565152146, 6172.876542469216, 6225.782097626561, 6335.936640633535, 5458.563314934875, 5954.880902381841, 6276.623402595676, 6279.937200627935, 6359.491240700825], "value": 5331.118514080277, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.52684144818977, 388.0531503132357, 402.07923854373485, 407.23439932926095, 409.1775531182324, 4024.766254728632, 5109.591232701384, 5733.9713301433485, 5882.735276912538, 5953.987822070248, 4626.0, 4673.252279635259, 5014.974925125379, 5754.827355179351, 5759.510441612468, 4284.6786491013145, 4291.184674911772, 4316.000379736085, 4320.567943205686, 4321.503027151886, 4154.646855017327, 4310.741355518663, 4315.223259813232, 4321.178975994551, 4323.502797178317, 3767.0017445377, 3851.0, 4223.070924396643, 4301.311790113583, 4319.444999800069, 3469.3928562501715, 4213.052063285767, 4244.8514301999285, 4252.192083504125, 4252.213340531996, 3703.7777733335815, 3724.9627503724887, 3728.2543491301576, 3730.608286129944, 3736.065983504124, 4095.9590404095875, 4853.781579828917, 5627.170522102556, 5641.464060914206, 5696.515796157331, 3947.5788716062193, 4338.8047537860875, 5013.298138260647, 5084.237364395333, 5105.33630628016, 5361.383440904268, 6061.848453788646, 6077.665728384937, 6092.45167934888, 6092.939070609282, 4009.3785463252952, 4228.090960443519, 4256.44666193396, 4269.338252570857, 4270.444842170529, 5898.528117750569, 6318.210223722035, 6491.675416229217, 6526.216853977506, 6666.100076489661, 3508.386032444312, 4308.116836048603, 4311.849085282063, 4350.347447882779, 4359.738415695068, 4190.832366705297, 4275.5724427557, 4396.559909250333, 4402.625776808943, 4413.646908247332, 5040.722760248222, 5115.693058416507, 5122.9754049190315, 5146.562542183956, 5173.379194496684, 6168.753249870045, 6176.907346389808, 6191.876162476725, 6194.5973511721595, 6206.72069756862, 1451.2816156002634, 1463.232842319984, 1481.8221813382247, 1484.2059498168428, 1503.5263891874024, 855.7774978505508, 964.609333220047, 974.4263015230493, 1002.1910451634869, 1002.7944271424341, 4948.505149485023, 5193.2989046479, 5931.1992880960515, 5937.821865344046, 5954.928112939626, 5603.971980140154, 6401.1678481798, 6401.935980640134, 6431.196100487439, 6457.515686323544, 5588.742532930059, 5880.088586269135, 5911.576253735948, 5961.195238642727, 5979.6412215266355, 2755.6910467528164, 2923.5906973023793, 3133.38898914714, 3381.2899291149133, 3390.5253264542985, 5334.333208348957, 6648.734050638017, 6654.467642588678, 6682.465402767767, 6690.029945657852, 5518.006758783377, 6053.273607167168, 6067.817965461043, 6599.8020059398295, 6666.566673166229, 5641.210230567724, 5962.582619216613, 6239.313675495782, 6382.0, 6574.737010519621, 6039.78860739872, 6385.776497822554, 6386.840328991812, 6409.7436102555375, 6437.742490300336, 5896.439838215363, 5913.793017244376, 6291.370862913672, 6298.559100862895, 6299.748010079546, 5201.833121998578, 6183.072539119168, 6351.079093531504, 6392.648404337806, 6494.7077381517, 3932.2725295820364, 4301.096769678403, 4304.74171549708, 4316.352547117957, 4323.394724738476, 4946.950530494793, 5696.715164241854, 6387.8722425550295, 6426.3573642635365, 6499.707513161825, 4701.694389864648, 4944.950550494449, 5631.014572449887, 6192.164057852132, 6193.597416167935, 5525.778968841201, 5614.07367784311, 6176.166217560572, 6209.627422354673, 6224.346443623535, 5062.392512898476, 5143.434222235476, 5977.625146216482, 6000.069989151689, 6008.639481631116, 6114.419130182803, 6121.204852990996, 6151.815445536641, 6195.659238741912, 6239.844003899939, 5711.7429715662065, 5896.435050752936, 5994.9808532548695, 6018.3981601841215, 6029.8191054268445], "value": 4468.348706404386, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.1442076718324, 859.3683642522747, 869.4739682492092, 870.0472982084615, 886.9955650221748, 13039.217646941179, 13381.7323653527, 13385.39765710544, 13401.99485038621, 13504.662383440418, 11797.646070617873, 14872.810175185987, 17076.743848842245, 17405.98526841075, 17566.385176518823, 978.8238117138894, 991.0634450444333, 1007.0433088565856, 1009.3741880034389, 1009.9141572966307, 1004.9196064314874, 1005.1004351105756, 1005.1807776662033, 1006.436395618456, 1011.4133802394593, 709.779968209854, 724.7390939261888, 999.7902537929115, 1049.5014867937707, 1068.754186537097, 1044.5560636729397, 1045.2526443592867, 1045.6653870761322, 1046.3878631000873, 1049.1921220660124, 773.872311068673, 774.082711986298, 774.5855967057654, 775.2325198053886, 776.2741836383, 16169.0, 16490.42283520083, 16622.92475891964, 16695.415660451825, 16798.244079016473, 16236.51290461288, 16292.83707162925, 16669.666606667797, 16830.410935617194, 17069.146542672817, 13211.801822972662, 13367.532136375277, 13474.12418192824, 13608.523701670394, 13623.114497557626, 3372.15696075981, 3373.139849338403, 3406.7104296134858, 3407.369636617234, 3410.4031794435873, 115497.0, 117721.0, 117810.0, 118231.40884295524, 119480.80519194697, 10966.780664386828, 11938.403079845973, 11996.280223186463, 12166.0, 12192.0, 16928.40750573724, 19660.70508942367, 24736.0, 25016.4996700065, 25064.87467562687, 7249.710011599583, 8591.613377398033, 8896.955515222382, 8903.821923561622, 8903.955480222687, 4427.424434823452, 4642.2340313847835, 4713.08094921487, 4821.831235906797, 4822.348982887335, 4638.0, 4726.1020406122725, 4815.398075240595, 4819.927701084486, 4830.806767729322, 862.5169904853302, 939.6523286384082, 975.7414285214423, 987.8370068938689, 988.3081842710131, 645.7029766307458, 660.8645227728305, 662.8177251255943, 672.0725398949419, 681.3969636871404, 10984.340939543654, 11123.332600044023, 11580.826287605692, 11679.532818687327, 11957.880421195676, 634.2484156274829, 642.1459458919626, 653.6764301670703, 654.6693919570681, 659.9109120268798, 4456.68803183774, 5352.330958630171, 5369.677819330853, 5427.6743395395615, 5615.803446879339, 104440.0, 105792.47103764433, 107228.46385768021, 107630.0, 110685.89314106917, 61301.0, 91965.0, 94605.053949461, 96269.0, 96952.0, 74452.62773686097, 74560.88158677625, 74765.0, 76622.0, 76773.0, 11896.167268291483, 11943.283402995849, 12207.877921220674, 12552.937235313764, 12607.495700172258, 37896.29466673951, 38517.422238667, 39100.80449597734, 39553.033522485355, 41080.0, 51594.0, 51775.74112129415, 52182.47817521928, 52294.215586765466, 53296.73351633217, 13991.650208744863, 15645.0, 15939.043657380595, 16027.118508482145, 16353.0, 17951.820481795014, 18011.919284842952, 18139.45581632553, 18256.817431826043, 18468.0, 16288.185590720654, 16351.918240408722, 16485.91757041207, 16501.33994640201, 16637.918535295168, 15902.602434938757, 16304.347826086823, 16498.505044848673, 16644.667106657558, 16651.583710407336, 13448.26034568069, 15563.377464901278, 15997.920010399874, 16146.19269036567, 16296.348146074024, 10045.69862904114, 11586.826197607212, 11825.113116516124, 11964.46159922823, 11991.22057066288, 12459.190152640049, 12528.0, 12539.24764514132, 12542.811857822322, 12597.685057873627], "value": 8184.4623691693305, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1452.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 110.0, 112.0, 111.0, 116.0, 163.0, 164.0, 110.0, 111.0], "value": 121.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 110.0, 107.0, 145.0, 110.0, 109.0, 118.0, 107.0, 127.0, 115.0], "value": 118.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 199.0, 186.0, 184.0, 180.0, 183.0, 186.0, 186.0, 185.0], "value": 186.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 61.0, 93.0, 65.0, 61.0, 58.0, 59.0, 77.0, 62.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 89.0, 69.0, 69.0, 70.0, 87.0, 69.0, 69.0, 84.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 94.0, 92.0, 93.0, 93.0, 94.0, 92.0, 93.0, 88.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 97.0, 96.0, 97.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 84.0, 86.0, 87.0, 87.0, 86.0, 94.0, 86.0, 86.0], "value": 86.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 49.0, 50.0, 50.0, 51.0, 50.0, 52.0, 51.0, 50.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 63.0, 66.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 82.0, 84.0, 82.0, 101.0, 102.0, 103.0, 104.0, 83.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 138.0, 137.0, 144.0, 133.0, 129.0, 141.0, 192.0, 175.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 188.0, 184.0, 188.0, 185.0, 201.0, 184.0, 188.0, 185.0, 182.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 68.0, 67.0, 65.0, 88.0, 69.0, 68.0, 67.0], "value": 69.7, "unit": "score"}]}]} 02:48:23 INFO - Return code: 0 02:48:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:48:23 ERROR - # TBPL SUCCESS # 02:48:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:48:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:48:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:48:23 INFO - 2017-04-13 02:48:23.657 screenresolution[3262:52856] starting screenresolution argv=screenresolution get 02:48:23 INFO - 2017-04-13 02:48:23.660 screenresolution[3262:52856] Display 0: 1600x1200x32@60 02:48:23 INFO - 2017-04-13 02:48:23.666 screenresolution[3263:52859] starting screenresolution argv=screenresolution list 02:48:23 INFO - Available Modes on Display 0 02:48:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:48:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:48:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:48:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:48:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:48:23 INFO - Intel Iris: 02:48:23 INFO - Chipset Model: Intel Iris 02:48:23 INFO - Type: GPU 02:48:23 INFO - Bus: Built-In 02:48:23 INFO - VRAM (Dynamic, Max): 1536 MB 02:48:23 INFO - Vendor: Intel (0x8086) 02:48:23 INFO - Device ID: 0x0a2e 02:48:23 INFO - Revision ID: 0x0009 02:48:23 INFO - Displays: 02:48:23 INFO - Display: 02:48:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:48:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:48:23 INFO - Display Serial Number: 1600x1200 60 02:48:23 INFO - Main Display: Yes 02:48:23 INFO - Mirror: Off 02:48:23 INFO - Online: Yes 02:48:23 INFO - Rotation: Supported 02:48:23 INFO - Return code: 0 02:48:23 INFO - Running post-action listener: _resource_record_post_action 02:48:23 INFO - [mozharness: 2017-04-13 09:48:23.902133Z] Finished run-tests step (success) 02:48:23 INFO - Running post-run listener: _resource_record_post_run 02:48:23 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 278528; Write bytes: 512601600; Read time: 2; Write time: 1705 02:48:23 INFO - TinderboxPrint: CPU usage
53.5% 02:48:23 INFO - TinderboxPrint: I/O read bytes / time
278,528 / 2 02:48:23 INFO - TinderboxPrint: I/O write bytes / time
512,601,600 / 1,705 02:48:23 INFO - TinderboxPrint: CPU idle
2,239.8 (46.5%) 02:48:23 INFO - TinderboxPrint: CPU system
964.3 (20.0%) 02:48:23 INFO - TinderboxPrint: CPU user
1,609.6 (33.4%) 02:48:23 INFO - TinderboxPrint: Swap in / out
207,564,800 / 0 02:48:23 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205197824; Write bytes: 203010048; Read time: 15834; Write time: 372 02:48:23 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 241664; Write bytes: 302521856; Read time: 2; Write time: 1303 02:48:24 INFO - Running post-run listener: _upload_blobber_files 02:48:24 INFO - Blob upload gear active. 02:48:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:48:24 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 02:48:24 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'] 02:48:24 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 02:48:24 INFO - (blobuploader) - INFO - Open directory for files ... 02:48:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:48:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:48:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:25 INFO - (blobuploader) - INFO - Done attempting. 02:48:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:48:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:48:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:26 INFO - (blobuploader) - INFO - Done attempting. 02:48:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:48:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:48:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:27 INFO - (blobuploader) - INFO - Done attempting. 02:48:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:48:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:48:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:29 INFO - (blobuploader) - INFO - Done attempting. 02:48:29 INFO - (blobuploader) - INFO - Iteration through files over. 02:48:29 INFO - Return code: 0 02:48:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:48:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:48:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f52948b6cf98970a769542193d40c59636de1bf1d55d17cfb36ada5865590508931ecf2c2eb186108384153f910fd6e483d70cb8b46237e15cad28df1d9f69b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58c517367c779a36cfdd174e91ed359ee010b2c809fe5d0c89419b7d70191ef59af57c9f09d7468f1cff5ca41658133c8b434f04c5051fa3e9563964f8ceab35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd9d0e0f46b950619a387adf6cedbd8b1a77765547c70faba89826d38bad077cc7d7f64a330b2265765e2a4956ecc2a5b15202673df990864a4c4f84eb6f5dea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8cb2489e5b4861a2c8b1210984a709c8030299d724a323e138886ae6d4af5c926c2e6c3d2693da3e4091d1880061ee6c70d720eb6727e5b05fd0085e03905e5"} 02:48:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:48:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:48:29 INFO - Contents: 02:48:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f52948b6cf98970a769542193d40c59636de1bf1d55d17cfb36ada5865590508931ecf2c2eb186108384153f910fd6e483d70cb8b46237e15cad28df1d9f69b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58c517367c779a36cfdd174e91ed359ee010b2c809fe5d0c89419b7d70191ef59af57c9f09d7468f1cff5ca41658133c8b434f04c5051fa3e9563964f8ceab35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd9d0e0f46b950619a387adf6cedbd8b1a77765547c70faba89826d38bad077cc7d7f64a330b2265765e2a4956ecc2a5b15202673df990864a4c4f84eb6f5dea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8cb2489e5b4861a2c8b1210984a709c8030299d724a323e138886ae6d4af5c926c2e6c3d2693da3e4091d1880061ee6c70d720eb6727e5b05fd0085e03905e5"} 02:48:29 INFO - Running post-run listener: copy_logs_to_upload_dir 02:48:29 INFO - Copying logs to upload dir... 02:48:29 INFO - mkdir: /builds/slave/test/build/upload/logs 02:48:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.994306 ========= master_lag: 0.97 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-13 02:48:30.730527) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 02:48:30.733968) ========= 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.5pIh4zL2yO/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.skwzHjheYZ/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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/f52948b6cf98970a769542193d40c59636de1bf1d55d17cfb36ada5865590508931ecf2c2eb186108384153f910fd6e483d70cb8b46237e15cad28df1d9f69b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58c517367c779a36cfdd174e91ed359ee010b2c809fe5d0c89419b7d70191ef59af57c9f09d7468f1cff5ca41658133c8b434f04c5051fa3e9563964f8ceab35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd9d0e0f46b950619a387adf6cedbd8b1a77765547c70faba89826d38bad077cc7d7f64a330b2265765e2a4956ecc2a5b15202673df990864a4c4f84eb6f5dea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8cb2489e5b4861a2c8b1210984a709c8030299d724a323e138886ae6d4af5c926c2e6c3d2693da3e4091d1880061ee6c70d720eb6727e5b05fd0085e03905e5"} build_url:https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011556 build_url: 'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f52948b6cf98970a769542193d40c59636de1bf1d55d17cfb36ada5865590508931ecf2c2eb186108384153f910fd6e483d70cb8b46237e15cad28df1d9f69b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58c517367c779a36cfdd174e91ed359ee010b2c809fe5d0c89419b7d70191ef59af57c9f09d7468f1cff5ca41658133c8b434f04c5051fa3e9563964f8ceab35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd9d0e0f46b950619a387adf6cedbd8b1a77765547c70faba89826d38bad077cc7d7f64a330b2265765e2a4956ecc2a5b15202673df990864a4c4f84eb6f5dea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8cb2489e5b4861a2c8b1210984a709c8030299d724a323e138886ae6d4af5c926c2e6c3d2693da3e4091d1880061ee6c70d720eb6727e5b05fd0085e03905e5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 02:48:30.773774) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:48:30.774076) ========= 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.5pIh4zL2yO/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.skwzHjheYZ/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.005257 ========= master_lag: 0.76 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:48:31.535205) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 02:48:31.535531) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 02:48:31.536483) ========= ========= Total master_lag: 4.08 =========