builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0292 starttime: 1492146472.05 results: success (0) buildid: 20170413201349 builduid: 9392e43db30746eb8fa6f63f70439b0b revision: f77f3057b8ee04f1a7546e9cd69066d201e9a221 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:52.052877) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:52.053373) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:52.053645) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:52.163917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:52.164371) ========= 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.kNeHMWLyKe/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.VaM2I09uHg/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:07:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-04-13 22:07:52 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144789 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:52.333090) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:52.333420) ========= 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.kNeHMWLyKe/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.VaM2I09uHg/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.035718 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:52.392543) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 22:07:52.392908) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f77f3057b8ee04f1a7546e9cd69066d201e9a221 --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 f77f3057b8ee04f1a7546e9cd69066d201e9a221 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kNeHMWLyKe/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.VaM2I09uHg/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:07:52,500 truncating revision to first 12 chars 2017-04-13 22:07:52,500 Setting DEBUG logging. 2017-04-13 22:07:52,500 attempt 1/10 2017-04-13 22:07:52,500 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f77f3057b8ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 22:07:53,204 unpacking tar archive at: mozilla-central-f77f3057b8ee/testing/mozharness/ program finished with exit code 0 elapsedTime=0.988792 ========= master_lag: 1.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 22:07:54.438027) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:54.438350) ========= script_repo_revision: f77f3057b8ee04f1a7546e9cd69066d201e9a221 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:54.438760) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:54.439029) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:54.461014) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-04-13 22:07:54.461400) ========= /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.kNeHMWLyKe/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.VaM2I09uHg/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:07:54 INFO - MultiFileLogger online at 20170413 22:07:54 in /builds/slave/test 22:07:54 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 22:07:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:07:54 INFO - {'append_to_log': False, 22:07:54 INFO - 'base_work_dir': '/builds/slave/test', 22:07:54 INFO - 'blob_upload_branch': 'Firefox', 22:07:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:07:54 INFO - 'branch': 'Firefox', 22:07:54 INFO - 'buildbot_json_path': 'buildprops.json', 22:07:54 INFO - 'config_files': ('talos/mac_config.py',), 22:07:54 INFO - 'default_actions': ('clobber', 22:07:54 INFO - 'read-buildbot-config', 22:07:54 INFO - 'download-and-extract', 22:07:54 INFO - 'populate-webroot', 22:07:54 INFO - 'create-virtualenv', 22:07:54 INFO - 'install', 22:07:54 INFO - 'run-tests'), 22:07:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:07:54 INFO - 'download_minidump_stackwalk': True, 22:07:54 INFO - 'download_symbols': 'ondemand', 22:07:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:07:54 INFO - 'tooltool.py': '/tools/tooltool.py', 22:07:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:07:54 INFO - '/tools/misc-python/virtualenv.py')}, 22:07:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:07:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:07:54 INFO - 'gecko_profile': False, 22:07:54 INFO - 'gecko_profile_interval': 0, 22:07:54 INFO - 'installer_path': 'installer.exe', 22:07:54 INFO - 'log_level': 'info', 22:07:54 INFO - 'log_name': 'talos', 22:07:54 INFO - 'log_to_console': True, 22:07:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:07:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:07:54 INFO - 'opt_config_files': (), 22:07:54 INFO - 'pip_index': False, 22:07:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:07:54 INFO - 'cmd': ('bash', 22:07:54 INFO - '-c', 22:07:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:07:54 INFO - 'enabled': True, 22:07:54 INFO - 'halt_on_failure': False, 22:07:54 INFO - 'name': 'check_screen_resolution'},), 22:07:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:07:54 INFO - 'cmd': ('bash', 22:07:54 INFO - '-c', 22:07:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:07:54 INFO - 'enabled': True, 22:07:54 INFO - 'halt_on_failure': False, 22:07:54 INFO - 'name': 'check_screen_resolution'},), 22:07:54 INFO - 'run_cmd_checks_enabled': True, 22:07:54 INFO - 'suite': 'dromaeojs', 22:07:54 INFO - 'system_bits': '32', 22:07:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:07:54 INFO - 'title': 't-yosemite-r7-0292', 22:07:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:07:54 INFO - 'use_talos_json': True, 22:07:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:07:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:07:54 INFO - 'work_dir': 'build'} 22:07:54 INFO - [mozharness: 2017-04-14 05:07:54.674731Z] Running clobber step. 22:07:54 INFO - Running pre-action listener: _resource_record_pre_action 22:07:54 INFO - Running main action method: clobber 22:07:54 INFO - rmtree: /builds/slave/test/build 22:07:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:07:57 INFO - Running post-action listener: _resource_record_post_action 22:07:57 INFO - [mozharness: 2017-04-14 05:07:57.043231Z] Finished clobber step (success) 22:07:57 INFO - [mozharness: 2017-04-14 05:07:57.043426Z] Running read-buildbot-config step. 22:07:57 INFO - Running pre-action listener: _resource_record_pre_action 22:07:57 INFO - Running main action method: read_buildbot_config 22:07:57 INFO - Using buildbot properties: 22:07:57 INFO - { 22:07:57 INFO - "project": "", 22:07:57 INFO - "product": "firefox", 22:07:57 INFO - "script_repo_revision": "production", 22:07:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 22:07:57 INFO - "repository": "", 22:07:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 22:07:57 INFO - "buildid": "20170413201349", 22:07:57 INFO - "pgo_build": "False", 22:07:57 INFO - "basedir": "/builds/slave/test", 22:07:57 INFO - "buildnumber": 75, 22:07:57 INFO - "slavename": "t-yosemite-r7-0292", 22:07:57 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 22:07:57 INFO - "platform": "yosemite_r7", 22:07:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:07:57 INFO - "branch": "mozilla-central", 22:07:57 INFO - "repo_path": "mozilla-central", 22:07:57 INFO - "revision": "f77f3057b8ee04f1a7546e9cd69066d201e9a221", 22:07:57 INFO - "stage_platform": "macosx64", 22:07:57 INFO - "builduid": "9392e43db30746eb8fa6f63f70439b0b", 22:07:57 INFO - "slavebuilddir": "test" 22:07:57 INFO - } 22:07:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 22:07:57 INFO - Running post-action listener: _resource_record_post_action 22:07:57 INFO - [mozharness: 2017-04-14 05:07:57.049005Z] Finished read-buildbot-config step (success) 22:07:57 INFO - [mozharness: 2017-04-14 05:07:57.049241Z] Running download-and-extract step. 22:07:57 INFO - Running pre-action listener: _resource_record_pre_action 22:07:57 INFO - Running main action method: download_and_extract 22:07:57 INFO - mkdir: /builds/slave/test/build/tests 22:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:57 INFO - trying https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:07:57 INFO - Downloading https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/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 22:07:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/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 22:07:58 INFO - Downloaded 1325 bytes. 22:07:58 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:07:58 INFO - Using the following test package requirements: 22:07:58 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:58 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:07:58 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:07:58 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:58 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:07:58 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:58 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:07:58 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:58 INFO - u'jsshell-mac.zip'], 22:07:58 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:58 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:07:58 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:07:58 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:58 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:07:58 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:58 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:07:58 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:58 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:07:58 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:58 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:07:58 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:07:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:07:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:07:58 INFO - Fetch https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:08:00 INFO - Expected file size: 52595812 22:08:00 INFO - Obtained file size: 52595812 22:08:16 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 22:08:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:08:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:08:16 INFO - Fetch https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:08:17 INFO - Expected file size: 52595812 22:08:17 INFO - Obtained file size: 52595812 22:08:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:08:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:08:33 INFO - Fetch https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:08:34 INFO - Expected file size: 14408853 22:08:34 INFO - Obtained file size: 14408853 22:08:34 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')]} 22:08:34 INFO - trying https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:08:34 INFO - Downloading https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:08:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/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 22:08:37 INFO - Downloaded 71095973 bytes. 22:08:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:08:37 INFO - mkdir: /builds/slave/test/properties 22:08:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:08:37 INFO - Writing to file /builds/slave/test/properties/build_url 22:08:37 INFO - Contents: 22:08:37 INFO - build_url:https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:08:37 INFO - Running post-action listener: _resource_record_post_action 22:08:37 INFO - Running post-action listener: set_extra_try_arguments 22:08:37 INFO - [mozharness: 2017-04-14 05:08:37.832936Z] Finished download-and-extract step (success) 22:08:37 INFO - [mozharness: 2017-04-14 05:08:37.833061Z] Running populate-webroot step. 22:08:37 INFO - Running pre-action listener: _resource_record_pre_action 22:08:37 INFO - Running main action method: populate_webroot 22:08:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:08:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:08:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:08:37 INFO - '--tppagecycles', 22:08:37 INFO - '7'], 22:08:37 INFO - 'tests': ['tsvgm']}}, 22:08:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:08:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:08:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:08:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:08:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:08:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:08:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:08:37 INFO - 'talos_options': ['--disable-e10s'], 22:08:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:08:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:08:37 INFO - 'talos_options': ['--disable-e10s'], 22:08:37 INFO - 'tests': ['damp', 'tps']}, 22:08:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:08:37 INFO - 'tests': ['damp', 'tps']}, 22:08:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:08:37 INFO - 'tests': ['dromaeo_dom']}, 22:08:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:08:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:08:37 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:08:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:08:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:08:37 INFO - 'tests': ['a11yr', 22:08:37 INFO - 'ts_paint', 22:08:37 INFO - 'tpaint', 22:08:37 INFO - 'sessionrestore', 22:08:37 INFO - 'sessionrestore_no_auto_restore', 22:08:37 INFO - 'tabpaint']}, 22:08:37 INFO - 'other-e10s': {'tests': ['a11yr', 22:08:37 INFO - 'ts_paint', 22:08:37 INFO - 'tpaint', 22:08:37 INFO - 'sessionrestore', 22:08:37 INFO - 'sessionrestore_no_auto_restore', 22:08:37 INFO - 'tabpaint']}, 22:08:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:08:37 INFO - 'ts_paint', 22:08:37 INFO - 'tpaint', 22:08:37 INFO - 'sessionrestore', 22:08:37 INFO - 'sessionrestore_no_auto_restore', 22:08:37 INFO - 'tabpaint']}, 22:08:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:08:37 INFO - 'ts_paint', 22:08:37 INFO - 'tpaint', 22:08:37 INFO - 'sessionrestore', 22:08:37 INFO - 'sessionrestore_no_auto_restore', 22:08:37 INFO - 'tabpaint']}, 22:08:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:08:37 INFO - 'tests': ['a11yr', 22:08:37 INFO - 'ts_paint', 22:08:37 INFO - 'tpaint', 22:08:37 INFO - 'sessionrestore', 22:08:37 INFO - 'sessionrestore_no_auto_restore', 22:08:37 INFO - 'tabpaint']}, 22:08:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:08:37 INFO - 'tests': ['a11yr', 22:08:37 INFO - 'ts_paint', 22:08:37 INFO - 'tpaint', 22:08:37 INFO - 'sessionrestore', 22:08:37 INFO - 'sessionrestore_no_auto_restore', 22:08:37 INFO - 'tabpaint']}, 22:08:37 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:08:37 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:08:37 INFO - 'bloom_basic_ref']}, 22:08:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:08:37 INFO - 'tests': ['tsvgx', 22:08:37 INFO - 'tsvgr_opacity', 22:08:37 INFO - 'tart', 22:08:37 INFO - 'tscrollx', 22:08:37 INFO - 'cart', 22:08:37 INFO - 'tsvg_static']}, 22:08:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:08:37 INFO - 'tsvgr_opacity', 22:08:37 INFO - 'tart', 22:08:37 INFO - 'tscrollx', 22:08:37 INFO - 'cart', 22:08:37 INFO - 'tsvg_static']}, 22:08:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:08:37 INFO - 'talos_options': ['--disable-e10s'], 22:08:37 INFO - 'tests': ['tp5o']}, 22:08:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:08:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:08:37 INFO - 'talos_options': ['--disable-e10s', 22:08:37 INFO - '--xperf_path', 22:08:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:37 INFO - 'tests': ['tp5n']}, 22:08:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:08:37 INFO - 'talos_options': ['--xperf_path', 22:08:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:37 INFO - 'tests': ['tp5n']}}, 22:08:37 INFO - 'talos.zip': {'path': '', 22:08:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:08:37 INFO - Running post-action listener: _resource_record_post_action 22:08:37 INFO - [mozharness: 2017-04-14 05:08:37.852219Z] Finished populate-webroot step (success) 22:08:37 INFO - [mozharness: 2017-04-14 05:08:37.852356Z] Running create-virtualenv step. 22:08:37 INFO - Running pre-action listener: _resource_record_pre_action 22:08:37 INFO - Running main action method: create_virtualenv 22:08:37 INFO - Creating virtualenv /builds/slave/test/build/venv 22:08:37 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 22:08:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:08:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:08:37 INFO - Using real prefix '/tools/python27' 22:08:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:08:38 INFO - Installing distribute.............................................................................................................................................................................................done. 22:08:41 INFO - Installing pip.................done. 22:08:41 INFO - Return code: 0 22:08:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:08:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:08:42 INFO - Reading from file tmpfile_stdout 22:08:42 INFO - Output received: 22:08:42 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) 22:08:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:08:42 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')]} 22:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:42 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')]} 22:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:42 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 0x10348eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103491768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd1d6daa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326cd30>, '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 0x103193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103261be8>, '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.kNeHMWLyKe/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.VaM2I09uHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:08:42 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 22:08:42 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 22:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kNeHMWLyKe/Render', 22:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:42 INFO - 'HOME': '/Users/cltbld', 22:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:42 INFO - 'LOGNAME': 'cltbld', 22:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:42 INFO - 'MOZ_NO_REMOTE': '1', 22:08:42 INFO - 'NO_EM_RESTART': '1', 22:08:42 INFO - 'PAGER': '/bin/cat', 22:08:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:42 INFO - 'PWD': '/builds/slave/test', 22:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:42 INFO - 'SHELL': '/bin/bash', 22:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VaM2I09uHg/Listeners', 22:08:42 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 22:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:42 INFO - 'USER': 'cltbld', 22:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:42 INFO - 'XPC_FLAGS': '0x0', 22:08:42 INFO - 'XPC_SERVICE_NAME': '0', 22:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:42 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 22:08:42 INFO - Cleaning up... 22:08:42 INFO - Return code: 0 22:08:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:08:42 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')]} 22:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:42 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')]} 22:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:42 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 0x10348eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103491768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd1d6daa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326cd30>, '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 0x103193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103261be8>, '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.kNeHMWLyKe/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.VaM2I09uHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:08:42 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 22:08:42 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 22:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kNeHMWLyKe/Render', 22:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:42 INFO - 'HOME': '/Users/cltbld', 22:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:42 INFO - 'LOGNAME': 'cltbld', 22:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:42 INFO - 'MOZ_NO_REMOTE': '1', 22:08:42 INFO - 'NO_EM_RESTART': '1', 22:08:42 INFO - 'PAGER': '/bin/cat', 22:08:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:42 INFO - 'PWD': '/builds/slave/test', 22:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:42 INFO - 'SHELL': '/bin/bash', 22:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VaM2I09uHg/Listeners', 22:08:42 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 22:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:42 INFO - 'USER': 'cltbld', 22:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:42 INFO - 'XPC_FLAGS': '0x0', 22:08:42 INFO - 'XPC_SERVICE_NAME': '0', 22:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:42 INFO - Downloading/unpacking psutil>=3.1.1 22:08:42 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 22:08:42 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 22:08:42 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 22:08:42 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 22:08:42 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 22:08:42 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 22:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:08:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:46 INFO - Installing collected packages: psutil 22:08:46 INFO - Running setup.py install for psutil 22:08:46 INFO - building 'psutil._psutil_osx' extension 22:08:46 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 22:08:46 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 22:08:46 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 22:08:46 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 22:08:46 INFO - building 'psutil._psutil_posix' extension 22:08:46 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 22:08:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:08:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:08:47 INFO - ^ 22:08:47 INFO - 1 warning generated. 22:08:47 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 22:08:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:47 INFO - Successfully installed psutil 22:08:47 INFO - Cleaning up... 22:08:47 INFO - Return code: 0 22:08:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:08:47 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')]} 22:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:47 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')]} 22:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:48 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 0x10348eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103491768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd1d6daa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326cd30>, '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 0x103193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103261be8>, '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.kNeHMWLyKe/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.VaM2I09uHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:08:48 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 22:08:48 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 22:08:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kNeHMWLyKe/Render', 22:08:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:48 INFO - 'HOME': '/Users/cltbld', 22:08:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:48 INFO - 'LOGNAME': 'cltbld', 22:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:48 INFO - 'MOZ_NO_REMOTE': '1', 22:08:48 INFO - 'NO_EM_RESTART': '1', 22:08:48 INFO - 'PAGER': '/bin/cat', 22:08:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:48 INFO - 'PWD': '/builds/slave/test', 22:08:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:48 INFO - 'SHELL': '/bin/bash', 22:08:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VaM2I09uHg/Listeners', 22:08:48 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 22:08:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:48 INFO - 'USER': 'cltbld', 22:08:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:48 INFO - 'XPC_FLAGS': '0x0', 22:08:48 INFO - 'XPC_SERVICE_NAME': '0', 22:08:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:08:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:08:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:08:51 INFO - Installing collected packages: mozsystemmonitor 22:08:51 INFO - Running setup.py install for mozsystemmonitor 22:08:51 INFO - Successfully installed mozsystemmonitor 22:08:51 INFO - Cleaning up... 22:08:51 INFO - Return code: 0 22:08:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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')]} 22:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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', '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 0x10348eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103491768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd1d6daa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326cd30>, '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 0x103193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103261be8>, '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.kNeHMWLyKe/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.VaM2I09uHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:08: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:08: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 jsonschema==2.5.1 22:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kNeHMWLyKe/Render', 22:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:51 INFO - 'HOME': '/Users/cltbld', 22:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:51 INFO - 'LOGNAME': 'cltbld', 22:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:51 INFO - 'MOZ_NO_REMOTE': '1', 22:08:51 INFO - 'NO_EM_RESTART': '1', 22:08:51 INFO - 'PAGER': '/bin/cat', 22:08:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:51 INFO - 'PWD': '/builds/slave/test', 22:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:51 INFO - 'SHELL': '/bin/bash', 22:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VaM2I09uHg/Listeners', 22:08:51 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 22:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:51 INFO - 'USER': 'cltbld', 22:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:51 INFO - 'XPC_FLAGS': '0x0', 22:08:51 INFO - 'XPC_SERVICE_NAME': '0', 22:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:51 INFO - Downloading/unpacking jsonschema==2.5.1 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 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 22:08:51 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 22:08:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:08:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:08:55 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 22:08:55 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 22:08:55 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 22:08:55 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 22:08:55 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 22:08:55 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 22:08:55 INFO - Downloading functools32-3.2.3-2.tar.gz 22:08:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:08:56 INFO - warning: no files found matching '*.txt' 22:08:56 INFO - no previously-included directories found matching 'build' 22:08:56 INFO - no previously-included directories found matching 'dist' 22:08:56 INFO - no previously-included directories found matching '.git*' 22:08:56 INFO - Installing collected packages: jsonschema, functools32 22:08:56 INFO - Running setup.py install for functools32 22:08:56 INFO - warning: no files found matching '*.txt' 22:08:56 INFO - no previously-included directories found matching 'build' 22:08:56 INFO - no previously-included directories found matching 'dist' 22:08:56 INFO - no previously-included directories found matching '.git*' 22:08:56 INFO - Successfully installed jsonschema functools32 22:08:56 INFO - Cleaning up... 22:08:56 INFO - Return code: 0 22:08:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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')]} 22:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:56 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 0x10348eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103491768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd1d6daa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326cd30>, '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 0x103193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103261be8>, '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.kNeHMWLyKe/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.VaM2I09uHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:08:56 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 22:08:56 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 22:08:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kNeHMWLyKe/Render', 22:08:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:56 INFO - 'HOME': '/Users/cltbld', 22:08:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:56 INFO - 'LOGNAME': 'cltbld', 22:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:56 INFO - 'MOZ_NO_REMOTE': '1', 22:08:56 INFO - 'NO_EM_RESTART': '1', 22:08:56 INFO - 'PAGER': '/bin/cat', 22:08:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:56 INFO - 'PWD': '/builds/slave/test', 22:08:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:56 INFO - 'SHELL': '/bin/bash', 22:08:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VaM2I09uHg/Listeners', 22:08:56 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 22:08:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:56 INFO - 'USER': 'cltbld', 22:08:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:56 INFO - 'XPC_FLAGS': '0x0', 22:08:56 INFO - 'XPC_SERVICE_NAME': '0', 22:08:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:08:56 INFO - Cleaning up... 22:08:56 INFO - Return code: 0 22:08:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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')]} 22:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:56 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 0x10348eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103491768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd1d6daa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326cd30>, '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 0x103193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103261be8>, '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.kNeHMWLyKe/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.VaM2I09uHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:08:56 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 22:08:56 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 22:08:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kNeHMWLyKe/Render', 22:08:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:56 INFO - 'HOME': '/Users/cltbld', 22:08:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:56 INFO - 'LOGNAME': 'cltbld', 22:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:56 INFO - 'MOZ_NO_REMOTE': '1', 22:08:56 INFO - 'NO_EM_RESTART': '1', 22:08:56 INFO - 'PAGER': '/bin/cat', 22:08:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:56 INFO - 'PWD': '/builds/slave/test', 22:08:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:56 INFO - 'SHELL': '/bin/bash', 22:08:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VaM2I09uHg/Listeners', 22:08:56 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 22:08:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:56 INFO - 'USER': 'cltbld', 22:08:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:56 INFO - 'XPC_FLAGS': '0x0', 22:08:56 INFO - 'XPC_SERVICE_NAME': '0', 22:08:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:56 INFO - Downloading/unpacking blobuploader==1.2.4 22:08:56 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 22:08:56 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 22:08:56 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 22:08:56 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 22:08:56 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 22:08:56 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 22:08:59 INFO - Downloading blobuploader-1.2.4.tar.gz 22:08:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:08:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:08:59 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 22:08:59 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 22:08:59 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 22:08:59 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 22:08:59 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 22:08:59 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 22:09:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:09:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:00 INFO - Downloading docopt-0.6.1.tar.gz 22:09:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:09:00 INFO - Installing collected packages: blobuploader, requests, docopt 22:09:00 INFO - Running setup.py install for blobuploader 22:09:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:09:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:09:00 INFO - Running setup.py install for requests 22:09:01 INFO - Running setup.py install for docopt 22:09:01 INFO - Successfully installed blobuploader requests docopt 22:09:01 INFO - Cleaning up... 22:09:01 INFO - Return code: 0 22:09:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:01 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 0x10348eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103491768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd1d6daa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326cd30>, '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 0x103193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103261be8>, '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.kNeHMWLyKe/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.VaM2I09uHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:09:01 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 22:09:01 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 22:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kNeHMWLyKe/Render', 22:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:01 INFO - 'HOME': '/Users/cltbld', 22:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:01 INFO - 'LOGNAME': 'cltbld', 22:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:01 INFO - 'MOZ_NO_REMOTE': '1', 22:09:01 INFO - 'NO_EM_RESTART': '1', 22:09:01 INFO - 'PAGER': '/bin/cat', 22:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:01 INFO - 'PWD': '/builds/slave/test', 22:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:01 INFO - 'SHELL': '/bin/bash', 22:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VaM2I09uHg/Listeners', 22:09:01 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 22:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:01 INFO - 'USER': 'cltbld', 22:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:01 INFO - 'XPC_FLAGS': '0x0', 22:09:01 INFO - 'XPC_SERVICE_NAME': '0', 22:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:01 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Pz2E3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-6br9M5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-oN1I6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Oqrxzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-TULFVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-wLLNvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-gXbsY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-_LM60b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-wbcBFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-s3_q1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:02 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-k35Np8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-1FMxrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-WZzcTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-L9uFIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-vmprPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-GNOVU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-_EnLuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:09:03 INFO - Running setup.py install for manifestparser 22:09:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:09:03 INFO - Running setup.py install for mozcrash 22:09:03 INFO - Running setup.py install for mozdebug 22:09:04 INFO - Running setup.py install for mozdevice 22:09:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:09:04 INFO - Running setup.py install for mozfile 22:09:04 INFO - Running setup.py install for mozhttpd 22:09:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:09:04 INFO - Running setup.py install for mozinfo 22:09:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:09:04 INFO - Running setup.py install for mozInstall 22:09:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:09:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:09:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:09:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:09:04 INFO - Running setup.py install for mozleak 22:09:04 INFO - Running setup.py install for mozlog 22:09:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:09:05 INFO - Running setup.py install for moznetwork 22:09:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:09:05 INFO - Running setup.py install for mozprocess 22:09:05 INFO - Running setup.py install for mozprofile 22:09:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:09:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:09:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:09:05 INFO - Running setup.py install for mozrunner 22:09:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:09:05 INFO - Running setup.py install for mozscreenshot 22:09:05 INFO - Running setup.py install for moztest 22:09:06 INFO - Running setup.py install for mozversion 22:09:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:09:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:09:06 INFO - Cleaning up... 22:09:06 INFO - Return code: 0 22:09:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09: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')]} 22:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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')]} 22:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:06 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 0x10348eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103491768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd1d6daa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326cd30>, '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 0x103193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103261be8>, '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.kNeHMWLyKe/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.VaM2I09uHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:09:06 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 22:09:06 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 22:09:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kNeHMWLyKe/Render', 22:09:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:06 INFO - 'HOME': '/Users/cltbld', 22:09:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:06 INFO - 'LOGNAME': 'cltbld', 22:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:06 INFO - 'MOZ_NO_REMOTE': '1', 22:09:06 INFO - 'NO_EM_RESTART': '1', 22:09:06 INFO - 'PAGER': '/bin/cat', 22:09:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:06 INFO - 'PWD': '/builds/slave/test', 22:09:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:06 INFO - 'SHELL': '/bin/bash', 22:09:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VaM2I09uHg/Listeners', 22:09:06 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 22:09:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:06 INFO - 'USER': 'cltbld', 22:09:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:06 INFO - 'XPC_FLAGS': '0x0', 22:09:06 INFO - 'XPC_SERVICE_NAME': '0', 22:09:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:06 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-j1NSGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:06 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)) 22:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:06 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-wj2VON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:06 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)) 22:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:06 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-QZXCqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:06 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)) 22:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:06 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-S_1qhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:06 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)) 22:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:06 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-gyxiTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:07 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)) 22:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-wPI0TJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:07 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)) 22:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Ze79et-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:07 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)) 22:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-noVD15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:07 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)) 22:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-iAl6ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:07 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)) 22:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-WNLysP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:07 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)) 22:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-hF8Ji_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:07 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)) 22:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-nO61zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:07 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)) 22:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-qRbYdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:07 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)) 22:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:07 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-JKnySs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:08 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)) 22:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-7AfSsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:08 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)) 22:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Fc74s3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:08 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)) 22:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-3z4vbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:08 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)) 22:09:08 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)) 22:09:08 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)) 22:09:08 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)) 22:09:08 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)) 22:09:08 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)) 22:09:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:09:11 INFO - Downloading blessings-1.6.tar.gz 22:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:09:11 INFO - Installing collected packages: blessings 22:09:11 INFO - Running setup.py install for blessings 22:09:11 INFO - Successfully installed blessings 22:09:11 INFO - Cleaning up... 22:09:11 INFO - Return code: 0 22:09:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:09:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:11 INFO - Reading from file tmpfile_stdout 22:09:11 INFO - Current package versions: 22:09:11 INFO - blessings == 1.6 22:09:11 INFO - blobuploader == 1.2.4 22:09:11 INFO - docopt == 0.6.1 22:09:11 INFO - functools32 == 3.2.3-2 22:09:11 INFO - jsonschema == 2.5.1 22:09:11 INFO - manifestparser == 1.1 22:09:11 INFO - mozInstall == 1.12 22:09:11 INFO - mozcrash == 1.0 22:09:11 INFO - mozdebug == 0.1 22:09:11 INFO - mozdevice == 0.50 22:09:11 INFO - mozfile == 1.2 22:09:11 INFO - mozhttpd == 0.7 22:09:11 INFO - mozinfo == 0.9 22:09:11 INFO - mozleak == 0.1 22:09:11 INFO - mozlog == 3.4 22:09:11 INFO - moznetwork == 0.27 22:09:11 INFO - mozprocess == 0.25 22:09:11 INFO - mozprofile == 0.28 22:09:11 INFO - mozrunner == 6.13 22:09:11 INFO - mozscreenshot == 0.1 22:09:11 INFO - mozsystemmonitor == 0.3 22:09:11 INFO - moztest == 0.8 22:09:11 INFO - mozversion == 1.4 22:09:11 INFO - psutil == 3.1.1 22:09:11 INFO - requests == 1.2.3 22:09:11 INFO - wsgiref == 0.1.2 22:09:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:11 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')]} 22:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:11 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')]} 22:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:11 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 0x10348eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103491768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd1d6daa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326cd30>, '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 0x103193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103261be8>, '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.kNeHMWLyKe/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.VaM2I09uHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:09:11 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 22:09:11 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 22:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kNeHMWLyKe/Render', 22:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:11 INFO - 'HOME': '/Users/cltbld', 22:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:11 INFO - 'LOGNAME': 'cltbld', 22:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:11 INFO - 'MOZ_NO_REMOTE': '1', 22:09:11 INFO - 'NO_EM_RESTART': '1', 22:09:11 INFO - 'PAGER': '/bin/cat', 22:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:11 INFO - 'PWD': '/builds/slave/test', 22:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:11 INFO - 'SHELL': '/bin/bash', 22:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VaM2I09uHg/Listeners', 22:09:11 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 22:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:11 INFO - 'USER': 'cltbld', 22:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:11 INFO - 'XPC_FLAGS': '0x0', 22:09:11 INFO - 'XPC_SERVICE_NAME': '0', 22:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:11 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)) 22:09:11 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)) 22:09:11 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)) 22:09:11 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)) 22:09:11 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)) 22:09:11 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)) 22:09:11 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)) 22:09:11 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)) 22:09:11 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)) 22:09:11 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)) 22:09:11 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)) 22:09:11 INFO - Cleaning up... 22:09:11 INFO - Return code: 0 22:09:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:09:11 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')]} 22:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:11 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')]} 22:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:11 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 0x10348eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103491768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdd1d6daa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10326cd30>, '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 0x103193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103261be8>, '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.kNeHMWLyKe/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.VaM2I09uHg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 22:09:11 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 22:09:11 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 22:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kNeHMWLyKe/Render', 22:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:11 INFO - 'HOME': '/Users/cltbld', 22:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:11 INFO - 'LOGNAME': 'cltbld', 22:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:11 INFO - 'MOZ_NO_REMOTE': '1', 22:09:11 INFO - 'NO_EM_RESTART': '1', 22:09:11 INFO - 'PAGER': '/bin/cat', 22:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:11 INFO - 'PWD': '/builds/slave/test', 22:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:11 INFO - 'SHELL': '/bin/bash', 22:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VaM2I09uHg/Listeners', 22:09:11 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 22:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:11 INFO - 'USER': 'cltbld', 22:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:11 INFO - 'XPC_FLAGS': '0x0', 22:09:11 INFO - 'XPC_SERVICE_NAME': '0', 22:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:09:12 INFO - Cleaning up... 22:09:12 INFO - Return code: 0 22:09:12 INFO - Running post-action listener: _resource_record_post_action 22:09:12 INFO - Running post-action listener: _start_resource_monitoring 22:09:12 INFO - Starting resource monitoring. 22:09:12 INFO - [mozharness: 2017-04-14 05:09:12.241109Z] Finished create-virtualenv step (success) 22:09:12 INFO - [mozharness: 2017-04-14 05:09:12.242152Z] Running install step. 22:09:12 INFO - Running pre-action listener: _resource_record_pre_action 22:09:12 INFO - Running main action method: install 22:09:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:12 INFO - Reading from file tmpfile_stdout 22:09:12 INFO - Detecting whether we're running mozinstall >=1.0... 22:09:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:09:12 INFO - Reading from file tmpfile_stdout 22:09:12 INFO - Output received: 22:09:12 INFO - Usage: mozinstall [options] installer 22:09:12 INFO - Options: 22:09:12 INFO - -h, --help show this help message and exit 22:09:12 INFO - -d DEST, --destination=DEST 22:09:12 INFO - Directory to install application into. [default: 22:09:12 INFO - "/builds/slave/test"] 22:09:12 INFO - --app=APP Application being installed. [default: firefox] 22:09:12 INFO - mkdir: /builds/slave/test/build/application 22:09:12 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'] 22:09:12 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 22:09:32 INFO - Reading from file tmpfile_stdout 22:09:32 INFO - Output received: 22:09:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:09:32 INFO - Running post-action listener: _resource_record_post_action 22:09:32 INFO - [mozharness: 2017-04-14 05:09:32.266831Z] Finished install step (success) 22:09:32 INFO - [mozharness: 2017-04-14 05:09:32.266973Z] Running run-tests step. 22:09:32 INFO - Running pre-action listener: _resource_record_pre_action 22:09:32 INFO - Running main action method: run_tests 22:09:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:09:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:09:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:09:32 INFO - 2017-04-13 22:09:32.281 screenresolution[1628:7421] starting screenresolution argv=screenresolution get 22:09:32 INFO - 2017-04-13 22:09:32.284 screenresolution[1628:7421] Display 0: 1600x1200x32@60 22:09:32 INFO - 2017-04-13 22:09:32.290 screenresolution[1629:7423] starting screenresolution argv=screenresolution list 22:09:32 INFO - Available Modes on Display 0 22:09:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:09:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:09:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:09:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:09:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:09:32 INFO - Intel Iris: 22:09:32 INFO - Chipset Model: Intel Iris 22:09:32 INFO - Type: GPU 22:09:32 INFO - Bus: Built-In 22:09:32 INFO - VRAM (Dynamic, Max): 1536 MB 22:09:32 INFO - Vendor: Intel (0x8086) 22:09:32 INFO - Device ID: 0x0a2e 22:09:32 INFO - Revision ID: 0x0009 22:09:32 INFO - Displays: 22:09:32 INFO - Display: 22:09:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:09:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:09:32 INFO - Display Serial Number: 1600x1200 60 22:09:32 INFO - Main Display: Yes 22:09:32 INFO - Mirror: Off 22:09:32 INFO - Online: Yes 22:09:32 INFO - Rotation: Supported 22:09:32 INFO - Return code: 0 22:09:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:09:32 INFO - Python 2.7.3 22:09:32 INFO - Return code: 0 22:09:32 INFO - grabbing minidump binary from tooltool 22:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:32 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 0x10326cd30>, '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 0x103193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103261be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:09:32 INFO - Return code: 0 22:09:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:09:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:09:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:09:32 INFO - ENV: RUST_BACKTRACE is now 1 22:09:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:09:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:09:32 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/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--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 22:09:32 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/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0292 --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 22:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kNeHMWLyKe/Render', 22:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:32 INFO - 'HOME': '/Users/cltbld', 22:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:32 INFO - 'LOGNAME': 'cltbld', 22:09:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:09:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:32 INFO - 'MOZ_NO_REMOTE': '1', 22:09:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:09:32 INFO - 'NO_EM_RESTART': '1', 22:09:32 INFO - 'PAGER': '/bin/cat', 22:09:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:32 INFO - 'PWD': '/builds/slave/test', 22:09:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:32 INFO - 'RUST_BACKTRACE': '1', 22:09:32 INFO - 'SHELL': '/bin/bash', 22:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VaM2I09uHg/Listeners', 22:09:32 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 22:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:32 INFO - 'USER': 'cltbld', 22:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:32 INFO - 'XPC_FLAGS': '0x0', 22:09:32 INFO - 'XPC_SERVICE_NAME': '0', 22:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:32 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/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--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 22:09:32 INFO - mozversion application_buildid: 20170413201349 22:09:32 INFO - mozversion application_changeset: f77f3057b8ee04f1a7546e9cd69066d201e9a221 22:09:32 INFO - mozversion application_display_name: Nightly 22:09:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:09:32 INFO - mozversion application_name: Firefox 22:09:32 INFO - mozversion application_remotingname: firefox 22:09:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:09:32 INFO - mozversion application_vendor: Mozilla 22:09:32 INFO - mozversion application_version: 55.0a1 22:09:32 INFO - mozversion platform_buildid: 20170413201349 22:09:32 INFO - mozversion platform_changeset: f77f3057b8ee04f1a7546e9cd69066d201e9a221 22:09:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:09:32 INFO - mozversion platform_version: 55.0a1 22:09:32 INFO - using testdate: 1492146572 22:09:32 INFO - actual date: 1492146572 22:09:32 INFO - starting webserver on 'localhost:49249' 22:09:32 INFO - SUITE-START | Running 2 tests 22:09:32 INFO - TEST-START | dromaeo_css 22:09:32 INFO - operating with platform_type : mac_ 22:09:32 INFO - Initialising browser for dromaeo_css test... 22:09:32 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpVbE7aU/profile http://localhost:49249/getInfo.html) 22:09:35 INFO - PID 1639 | __metrics Screen width/height:1600/1200 22:09:35 INFO - PID 1639 | colorDepth:24 22:09:35 INFO - PID 1639 | Browser inner width/height: 1024/689 22:09:35 INFO - PID 1639 | __metrics 22:09:35 INFO - PID 1639 | 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" 22:09:35 INFO - PID 1639 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:09:36 INFO - TEST-INFO | 1639: exit 0 22:09:36 INFO - Browser initialized. 22:09:36 INFO - Running cycle 1/1 for dromaeo_css test... 22:09:36 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpVbE7aU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:09:48 INFO - PID 1640 | RSS: Main: 243810304 22:09:48 INFO - PID 1640 | 22:12:53 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 22:12:56 INFO - PID 1640 | RSS: Main: 442433536 22:12:56 INFO - PID 1640 | 22:14:38 INFO - PID 1640 | ************************* 22:14:38 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 22:14:38 INFO - PID 1640 | 22:14:38 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:14:38 INFO - PID 1640 | Full stack: 22:14:38 INFO - PID 1640 | ************************* 22:14:38 INFO - PID 1640 | ************************* 22:14:38 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 22:14:38 INFO - PID 1640 | 22:14:38 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:14:38 INFO - PID 1640 | Full stack: 22:14:38 INFO - PID 1640 | ************************* 22:14:38 INFO - PID 1640 | ************************* 22:14:38 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 22:14:38 INFO - PID 1640 | 22:14:38 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:14:38 INFO - PID 1640 | Full stack: 22:14:38 INFO - PID 1640 | ************************* 22:14:38 INFO - PID 1640 | ************************* 22:14:38 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 22:14:38 INFO - PID 1640 | 22:14:38 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:14:38 INFO - PID 1640 | Full stack: 22:14:38 INFO - PID 1640 | ************************* 22:15:58 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 22:15:59 INFO - PID 1640 | RSS: Main: 369143808 22:15:59 INFO - PID 1640 | 22:19:01 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 22:19:02 INFO - PID 1640 | RSS: Main: 331534336 22:19:02 INFO - PID 1640 | 22:22:04 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 22:22:05 INFO - PID 1640 | RSS: Main: 361693184 22:22:05 INFO - PID 1640 | 22:25:08 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 22:25:09 INFO - PID 1640 | RSS: Main: 338509824 22:25:09 INFO - PID 1640 | 22:28:11 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 22:28:11 INFO - PID 1640 | RSS: Main: 373522432 22:28:11 INFO - PID 1640 | 22:28:11 INFO - PID 1640 | __start_tp_report 22:28:11 INFO - PID 1640 | _x_x_mozilla_page_load 22:28:11 INFO - PID 1640 | _x_x_mozilla_page_load_details 22:28:11 INFO - PID 1640 | |i|pagename|runs| 22:28:11 INFO - PID 1640 | |0;dojo.html;3480.738944579157;3484.8083355415474;3498.7725797823155;3536.7701099428537;3575.5530558680166;5965.1946987156725;6127.647695153012;6231.345708700589;6239.126522286883;6243.56295059346;6157.445829875311;6194.845128871781;6259.530535209866;6270.090836828657;6272.184615999914;5235.476452354753;5347.465253474641;5372.597055220855;5408.91886621701;5515;5434;5501.092319767254;5503.697296648683;5510.6969116698565;5512.751926163332;4422.7346359218545;5288.603354748371;5376.435474275184;5404.32445944257;5465.836024919239;5341.866453338659;5347.0909945309395;5355.544778693777;5368.597355198376;5382.650127741724;5289.761960711778;5345.224942383333;5367.2485851980755;5444.992676354887;5456.7544460499375;6185.6597887116195;6194.25668919728;6209.9068513972325;6209.968950155266;6222.471089957359;6589.011648252753;6595.340465953414;6602.735890564371;6607.834804129887;6617.735290588419;5202.97398513005;6451.870962580722;6458.806235812933;6519.739210431577;6668.699908504129;5239.187925871534;6250.4374606285655;6342.080398342307;6344.587601805868;6345.460635845912;6807.659617019228;6819.727210911508;6827.0783444235385;6828.5902845829405;6841.247462779091;6223.471004964538;6338.461230795342;6342.8414289642205;6350.33321501245;6493.480521558264;6573.506986975996;6637.701303441357;6657.866842663119;6675.065490831287;6690.632015239111;5629.324481062299;6298.8976929037235;6548.574342667712;6566.179227596551;6581.703823327962;6361.332060133712;6581.14445122131;6612.8346791330605;6616.702248398834;6630.834229144213;5233.816816411408;5238.057149713089;5244.947550524522;5253.50091741284;5257.185136303879;3970.7617542947514;4012.378081397388;4719.528047195253;4767.118083154627;4784.114938736276;5170.508801663836;6202.44178023971;6240;6252.624842509464;6269.65516896566;6637.601743895381;6669.566478178903;6672.299408562031;6679.465642748569;6696.2968888267005;6108.908366374508;6171.197744293213;6175.228096487939;6250.656213908188;6264;5769.221155144001;5783.942160578425;5787.363390027093;5787.71061446926;5787.826365209051;6574.80275591733;6631.30371311015;6637.236717777324;6639.502037347218;6652.168478940132;6526.347365263436;6529.51028672861;6532.281449040506;6541.378569035934;6558.80323590293;6627.271000190071;6712.026756120433;6803.4557235422035;6812.659367031728;6816.147981502312;6499.805005849643;6518.185226846645;6519.837004074936;6525.2169739631545;6531.706073226811;6516.348365163446;6635.502337324817;6705.1283333166375;6709.496787740842;6709.865802683821;6304.810855674337;6393.904091438539;6394.296627370893;6400.583962042452;6406.647634380154;5391;6232.034034724625;6414.326495717883;6418.839529011812;6419.871602568016;6156.29202641702;6324.620522768649;6343.302236754045;6351.2696039956;6355.396237357444;6461;6471.838204044937;6473.643949582818;6486.156799616019;6496.740130394731;6332.240131184288;6343.397377249154;6362.840928976812;6370.1718776558755;6371.649559274099 22:28:11 INFO - PID 1640 | |1;ext.html;744.1442341307496;795.8965334506515;814;1128.9379084150373;1143.342578017639;26931.32671683209;36185.638143618555;36628.63371366286;36714.44928326071;36750.44874326887;38114.61885381145;38427.423588646125;38461.23077538447;38498.230035399276;38634;6109.450149486545;8449.831003379928;8585;8611.181937715939;8616.26761725254;6118.60229085108;8257.917420825805;8431.578421078952;8459.66161353545;8463.619137138843;8478.703245386412;8523.14768523149;8531.573421328909;8533.14668533148;8533.701320453785;8435.325173986066;8496.362772792034;8497.702580409717;8510.574471276474;8518.957405212934;8282.378821588345;8363.247307742271;8367.790805229857;8367.95816020916;8377.832443351099;36582.26835463276;36805.44791828125;36854.07864803375;36865.44701829475;36883;3459.705924996378;3482.2861313430685;3488.965110348915;3491.0574144980965;3493.6681015303507;30236.69763302383;30358.696413035806;30361.63372648236;30372.8481357594;30385.088447346614;3118.984405077983;3148.386064717381;3181.904542863718;3191.393635209303;3191.9680803191904;206546;279544.82958607626;279628;279792.60103699553;280267.99999999796;47574.76212618915;47671.52328476671;47800;47806.282905756445;47818.89177203471;49108.50891491111;49240.75379623079;49261;49264.50735492599;49300.013999719806;14086.295685215699;14147.787783183261;14178.574642760734;14203.005789594628;14211.431542738175;10032.44821534808;10170.542325595366;11607.419629018515;11616.767664646659;11631.88368116325;10618.256722029539;11310.151738619636;12115.030797536176;12265.202761820454;12288.508459661514;4662.487126416091;5542.035011269032;5636.3799982002765;5664.376918538957;5707.6290041147195;1007.7631756537207;1025.8100603300136;1039.4698703661188;1063.1282348474176;1072.4316112460408;22386;23746.88126559356;24061.037558497814;28952.710472895422;29848.403031939673;880.3749337970113;1021.2953062386929;1033.3644808442752;1064.3347907557777;1134.2287244673537;8717.782055448664;11278.323300601991;11332;11410;11420.257683250562;275323.6233818818;328915;329410;329875;330441;49587.25619115716;49598;49612;49636.25545616819;49777.751111244215;1429.906121816811;1450.9564713058626;1470.206088712105;1518.3091693279753;1533.5859317984082;21048.26331078405;21338.35984920455;21346.78653213448;21399.78600213978;21831;36361.454578181874;36409.90770276896;36414.63585364218;36536.08659783527;36560.08599785026;292707;293065;293670;293932;296213;31816;32402.513962291057;32420;32501.837490812395;32574.34851303008;41926.58073419227;42014;42055;42063;42096.3685544711;26515.60226596564;27492.312692182855;28784.841136914398;29555.85222073962;29936.401271974875;25789.613155802304;29014.27464313409;29049.6927638261;29564.96522621698;29649;34551;35150.12124696903;35300;35324.11689707778;35346.17230621596;22334;25379.98480060777;28583;29987.700122999362;30083.097507074825;19718.8028119717;22902.87855832871;23175;23333;23391.88304058537 22:28:11 INFO - PID 1640 | |2;jquery.html;352.50297081115633;386.3297179393753;427.0796433685408;428.2740754421256;443.3748414735224;13362.465501379946;15408.537743867673;19431.51421214467;22157.446063848372;22190.11239550418;22120.557588848213;22392.552148957013;22899.771002289974;23076;23451.882740586316;6023.247094113236;6078.574499785017;6136;6192.535559833008;6244.906326405107;3623.655752703489;6101.542384321171;6229.065640153991;6234.127222188898;6242.1261023456755;3882.514685664292;3920.666743326821;6162.630242185461;6192.040233763774;6199.101130336121;5899.439553242435;6177.598456100362;6185.6597887116195;6190.102435146923;6208.627482351051;5445.210444485567;5491.039068163095;5517.944820551783;5518.172274158868;5534.308211473566;22605.547889042125;27357.316067098283;27594.44811103787;28163.85918070417;28341.57487637687;2058.87646741196;2279.5326957973575;2286.1084177170915;2286.451251699597;2288.0047179476815;17466.388676396266;18564.535886602807;18613;18653.44039678812;18820.435386938414;1951.7853036166011;2073.39871437283;2108.0724481228212;2148.6239908016037;2150.1721837092678;147780;153435;155366;156737;158621.99999999884;11870.821937670942;12467.812982805266;12474;12474.064445166649;12499.687507812378;16586.834131658772;16598.336066557444;16612;16622.002679839006;16634.25145868439;8852.911470885208;9068.410553314014;9069.229115525122;9109.681161159328;9226.723198304062;8607;8622.31021518277;8651.04838467768;8829.867551986725;8831.470111793184;7006.407629177237;7421.777346679608;7740.651670674833;7742.845143097106;7895.526268423798;2078.1064142418904;2092.288621868572;2095.413284280404;2096.6540520813896;2232.3079845248026;839.5130824121994;922.9307801914883;947.2043483473915;950.7342279270589;965.2567523007385;20843.58312833765;21168.682469762967;21213.15147394087;21230.78769212319;21240.78759212419;992.4194346307492;1001.8397056471075;1005.215552918412;1016.0855230292648;1016.893226211252;10224.488775561193;10611.84082238767;10956.397398143179;11025.83461248082;11032.28290161137;659854;661780;677210;682812;683236;117231.41384293024;143080.5691943088;144217;149930;151749;1091.2906610703108;1140.526681427215;1167.6788883057056;1167.8890505402144;1169.8011338072379;17385.391511297043;17998.37005704794;18126.728099078788;18562.81437185665;18623.34818281408;58368;58461.12308315294;61615;62991.37008629855;63487;66770;86919.56540217258;87554.68667970113;87703;88273.55863220643;8419;8497.575121244037;8531.036643278958;8838.779030524289;8903.19871211594;13563.321833908069;13588.388522516312;13798.517051903134;13999.090059146123;14080.647983800487;11768.70578235551;11801.17391782567;11910.047196224454;12069.456874440846;12188.45151968146;8972.551372431482;10388.844167337345;10786.730331741455;10934;11135.72160695989;11697.532098716296;11801.114916381477;11878;11952.820707689218;12099.81850272229;8647.357002169567;8667.91332086671;8916.15296546827;8972;9190.54047297619;11683.766324673628;11892;12196.51213951432;12230.510779568718;12319.69200769988 22:28:11 INFO - PID 1640 | |3;mootools.html;1341.2220911871116;1420.3821337718086;1427.8286605607325;1446.0600609603762;1448.3482432905182;8663.350248731347;9857.852132218022;11946.522139114424;11957.22278051926;11959.282443053424;11553.422328883564;12020.759584808298;12035.879641203584;12049.15655904087;12056.69858253544;2536.467341858212;2579.3422677217377;2581.5224183526107;2583.702874169466;2588.805839562031;2116.460302622837;2408.4701365699607;2500.749925007503;2549.1842610364693;2556.169244995375;2023.231172154579;2190.277208521184;2213.1921848525335;2546.681664791901;2554.6551215585846;2488.1042824583137;2509.0716434919013;2515.559777039011;2516.119358224616;2521.583938650139;2495.6755621769053;2509.2346834215555;2510.2971168072786;2515.2328539795353;2515.7987361011074;11742.060635149168;11998.64004079879;12027.458764355624;12039.217450865666;12039.759204815942;6469.547131700783;6693;6694.598324100521;6703.1285932828905;6708.12794336738;4056.7971601420104;4271.850485233033;4366;4436.778161091965;4451.1765323415275;1217.1540779158488;1248.4694005047818;1285.2995117660898;1304.2891624064907;1308.7513372459202;16923.06923119228;17039.829601703947;17042.914785426117;17043.57391065221;17106.743398849027;5460.726963651801;5574.58190635704;5604.635698679573;5806.390329015477;5812.88374232513;12463.875361246453;12497.18768280077;12520.624381268577;12537.623871283877;12559.811602825965;6552.705128269239;6671.966640166768;6671.966640166768;6679.732810687616;6703.631300278434;6033.607815491979;6211.409916057967;6276.340984196685;6289.465395441347;6298.748050077946;4265.722728022669;4399.932031944981;4407.382966384709;4456.799444025027;4485.730856148642;1252.7557126360273;1275.5153041844226;1278.1244847279643;1302.6873550347848;1304.7325298313822;642.5937076622068;700.3136925812732;768.8577613141587;785.5208322923013;814.4136221920327;5814.854628634318;6811.523193376416;6933.93066069343;7053.612051337123;7084.220735719066;578.23672751967;588.6732863260931;591.7485068845723;602.8764103358801;701.8875082993411;4338.175746608134;4350.347447882779;4422.5356337584735;4501.07727915783;4505.459344878635;14771.482998095014;15267;15554.844451555566;15567.143807090491;15589.766153507706;15594.766078508832;15606.453774117852;15611.3755449783;15629.68740625181;15632;10775.191860610328;11052.281601695864;11199.552017919355;11219.607313744144;11235.438228088562;6741.584237396268;6795.422389096982;6957.031560537355;6980.930190698028;7029.332213439715;13584.252866092065;13786.65533361674;13812.37844296989;13995.790063149265;14118;12529.248245105322;13431.04517006257;14185.36165872559;14348.210848403021;14356.712865742418;8528.658853645784;8727.563621818757;8728.345374097094;8741.300695944436;8744.606492707717;10590.788184236426;10690.518926648476;10845.728856778644;10851.67444976652;10854.45727713627;9226.907730922874;9236.72289831306;9347.298952578447;9384.061593840834;9443.669471568463;9014.459132451811;9066.818663626822;9114.772130696785;9162.175404213653;9176.6788162414;8099.757007289791;8303.294219991369;8306.377021723274;8310.584470776315;8312.376571757022;5349.304590403222;5791.942080579141;5895.616784908967;6000.339962604197;6316.210473690789;5985.491233245223;6110.7250173741395;6225.252969643671;6254.9687251563455;6302.243730752339 22:28:11 INFO - PID 1640 | |4;prototype.html;346.906335289472;382.867910570853;390.1163863848383;403.91113954929915;409.98360065597376;4860.90629608338;5915.8168366326745;5969.850853692673;5976.103584462333;5983.24332155936;4736.976315118428;5725.562688916092;5778.27771528559;5803.245578074844;5810.448007439297;4341.565843415665;4347.478302603692;4355.978220108903;4378.6934914555995;4384.758838263894;3799.1072098056934;3816.484774555427;4014.5784692607294;4373.540778218289;4411.713238639494;4130.669546436278;4286.164197981379;4331.155424692186;4337.024169561855;4344.869653910388;3539.2744487380023;4295.312749959991;4307.741535507879;4311.439512863308;4320.805563749607;3116.8753249870206;3764.190698999701;3769.2838360711376;3780.3384407728813;3783.754055986352;4362.825486980517;5638.802641907513;5682.034054210794;5709.914351284734;5759.22250496186;5072.644914855961;5076.44159142494;5085.084684756743;5088.872778180576;5097.541221290102;4966.230234313651;6152.753889844401;6188.916939535608;6189.81430557084;6190.41191086846;3646.3436581415335;4014.297497937849;4026.57720939303;4224.429701990222;4236.809343579546;5302.204669299597;6102.816915492543;6385.48916086712;6394.968025159891;6493.70778314977;4006.5192176938954;4208.169446290874;4271.359296105609;4303.892402689958;4318.417013703172;4129.2773764591075;4344.717593356421;4353.804078816461;4383.736975781399;4388.451443569554;3950.644442000234;4573.268277075652;4840.83057093;4954.628402869727;5045;5123.077845987684;5540.695261760642;6214.223222097238;6235.314115447297;6246.031865060922;1440.3014537949086;1474.329180222996;1485.695432436348;1486.9851301487063;1495.364470100203;876.8162979977108;964.3702143377809;976.3799987008301;998.3560603410905;1005.215931573374;5121.847584293504;5830.679312637845;5849.678267695318;5851.6781577012825;5859.359379373697;6077.4226448487325;6168.845778855564;6430.421262086435;6441.452476539453;6453.903191452132;5842.766289348463;5854.824355269348;5871.207387002785;5887.2052272943465;5901.527877769769;3326.866925323008;3350.6146793118614;3359.740097463451;3371.7302615790677;3385.8173317082355;5282.841514754564;5410.864728381822;6207.906881396782;6251.562390632703;6605.174353205849;6063.454289114001;6470.708818103197;6496.447801936888;6601.438877695449;6659.433948114464;6381.521385896076;6405.19935008124;6555.934440655532;6560.245571759308;6640.800775976729;6449.5485316027425;6452.645104519296;6456.644884531208;6458.967705161444;6466.154891847572;5018.849434516825;6114.480269176992;6374.58565193261;6393.808185754435;6489.675516224075;5775.537956963349;6435.871282574416;6496.70764815575;6552.443042341454;6586.242582102927;3851.5763266040153;4262.403263543044;4294.162638285505;4316.740995540152;4316.870493885189;6203.286622038523;6420.3131713848215;6466.385693359123;6506.674666266762;6525.64108974011;6233.781817636361;6243.906341404973;6256.185706145349;6257.186565746424;6291.559590828781;4830.106430310263;4982.202847544376;5420.457954204548;6030.638161710312;6208.4722798562625;5461.399246083007;6079.635221886701;6092.360302168386;6094.634321940521;6144.877102457836;6176.6911654418;6210.782622608187;6218.450435321496;6244.281907580505;6263.123162757309;5769.105788602773;6018.4884284834525;6064.48451881595;6067.757289708362;6123.632582045091 22:28:11 INFO - PID 1640 | |5;yui.html;712.579578048951;856.6830272799064;872.9214370706635;873.9825203495931;882.2016075451714;12790.936045319771;16581.50255492334;17105.914470427662;17319.960802351838;17372.391966281168;15673.608159796011;15897.1256580888;16055;16085.758713619305;16123.113228772412;948.1040416806121;971.1939090554857;973.6884197056945;976.0483528559633;977.8826540815077;659.9241087274977;814.0665918443697;980.0542476510167;998.7203582996736;999.3604093380243;1038.9973675403235;1043.639944219247;1044.9947750261276;1045.4563626914;1046.1735229168946;1031.3554028732042;1035.6375268656022;1036.2797855490437;1037.8287582548871;1041.5833666533372;717.2003216413714;766.6856588798565;767.2595944913186;767.2787579675088;767.7850201943464;15831;16104.919475402548;16216.675666486723;16283.267252973645;16508.504744857553;16183.757243641356;16203.189840508045;16583.336666533323;16666.83333166665;16778.496645100666;13451.260180690058;13649.522266720716;13755.724885502334;13822.93088534561;13922.93038534811;3353.2119951811305;3375.1224681582967;3376.8142752148624;3381.137809858495;3388.4747864081105;104568;111265;111567;112363.87636123617;112996;11255.831162532568;13361.73276534464;13713.725725485434;13721.45114195421;13743.656408589664;16948.74576881348;18978.81021189798;19074.2370305189;23775;23840.761592384202;8760.474371537728;8882.289416846637;8984.32617553686;9099.45309297421;9120.543972801333;4809.5430934061205;4904.117258893362;4904.485029071896;4905.288733133747;4906.754662266872;4310.89222769427;4326.41593384889;4402.25161722508;4445.155420470101;4452.708714472767;830.8338332333558;853.7182729699184;866.7616405488541;870.8954925408991;871.9215270625674;626.7493002798917;656.4125108028288;656.592912394309;665.9467242620578;688.3048121397325;10450.947745261225;10839.945800271105;11703.590374336858;11872.821907671392;11933.284002959677;642.1267076775581;644.22049320322;650.1515522243518;680.4726337088796;682.5938566552893;5210.583153347723;5304.92042619361;5422.728863556807;5424.647397919122;5530.281063461724;100151.99848001573;100428.99571004343;101944.49027754813;103755;104574.95425045805;72373.05844535593;75323;90313;90962.54518727364;91665;76304;76967.61516192496;77035.61482192665;77403;77415;8771.947257027472;10818.080463160719;11201.71995700114;11349.37578433161;12216.328101954477;36136.91589252327;37139;38644.80677596594;39980.400293996194;40151.59848401479;65697.67151164214;65751;65806.67096664486;66061.33938660551;69014;16319;16383.836161638232;16582.17089145514;16621.168941553115;16705;17452.650946980735;17522.91238543799;17589.824101759332;17601.471955841345;17624.206910688794;16520.173991300628;16610.83389166093;16631.417900373428;16844.410445634345;16985.660286794442;16361.100139492442;16939;16968.49094527166;17009.404670836462;17118.657626847642;16132.919335403249;16352.918235408746;16710.4151354702;16771.496855094367;16879.324827007273;10701.518431670438;11555.942220288845;12162.02061917378;12310.446029928495;12346.3826808661;12132.696682583006;12915.483380664667;12958.287294198533;13610.659733506742;13668.384922678702 22:28:11 INFO - PID 1640 | __end_tp_report 22:28:11 INFO - PID 1640 | __start_cc_report 22:28:11 INFO - PID 1640 | _x_x_mozilla_cycle_collect,2135 22:28:11 INFO - PID 1640 | __end_cc_report 22:28:11 INFO - PID 1640 | __startTimestamp1492147691816__endTimestamp 22:28:11 INFO - PID 1640 | 22:28:11 INFO - PID 1640 | ------- Summary: start ------- 22:28:11 INFO - PID 1640 | Number of tests: 6 22:28:11 INFO - PID 1640 | 22:28:11 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6052.92 Median:6338.46 stddev:736.07 (11.6%) stddev-sans-first:710.13 22:28:11 INFO - PID 1640 | Values: 3480.7 3484.8 3498.8 3536.8 3575.6 5965.2 6127.6 6231.3 6239.1 6243.6 6157.4 6194.8 6259.5 6270.1 6272.2 5235.5 5347.5 5372.6 5408.9 5515.0 5434.0 5501.1 5503.7 5510.7 5512.8 4422.7 5288.6 5376.4 5404.3 5465.8 5341.9 5347.1 5355.5 5368.6 5382.7 5289.8 5345.2 5367.2 5445.0 5456.8 6185.7 6194.3 6209.9 6210.0 6222.5 6589.0 6595.3 6602.7 6607.8 6617.7 5203.0 6451.9 6458.8 6519.7 6668.7 5239.2 6250.4 6342.1 6344.6 6345.5 6807.7 6819.7 6827.1 6828.6 6841.2 6223.5 6338.5 6342.8 6350.3 6493.5 6573.5 6637.7 6657.9 6675.1 6690.6 5629.3 6298.9 6548.6 6566.2 6581.7 6361.3 6581.1 6612.8 6616.7 6630.8 5233.8 5238.1 5244.9 5253.5 5257.2 3970.8 4012.4 4719.5 4767.1 4784.1 5170.5 6202.4 6240.0 6252.6 6269.7 6637.6 6669.6 6672.3 6679.5 6696.3 6108.9 6171.2 6175.2 6250.7 6264.0 5769.2 5783.9 5787.4 5787.7 5787.8 6574.8 6631.3 6637.2 6639.5 6652.2 6526.3 6529.5 6532.3 6541.4 6558.8 6627.3 6712.0 6803.5 6812.7 6816.1 6499.8 6518.2 6519.8 6525.2 6531.7 6516.3 6635.5 6705.1 6709.5 6709.9 6304.8 6393.9 6394.3 6400.6 6406.6 5391.0 6232.0 6414.3 6418.8 6419.9 6156.3 6324.6 6343.3 6351.3 6355.4 6461.0 6471.8 6473.6 6486.2 6496.7 6332.2 6343.4 6362.8 6370.2 6371.6 22:28:11 INFO - PID 1640 | 22:28:11 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:43621.44 Median:32538.09 stddev:77255.87 (237.4%) stddev-sans-first:77405.52 22:28:11 INFO - PID 1640 | Values: 744.1 795.9 814.0 1128.9 1143.3 26931.3 36185.6 36628.6 36714.4 36750.4 38114.6 38427.4 38461.2 38498.2 38634.0 6109.5 8449.8 8585.0 8611.2 8616.3 6118.6 8257.9 8431.6 8459.7 8463.6 8478.7 8523.1 8531.6 8533.1 8533.7 8435.3 8496.4 8497.7 8510.6 8519.0 8282.4 8363.2 8367.8 8368.0 8377.8 36582.3 36805.4 36854.1 36865.4 36883.0 3459.7 3482.3 3489.0 3491.1 3493.7 30236.7 30358.7 30361.6 30372.8 30385.1 3119.0 3148.4 3181.9 3191.4 3192.0 206546.0 279544.8 279628.0 279792.6 280268.0 47574.8 47671.5 47800.0 47806.3 47818.9 49108.5 49240.8 49261.0 49264.5 49300.0 14086.3 14147.8 14178.6 14203.0 14211.4 10032.4 10170.5 11607.4 11616.8 11631.9 10618.3 11310.2 12115.0 12265.2 12288.5 4662.5 5542.0 5636.4 5664.4 5707.6 1007.8 1025.8 1039.5 1063.1 1072.4 22386.0 23746.9 24061.0 28952.7 29848.4 880.4 1021.3 1033.4 1064.3 1134.2 8717.8 11278.3 11332.0 11410.0 11420.3 275323.6 328915.0 329410.0 329875.0 330441.0 49587.3 49598.0 49612.0 49636.3 49777.8 1429.9 1451.0 1470.2 1518.3 1533.6 21048.3 21338.4 21346.8 21399.8 21831.0 36361.5 36409.9 36414.6 36536.1 36560.1 292707.0 293065.0 293670.0 293932.0 296213.0 31816.0 32402.5 32420.0 32501.8 32574.3 41926.6 42014.0 42055.0 42063.0 42096.4 26515.6 27492.3 28784.8 29555.9 29936.4 25789.6 29014.3 29049.7 29565.0 29649.0 34551.0 35150.1 35300.0 35324.1 35346.2 22334.0 25380.0 28583.0 29987.7 30083.1 19718.8 22902.9 23175.0 23333.0 23391.9 22:28:11 INFO - PID 1640 | 22:28:11 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:39451.25 Median:22499.05 stddev:112888.19 (501.7%) stddev-sans-first:113166.70 22:28:11 INFO - PID 1640 | Values: 352.5 386.3 427.1 428.3 443.4 13362.5 15408.5 19431.5 22157.4 22190.1 22120.6 22392.6 22899.8 23076.0 23451.9 6023.2 6078.6 6136.0 6192.5 6244.9 3623.7 6101.5 6229.1 6234.1 6242.1 3882.5 3920.7 6162.6 6192.0 6199.1 5899.4 6177.6 6185.7 6190.1 6208.6 5445.2 5491.0 5517.9 5518.2 5534.3 22605.5 27357.3 27594.4 28163.9 28341.6 2058.9 2279.5 2286.1 2286.5 2288.0 17466.4 18564.5 18613.0 18653.4 18820.4 1951.8 2073.4 2108.1 2148.6 2150.2 147780.0 153435.0 155366.0 156737.0 158622.0 11870.8 12467.8 12474.0 12474.1 12499.7 16586.8 16598.3 16612.0 16622.0 16634.3 8852.9 9068.4 9069.2 9109.7 9226.7 8607.0 8622.3 8651.0 8829.9 8831.5 7006.4 7421.8 7740.7 7742.8 7895.5 2078.1 2092.3 2095.4 2096.7 2232.3 839.5 922.9 947.2 950.7 965.3 20843.6 21168.7 21213.2 21230.8 21240.8 992.4 1001.8 1005.2 1016.1 1016.9 10224.5 10611.8 10956.4 11025.8 11032.3 659854.0 661780.0 677210.0 682812.0 683236.0 117231.4 143080.6 144217.0 149930.0 151749.0 1091.3 1140.5 1167.7 1167.9 1169.8 17385.4 17998.4 18126.7 18562.8 18623.3 58368.0 58461.1 61615.0 62991.4 63487.0 66770.0 86919.6 87554.7 87703.0 88273.6 8419.0 8497.6 8531.0 8838.8 8903.2 13563.3 13588.4 13798.5 13999.1 14080.6 11768.7 11801.2 11910.0 12069.5 12188.5 8972.6 10388.8 10786.7 10934.0 11135.7 11697.5 11801.1 11878.0 11952.8 12099.8 8647.4 8667.9 8916.2 8972.0 9190.5 11683.8 11892.0 12196.5 12230.5 12319.7 22:28:11 INFO - PID 1640 | 22:28:11 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:7273.76 Median:2582.61 stddev:4675.33 (181.0%) stddev-sans-first:4667.19 22:28:11 INFO - PID 1640 | Values: 1341.2 1420.4 1427.8 1446.1 1448.3 8663.4 9857.9 11946.5 11957.2 11959.3 11553.4 12020.8 12035.9 12049.2 12056.7 2536.5 2579.3 2581.5 2583.7 2588.8 2116.5 2408.5 2500.7 2549.2 2556.2 2023.2 2190.3 2213.2 2546.7 2554.7 2488.1 2509.1 2515.6 2516.1 2521.6 2495.7 2509.2 2510.3 2515.2 2515.8 11742.1 11998.6 12027.5 12039.2 12039.8 6469.5 6693.0 6694.6 6703.1 6708.1 4056.8 4271.9 4366.0 4436.8 4451.2 1217.2 1248.5 1285.3 1304.3 1308.8 16923.1 17039.8 17042.9 17043.6 17106.7 5460.7 5574.6 5604.6 5806.4 5812.9 12463.9 12497.2 12520.6 12537.6 12559.8 6552.7 6672.0 6672.0 6679.7 6703.6 6033.6 6211.4 6276.3 6289.5 6298.7 4265.7 4399.9 4407.4 4456.8 4485.7 1252.8 1275.5 1278.1 1302.7 1304.7 642.6 700.3 768.9 785.5 814.4 5814.9 6811.5 6933.9 7053.6 7084.2 578.2 588.7 591.7 602.9 701.9 4338.2 4350.3 4422.5 4501.1 4505.5 14771.5 15267.0 15554.8 15567.1 15589.8 15594.8 15606.5 15611.4 15629.7 15632.0 10775.2 11052.3 11199.6 11219.6 11235.4 6741.6 6795.4 6957.0 6980.9 7029.3 13584.3 13786.7 13812.4 13995.8 14118.0 12529.2 13431.0 14185.4 14348.2 14356.7 8528.7 8727.6 8728.3 8741.3 8744.6 10590.8 10690.5 10845.7 10851.7 10854.5 9226.9 9236.7 9347.3 9384.1 9443.7 9014.5 9066.8 9114.8 9162.2 9176.7 8099.8 8303.3 8306.4 8310.6 8312.4 5349.3 5791.9 5895.6 6000.3 6316.2 5985.5 6110.7 6225.3 6255.0 6302.2 22:28:11 INFO - PID 1640 | 22:28:11 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5000.91 Median:5764.16 stddev:1563.40 (27.1%) stddev-sans-first:1528.26 22:28:11 INFO - PID 1640 | Values: 346.9 382.9 390.1 403.9 410.0 4860.9 5915.8 5969.9 5976.1 5983.2 4737.0 5725.6 5778.3 5803.2 5810.4 4341.6 4347.5 4356.0 4378.7 4384.8 3799.1 3816.5 4014.6 4373.5 4411.7 4130.7 4286.2 4331.2 4337.0 4344.9 3539.3 4295.3 4307.7 4311.4 4320.8 3116.9 3764.2 3769.3 3780.3 3783.8 4362.8 5638.8 5682.0 5709.9 5759.2 5072.6 5076.4 5085.1 5088.9 5097.5 4966.2 6152.8 6188.9 6189.8 6190.4 3646.3 4014.3 4026.6 4224.4 4236.8 5302.2 6102.8 6385.5 6395.0 6493.7 4006.5 4208.2 4271.4 4303.9 4318.4 4129.3 4344.7 4353.8 4383.7 4388.5 3950.6 4573.3 4840.8 4954.6 5045.0 5123.1 5540.7 6214.2 6235.3 6246.0 1440.3 1474.3 1485.7 1487.0 1495.4 876.8 964.4 976.4 998.4 1005.2 5121.8 5830.7 5849.7 5851.7 5859.4 6077.4 6168.8 6430.4 6441.5 6453.9 5842.8 5854.8 5871.2 5887.2 5901.5 3326.9 3350.6 3359.7 3371.7 3385.8 5282.8 5410.9 6207.9 6251.6 6605.2 6063.5 6470.7 6496.4 6601.4 6659.4 6381.5 6405.2 6555.9 6560.2 6640.8 6449.5 6452.6 6456.6 6459.0 6466.2 5018.8 6114.5 6374.6 6393.8 6489.7 5775.5 6435.9 6496.7 6552.4 6586.2 3851.6 4262.4 4294.2 4316.7 4316.9 6203.3 6420.3 6466.4 6506.7 6525.6 6233.8 6243.9 6256.2 6257.2 6291.6 4830.1 4982.2 5420.5 6030.6 6208.5 5461.4 6079.6 6092.4 6094.6 6144.9 6176.7 6210.8 6218.5 6244.3 6263.1 5769.1 6018.5 6064.5 6067.8 6123.6 22:28:11 INFO - PID 1640 | 22:28:11 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:21030.31 Median:17487.78 stddev:28816.53 (164.8%) stddev-sans-first:28856.98 22:28:11 INFO - PID 1640 | Values: 712.6 856.7 872.9 874.0 882.2 12790.9 16581.5 17105.9 17320.0 17372.4 15673.6 15897.1 16055.0 16085.8 16123.1 948.1 971.2 973.7 976.0 977.9 659.9 814.1 980.1 998.7 999.4 1039.0 1043.6 1045.0 1045.5 1046.2 1031.4 1035.6 1036.3 1037.8 1041.6 717.2 766.7 767.3 767.3 767.8 15831.0 16104.9 16216.7 16283.3 16508.5 16183.8 16203.2 16583.3 16666.8 16778.5 13451.3 13649.5 13755.7 13822.9 13922.9 3353.2 3375.1 3376.8 3381.1 3388.5 104568.0 111265.0 111567.0 112363.9 112996.0 11255.8 13361.7 13713.7 13721.5 13743.7 16948.7 18978.8 19074.2 23775.0 23840.8 8760.5 8882.3 8984.3 9099.5 9120.5 4809.5 4904.1 4904.5 4905.3 4906.8 4310.9 4326.4 4402.3 4445.2 4452.7 830.8 853.7 866.8 870.9 871.9 626.7 656.4 656.6 665.9 688.3 10450.9 10839.9 11703.6 11872.8 11933.3 642.1 644.2 650.2 680.5 682.6 5210.6 5304.9 5422.7 5424.6 5530.3 100152.0 100429.0 101944.5 103755.0 104575.0 72373.1 75323.0 90313.0 90962.5 91665.0 76304.0 76967.6 77035.6 77403.0 77415.0 8771.9 10818.1 11201.7 11349.4 12216.3 36136.9 37139.0 38644.8 39980.4 40151.6 65697.7 65751.0 65806.7 66061.3 69014.0 16319.0 16383.8 16582.2 16621.2 16705.0 17452.7 17522.9 17589.8 17601.5 17624.2 16520.2 16610.8 16631.4 16844.4 16985.7 16361.1 16939.0 16968.5 17009.4 17118.7 16132.9 16352.9 16710.4 16771.5 16879.3 10701.5 11555.9 12162.0 12310.4 12346.4 12132.7 12915.5 12958.3 13610.7 13668.4 22:28:11 INFO - PID 1640 | -------- Summary: end -------- 22:28:11 INFO - PID 1640 | 22:28:12 INFO - TEST-INFO | 1640: exit 0 22:28:12 INFO - TEST-OK | dromaeo_css | took 1119637ms 22:28:12 INFO - TEST-START | kraken 22:28:12 INFO - operating with platform_type : mac_ 22:28:12 INFO - Initialising browser for kraken test... 22:28:12 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpn2bTE6/profile http://localhost:49249/getInfo.html) 22:28:15 INFO - PID 1650 | __metrics Screen width/height:1600/1200 22:28:15 INFO - PID 1650 | colorDepth:24 22:28:15 INFO - PID 1650 | Browser inner width/height: 1024/689 22:28:15 INFO - PID 1650 | __metrics 22:28:15 INFO - PID 1650 | 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" 22:28:15 INFO - PID 1650 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:28:16 INFO - TEST-INFO | 1650: exit 0 22:28:16 INFO - Browser initialized. 22:28:16 INFO - Running cycle 1/1 for kraken test... 22:28:16 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpn2bTE6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:28:28 INFO - PID 1651 | RSS: Main: 243318784 22:28:28 INFO - PID 1651 | 22:28:31 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 22:28:32 INFO - PID 1651 | RSS: Main: 313008128 22:28:32 INFO - PID 1651 | 22:28:35 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 22:28:35 INFO - PID 1651 | RSS: Main: 294080512 22:28:35 INFO - PID 1651 | 22:28:38 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 22:28:39 INFO - PID 1651 | RSS: Main: 386850816 22:28:39 INFO - PID 1651 | 22:28:41 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 22:28:42 INFO - PID 1651 | RSS: Main: 290729984 22:28:42 INFO - PID 1651 | 22:28:44 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:28:44 INFO - PID 1651 | RSS: Main: 292880384 22:28:44 INFO - PID 1651 | 22:28:47 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 22:28:48 INFO - PID 1651 | RSS: Main: 355303424 22:28:48 INFO - PID 1651 | 22:28:51 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 22:28:52 INFO - PID 1651 | RSS: Main: 375316480 22:28:52 INFO - PID 1651 | 22:28:54 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 22:28:55 INFO - PID 1651 | RSS: Main: 384536576 22:28:55 INFO - PID 1651 | 22:28:57 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:28:57 INFO - PID 1651 | RSS: Main: 334671872 22:28:57 INFO - PID 1651 | 22:28:59 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:29:00 INFO - PID 1651 | RSS: Main: 335839232 22:29:00 INFO - PID 1651 | 22:29:02 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:29:02 INFO - PID 1651 | RSS: Main: 318517248 22:29:02 INFO - PID 1651 | 22:29:05 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:29:06 INFO - PID 1651 | RSS: Main: 335998976 22:29:06 INFO - PID 1651 | 22:29:09 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:29:10 INFO - PID 1651 | RSS: Main: 346107904 22:29:10 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 22:29:12 INFO - PID 1651 | RSS: Main: 389128192 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | __start_tp_report 22:29:12 INFO - PID 1651 | _x_x_mozilla_page_load 22:29:12 INFO - PID 1651 | _x_x_mozilla_page_load_details 22:29:12 INFO - PID 1651 | |i|pagename|runs| 22:29:12 INFO - PID 1651 | |0;ai-astar;111;111;111;112;112;111;112;112;111;111 22:29:12 INFO - PID 1651 | |1;audio-beat-detection;145;110;110;162;144;143;113;108;138;110 22:29:12 INFO - PID 1651 | |2;audio-dft;186;183;184;187;185;185;181;187;182;186 22:29:12 INFO - PID 1651 | |3;audio-fft;80;61;59;89;61;62;80;59;81;61 22:29:12 INFO - PID 1651 | |4;audio-oscillator;90;71;85;71;70;71;90;68;69;90 22:29:12 INFO - PID 1651 | |5;imaging-gaussian-blur;88;89;88;88;89;88;88;89;96;134 22:29:12 INFO - PID 1651 | |6;imaging-darkroom;94;94;95;116;142;95;95;96;95;94 22:29:12 INFO - PID 1651 | |7;imaging-desaturate;85;83;84;84;84;84;84;85;84;86 22:29:12 INFO - PID 1651 | |8;json-parse-financial;51;50;49;50;49;51;49;50;48;68 22:29:12 INFO - PID 1651 | |9;json-stringify-tinderbox;51;56;57;63;60;59;57;58;59;59 22:29:12 INFO - PID 1651 | |10;stanford-crypto-aes;85;84;84;83;82;83;83;84;83;84 22:29:12 INFO - PID 1651 | |11;stanford-crypto-ccm;138;138;158;142;135;139;136;138;184;159 22:29:12 INFO - PID 1651 | |12;stanford-crypto-pbkdf2;190;186;183;183;186;188;188;186;188;187 22:29:12 INFO - PID 1651 | |13;stanford-crypto-sha256-iterative;68;68;72;64;58;71;89;93;91;80 22:29:12 INFO - PID 1651 | __end_tp_report 22:29:12 INFO - PID 1651 | __start_cc_report 22:29:12 INFO - PID 1651 | _x_x_mozilla_cycle_collect,2201 22:29:12 INFO - PID 1651 | __end_cc_report 22:29:12 INFO - PID 1651 | __startTimestamp1492147752549__endTimestamp 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | ------- Summary: start ------- 22:29:12 INFO - PID 1651 | Number of tests: 14 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#0] ai-astar Cycles:10 Average:111.40 Median:111.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 22:29:12 INFO - PID 1651 | Values: 111.0 111.0 111.0 112.0 112.0 111.0 112.0 112.0 111.0 111.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:140.50 stddev:20.06 (14.3%) stddev-sans-first:20.35 22:29:12 INFO - PID 1651 | Values: 145.0 110.0 110.0 162.0 144.0 143.0 113.0 108.0 138.0 110.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.50 stddev:2.07 (1.1%) stddev-sans-first:2.13 22:29:12 INFO - PID 1651 | Values: 186.0 183.0 184.0 187.0 185.0 185.0 181.0 187.0 182.0 186.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#3] audio-fft Cycles:10 Average:69.30 Median:71.00 stddev:11.67 (16.4%) stddev-sans-first:11.72 22:29:12 INFO - PID 1651 | Values: 80.0 61.0 59.0 89.0 61.0 62.0 80.0 59.0 81.0 61.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.00 stddev:9.83 (12.6%) stddev-sans-first:9.33 22:29:12 INFO - PID 1651 | Values: 90.0 71.0 85.0 71.0 70.0 71.0 90.0 68.0 69.0 90.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:88.50 stddev:14.37 (16.2%) stddev-sans-first:15.09 22:29:12 INFO - PID 1651 | Values: 88.0 89.0 88.0 88.0 89.0 88.0 88.0 89.0 96.0 134.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:95.00 stddev:15.70 (16.5%) stddev-sans-first:16.41 22:29:12 INFO - PID 1651 | Values: 94.0 94.0 95.0 116.0 142.0 95.0 95.0 96.0 95.0 94.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:0.82 (1.0%) stddev-sans-first:0.83 22:29:12 INFO - PID 1651 | Values: 85.0 83.0 84.0 84.0 84.0 84.0 84.0 85.0 84.0 86.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:50.00 stddev:5.87 (11.7%) stddev-sans-first:6.23 22:29:12 INFO - PID 1651 | Values: 51.0 50.0 49.0 50.0 49.0 51.0 49.0 50.0 48.0 68.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:57.90 Median:59.00 stddev:3.11 (5.3%) stddev-sans-first:2.06 22:29:12 INFO - PID 1651 | Values: 51.0 56.0 57.0 63.0 60.0 59.0 57.0 58.0 59.0 59.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:0.85 (1.0%) stddev-sans-first:0.71 22:29:12 INFO - PID 1651 | Values: 85.0 84.0 84.0 83.0 82.0 83.0 83.0 84.0 83.0 84.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:140.50 stddev:15.74 (11.2%) stddev-sans-first:16.38 22:29:12 INFO - PID 1651 | Values: 138.0 138.0 158.0 142.0 135.0 139.0 136.0 138.0 184.0 159.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.50 Median:187.50 stddev:2.22 (1.2%) stddev-sans-first:1.96 22:29:12 INFO - PID 1651 | Values: 190.0 186.0 183.0 183.0 186.0 188.0 188.0 186.0 188.0 187.0 22:29:12 INFO - PID 1651 | 22:29:12 INFO - PID 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.40 Median:76.00 stddev:12.17 (16.0%) stddev-sans-first:12.61 22:29:12 INFO - PID 1651 | Values: 68.0 68.0 72.0 64.0 58.0 71.0 89.0 93.0 91.0 80.0 22:29:12 INFO - PID 1651 | -------- Summary: end -------- 22:29:12 INFO - PID 1651 | 22:29:13 INFO - TEST-INFO | 1651: exit 0 22:29:13 INFO - TEST-OK | kraken | took 61133ms 22:29:13 INFO - SUITE-END | took 1180s 22:29:13 INFO - Completed test suite (00:19:40) 22:29:13 INFO - javascript benchmark 22:29:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7718.76657164518, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3480.738944579157, 3484.8083355415474, 3498.7725797823155, 3536.7701099428537, 3575.5530558680166, 5965.1946987156725, 6127.647695153012, 6231.345708700589, 6239.126522286883, 6243.56295059346, 6157.445829875311, 6194.845128871781, 6259.530535209866, 6270.090836828657, 6272.184615999914, 5235.476452354753, 5347.465253474641, 5372.597055220855, 5408.91886621701, 5515.0, 5434.0, 5501.092319767254, 5503.697296648683, 5510.6969116698565, 5512.751926163332, 4422.7346359218545, 5288.603354748371, 5376.435474275184, 5404.32445944257, 5465.836024919239, 5341.866453338659, 5347.0909945309395, 5355.544778693777, 5368.597355198376, 5382.650127741724, 5289.761960711778, 5345.224942383333, 5367.2485851980755, 5444.992676354887, 5456.7544460499375, 6185.6597887116195, 6194.25668919728, 6209.9068513972325, 6209.968950155266, 6222.471089957359, 6589.011648252753, 6595.340465953414, 6602.735890564371, 6607.834804129887, 6617.735290588419, 5202.97398513005, 6451.870962580722, 6458.806235812933, 6519.739210431577, 6668.699908504129, 5239.187925871534, 6250.4374606285655, 6342.080398342307, 6344.587601805868, 6345.460635845912, 6807.659617019228, 6819.727210911508, 6827.0783444235385, 6828.5902845829405, 6841.247462779091, 6223.471004964538, 6338.461230795342, 6342.8414289642205, 6350.33321501245, 6493.480521558264, 6573.506986975996, 6637.701303441357, 6657.866842663119, 6675.065490831287, 6690.632015239111, 5629.324481062299, 6298.8976929037235, 6548.574342667712, 6566.179227596551, 6581.703823327962, 6361.332060133712, 6581.14445122131, 6612.8346791330605, 6616.702248398834, 6630.834229144213, 5233.816816411408, 5238.057149713089, 5244.947550524522, 5253.50091741284, 5257.185136303879, 3970.7617542947514, 4012.378081397388, 4719.528047195253, 4767.118083154627, 4784.114938736276, 5170.508801663836, 6202.44178023971, 6240.0, 6252.624842509464, 6269.65516896566, 6637.601743895381, 6669.566478178903, 6672.299408562031, 6679.465642748569, 6696.2968888267005, 6108.908366374508, 6171.197744293213, 6175.228096487939, 6250.656213908188, 6264.0, 5769.221155144001, 5783.942160578425, 5787.363390027093, 5787.71061446926, 5787.826365209051, 6574.80275591733, 6631.30371311015, 6637.236717777324, 6639.502037347218, 6652.168478940132, 6526.347365263436, 6529.51028672861, 6532.281449040506, 6541.378569035934, 6558.80323590293, 6627.271000190071, 6712.026756120433, 6803.4557235422035, 6812.659367031728, 6816.147981502312, 6499.805005849643, 6518.185226846645, 6519.837004074936, 6525.2169739631545, 6531.706073226811, 6516.348365163446, 6635.502337324817, 6705.1283333166375, 6709.496787740842, 6709.865802683821, 6304.810855674337, 6393.904091438539, 6394.296627370893, 6400.583962042452, 6406.647634380154, 5391.0, 6232.034034724625, 6414.326495717883, 6418.839529011812, 6419.871602568016, 6156.29202641702, 6324.620522768649, 6343.302236754045, 6351.2696039956, 6355.396237357444, 6461.0, 6471.838204044937, 6473.643949582818, 6486.156799616019, 6496.740130394731, 6332.240131184288, 6343.397377249154, 6362.840928976812, 6370.1718776558755, 6371.649559274099], "value": 6003.766731251909, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.1442341307496, 795.8965334506515, 814.0, 1128.9379084150373, 1143.342578017639, 26931.32671683209, 36185.638143618555, 36628.63371366286, 36714.44928326071, 36750.44874326887, 38114.61885381145, 38427.423588646125, 38461.23077538447, 38498.230035399276, 38634.0, 6109.450149486545, 8449.831003379928, 8585.0, 8611.181937715939, 8616.26761725254, 6118.60229085108, 8257.917420825805, 8431.578421078952, 8459.66161353545, 8463.619137138843, 8478.703245386412, 8523.14768523149, 8531.573421328909, 8533.14668533148, 8533.701320453785, 8435.325173986066, 8496.362772792034, 8497.702580409717, 8510.574471276474, 8518.957405212934, 8282.378821588345, 8363.247307742271, 8367.790805229857, 8367.95816020916, 8377.832443351099, 36582.26835463276, 36805.44791828125, 36854.07864803375, 36865.44701829475, 36883.0, 3459.705924996378, 3482.2861313430685, 3488.965110348915, 3491.0574144980965, 3493.6681015303507, 30236.69763302383, 30358.696413035806, 30361.63372648236, 30372.8481357594, 30385.088447346614, 3118.984405077983, 3148.386064717381, 3181.904542863718, 3191.393635209303, 3191.9680803191904, 206546.0, 279544.82958607626, 279628.0, 279792.60103699553, 280267.99999999796, 47574.76212618915, 47671.52328476671, 47800.0, 47806.282905756445, 47818.89177203471, 49108.50891491111, 49240.75379623079, 49261.0, 49264.50735492599, 49300.013999719806, 14086.295685215699, 14147.787783183261, 14178.574642760734, 14203.005789594628, 14211.431542738175, 10032.44821534808, 10170.542325595366, 11607.419629018515, 11616.767664646659, 11631.88368116325, 10618.256722029539, 11310.151738619636, 12115.030797536176, 12265.202761820454, 12288.508459661514, 4662.487126416091, 5542.035011269032, 5636.3799982002765, 5664.376918538957, 5707.6290041147195, 1007.7631756537207, 1025.8100603300136, 1039.4698703661188, 1063.1282348474176, 1072.4316112460408, 22386.0, 23746.88126559356, 24061.037558497814, 28952.710472895422, 29848.403031939673, 880.3749337970113, 1021.2953062386929, 1033.3644808442752, 1064.3347907557777, 1134.2287244673537, 8717.782055448664, 11278.323300601991, 11332.0, 11410.0, 11420.257683250562, 275323.6233818818, 328915.0, 329410.0, 329875.0, 330441.0, 49587.25619115716, 49598.0, 49612.0, 49636.25545616819, 49777.751111244215, 1429.906121816811, 1450.9564713058626, 1470.206088712105, 1518.3091693279753, 1533.5859317984082, 21048.26331078405, 21338.35984920455, 21346.78653213448, 21399.78600213978, 21831.0, 36361.454578181874, 36409.90770276896, 36414.63585364218, 36536.08659783527, 36560.08599785026, 292707.0, 293065.0, 293670.0, 293932.0, 296213.0, 31816.0, 32402.513962291057, 32420.0, 32501.837490812395, 32574.34851303008, 41926.58073419227, 42014.0, 42055.0, 42063.0, 42096.3685544711, 26515.60226596564, 27492.312692182855, 28784.841136914398, 29555.85222073962, 29936.401271974875, 25789.613155802304, 29014.27464313409, 29049.6927638261, 29564.96522621698, 29649.0, 34551.0, 35150.12124696903, 35300.0, 35324.11689707778, 35346.17230621596, 22334.0, 25379.98480060777, 28583.0, 29987.700122999362, 30083.097507074825, 19718.8028119717, 22902.87855832871, 23175.0, 23333.0, 23391.88304058537], "value": 16838.49097997993, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.50297081115633, 386.3297179393753, 427.0796433685408, 428.2740754421256, 443.3748414735224, 13362.465501379946, 15408.537743867673, 19431.51421214467, 22157.446063848372, 22190.11239550418, 22120.557588848213, 22392.552148957013, 22899.771002289974, 23076.0, 23451.882740586316, 6023.247094113236, 6078.574499785017, 6136.0, 6192.535559833008, 6244.906326405107, 3623.655752703489, 6101.542384321171, 6229.065640153991, 6234.127222188898, 6242.1261023456755, 3882.514685664292, 3920.666743326821, 6162.630242185461, 6192.040233763774, 6199.101130336121, 5899.439553242435, 6177.598456100362, 6185.6597887116195, 6190.102435146923, 6208.627482351051, 5445.210444485567, 5491.039068163095, 5517.944820551783, 5518.172274158868, 5534.308211473566, 22605.547889042125, 27357.316067098283, 27594.44811103787, 28163.85918070417, 28341.57487637687, 2058.87646741196, 2279.5326957973575, 2286.1084177170915, 2286.451251699597, 2288.0047179476815, 17466.388676396266, 18564.535886602807, 18613.0, 18653.44039678812, 18820.435386938414, 1951.7853036166011, 2073.39871437283, 2108.0724481228212, 2148.6239908016037, 2150.1721837092678, 147780.0, 153435.0, 155366.0, 156737.0, 158621.99999999884, 11870.821937670942, 12467.812982805266, 12474.0, 12474.064445166649, 12499.687507812378, 16586.834131658772, 16598.336066557444, 16612.0, 16622.002679839006, 16634.25145868439, 8852.911470885208, 9068.410553314014, 9069.229115525122, 9109.681161159328, 9226.723198304062, 8607.0, 8622.31021518277, 8651.04838467768, 8829.867551986725, 8831.470111793184, 7006.407629177237, 7421.777346679608, 7740.651670674833, 7742.845143097106, 7895.526268423798, 2078.1064142418904, 2092.288621868572, 2095.413284280404, 2096.6540520813896, 2232.3079845248026, 839.5130824121994, 922.9307801914883, 947.2043483473915, 950.7342279270589, 965.2567523007385, 20843.58312833765, 21168.682469762967, 21213.15147394087, 21230.78769212319, 21240.78759212419, 992.4194346307492, 1001.8397056471075, 1005.215552918412, 1016.0855230292648, 1016.893226211252, 10224.488775561193, 10611.84082238767, 10956.397398143179, 11025.83461248082, 11032.28290161137, 659854.0, 661780.0, 677210.0, 682812.0, 683236.0, 117231.41384293024, 143080.5691943088, 144217.0, 149930.0, 151749.0, 1091.2906610703108, 1140.526681427215, 1167.6788883057056, 1167.8890505402144, 1169.8011338072379, 17385.391511297043, 17998.37005704794, 18126.728099078788, 18562.81437185665, 18623.34818281408, 58368.0, 58461.12308315294, 61615.0, 62991.37008629855, 63487.0, 66770.0, 86919.56540217258, 87554.68667970113, 87703.0, 88273.55863220643, 8419.0, 8497.575121244037, 8531.036643278958, 8838.779030524289, 8903.19871211594, 13563.321833908069, 13588.388522516312, 13798.517051903134, 13999.090059146123, 14080.647983800487, 11768.70578235551, 11801.17391782567, 11910.047196224454, 12069.456874440846, 12188.45151968146, 8972.551372431482, 10388.844167337345, 10786.730331741455, 10934.0, 11135.72160695989, 11697.532098716296, 11801.114916381477, 11878.0, 11952.820707689218, 12099.81850272229, 8647.357002169567, 8667.91332086671, 8916.15296546827, 8972.0, 9190.54047297619, 11683.766324673628, 11892.0, 12196.51213951432, 12230.510779568718, 12319.69200769988], "value": 10393.523144663612, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.2220911871116, 1420.3821337718086, 1427.8286605607325, 1446.0600609603762, 1448.3482432905182, 8663.350248731347, 9857.852132218022, 11946.522139114424, 11957.22278051926, 11959.282443053424, 11553.422328883564, 12020.759584808298, 12035.879641203584, 12049.15655904087, 12056.69858253544, 2536.467341858212, 2579.3422677217377, 2581.5224183526107, 2583.702874169466, 2588.805839562031, 2116.460302622837, 2408.4701365699607, 2500.749925007503, 2549.1842610364693, 2556.169244995375, 2023.231172154579, 2190.277208521184, 2213.1921848525335, 2546.681664791901, 2554.6551215585846, 2488.1042824583137, 2509.0716434919013, 2515.559777039011, 2516.119358224616, 2521.583938650139, 2495.6755621769053, 2509.2346834215555, 2510.2971168072786, 2515.2328539795353, 2515.7987361011074, 11742.060635149168, 11998.64004079879, 12027.458764355624, 12039.217450865666, 12039.759204815942, 6469.547131700783, 6693.0, 6694.598324100521, 6703.1285932828905, 6708.12794336738, 4056.7971601420104, 4271.850485233033, 4366.0, 4436.778161091965, 4451.1765323415275, 1217.1540779158488, 1248.4694005047818, 1285.2995117660898, 1304.2891624064907, 1308.7513372459202, 16923.06923119228, 17039.829601703947, 17042.914785426117, 17043.57391065221, 17106.743398849027, 5460.726963651801, 5574.58190635704, 5604.635698679573, 5806.390329015477, 5812.88374232513, 12463.875361246453, 12497.18768280077, 12520.624381268577, 12537.623871283877, 12559.811602825965, 6552.705128269239, 6671.966640166768, 6671.966640166768, 6679.732810687616, 6703.631300278434, 6033.607815491979, 6211.409916057967, 6276.340984196685, 6289.465395441347, 6298.748050077946, 4265.722728022669, 4399.932031944981, 4407.382966384709, 4456.799444025027, 4485.730856148642, 1252.7557126360273, 1275.5153041844226, 1278.1244847279643, 1302.6873550347848, 1304.7325298313822, 642.5937076622068, 700.3136925812732, 768.8577613141587, 785.5208322923013, 814.4136221920327, 5814.854628634318, 6811.523193376416, 6933.93066069343, 7053.612051337123, 7084.220735719066, 578.23672751967, 588.6732863260931, 591.7485068845723, 602.8764103358801, 701.8875082993411, 4338.175746608134, 4350.347447882779, 4422.5356337584735, 4501.07727915783, 4505.459344878635, 14771.482998095014, 15267.0, 15554.844451555566, 15567.143807090491, 15589.766153507706, 15594.766078508832, 15606.453774117852, 15611.3755449783, 15629.68740625181, 15632.0, 10775.191860610328, 11052.281601695864, 11199.552017919355, 11219.607313744144, 11235.438228088562, 6741.584237396268, 6795.422389096982, 6957.031560537355, 6980.930190698028, 7029.332213439715, 13584.252866092065, 13786.65533361674, 13812.37844296989, 13995.790063149265, 14118.0, 12529.248245105322, 13431.04517006257, 14185.36165872559, 14348.210848403021, 14356.712865742418, 8528.658853645784, 8727.563621818757, 8728.345374097094, 8741.300695944436, 8744.606492707717, 10590.788184236426, 10690.518926648476, 10845.728856778644, 10851.67444976652, 10854.45727713627, 9226.907730922874, 9236.72289831306, 9347.298952578447, 9384.061593840834, 9443.669471568463, 9014.459132451811, 9066.818663626822, 9114.772130696785, 9162.175404213653, 9176.6788162414, 8099.757007289791, 8303.294219991369, 8306.377021723274, 8310.584470776315, 8312.376571757022, 5349.304590403222, 5791.942080579141, 5895.616784908967, 6000.339962604197, 6316.210473690789, 5985.491233245223, 6110.7250173741395, 6225.252969643671, 6254.9687251563455, 6302.243730752339], "value": 5400.951338690376, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.906335289472, 382.867910570853, 390.1163863848383, 403.91113954929915, 409.98360065597376, 4860.90629608338, 5915.8168366326745, 5969.850853692673, 5976.103584462333, 5983.24332155936, 4736.976315118428, 5725.562688916092, 5778.27771528559, 5803.245578074844, 5810.448007439297, 4341.565843415665, 4347.478302603692, 4355.978220108903, 4378.6934914555995, 4384.758838263894, 3799.1072098056934, 3816.484774555427, 4014.5784692607294, 4373.540778218289, 4411.713238639494, 4130.669546436278, 4286.164197981379, 4331.155424692186, 4337.024169561855, 4344.869653910388, 3539.2744487380023, 4295.312749959991, 4307.741535507879, 4311.439512863308, 4320.805563749607, 3116.8753249870206, 3764.190698999701, 3769.2838360711376, 3780.3384407728813, 3783.754055986352, 4362.825486980517, 5638.802641907513, 5682.034054210794, 5709.914351284734, 5759.22250496186, 5072.644914855961, 5076.44159142494, 5085.084684756743, 5088.872778180576, 5097.541221290102, 4966.230234313651, 6152.753889844401, 6188.916939535608, 6189.81430557084, 6190.41191086846, 3646.3436581415335, 4014.297497937849, 4026.57720939303, 4224.429701990222, 4236.809343579546, 5302.204669299597, 6102.816915492543, 6385.48916086712, 6394.968025159891, 6493.70778314977, 4006.5192176938954, 4208.169446290874, 4271.359296105609, 4303.892402689958, 4318.417013703172, 4129.2773764591075, 4344.717593356421, 4353.804078816461, 4383.736975781399, 4388.451443569554, 3950.644442000234, 4573.268277075652, 4840.83057093, 4954.628402869727, 5045.0, 5123.077845987684, 5540.695261760642, 6214.223222097238, 6235.314115447297, 6246.031865060922, 1440.3014537949086, 1474.329180222996, 1485.695432436348, 1486.9851301487063, 1495.364470100203, 876.8162979977108, 964.3702143377809, 976.3799987008301, 998.3560603410905, 1005.215931573374, 5121.847584293504, 5830.679312637845, 5849.678267695318, 5851.6781577012825, 5859.359379373697, 6077.4226448487325, 6168.845778855564, 6430.421262086435, 6441.452476539453, 6453.903191452132, 5842.766289348463, 5854.824355269348, 5871.207387002785, 5887.2052272943465, 5901.527877769769, 3326.866925323008, 3350.6146793118614, 3359.740097463451, 3371.7302615790677, 3385.8173317082355, 5282.841514754564, 5410.864728381822, 6207.906881396782, 6251.562390632703, 6605.174353205849, 6063.454289114001, 6470.708818103197, 6496.447801936888, 6601.438877695449, 6659.433948114464, 6381.521385896076, 6405.19935008124, 6555.934440655532, 6560.245571759308, 6640.800775976729, 6449.5485316027425, 6452.645104519296, 6456.644884531208, 6458.967705161444, 6466.154891847572, 5018.849434516825, 6114.480269176992, 6374.58565193261, 6393.808185754435, 6489.675516224075, 5775.537956963349, 6435.871282574416, 6496.70764815575, 6552.443042341454, 6586.242582102927, 3851.5763266040153, 4262.403263543044, 4294.162638285505, 4316.740995540152, 4316.870493885189, 6203.286622038523, 6420.3131713848215, 6466.385693359123, 6506.674666266762, 6525.64108974011, 6233.781817636361, 6243.906341404973, 6256.185706145349, 6257.186565746424, 6291.559590828781, 4830.106430310263, 4982.202847544376, 5420.457954204548, 6030.638161710312, 6208.4722798562625, 5461.399246083007, 6079.635221886701, 6092.360302168386, 6094.634321940521, 6144.877102457836, 6176.6911654418, 6210.782622608187, 6218.450435321496, 6244.281907580505, 6263.123162757309, 5769.105788602773, 6018.4884284834525, 6064.48451881595, 6067.757289708362, 6123.632582045091], "value": 4519.0176075601385, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.579578048951, 856.6830272799064, 872.9214370706635, 873.9825203495931, 882.2016075451714, 12790.936045319771, 16581.50255492334, 17105.914470427662, 17319.960802351838, 17372.391966281168, 15673.608159796011, 15897.1256580888, 16055.0, 16085.758713619305, 16123.113228772412, 948.1040416806121, 971.1939090554857, 973.6884197056945, 976.0483528559633, 977.8826540815077, 659.9241087274977, 814.0665918443697, 980.0542476510167, 998.7203582996736, 999.3604093380243, 1038.9973675403235, 1043.639944219247, 1044.9947750261276, 1045.4563626914, 1046.1735229168946, 1031.3554028732042, 1035.6375268656022, 1036.2797855490437, 1037.8287582548871, 1041.5833666533372, 717.2003216413714, 766.6856588798565, 767.2595944913186, 767.2787579675088, 767.7850201943464, 15831.0, 16104.919475402548, 16216.675666486723, 16283.267252973645, 16508.504744857553, 16183.757243641356, 16203.189840508045, 16583.336666533323, 16666.83333166665, 16778.496645100666, 13451.260180690058, 13649.522266720716, 13755.724885502334, 13822.93088534561, 13922.93038534811, 3353.2119951811305, 3375.1224681582967, 3376.8142752148624, 3381.137809858495, 3388.4747864081105, 104568.0, 111265.0, 111567.0, 112363.87636123617, 112996.0, 11255.831162532568, 13361.73276534464, 13713.725725485434, 13721.45114195421, 13743.656408589664, 16948.74576881348, 18978.81021189798, 19074.2370305189, 23775.0, 23840.761592384202, 8760.474371537728, 8882.289416846637, 8984.32617553686, 9099.45309297421, 9120.543972801333, 4809.5430934061205, 4904.117258893362, 4904.485029071896, 4905.288733133747, 4906.754662266872, 4310.89222769427, 4326.41593384889, 4402.25161722508, 4445.155420470101, 4452.708714472767, 830.8338332333558, 853.7182729699184, 866.7616405488541, 870.8954925408991, 871.9215270625674, 626.7493002798917, 656.4125108028288, 656.592912394309, 665.9467242620578, 688.3048121397325, 10450.947745261225, 10839.945800271105, 11703.590374336858, 11872.821907671392, 11933.284002959677, 642.1267076775581, 644.22049320322, 650.1515522243518, 680.4726337088796, 682.5938566552893, 5210.583153347723, 5304.92042619361, 5422.728863556807, 5424.647397919122, 5530.281063461724, 100151.99848001573, 100428.99571004343, 101944.49027754813, 103755.0, 104574.95425045805, 72373.05844535593, 75323.0, 90313.0, 90962.54518727364, 91665.0, 76304.0, 76967.61516192496, 77035.61482192665, 77403.0, 77415.0, 8771.947257027472, 10818.080463160719, 11201.71995700114, 11349.37578433161, 12216.328101954477, 36136.91589252327, 37139.0, 38644.80677596594, 39980.400293996194, 40151.59848401479, 65697.67151164214, 65751.0, 65806.67096664486, 66061.33938660551, 69014.0, 16319.0, 16383.836161638232, 16582.17089145514, 16621.168941553115, 16705.0, 17452.650946980735, 17522.91238543799, 17589.824101759332, 17601.471955841345, 17624.206910688794, 16520.173991300628, 16610.83389166093, 16631.417900373428, 16844.410445634345, 16985.660286794442, 16361.100139492442, 16939.0, 16968.49094527166, 17009.404670836462, 17118.657626847642, 16132.919335403249, 16352.918235408746, 16710.4151354702, 16771.496855094367, 16879.324827007273, 10701.518431670438, 11555.942220288845, 12162.02061917378, 12310.446029928495, 12346.3826808661, 12132.696682583006, 12915.483380664667, 12958.287294198533, 13610.659733506742, 13668.384922678702], "value": 8246.16237455379, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1452.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 111.0, 112.0, 112.0, 111.0, 112.0, 112.0, 111.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 110.0, 110.0, 162.0, 144.0, 143.0, 113.0, 108.0, 138.0, 110.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 184.0, 187.0, 185.0, 185.0, 181.0, 187.0, 182.0, 186.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 59.0, 89.0, 61.0, 62.0, 80.0, 59.0, 81.0, 61.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 85.0, 71.0, 70.0, 71.0, 90.0, 68.0, 69.0, 90.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 88.0, 89.0, 88.0, 88.0, 89.0, 96.0, 134.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 116.0, 142.0, 95.0, 95.0, 96.0, 95.0, 94.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 84.0, 84.0, 84.0, 84.0, 85.0, 84.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 50.0, 49.0, 51.0, 49.0, 50.0, 48.0, 68.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 56.0, 57.0, 63.0, 60.0, 59.0, 57.0, 58.0, 59.0, 59.0], "value": 57.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 83.0, 82.0, 83.0, 83.0, 84.0, 83.0, 84.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 158.0, 142.0, 135.0, 139.0, 136.0, 138.0, 184.0, 159.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 186.0, 183.0, 183.0, 186.0, 188.0, 188.0, 186.0, 188.0, 187.0], "value": 186.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 72.0, 64.0, 58.0, 71.0, 89.0, 93.0, 91.0, 80.0], "value": 75.4, "unit": "score"}]}]} 22:29:13 INFO - Return code: 0 22:29:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:29:13 ERROR - # TBPL SUCCESS # 22:29:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:29:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:29:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:29:13 INFO - 2017-04-13 22:29:13.887 screenresolution[1655:10134] starting screenresolution argv=screenresolution get 22:29:13 INFO - 2017-04-13 22:29:13.890 screenresolution[1655:10134] Display 0: 1600x1200x32@60 22:29:13 INFO - 2017-04-13 22:29:13.896 screenresolution[1656:10137] starting screenresolution argv=screenresolution list 22:29:13 INFO - Available Modes on Display 0 22:29:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:29:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:29:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:29:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:29:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:29:14 INFO - Intel Iris: 22:29:14 INFO - Chipset Model: Intel Iris 22:29:14 INFO - Type: GPU 22:29:14 INFO - Bus: Built-In 22:29:14 INFO - VRAM (Dynamic, Max): 1536 MB 22:29:14 INFO - Vendor: Intel (0x8086) 22:29:14 INFO - Device ID: 0x0a2e 22:29:14 INFO - Revision ID: 0x0009 22:29:14 INFO - Displays: 22:29:14 INFO - Display: 22:29:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:29:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:29:14 INFO - Display Serial Number: 1600x1200 60 22:29:14 INFO - Main Display: Yes 22:29:14 INFO - Mirror: Off 22:29:14 INFO - Online: Yes 22:29:14 INFO - Rotation: Supported 22:29:14 INFO - Return code: 0 22:29:14 INFO - Running post-action listener: _resource_record_post_action 22:29:14 INFO - [mozharness: 2017-04-14 05:29:14.144239Z] Finished run-tests step (success) 22:29:14 INFO - Running post-run listener: _resource_record_post_run 22:29:14 INFO - Total resource usage - Wall time: 1202s; CPU: 53.0%; Read bytes: 36914176; Write bytes: 515046912; Read time: 269; Write time: 1618 22:29:14 INFO - TinderboxPrint: CPU usage
53.2% 22:29:14 INFO - TinderboxPrint: I/O read bytes / time
36,914,176 / 269 22:29:14 INFO - TinderboxPrint: I/O write bytes / time
515,046,912 / 1,618 22:29:14 INFO - TinderboxPrint: CPU idle
2,251.2 (46.8%) 22:29:14 INFO - TinderboxPrint: CPU system
957.4 (19.9%) 22:29:14 INFO - TinderboxPrint: CPU user
1,597.1 (33.2%) 22:29:14 INFO - TinderboxPrint: Swap in / out
244,281,344 / 0 22:29:14 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209252864; Write bytes: 210466304; Read time: 16174; Write time: 486 22:29:14 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 34608128; Write bytes: 303302656; Read time: 246; Write time: 1124 22:29:14 INFO - Running post-run listener: _upload_blobber_files 22:29:14 INFO - Blob upload gear active. 22:29:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:29:14 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 22:29:14 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'] 22:29:14 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 22:29:15 INFO - (blobuploader) - INFO - Open directory for files ... 22:29:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:29:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:29:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:16 INFO - (blobuploader) - INFO - Done attempting. 22:29:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:29:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:29:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:17 INFO - (blobuploader) - INFO - Done attempting. 22:29:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:29:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:29:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:17 INFO - (blobuploader) - INFO - Done attempting. 22:29:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:29:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:29:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:20 INFO - (blobuploader) - INFO - Done attempting. 22:29:20 INFO - (blobuploader) - INFO - Iteration through files over. 22:29:20 INFO - Return code: 0 22:29:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:29:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:29:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed409d7df56ee2044c5ad46f11817bd11e77322336c8a3a54d18290d0274cc4410cdaec5b8da0a5e740d75f0647a2842b04604740ebf665625566005fc349380", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d4c27bce052639ad5da2e90cb46bf40021c5f31c8e893d523cbe49c8d6bd6e750a23bb1b204d25cc774af29cc65e6adc5acbcecf764a0d9e1c9e352be2f67e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43a68c8191d72584c1cf5215828aa23b0eb51622a160e77032c8b09f9f6799e7588e0e30190d6067ccfc14485a59f0bf89bffb014b4dd39e9003eaa24eaede1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38a8ea8392d94a53cff12f8988a6288c1d57bfa22d7025667187e4e561e552efa73a513ada22a9048d97246eac15d7b1d6d5dac22be12c0bfa5c5c8542643ac5"} 22:29:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:29:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:29:20 INFO - Contents: 22:29:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed409d7df56ee2044c5ad46f11817bd11e77322336c8a3a54d18290d0274cc4410cdaec5b8da0a5e740d75f0647a2842b04604740ebf665625566005fc349380", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d4c27bce052639ad5da2e90cb46bf40021c5f31c8e893d523cbe49c8d6bd6e750a23bb1b204d25cc774af29cc65e6adc5acbcecf764a0d9e1c9e352be2f67e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43a68c8191d72584c1cf5215828aa23b0eb51622a160e77032c8b09f9f6799e7588e0e30190d6067ccfc14485a59f0bf89bffb014b4dd39e9003eaa24eaede1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38a8ea8392d94a53cff12f8988a6288c1d57bfa22d7025667187e4e561e552efa73a513ada22a9048d97246eac15d7b1d6d5dac22be12c0bfa5c5c8542643ac5"} 22:29:20 INFO - Running post-run listener: copy_logs_to_upload_dir 22:29:20 INFO - Copying logs to upload dir... 22:29:20 INFO - mkdir: /builds/slave/test/build/upload/logs 22:29:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.679774 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-04-13 22:29:20.181756) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 22:29:20.185170) ========= 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.kNeHMWLyKe/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.VaM2I09uHg/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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/ed409d7df56ee2044c5ad46f11817bd11e77322336c8a3a54d18290d0274cc4410cdaec5b8da0a5e740d75f0647a2842b04604740ebf665625566005fc349380", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d4c27bce052639ad5da2e90cb46bf40021c5f31c8e893d523cbe49c8d6bd6e750a23bb1b204d25cc774af29cc65e6adc5acbcecf764a0d9e1c9e352be2f67e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43a68c8191d72584c1cf5215828aa23b0eb51622a160e77032c8b09f9f6799e7588e0e30190d6067ccfc14485a59f0bf89bffb014b4dd39e9003eaa24eaede1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38a8ea8392d94a53cff12f8988a6288c1d57bfa22d7025667187e4e561e552efa73a513ada22a9048d97246eac15d7b1d6d5dac22be12c0bfa5c5c8542643ac5"} build_url:https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011187 build_url: 'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/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/ed409d7df56ee2044c5ad46f11817bd11e77322336c8a3a54d18290d0274cc4410cdaec5b8da0a5e740d75f0647a2842b04604740ebf665625566005fc349380", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d4c27bce052639ad5da2e90cb46bf40021c5f31c8e893d523cbe49c8d6bd6e750a23bb1b204d25cc774af29cc65e6adc5acbcecf764a0d9e1c9e352be2f67e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43a68c8191d72584c1cf5215828aa23b0eb51622a160e77032c8b09f9f6799e7588e0e30190d6067ccfc14485a59f0bf89bffb014b4dd39e9003eaa24eaede1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38a8ea8392d94a53cff12f8988a6288c1d57bfa22d7025667187e4e561e552efa73a513ada22a9048d97246eac15d7b1d6d5dac22be12c0bfa5c5c8542643ac5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 22:29:20.220367) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:29:20.220784) ========= 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.kNeHMWLyKe/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.VaM2I09uHg/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.004916 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:29:20.269933) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 22:29:20.270279) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 22:29:20.270654) ========= ========= Total master_lag: 1.21 =========