builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0198 starttime: 1491603710.19 results: success (0) buildid: 20170407134821 builduid: fb76f0db593f4d04bfd782422661c119 revision: f6712e7858f391923006b174d27cb919c454a9ce ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:50.194257) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:50.194709) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:50.195002) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:50.221281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:50.221611) ========= 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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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-07 15:21:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.5M=0.001s 2017-04-07 15:21:50 (15.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115419 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:50.362243) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:50.362552) ========= 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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.041814 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:50.425790) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 15:21:50.426148) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f6712e7858f391923006b174d27cb919c454a9ce --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f6712e7858f391923006b174d27cb919c454a9ce --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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-07 15:21:50,532 truncating revision to first 12 chars 2017-04-07 15:21:50,532 Setting DEBUG logging. 2017-04-07 15:21:50,532 attempt 1/10 2017-04-07 15:21:50,532 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f6712e7858f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-07 15:21:52,003 unpacking tar archive at: mozilla-aurora-f6712e7858f3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.747882 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 15:21:52.199468) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:52.199808) ========= script_repo_revision: f6712e7858f391923006b174d27cb919c454a9ce ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:52.200202) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:52.200490) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 15:21:52.234313) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-07 15:21:52.234730) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:21:52 INFO - MultiFileLogger online at 20170407 15:21:52 in /builds/slave/test 15:21:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:21:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:21:52 INFO - {'append_to_log': False, 15:21:52 INFO - 'base_work_dir': '/builds/slave/test', 15:21:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:21:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:21:52 INFO - 'branch': 'Mozilla-Aurora', 15:21:52 INFO - 'buildbot_json_path': 'buildprops.json', 15:21:52 INFO - 'config_files': ('talos/mac_config.py',), 15:21:52 INFO - 'default_actions': ('clobber', 15:21:52 INFO - 'read-buildbot-config', 15:21:52 INFO - 'download-and-extract', 15:21:52 INFO - 'populate-webroot', 15:21:52 INFO - 'create-virtualenv', 15:21:52 INFO - 'install', 15:21:52 INFO - 'run-tests'), 15:21:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:21:52 INFO - 'download_minidump_stackwalk': True, 15:21:52 INFO - 'download_symbols': 'ondemand', 15:21:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:21:52 INFO - 'tooltool.py': '/tools/tooltool.py', 15:21:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:21:52 INFO - '/tools/misc-python/virtualenv.py')}, 15:21:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:21:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:21:52 INFO - 'gecko_profile': False, 15:21:52 INFO - 'gecko_profile_interval': 0, 15:21:52 INFO - 'installer_path': 'installer.exe', 15:21:52 INFO - 'log_level': 'info', 15:21:52 INFO - 'log_name': 'talos', 15:21:52 INFO - 'log_to_console': True, 15:21:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:21:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:21:52 INFO - 'opt_config_files': (), 15:21:52 INFO - 'pip_index': False, 15:21:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:21:52 INFO - 'cmd': ('bash', 15:21:52 INFO - '-c', 15:21:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:21:52 INFO - 'enabled': True, 15:21:52 INFO - 'halt_on_failure': False, 15:21:52 INFO - 'name': 'check_screen_resolution'},), 15:21:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:21:52 INFO - 'cmd': ('bash', 15:21:52 INFO - '-c', 15:21:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:21:52 INFO - 'enabled': True, 15:21:52 INFO - 'halt_on_failure': False, 15:21:52 INFO - 'name': 'check_screen_resolution'},), 15:21:52 INFO - 'run_cmd_checks_enabled': True, 15:21:52 INFO - 'suite': 'dromaeojs', 15:21:52 INFO - 'system_bits': '32', 15:21:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:21:52 INFO - 'title': 't-yosemite-r7-0198', 15:21:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:21:52 INFO - 'use_talos_json': True, 15:21:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:21:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:21:52 INFO - 'work_dir': 'build'} 15:21:52 INFO - [mozharness: 2017-04-07 22:21:52.450836Z] Running clobber step. 15:21:52 INFO - Running pre-action listener: _resource_record_pre_action 15:21:52 INFO - Running main action method: clobber 15:21:52 INFO - rmtree: /builds/slave/test/build 15:21:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:21:54 INFO - Running post-action listener: _resource_record_post_action 15:21:54 INFO - [mozharness: 2017-04-07 22:21:54.896924Z] Finished clobber step (success) 15:21:54 INFO - [mozharness: 2017-04-07 22:21:54.897048Z] Running read-buildbot-config step. 15:21:54 INFO - Running pre-action listener: _resource_record_pre_action 15:21:54 INFO - Running main action method: read_buildbot_config 15:21:54 INFO - Using buildbot properties: 15:21:54 INFO - { 15:21:54 INFO - "project": "", 15:21:54 INFO - "product": "firefox", 15:21:54 INFO - "script_repo_revision": "production", 15:21:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:21:54 INFO - "repository": "", 15:21:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:21:54 INFO - "buildid": "20170407134821", 15:21:54 INFO - "pgo_build": "False", 15:21:54 INFO - "basedir": "/builds/slave/test", 15:21:54 INFO - "buildnumber": 92, 15:21:54 INFO - "slavename": "t-yosemite-r7-0198", 15:21:54 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 15:21:54 INFO - "platform": "yosemite_r7", 15:21:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:21:54 INFO - "branch": "mozilla-aurora", 15:21:54 INFO - "repo_path": "releases/mozilla-aurora", 15:21:54 INFO - "revision": "f6712e7858f391923006b174d27cb919c454a9ce", 15:21:54 INFO - "stage_platform": "macosx64", 15:21:54 INFO - "builduid": "fb76f0db593f4d04bfd782422661c119", 15:21:54 INFO - "slavebuilddir": "test" 15:21:54 INFO - } 15:21:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 15:21:54 INFO - Running post-action listener: _resource_record_post_action 15:21:54 INFO - [mozharness: 2017-04-07 22:21:54.900364Z] Finished read-buildbot-config step (success) 15:21:54 INFO - [mozharness: 2017-04-07 22:21:54.900479Z] Running download-and-extract step. 15:21:54 INFO - Running pre-action listener: _resource_record_pre_action 15:21:54 INFO - Running main action method: download_and_extract 15:21:54 INFO - mkdir: /builds/slave/test/build/tests 15:21:54 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')]} 15:21:54 INFO - trying https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 15:21:54 INFO - Downloading https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 15:21:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 15:21:56 INFO - Downloaded 1199 bytes. 15:21:56 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 15:21:56 INFO - Using the following test package requirements: 15:21:56 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 15:21:56 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:56 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 15:21:56 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:56 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 15:21:56 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:56 INFO - u'jsshell-mac.zip'], 15:21:56 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:56 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 15:21:56 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 15:21:56 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:56 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 15:21:56 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:56 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 15:21:56 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:56 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 15:21:56 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:21:56 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 15:21:56 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:21:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 15:21:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:21:56 INFO - Fetch https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 15:21:57 INFO - Expected file size: 51790303 15:21:57 INFO - Obtained file size: 51790303 15:22:13 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:22:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 15:22:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:22:13 INFO - Fetch https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 15:22:15 INFO - Expected file size: 51790303 15:22:15 INFO - Obtained file size: 51790303 15:22:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 15:22:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:22:30 INFO - Fetch https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 15:22:30 INFO - Expected file size: 14404424 15:22:30 INFO - Obtained file size: 14404424 15:22:31 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')]} 15:22:31 INFO - trying https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:22:31 INFO - Downloading https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 15:22:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 15:22:33 INFO - Downloaded 74691647 bytes. 15:22:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:22:33 INFO - mkdir: /builds/slave/test/properties 15:22:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:22:33 INFO - Writing to file /builds/slave/test/properties/build_url 15:22:33 INFO - Contents: 15:22:33 INFO - build_url:https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:22:33 INFO - Running post-action listener: _resource_record_post_action 15:22:33 INFO - Running post-action listener: set_extra_try_arguments 15:22:33 INFO - [mozharness: 2017-04-07 22:22:33.910197Z] Finished download-and-extract step (success) 15:22:33 INFO - [mozharness: 2017-04-07 22:22:33.910334Z] Running populate-webroot step. 15:22:33 INFO - Running pre-action listener: _resource_record_pre_action 15:22:33 INFO - Running main action method: populate_webroot 15:22:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:22:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:22:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:22:33 INFO - '--tppagecycles', 15:22:33 INFO - '7'], 15:22:33 INFO - 'tests': ['tsvgm']}}, 15:22:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:22:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:22:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:22:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:22:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:22:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:22:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:22:33 INFO - 'talos_options': ['--disable-e10s'], 15:22:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:22:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:22:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:22:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:22:33 INFO - 'talos_options': ['--disable-e10s'], 15:22:33 INFO - 'tests': ['damp', 'tps']}, 15:22:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:22:33 INFO - 'tests': ['damp', 'tps']}, 15:22:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:22:33 INFO - 'tests': ['dromaeo_dom']}, 15:22:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:22:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:22:33 INFO - 'tests': ['basic_compositor_video']}, 15:22:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:22:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:22:33 INFO - 'tests': ['a11yr', 15:22:33 INFO - 'ts_paint', 15:22:33 INFO - 'tpaint', 15:22:33 INFO - 'sessionrestore', 15:22:33 INFO - 'sessionrestore_no_auto_restore', 15:22:33 INFO - 'tabpaint']}, 15:22:33 INFO - 'other-e10s': {'tests': ['a11yr', 15:22:33 INFO - 'ts_paint', 15:22:33 INFO - 'tpaint', 15:22:33 INFO - 'sessionrestore', 15:22:33 INFO - 'sessionrestore_no_auto_restore', 15:22:33 INFO - 'tabpaint']}, 15:22:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:22:33 INFO - 'ts_paint', 15:22:33 INFO - 'tpaint', 15:22:33 INFO - 'sessionrestore', 15:22:33 INFO - 'sessionrestore_no_auto_restore', 15:22:33 INFO - 'tabpaint']}, 15:22:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:22:33 INFO - 'ts_paint', 15:22:33 INFO - 'tpaint', 15:22:33 INFO - 'sessionrestore', 15:22:33 INFO - 'sessionrestore_no_auto_restore', 15:22:33 INFO - 'tabpaint']}, 15:22:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:22:33 INFO - 'tests': ['a11yr', 15:22:33 INFO - 'ts_paint', 15:22:33 INFO - 'tpaint', 15:22:33 INFO - 'sessionrestore', 15:22:33 INFO - 'sessionrestore_no_auto_restore', 15:22:33 INFO - 'tabpaint']}, 15:22:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:22:33 INFO - 'tests': ['a11yr', 15:22:33 INFO - 'ts_paint', 15:22:33 INFO - 'tpaint', 15:22:33 INFO - 'sessionrestore', 15:22:33 INFO - 'sessionrestore_no_auto_restore', 15:22:33 INFO - 'tabpaint']}, 15:22:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:22:33 INFO - 'tests': ['tsvgx', 15:22:33 INFO - 'tsvgr_opacity', 15:22:33 INFO - 'tart', 15:22:33 INFO - 'tscrollx', 15:22:33 INFO - 'cart', 15:22:33 INFO - 'tsvg_static']}, 15:22:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:22:33 INFO - 'tsvgr_opacity', 15:22:33 INFO - 'tart', 15:22:33 INFO - 'tscrollx', 15:22:33 INFO - 'cart', 15:22:33 INFO - 'tsvg_static']}, 15:22:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:22:33 INFO - 'talos_options': ['--disable-e10s'], 15:22:33 INFO - 'tests': ['tp5o']}, 15:22:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:22:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:22:33 INFO - 'talos_options': ['--disable-e10s', 15:22:33 INFO - '--xperf_path', 15:22:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:22:33 INFO - 'tests': ['tp5n']}, 15:22:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:22:33 INFO - 'talos_options': ['--xperf_path', 15:22:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:22:33 INFO - 'tests': ['tp5n']}}, 15:22:33 INFO - 'talos.zip': {'path': '', 15:22:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:22:33 INFO - Running post-action listener: _resource_record_post_action 15:22:33 INFO - [mozharness: 2017-04-07 22:22:33.928590Z] Finished populate-webroot step (success) 15:22:33 INFO - [mozharness: 2017-04-07 22:22:33.928724Z] Running create-virtualenv step. 15:22:33 INFO - Running pre-action listener: _resource_record_pre_action 15:22:33 INFO - Running main action method: create_virtualenv 15:22:33 INFO - Creating virtualenv /builds/slave/test/build/venv 15:22:33 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 15:22:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:22:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:22:34 INFO - Using real prefix '/tools/python27' 15:22:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:22:34 INFO - Installing distribute.............................................................................................................................................................................................done. 15:22:37 INFO - Installing pip.................done. 15:22:37 INFO - Return code: 0 15:22:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:22:37 INFO - Reading from file tmpfile_stdout 15:22:37 INFO - Output received: 15:22:37 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) 15:22:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:22:37 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')]} 15:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:37 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')]} 15:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:37 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 0x10247db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102480768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b85ce3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10225bd30>, '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 0x102182db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102250be8>, '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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:22:37 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 15:22:37 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 15:22:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DvWSj6k5OB/Render', 15:22:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:37 INFO - 'HOME': '/Users/cltbld', 15:22:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:37 INFO - 'LOGNAME': 'cltbld', 15:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:37 INFO - 'MOZ_NO_REMOTE': '1', 15:22:37 INFO - 'NO_EM_RESTART': '1', 15:22:37 INFO - 'PAGER': '/bin/cat', 15:22:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:37 INFO - 'PWD': '/builds/slave/test', 15:22:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:37 INFO - 'SHELL': '/bin/bash', 15:22:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pjo8BEqDZh/Listeners', 15:22:37 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:22:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:37 INFO - 'USER': 'cltbld', 15:22:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:37 INFO - 'XPC_FLAGS': '0x0', 15:22:37 INFO - 'XPC_SERVICE_NAME': '0', 15:22:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:38 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 15:22:38 INFO - Cleaning up... 15:22:38 INFO - Return code: 0 15:22:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:22:38 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')]} 15:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:38 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')]} 15:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:38 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 0x10247db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102480768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b85ce3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10225bd30>, '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 0x102182db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102250be8>, '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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:22:38 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 15:22:38 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 15:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DvWSj6k5OB/Render', 15:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:38 INFO - 'HOME': '/Users/cltbld', 15:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:38 INFO - 'LOGNAME': 'cltbld', 15:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:38 INFO - 'MOZ_NO_REMOTE': '1', 15:22:38 INFO - 'NO_EM_RESTART': '1', 15:22:38 INFO - 'PAGER': '/bin/cat', 15:22:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:38 INFO - 'PWD': '/builds/slave/test', 15:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:38 INFO - 'SHELL': '/bin/bash', 15:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pjo8BEqDZh/Listeners', 15:22:38 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:38 INFO - 'USER': 'cltbld', 15:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:38 INFO - 'XPC_FLAGS': '0x0', 15:22:38 INFO - 'XPC_SERVICE_NAME': '0', 15:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:38 INFO - Downloading/unpacking psutil>=3.1.1 15:22:38 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 15:22:38 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 15:22:38 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 15:22:38 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 15:22:38 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 15:22:38 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 15:22:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:22:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:22:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:22:41 INFO - Installing collected packages: psutil 15:22:41 INFO - Running setup.py install for psutil 15:22:42 INFO - building 'psutil._psutil_osx' extension 15:22:42 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 15:22:42 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 15:22:42 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 15:22:42 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 15:22:42 INFO - building 'psutil._psutil_posix' extension 15:22:42 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 15:22:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:22:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:22:42 INFO - ^ 15:22:43 INFO - 1 warning generated. 15:22:43 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 15:22:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:22:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:22:43 INFO - Successfully installed psutil 15:22:43 INFO - Cleaning up... 15:22:43 INFO - Return code: 0 15:22:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:22:43 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')]} 15:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:43 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')]} 15:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:43 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 0x10247db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102480768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b85ce3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10225bd30>, '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 0x102182db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102250be8>, '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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:22:43 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 15:22:43 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 15:22:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DvWSj6k5OB/Render', 15:22:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:43 INFO - 'HOME': '/Users/cltbld', 15:22:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:43 INFO - 'LOGNAME': 'cltbld', 15:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:43 INFO - 'MOZ_NO_REMOTE': '1', 15:22:43 INFO - 'NO_EM_RESTART': '1', 15:22:43 INFO - 'PAGER': '/bin/cat', 15:22:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:43 INFO - 'PWD': '/builds/slave/test', 15:22:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:43 INFO - 'SHELL': '/bin/bash', 15:22:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pjo8BEqDZh/Listeners', 15:22:43 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:22:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:43 INFO - 'USER': 'cltbld', 15:22:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:43 INFO - 'XPC_FLAGS': '0x0', 15:22:43 INFO - 'XPC_SERVICE_NAME': '0', 15:22:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:22:43 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 15:22:43 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 15:22:43 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 15:22:43 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 15:22:43 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 15:22:43 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 15:22:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:22:46 INFO - Installing collected packages: mozsystemmonitor 15:22:46 INFO - Running setup.py install for mozsystemmonitor 15:22:46 INFO - Successfully installed mozsystemmonitor 15:22:46 INFO - Cleaning up... 15:22:46 INFO - Return code: 0 15:22:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22: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')]} 15:22:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:47 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 0x10247db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102480768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b85ce3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10225bd30>, '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 0x102182db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102250be8>, '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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:22:47 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 15:22:47 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 15:22:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DvWSj6k5OB/Render', 15:22:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:47 INFO - 'HOME': '/Users/cltbld', 15:22:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:47 INFO - 'LOGNAME': 'cltbld', 15:22:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:47 INFO - 'MOZ_NO_REMOTE': '1', 15:22:47 INFO - 'NO_EM_RESTART': '1', 15:22:47 INFO - 'PAGER': '/bin/cat', 15:22:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:47 INFO - 'PWD': '/builds/slave/test', 15:22:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:47 INFO - 'SHELL': '/bin/bash', 15:22:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pjo8BEqDZh/Listeners', 15:22:47 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:22:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:47 INFO - 'USER': 'cltbld', 15:22:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:47 INFO - 'XPC_FLAGS': '0x0', 15:22:47 INFO - 'XPC_SERVICE_NAME': '0', 15:22:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:47 INFO - Downloading/unpacking jsonschema==2.5.1 15:22:47 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 15:22:47 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 15:22:47 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 15:22:47 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 15:22:47 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 15:22:47 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 15:22:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:22:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:22:50 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 15:22:50 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 15:22:50 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 15:22:50 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 15:22:50 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 15:22:50 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 15:22:51 INFO - Downloading functools32-3.2.3-2.tar.gz 15:22:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:22:51 INFO - warning: no files found matching '*.txt' 15:22:51 INFO - no previously-included directories found matching 'build' 15:22:51 INFO - no previously-included directories found matching 'dist' 15:22:51 INFO - no previously-included directories found matching '.git*' 15:22:51 INFO - Installing collected packages: jsonschema, functools32 15:22:51 INFO - Running setup.py install for functools32 15:22:51 INFO - warning: no files found matching '*.txt' 15:22:51 INFO - no previously-included directories found matching 'build' 15:22:51 INFO - no previously-included directories found matching 'dist' 15:22:51 INFO - no previously-included directories found matching '.git*' 15:22:51 INFO - Successfully installed jsonschema functools32 15:22:51 INFO - Cleaning up... 15:22:51 INFO - Return code: 0 15:22:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:22: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')]} 15:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22: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')]} 15:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22: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', '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 0x10247db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102480768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b85ce3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10225bd30>, '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 0x102182db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102250be8>, '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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:22: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:22: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 functools32==3.2.3-2 15:22:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DvWSj6k5OB/Render', 15:22:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:51 INFO - 'HOME': '/Users/cltbld', 15:22:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:51 INFO - 'LOGNAME': 'cltbld', 15:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:51 INFO - 'MOZ_NO_REMOTE': '1', 15:22:51 INFO - 'NO_EM_RESTART': '1', 15:22:51 INFO - 'PAGER': '/bin/cat', 15:22:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:51 INFO - 'PWD': '/builds/slave/test', 15:22:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:51 INFO - 'SHELL': '/bin/bash', 15:22:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pjo8BEqDZh/Listeners', 15:22:51 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:22:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:51 INFO - 'USER': 'cltbld', 15:22:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:51 INFO - 'XPC_FLAGS': '0x0', 15:22:51 INFO - 'XPC_SERVICE_NAME': '0', 15:22:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:22:51 INFO - Cleaning up... 15:22:51 INFO - Return code: 0 15:22:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:22: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')]} 15:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22: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')]} 15:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22: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', '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 0x10247db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102480768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b85ce3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10225bd30>, '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 0x102182db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102250be8>, '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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:22: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:22: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 blobuploader==1.2.4 15:22:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DvWSj6k5OB/Render', 15:22:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:51 INFO - 'HOME': '/Users/cltbld', 15:22:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:51 INFO - 'LOGNAME': 'cltbld', 15:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:51 INFO - 'MOZ_NO_REMOTE': '1', 15:22:51 INFO - 'NO_EM_RESTART': '1', 15:22:51 INFO - 'PAGER': '/bin/cat', 15:22:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:51 INFO - 'PWD': '/builds/slave/test', 15:22:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:51 INFO - 'SHELL': '/bin/bash', 15:22:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pjo8BEqDZh/Listeners', 15:22:51 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:22:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:51 INFO - 'USER': 'cltbld', 15:22:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:51 INFO - 'XPC_FLAGS': '0x0', 15:22:51 INFO - 'XPC_SERVICE_NAME': '0', 15:22:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:52 INFO - Downloading/unpacking blobuploader==1.2.4 15:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:54 INFO - Downloading blobuploader-1.2.4.tar.gz 15:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:22:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:22:54 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 15:22:54 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 15:22:54 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 15:22:54 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 15:22:54 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 15:22:54 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 15:22:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:22:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:22: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 15:22: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 15:22: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 15:22: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 15:22: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 15:22: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 15:22:56 INFO - Downloading docopt-0.6.1.tar.gz 15:22:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:22:56 INFO - Installing collected packages: blobuploader, requests, docopt 15:22:56 INFO - Running setup.py install for blobuploader 15:22:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:22:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:22:56 INFO - Running setup.py install for requests 15:22:57 INFO - Running setup.py install for docopt 15:22:57 INFO - Successfully installed blobuploader requests docopt 15:22:57 INFO - Cleaning up... 15:22:57 INFO - Return code: 0 15:22:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:22: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')]} 15:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22: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')]} 15:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:57 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 0x10247db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102480768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b85ce3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10225bd30>, '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 0x102182db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102250be8>, '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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:22:57 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 15:22:57 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 15:22:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DvWSj6k5OB/Render', 15:22:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:22:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:22:57 INFO - 'HOME': '/Users/cltbld', 15:22:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:22:57 INFO - 'LOGNAME': 'cltbld', 15:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:57 INFO - 'MOZ_NO_REMOTE': '1', 15:22:57 INFO - 'NO_EM_RESTART': '1', 15:22:57 INFO - 'PAGER': '/bin/cat', 15:22:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:57 INFO - 'PWD': '/builds/slave/test', 15:22:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:22:57 INFO - 'SHELL': '/bin/bash', 15:22:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pjo8BEqDZh/Listeners', 15:22:57 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:22:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:22:57 INFO - 'USER': 'cltbld', 15:22:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:22:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:57 INFO - 'XPC_FLAGS': '0x0', 15:22:57 INFO - 'XPC_SERVICE_NAME': '0', 15:22:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:22:57 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Wymw5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:22:57 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-7E5g2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:22:57 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-E9lQqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:22:57 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-6CToZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:22:57 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-vFl2z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:22:58 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-pEph5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:22:58 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-U_bVg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:22:58 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-MmhaoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:22:58 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Nnb_om-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:22:58 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-6eYZzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:22:58 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ogT1mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:22:58 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-cm6nJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:22:58 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-bf9HBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:22:58 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-AgfdKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:59 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-9K34pJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:22:59 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-8h4Bpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:22:59 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-dbDDgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:22:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:22:59 INFO - Running setup.py install for manifestparser 15:22:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:22:59 INFO - Running setup.py install for mozcrash 15:22:59 INFO - Running setup.py install for mozdebug 15:22:59 INFO - Running setup.py install for mozdevice 15:22:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:22:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:22:59 INFO - Running setup.py install for mozfile 15:23:00 INFO - Running setup.py install for mozhttpd 15:23:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:23:00 INFO - Running setup.py install for mozinfo 15:23:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:23:00 INFO - Running setup.py install for mozInstall 15:23:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:23:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:23:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:23:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:23:00 INFO - Running setup.py install for mozleak 15:23:00 INFO - Running setup.py install for mozlog 15:23:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:23:00 INFO - Running setup.py install for moznetwork 15:23:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:23:00 INFO - Running setup.py install for mozprocess 15:23:01 INFO - Running setup.py install for mozprofile 15:23:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:23:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:23:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:23:01 INFO - Running setup.py install for mozrunner 15:23:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:23:01 INFO - Running setup.py install for mozscreenshot 15:23:01 INFO - Running setup.py install for moztest 15:23:01 INFO - Running setup.py install for mozversion 15:23:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:23:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:23:01 INFO - Cleaning up... 15:23:01 INFO - Return code: 0 15:23:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:23: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')]} 15:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23: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')]} 15:23:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:01 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 0x10247db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102480768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b85ce3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10225bd30>, '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 0x102182db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102250be8>, '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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:23:01 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 15:23:01 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 15:23:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DvWSj6k5OB/Render', 15:23:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:01 INFO - 'HOME': '/Users/cltbld', 15:23:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:01 INFO - 'LOGNAME': 'cltbld', 15:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:01 INFO - 'MOZ_NO_REMOTE': '1', 15:23:01 INFO - 'NO_EM_RESTART': '1', 15:23:01 INFO - 'PAGER': '/bin/cat', 15:23:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:01 INFO - 'PWD': '/builds/slave/test', 15:23:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:01 INFO - 'SHELL': '/bin/bash', 15:23:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pjo8BEqDZh/Listeners', 15:23:01 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:23:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:01 INFO - 'USER': 'cltbld', 15:23:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:01 INFO - 'XPC_FLAGS': '0x0', 15:23:01 INFO - 'XPC_SERVICE_NAME': '0', 15:23:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:23:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-_MEZWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:23:02 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)) 15:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:23:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-JVbjXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:23:02 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)) 15:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:23:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-8O9s8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:23:02 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)) 15:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:23:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-M5UWNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:23:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:23:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-72iGxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:23:02 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)) 15:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:23:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ZO9R9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:23:02 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)) 15:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:23:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-1_UJY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:23:02 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)) 15:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:23:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Xcpjo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:23:03 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)) 15:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:23:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-NOOC_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:23:03 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)) 15:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:23:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-7ta_xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:23:03 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)) 15:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:23:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-4pF1JT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:23:03 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)) 15:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:23:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-QeJFfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:23:03 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)) 15:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:23:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-oLBfOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:23:03 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)) 15:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:23:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-CTeHNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:23:03 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)) 15:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:23:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-l9gyTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:23:03 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)) 15:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:23:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-_sp2VN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:23:03 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)) 15:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:23:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-PdnSKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:23:03 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)) 15:23:03 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)) 15:23:03 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)) 15:23:03 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)) 15:23:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:23:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:23:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:23:06 INFO - Downloading blessings-1.6.tar.gz 15:23:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:23:06 INFO - Installing collected packages: blessings 15:23:06 INFO - Running setup.py install for blessings 15:23:06 INFO - Successfully installed blessings 15:23:06 INFO - Cleaning up... 15:23:06 INFO - Return code: 0 15:23:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:23:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:23:07 INFO - Reading from file tmpfile_stdout 15:23:07 INFO - Current package versions: 15:23:07 INFO - blessings == 1.6 15:23:07 INFO - blobuploader == 1.2.4 15:23:07 INFO - docopt == 0.6.1 15:23:07 INFO - functools32 == 3.2.3-2 15:23:07 INFO - jsonschema == 2.5.1 15:23:07 INFO - manifestparser == 1.1 15:23:07 INFO - mozInstall == 1.12 15:23:07 INFO - mozcrash == 1.0 15:23:07 INFO - mozdebug == 0.1 15:23:07 INFO - mozdevice == 0.49 15:23:07 INFO - mozfile == 1.2 15:23:07 INFO - mozhttpd == 0.7 15:23:07 INFO - mozinfo == 0.9 15:23:07 INFO - mozleak == 0.1 15:23:07 INFO - mozlog == 3.4 15:23:07 INFO - moznetwork == 0.27 15:23:07 INFO - mozprocess == 0.25 15:23:07 INFO - mozprofile == 0.28 15:23:07 INFO - mozrunner == 6.13 15:23:07 INFO - mozscreenshot == 0.1 15:23:07 INFO - mozsystemmonitor == 0.3 15:23:07 INFO - moztest == 0.8 15:23:07 INFO - mozversion == 1.4 15:23:07 INFO - psutil == 3.1.1 15:23:07 INFO - requests == 1.2.3 15:23:07 INFO - wsgiref == 0.1.2 15:23:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:07 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 0x10247db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102480768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b85ce3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10225bd30>, '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 0x102182db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102250be8>, '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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:23:07 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 15:23:07 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 15:23:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DvWSj6k5OB/Render', 15:23:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:07 INFO - 'HOME': '/Users/cltbld', 15:23:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:07 INFO - 'LOGNAME': 'cltbld', 15:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:07 INFO - 'MOZ_NO_REMOTE': '1', 15:23:07 INFO - 'NO_EM_RESTART': '1', 15:23:07 INFO - 'PAGER': '/bin/cat', 15:23:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:07 INFO - 'PWD': '/builds/slave/test', 15:23:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:07 INFO - 'SHELL': '/bin/bash', 15:23:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pjo8BEqDZh/Listeners', 15:23:07 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:23:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:07 INFO - 'USER': 'cltbld', 15:23:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:07 INFO - 'XPC_FLAGS': '0x0', 15:23:07 INFO - 'XPC_SERVICE_NAME': '0', 15:23:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:07 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)) 15:23:07 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)) 15:23:07 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)) 15:23:07 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)) 15:23:07 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)) 15:23:07 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)) 15:23:07 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)) 15:23:07 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)) 15:23:07 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)) 15:23:07 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)) 15:23:07 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)) 15:23:07 INFO - Cleaning up... 15:23:07 INFO - Return code: 0 15:23:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10247db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102480768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b85ce3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10225bd30>, '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 0x102182db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102250be8>, '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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:23:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:23:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DvWSj6k5OB/Render', 15:23:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:07 INFO - 'HOME': '/Users/cltbld', 15:23:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:07 INFO - 'LOGNAME': 'cltbld', 15:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:07 INFO - 'MOZ_NO_REMOTE': '1', 15:23:07 INFO - 'NO_EM_RESTART': '1', 15:23:07 INFO - 'PAGER': '/bin/cat', 15:23:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:07 INFO - 'PWD': '/builds/slave/test', 15:23:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:07 INFO - 'SHELL': '/bin/bash', 15:23:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pjo8BEqDZh/Listeners', 15:23:07 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:23:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:07 INFO - 'USER': 'cltbld', 15:23:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:07 INFO - 'XPC_FLAGS': '0x0', 15:23:07 INFO - 'XPC_SERVICE_NAME': '0', 15:23:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:23:07 INFO - Cleaning up... 15:23:07 INFO - Return code: 0 15:23:07 INFO - Running post-action listener: _resource_record_post_action 15:23:07 INFO - Running post-action listener: _start_resource_monitoring 15:23:07 INFO - Starting resource monitoring. 15:23:07 INFO - [mozharness: 2017-04-07 22:23:07.838750Z] Finished create-virtualenv step (success) 15:23:07 INFO - [mozharness: 2017-04-07 22:23:07.839686Z] Running install step. 15:23:07 INFO - Running pre-action listener: _resource_record_pre_action 15:23:07 INFO - Running main action method: install 15:23:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:23:08 INFO - Reading from file tmpfile_stdout 15:23:08 INFO - Detecting whether we're running mozinstall >=1.0... 15:23:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:23:08 INFO - Reading from file tmpfile_stdout 15:23:08 INFO - Output received: 15:23:08 INFO - Usage: mozinstall [options] installer 15:23:08 INFO - Options: 15:23:08 INFO - -h, --help show this help message and exit 15:23:08 INFO - -d DEST, --destination=DEST 15:23:08 INFO - Directory to install application into. [default: 15:23:08 INFO - "/builds/slave/test"] 15:23:08 INFO - --app=APP Application being installed. [default: firefox] 15:23:08 INFO - mkdir: /builds/slave/test/build/application 15:23:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:23:28 INFO - Reading from file tmpfile_stdout 15:23:28 INFO - Output received: 15:23:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:23:28 INFO - Running post-action listener: _resource_record_post_action 15:23:28 INFO - [mozharness: 2017-04-07 22:23:28.110308Z] Finished install step (success) 15:23:28 INFO - [mozharness: 2017-04-07 22:23:28.110429Z] Running run-tests step. 15:23:28 INFO - Running pre-action listener: _resource_record_pre_action 15:23:28 INFO - Running main action method: run_tests 15:23:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:23:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:23:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:23:28 INFO - 2017-04-07 15:23:28.123 screenresolution[1629:7317] starting screenresolution argv=screenresolution get 15:23:28 INFO - 2017-04-07 15:23:28.125 screenresolution[1629:7317] Display 0: 1600x1200x32@60 15:23:28 INFO - 2017-04-07 15:23:28.130 screenresolution[1630:7319] starting screenresolution argv=screenresolution list 15:23:28 INFO - Available Modes on Display 0 15:23:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:23:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:23:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:23:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:23:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:23:28 INFO - Intel Iris: 15:23:28 INFO - Chipset Model: Intel Iris 15:23:28 INFO - Type: GPU 15:23:28 INFO - Bus: Built-In 15:23:28 INFO - VRAM (Dynamic, Max): 1536 MB 15:23:28 INFO - Vendor: Intel (0x8086) 15:23:28 INFO - Device ID: 0x0a2e 15:23:28 INFO - Revision ID: 0x0009 15:23:28 INFO - Displays: 15:23:28 INFO - Display: 15:23:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:23:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:23:28 INFO - Display Serial Number: 1600x1200 60 15:23:28 INFO - Main Display: Yes 15:23:28 INFO - Mirror: Off 15:23:28 INFO - Online: Yes 15:23:28 INFO - Rotation: Supported 15:23:28 INFO - Return code: 0 15:23:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:23:28 INFO - Python 2.7.3 15:23:28 INFO - Return code: 0 15:23:28 INFO - grabbing minidump binary from tooltool 15:23:28 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')]} 15:23:28 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 0x10225bd30>, '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 0x102182db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102250be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:23:28 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 15:23:28 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 15:23:28 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 15:23:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:23:28 INFO - Return code: 0 15:23:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:23:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:23:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:23:28 INFO - ENV: RUST_BACKTRACE is now 1 15:23:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:23:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:23:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--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 15:23:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0198 --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 15:23:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DvWSj6k5OB/Render', 15:23:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:28 INFO - 'HOME': '/Users/cltbld', 15:23:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:28 INFO - 'LOGNAME': 'cltbld', 15:23:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:23:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:28 INFO - 'MOZ_NO_REMOTE': '1', 15:23:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:23:28 INFO - 'NO_EM_RESTART': '1', 15:23:28 INFO - 'PAGER': '/bin/cat', 15:23:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:28 INFO - 'PWD': '/builds/slave/test', 15:23:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:23:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:28 INFO - 'RUST_BACKTRACE': '1', 15:23:28 INFO - 'SHELL': '/bin/bash', 15:23:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pjo8BEqDZh/Listeners', 15:23:28 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:23:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:28 INFO - 'USER': 'cltbld', 15:23:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:28 INFO - 'XPC_FLAGS': '0x0', 15:23:28 INFO - 'XPC_SERVICE_NAME': '0', 15:23:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--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 15:23:28 INFO - mozversion application_buildid: 20170407134821 15:23:28 INFO - mozversion application_changeset: f6712e7858f391923006b174d27cb919c454a9ce 15:23:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:23:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:23:28 INFO - mozversion application_name: Firefox 15:23:28 INFO - mozversion application_remotingname: firefox-dev 15:23:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:23:28 INFO - mozversion application_vendor: Mozilla 15:23:28 INFO - mozversion application_version: 54.0a2 15:23:28 INFO - mozversion platform_buildid: 20170407134821 15:23:28 INFO - mozversion platform_changeset: f6712e7858f391923006b174d27cb919c454a9ce 15:23:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:23:28 INFO - mozversion platform_version: 54.0a2 15:23:28 INFO - using testdate: 1491603808 15:23:28 INFO - actual date: 1491603808 15:23:28 INFO - starting webserver on 'localhost:49243' 15:23:28 INFO - SUITE-START | Running 2 tests 15:23:28 INFO - TEST-START | dromaeo_css 15:23:28 INFO - operating with platform_type : mac_ 15:23:28 INFO - Initialising browser for dromaeo_css test... 15:23:28 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmphAWWk0/profile http://localhost:49243/getInfo.html) 15:23:31 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 15:23:31 INFO - PROCESS | 1640 | colorDepth:24 15:23:31 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 15:23:31 INFO - PROCESS | 1640 | __metrics 15:23:31 INFO - PROCESS | 1640 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:23:31 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:23:32 INFO - TEST-INFO | 1640: exit 0 15:23:32 INFO - Browser initialized. 15:23:32 INFO - Running cycle 1/1 for dromaeo_css test... 15:23:32 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmphAWWk0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:23:44 INFO - PROCESS | 1642 | RSS: Main: 214896640 15:23:44 INFO - PROCESS | 1642 | 15:26:48 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 15:26:50 INFO - PROCESS | 1642 | RSS: Main: 440262656 15:26:50 INFO - PROCESS | 1642 | 15:28:34 INFO - PROCESS | 1642 | ************************* 15:28:34 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 15:28:34 INFO - PROCESS | 1642 | 15:28:34 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:28:34 INFO - PROCESS | 1642 | Full stack: 15:28:34 INFO - PROCESS | 1642 | ************************* 15:28:34 INFO - PROCESS | 1642 | ************************* 15:28:34 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 15:28:34 INFO - PROCESS | 1642 | 15:28:34 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:28:34 INFO - PROCESS | 1642 | Full stack: 15:28:34 INFO - PROCESS | 1642 | ************************* 15:28:34 INFO - PROCESS | 1642 | ************************* 15:28:34 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 15:28:34 INFO - PROCESS | 1642 | 15:28:34 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:28:34 INFO - PROCESS | 1642 | Full stack: 15:28:34 INFO - PROCESS | 1642 | ************************* 15:28:34 INFO - PROCESS | 1642 | ************************* 15:28:34 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 15:28:34 INFO - PROCESS | 1642 | 15:28:34 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:28:34 INFO - PROCESS | 1642 | Full stack: 15:28:34 INFO - PROCESS | 1642 | ************************* 15:29:54 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 15:29:54 INFO - PROCESS | 1642 | RSS: Main: 350695424 15:29:54 INFO - PROCESS | 1642 | 15:32:57 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 15:32:57 INFO - PROCESS | 1642 | RSS: Main: 338886656 15:32:57 INFO - PROCESS | 1642 | 15:36:00 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 15:36:01 INFO - PROCESS | 1642 | RSS: Main: 329764864 15:36:01 INFO - PROCESS | 1642 | 15:39:04 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 15:39:05 INFO - PROCESS | 1642 | RSS: Main: 330702848 15:39:05 INFO - PROCESS | 1642 | 15:42:08 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 15:42:08 INFO - PROCESS | 1642 | RSS: Main: 519372800 15:42:08 INFO - PROCESS | 1642 | 15:42:08 INFO - PROCESS | 1642 | __start_tp_report 15:42:08 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 15:42:08 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 15:42:08 INFO - PROCESS | 1642 | |i|pagename|runs| 15:42:08 INFO - PROCESS | 1642 | |0;dojo.html;2850.2731803390134;2940.985360050783;3435.089701229175;3440.879569215078;3454.2918701666163;5963.522918166536;5981.820545383634;6014;6035.818925432233;6055.333913269536;5989.9101513477335;6045.576809623317;6051.606645568035;6058;6064.605800622957;5384.515393614564;5403.189521571757;5446.128619420893;5471.699056551888;5506.119020956648;5300.178472336794;5313.73431328434;5525.972370138144;5527.060399732055;5551.9722401387935;5354.571634269249;5384.273123128386;5396.055690254209;5422.783088676449;5427.674339539641;5382.219578161144;5406.162044883049;5406.756695948672;5419.376771671229;5425.077736784737;5255.973720131413;5345.786168553253;5346.91979620306;5362.892742145174;5368.543673787733;5931.822045338647;5944.583879128462;5945.970270148665;5947.6431414115295;5950.9107363389585;6336.46140078094;6345;6353.5870168438905;6369.617822930639;6381;6475.028745688137;6479.8056058318325;6483.9027414588345;6495.253045899734;6505.772297969548;5028.723420211887;6096.878062438771;6120.357362476965;6128.325884152784;6158.1686472326555;6324.810255692337;6356.586821856565;6363.490920726331;6545.14913061299;6661.233958094785;4888.462269150391;6354.968225158845;6359;6372.7132279047555;6376.011718183689;6463.935360646427;6472.708728107246;6473.643949582724;6475.870482590321;6486.091947127406;6369.904451433232;6405.423511883952;6431.5819471734185;6433.5496515243485;6435.549511534241;6261.060840873905;6328.905066424008;6332.968335158294;6333.36666333363;6380.393862583047;5064.645474816801;5192.065428222956;5193.480651934777;5198.9740051299505;5217.608679349064;4497.865064048084;4547.15877562652;4563.133004729091;4573.839915602938;4589.541045895384;5820.534357251409;5858.824235272863;5980.401959804072;6055.091736239511;6139.293981192131;6482.67586620667;6485.059666348353;6489.513286503531;6489.740410383626;6512.902306465407;6113.419225173601;6113.755449781959;6115.541334399938;6116.174316467309;6123.020316749299;5500.064988951888;5525.696086715189;5542.251796007568;5670.829875103754;5677.602567820296;6566.343365663491;6583.835404114935;6611.768588099393;6652.168478940132;6668.299828517936;6444.45222156122;6460.547761656639;6468.126802881739;6469.902951455826;6470.6441145737435;6580.1116849226655;6658.700358483783;6659.4006539411685;6676.298988606321;6682.398584127257;6215.160953271251;6282.905756413566;6302.936970630235;6314.747410103544;6321.336259692666;6354.3963323484195;6374.298827129105;6378.2346118466085;6405.775797847053;6452.645104519296;6296.905546416716;6308.842278943063;6315.5894866833505;6361.522885783492;6375.362463753588;6359.268684101387;6359.745610175726;6361;6368.4586810121655;6380.872382552416;5907.3797251288;5976.103584462366;6129.358790882597;6297.905531417124;6299.590526615755;6136.243373036893;6174.660393678211;6224.782132625336;6277.591956522811;6318;6131.682521312785;6140.565599444434;6208.782692605737;6215.160953271431;6235.563510554399 15:42:08 INFO - PROCESS | 1642 | |1;ext.html;767.9040119984999;772.1352085664057;808.0586117173493;878.4158534574508;887.3344991256557;38455.615443845556;39232.01919952003;39323.41014884772;39438.80280598593;39462.8026859866;40490.39264411029;40549.986250343754;40655.39016914749;40663.186736265256;40694;7202.963985180067;9017.278617710585;9169.495677737721;9181.770455738626;9187.724368268962;7251.818704532376;10817.783644327108;11015.669529914116;11180.944095279512;11189.44052797361;11040.834387484194;11047.668569942914;11060.668179954615;11074;11108.277961932448;11015.339079655207;11020.283681560713;11033.889661103367;11047.226694131414;11054.336739795557;7106.751263705745;7277;10619.20355973305;10810.891891081068;10859.18556108295;38915.416268755995;38931;39027;39058.21883562341;39149.60850391488;3822.3310920589065;3827.6555110039953;3852.081655380874;3866.072142685763;3900.278448487011;27195.86402067997;27230;27592.862035689894;28780.568291475643;28807;3178.1577881861326;3196.6164060312913;3200;3272.2637406583567;3284.2446237365302;300521;351150;356818;357027;359403;41594;42467.57532424698;44528.55471445244;51855;51985.48014519807;42506.57493425088;53143;55778;55813.72093139508;55870.720646396505;14295.92852035754;14357.425702971974;14373.06575072617;14375.496857609933;14383.496577619733;12526.81209781854;12565.685857853445;12570.560030398892;12571.811422828665;12612.81080783789;11873.703157420961;11920.821187682192;11930.88069119315;11943.581974630846;11978.281303121841;5639.210510528529;5655.151727240865;5656.575756818255;5661.754414028907;5664.54683625309;1126.9943650281696;1147.9770404591861;1151.476078384333;1160.2052593972987;1180.7447774750185;26127.346816329362;26455;26657.600135997975;26771.46457070848;26787.598186027226;1175.9299037875492;1178.4873579992732;1226.7055906582357;1242.9067819913362;1287.2533930320392;13562.93218533901;13799.89600831931;13887.861121388858;13912.234827084405;13929.58211253664;434399.82800085796;434502;435046;435200;453310;55290.72354638201;55385;55395.723021384634;55482.89034219293;55521.88956220934;1340.8468716903456;1360.5578187089236;1477.697072100217;1478.3273610507194;1479.3786609624199;25093.498130037664;25886.611700824127;25923.481530369663;25947;26059.609105863805;44883.55116448794;44926.55073449355;44979.32531011972;45032.324515132954;45033.32450013187;408307;408333;409240;409256;409570;28318.734501240262;28896.98860539871;30021.099367019026;30195.849020754755;33319;36219.09452263609;36819;37280;37490.62509374871;37589.872303830925;25771.484570308865;26409;26594.468110637292;26710.332241694112;26830.463390731686;26800.32999175036;26927.730722692522;27112.593311100743;27734.722652773213;27949;30530.542041869834;31192.688073118978;31344.52983205208;31465.37069258648;31494.37011259716;24420;25372.619410708485;27694.169174924784;27895.721042790123;28003.719962800926;21272.574548509252;22416;22493.21273755476;22493.66259506076;22534.985925633715 15:42:08 INFO - PROCESS | 1642 | |2;jquery.html;385.09118480386303;406.6990427083958;414.2646801926578;424.74727537115416;453.71189294797813;13840;20865.897779288014;21878.671819922674;22024.339269821892;22186.667199991974;21272.787272127272;22032.669509957323;22158.335249942487;22366.66450003251;22799.658005129895;5311.760970756325;6433.260175079868;6590.769323073693;6626.536142470029;7384.261573842599;7490.325870671666;7595.658195381194;7600.6959721611065;7602.809929751746;7610.010698609173;7320.194778574352;7454.142773581027;7475.551466911976;7488.962555187217;7554.660040298199;6717.832054198635;7229.060222171084;7288.052553168107;7483.476156669035;7531.9246807532045;6630.403263706289;7245.818854528627;7294.562326260441;7300.525465844703;7315.451341149382;25384;25604.35989100269;25659.486810263876;26021.479570408675;26144.084957026604;2483.1060818105466;2661.2814540074073;2880.0783749200264;2923.0353983185496;2925.444165608528;15746.370145194178;16067.678646427006;16123.274452649659;16292.674146517003;16749.66500669992;2021.8989050547561;2062.422521693928;2098.213170061227;2107.557412943284;2122.1935664447565;159893.40106598902;160860;165074;168023;168319.3168068316;14553.492659813819;19827.20691172337;19833.900830495757;19855.205791768458;20017.996400719916;23095.88452057729;28765.712342876723;29874.954376596714;29972;30012.699873001427;8803.031666516677;8972.955135224282;9875.851862222073;10178.796424071477;10427.113695335982;8448.197421244971;8500;8635.870461943075;8710.520921349387;8733.91266087331;9218.492982886006;9332.95333523328;9338.299627527962;9406.482643454676;9410.38832475887;3296.297036430104;3404.7276217903004;3633.091727068233;3932.528096628423;3932.6853851691794;876.6274333408276;882.9690960816469;887.2281115429618;919.2599957034652;921.0421161991587;20498.897505512676;21608.135674573154;21630.56738865214;21705;21705.17832681741;912.1653686876434;923.3075193604797;928.9410072517336;941.3880977364768;944.0653752784693;10712.303700259457;11115.722106947229;11184.44077796107;11255.831162532568;11393.373364465035;700755;718694;723827;733800;740533;151137.73293400608;152847;155449;158135;161556;1093.4095588382343;1142.1719253541116;1168.5617893290016;1171.3733152763232;1173.143605168249;18371.816281837546;18830.52923676919;19023.71464428007;19044.619107617495;19071.52321191926;57548.561285968186;58136.127958079815;58939.41060589511;59068;59130.11304830517;79302.60348698219;79395;81879.590602049;82459;82790.17209828066;10954.028274910592;11068.225224234226;11293.491792869176;11364.090872729996;11454.713632159262;12164.330961797186;12188.329641869783;12409.379531023593;12505.124641275024;12608.306543140216;9878.950605246928;10033.648822291185;10158;10196.133328667147;10243.795124097625;8596.398252122292;9898.950505247427;9912.553935073083;10376.740581485523;10381.688549343531;10696.786064278827;10852;10858.511366988347;10911.890881091087;10942.45287735626;11126.666200014013;11247.881544911352;11403.885961140615;11472.082233421474;11568.710782230512;9122.224610908148;10530.736731581772;10690.518926648165;10908.454577271263;10918.72703182395 15:42:08 INFO - PROCESS | 1642 | |3;mootools.html;1514.3261248744313;1543.9614009649758;1563.7419825728753;1573.8662213711834;1589.1736297125494;10508.422036787973;11066.778664426707;11091.778164436706;11104.722381940455;11116.833247501283;10617;11045.33727976322;11165.72085697858;11190.608328708497;11203.943980280088;2402.8197885158597;2433.294344640061;2436.3421876093535;2437.609982402816;2445.3764290105996;2323.0939933425893;2390.521895620874;2395.197608801054;2408.542376948374;2423.8909249083745;2337.91817286395;2400.195934361982;2407.7953373963232;2419.5402873454077;2422.103821586016;2315;2381.642753586967;2388.856668599872;2389.5340408620327;2402.039184326266;2363.409147713072;2384.403899025244;2393.0786647140803;2396.9640455393182;2398.2445529658103;10867.456627168689;11109;11120.332780033143;11125.165612579089;11126.888731112665;5658.632188907708;5818.4763371296785;5825.708714564297;5827.679477628729;5832.066869300891;3036.75705943524;3433.8798142064907;3581.5164952731307;3596.9677132420097;3660.084978755311;1156.554726430322;1174.4597485156837;1179.7168679516853;1207.468713765949;1224.2470880408573;13600.659983500393;13970.51103211393;14216.573502794932;15361.46234881785;15606.765898511532;4512.616427603625;4659.58063774262;4673.7429441381055;4680.648951328664;4696;11675.532978680927;11688.70778230551;11695.239809412362;11706.88293117058;11761.411929403495;4799.808007679654;4976.2038073908725;5446.319210098738;5638.520725738358;5660.8867822643315;5117.078925793318;5226.869328266824;5270.1040823060175;5294.708791016531;5306.469353064663;3781.6029316921877;3801.2967600993907;3902.687784977195;3905.7851818149707;3906.7655940643654;1000.3247807729754;1001.2990906365428;1017.5624481473037;1021.2902033087082;1022.7340891368298;795.1611050341829;801.6032064128294;808.5552945879742;822.3051521464353;831.5509624802539;6442.645654489048;6511.088447617338;6705.899411508832;6733.494987875929;6758.256591774901;998.8501724741346;1029.9443070852287;1034.818906691326;1035.4615599888016;1035.53400969564;4206.915861682749;4232.534421213664;4247.362895565628;4367.235733746582;4370.300751879684;13356.866431335562;13882.652933676738;13885.791713124312;13900;13921.721565568834;12625;12993;13654.317284135754;13791.862081379259;13825.51610693621;10016.298859079794;10086.344387614781;10263.794724105475;10296.227782916161;10303.278770486144;5638.830835074954;6739.292374300824;6807.115075040213;6853.897191542031;6936.653167341714;12022.278663279882;12410.627681169579;12410.689732756753;12416.192947458387;12441.688957776129;10057.446840423847;12425.31660758667;12470.688232794253;12491.875081249435;12613.369331533204;9249.213816825646;9265.72202833916;9355.158035776813;9447.149756521945;9468.857967130361;10093.596256149965;10121.69634910954;10308.536115874953;10316.896831031594;10348.482575871327;8579.742607721779;8761.518116503654;8777.385583009129;8796.428232164888;8809.735707928772;8566.528840913561;8614.65541378338;8666.263367613572;8668;8671.393002489765;7800.6099695016155;7900.2889739923685;7906.051273847175;7913.643886025257;7921.841563168819;5775.595708300379;5811.157382179645;5860.970695146668;6006.489448396936;6037.45662890342;5671.120976248688;5740.368559458373;5814.30228372598;5983.982722937024;5995.940040599538 15:42:08 INFO - PROCESS | 1642 | |4;prototype.html;380.9961900380996;384.0590553144796;389.7349802134551;391.2488022995848;396.81349765610173;4602.585767280944;4619.838305659303;4665.393498845146;4934.481879402665;5044.344235249421;4421.2809122389735;4452.755098469583;4473.642108631309;4808.422989241287;4886.459041298523;3222.3716375306826;3504.649535046488;3884.475595794574;3908.736205268604;3932.58707835676;3508.350955073307;3638.6179451157645;3928.2143571285715;3969.6625786808154;3993.5407428145845;3364.7308215342837;3697.482352470656;3776.5090538229992;3804.858542437272;3965.266425711253;3262.5758651375168;3512.0693016350688;3532.1699400640828;3890.435886796399;3919.9216015679526;3137.074563003919;3289.901302960915;3429.53701250333;3450.3955660617935;3524.8766293179615;4072.9796351018354;4101.876943691694;4510.887227819298;4880.560749532559;4920.950790492085;3660.584014550384;3755.868544600926;3963.2271707017144;4402.204600078973;4599.022420359278;4607.285870690049;4658.254679251338;4733.313669517901;5193.5845132389695;5213.061648903196;3818.694504439638;3827.956774364387;3835.8082095895093;3847.5763967331986;3911.2959667259884;4208.117439553885;5130.51260130287;5526.447355264481;5561.360443549003;5563.552037103662;4265.87202383929;4428.800703968329;4615.730674064659;4692.741899195509;4822.855314340575;4893.877653058702;4894.388201474816;4917.434495033045;4956.677815941952;4973.477784832613;4430.629613156945;4954.578860796801;4979.676321039121;5026.974865125724;5110.192906284841;5033.833553896521;5258.763355649005;5340.839774806762;5366.248725178478;5476.863078422992;1529.1589625705817;1565.3190861975077;1644.7368421052813;1666.7332827051528;1693.992618746789;1175.629676651869;1215.750771091924;1358.932053397346;1359.9732202187483;1366.938487768053;4291.892702682396;4498.489350545556;4689.499360204702;4797.856064318006;4948.579370753454;5080.872978175576;5422.61723260566;5459.481349271892;5514.972425137848;5524.723763811793;4218.152746895833;4728.71627702339;4929.260610908321;4957.025784529325;4963.57513315811;2752.9862350688118;2772.5167035635836;2825.9152225433268;2849.7577705894814;2872.296287409598;5225;5636.887262254732;5680.943190568124;5780.855478613068;5839.992403911989;4759.906061060338;4894.828680996148;5375.81184658535;5393.9730301348245;5428.294321738149;4750.762461876961;5029.673071250357;5469.343678758495;5810.767569297265;5873.2658417697785;5226.865328826201;5272.288241087557;5429.782808687608;5453.618246722692;5598.5086636446395;4673.883152921204;4710.481846996896;4758.547937945889;4834.734089625104;5458.972705136449;4920.483349248278;5552.0567801269235;5634.478690753555;5681.857953551194;5695.288088988877;4652.231922006401;4701.670883038291;4748.738819364969;4794.568488836021;4799.640026997919;5144.948550514597;5250.317458730341;5538.867535998589;5657.066584013592;5668.404817494269;4455.220336441174;5151.871203219949;5160.045391602565;5284.418713941386;5351.3043304370185;4298.161858437575;4333.891652708708;5302.416734159162;5398.62209645321;5400.271912987963;4283.357496375568;4449.51055383914;5221.451747566451;5289.709066001406;5308.309919710413;4480.686351955332;4830.686005409637;4906.546726636681;4974.950250497449;5213.869653258699;4520.434945631796;4756.215224488059;5069.366329208849;5287.57699384056;5353.357597088374 15:42:08 INFO - PROCESS | 1642 | |5;yui.html;719.8056524738317;745.5973774161953;772.9227077292271;829.100426037749;838.5849004742652;14925.850741492583;15738.527844164664;15746.606334841636;15763.84236157638;15841.920790396061;12200.694982625417;14383.208923509203;14483.058601190914;14506.274686265697;14586.708265834677;919.4345477531303;929.6513807322237;932.4545141092435;939.4973689076346;959.0601210813408;836.6987884361625;857.5378978841061;919.9080091990814;923.0769230769231;923.9861402078974;963.1524258652377;963.9614415423374;964;966.1690945786618;966.2608104799799;960.2414092866627;960.8414611589079;961.0773657289011;961.9182369498601;962.3311798300219;720.7693538067821;721.4877437019722;721.761818599866;721.9205887352387;722.000029958506;15706;15957.44148954781;16017.679646407007;16281.755773663404;16502.33990640373;14146.009779315453;14214.360353784105;14257.500987465493;14280.92859535706;14388.424463021467;11379.829302560469;11661.358625275607;11797.528098876035;11850.762984740257;12362.567310144192;3650.2152037312107;3745.812709364548;3810.8856734298015;3836.3669994450884;4025.9597404025876;107668.92331076667;112694;113879.43060284728;114725.42637286843;118081;8899.510526921082;10173.94913025445;10663.253572249869;10935.945320273348;11112.55549778016;21261;21615.783842161378;22021.66967495489;22069.779302207095;22088.558228835333;6734.730610775514;7513.699452021858;7608.923910760932;7630.656620452093;7905.169957154416;4231.174920890397;4295.613394794484;4296.37702533131;4406.405135306757;4556;3441.174118211611;3509.052555809917;3537.3102245061964;3542.6103128655827;3549.432090865501;645.1838424393146;674.7098747538507;675.5676367125044;675.9628220447922;679.1714108787272;674.4402146218627;683.4156795939499;688.7980474072675;721.1418412089597;722.6928555364054;9744.707658770249;10406.375617462976;10445.321054131457;10483.633072842415;10709.357438553556;706.2478460439718;713.3116542536477;719.5610677486729;720.6792977125234;720.8089856188114;4476.6418686505;4778.042013476034;5008.924866127011;5014.799408023711;5015.871428928534;107082.92917070886;108432;110387;111435;113468;91385;91984;94075.11849763103;94235.52882235545;94929;48947.51052489572;84623.5768821152;87055.56472217725;87058;87318.56340718256;10595.576176952836;10964.28732132409;11063.336199828036;11141.442927853413;11309.71725706864;45220.54779452295;46481;46804;46870.76564617291;49511.752441237564;42529;68074.97887531592;69005;69139.52922601803;70365.29634703588;12381.876181238433;12584.74830503403;12624.621261362174;12664.366781660696;13132.868671313547;14404.783928240875;14542.781858272347;14608.78086828677;14622.780658289921;15402.84597154014;13332.333383330988;13475.19148851072;13496.392662330416;13576.93211533936;13717.245551494763;11375.26060806045;13030.674233144247;13131.60605181846;13521.391537380645;13538.86461135376;12777.488900443877;13131.540396086091;13310.401031953392;13336.333183340601;13445.260510672008;9125.817483650157;9390.95304523473;9930.25523085786;9970.152537034433;10103.04021118022;9990;11235.185175990597;11431.885681143414;11464.426778660867;11479.8278025828 15:42:08 INFO - PROCESS | 1642 | __end_tp_report 15:42:08 INFO - PROCESS | 1642 | __start_cc_report 15:42:08 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,2845 15:42:08 INFO - PROCESS | 1642 | __end_cc_report 15:42:08 INFO - PROCESS | 1642 | __startTimestamp1491604928864__endTimestamp 15:42:08 INFO - PROCESS | 1642 | 15:42:08 INFO - PROCESS | 1642 | ------- Summary: start ------- 15:42:08 INFO - PROCESS | 1642 | Number of tests: 6 15:42:08 INFO - PROCESS | 1642 | 15:42:08 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5947.58 Median:6174.66 stddev:698.45 (11.3%) stddev-sans-first:656.97 15:42:08 INFO - PROCESS | 1642 | Values: 2850.3 2941.0 3435.1 3440.9 3454.3 5963.5 5981.8 6014.0 6035.8 6055.3 5989.9 6045.6 6051.6 6058.0 6064.6 5384.5 5403.2 5446.1 5471.7 5506.1 5300.2 5313.7 5526.0 5527.1 5552.0 5354.6 5384.3 5396.1 5422.8 5427.7 5382.2 5406.2 5406.8 5419.4 5425.1 5256.0 5345.8 5346.9 5362.9 5368.5 5931.8 5944.6 5946.0 5947.6 5950.9 6336.5 6345.0 6353.6 6369.6 6381.0 6475.0 6479.8 6483.9 6495.3 6505.8 5028.7 6096.9 6120.4 6128.3 6158.2 6324.8 6356.6 6363.5 6545.1 6661.2 4888.5 6355.0 6359.0 6372.7 6376.0 6463.9 6472.7 6473.6 6475.9 6486.1 6369.9 6405.4 6431.6 6433.5 6435.5 6261.1 6328.9 6333.0 6333.4 6380.4 5064.6 5192.1 5193.5 5199.0 5217.6 4497.9 4547.2 4563.1 4573.8 4589.5 5820.5 5858.8 5980.4 6055.1 6139.3 6482.7 6485.1 6489.5 6489.7 6512.9 6113.4 6113.8 6115.5 6116.2 6123.0 5500.1 5525.7 5542.3 5670.8 5677.6 6566.3 6583.8 6611.8 6652.2 6668.3 6444.5 6460.5 6468.1 6469.9 6470.6 6580.1 6658.7 6659.4 6676.3 6682.4 6215.2 6282.9 6302.9 6314.7 6321.3 6354.4 6374.3 6378.2 6405.8 6452.6 6296.9 6308.8 6315.6 6361.5 6375.4 6359.3 6359.7 6361.0 6368.5 6380.9 5907.4 5976.1 6129.4 6297.9 6299.6 6136.2 6174.7 6224.8 6277.6 6318.0 6131.7 6140.6 6208.8 6215.2 6235.6 15:42:08 INFO - PROCESS | 1642 | 15:42:08 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:53128.35 Median:165358.42 stddev:105912.92 (64.1%) stddev-sans-first:106137.08 15:42:08 INFO - PROCESS | 1642 | Values: 767.9 772.1 808.1 878.4 887.3 38455.6 39232.0 39323.4 39438.8 39462.8 40490.4 40550.0 40655.4 40663.2 40694.0 7203.0 9017.3 9169.5 9181.8 9187.7 7251.8 10817.8 11015.7 11180.9 11189.4 11040.8 11047.7 11060.7 11074.0 11108.3 11015.3 11020.3 11033.9 11047.2 11054.3 7106.8 7277.0 10619.2 10810.9 10859.2 38915.4 38931.0 39027.0 39058.2 39149.6 3822.3 3827.7 3852.1 3866.1 3900.3 27195.9 27230.0 27592.9 28780.6 28807.0 3178.2 3196.6 3200.0 3272.3 3284.2 300521.0 351150.0 356818.0 357027.0 359403.0 41594.0 42467.6 44528.6 51855.0 51985.5 42506.6 53143.0 55778.0 55813.7 55870.7 14295.9 14357.4 14373.1 14375.5 14383.5 12526.8 12565.7 12570.6 12571.8 12612.8 11873.7 11920.8 11930.9 11943.6 11978.3 5639.2 5655.2 5656.6 5661.8 5664.5 1127.0 1148.0 1151.5 1160.2 1180.7 26127.3 26455.0 26657.6 26771.5 26787.6 1175.9 1178.5 1226.7 1242.9 1287.3 13562.9 13799.9 13887.9 13912.2 13929.6 434399.8 434502.0 435046.0 435200.0 453310.0 55290.7 55385.0 55395.7 55482.9 55521.9 1340.8 1360.6 1477.7 1478.3 1479.4 25093.5 25886.6 25923.5 25947.0 26059.6 44883.6 44926.6 44979.3 45032.3 45033.3 408307.0 408333.0 409240.0 409256.0 409570.0 28318.7 28897.0 30021.1 30195.8 33319.0 36219.1 36819.0 37280.0 37490.6 37589.9 25771.5 26409.0 26594.5 26710.3 26830.5 26800.3 26927.7 27112.6 27734.7 27949.0 30530.5 31192.7 31344.5 31465.4 31494.4 24420.0 25372.6 27694.2 27895.7 28003.7 21272.6 22416.0 22493.2 22493.7 22535.0 15:42:08 INFO - PROCESS | 1642 | 15:42:08 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:42164.97 Median:25631.92 stddev:121254.21 (473.1%) stddev-sans-first:121553.78 15:42:08 INFO - PROCESS | 1642 | Values: 385.1 406.7 414.3 424.7 453.7 13840.0 20865.9 21878.7 22024.3 22186.7 21272.8 22032.7 22158.3 22366.7 22799.7 5311.8 6433.3 6590.8 6626.5 7384.3 7490.3 7595.7 7600.7 7602.8 7610.0 7320.2 7454.1 7475.6 7489.0 7554.7 6717.8 7229.1 7288.1 7483.5 7531.9 6630.4 7245.8 7294.6 7300.5 7315.5 25384.0 25604.4 25659.5 26021.5 26144.1 2483.1 2661.3 2880.1 2923.0 2925.4 15746.4 16067.7 16123.3 16292.7 16749.7 2021.9 2062.4 2098.2 2107.6 2122.2 159893.4 160860.0 165074.0 168023.0 168319.3 14553.5 19827.2 19833.9 19855.2 20018.0 23095.9 28765.7 29875.0 29972.0 30012.7 8803.0 8973.0 9875.9 10178.8 10427.1 8448.2 8500.0 8635.9 8710.5 8733.9 9218.5 9333.0 9338.3 9406.5 9410.4 3296.3 3404.7 3633.1 3932.5 3932.7 876.6 883.0 887.2 919.3 921.0 20498.9 21608.1 21630.6 21705.0 21705.2 912.2 923.3 928.9 941.4 944.1 10712.3 11115.7 11184.4 11255.8 11393.4 700755.0 718694.0 723827.0 733800.0 740533.0 151137.7 152847.0 155449.0 158135.0 161556.0 1093.4 1142.2 1168.6 1171.4 1173.1 18371.8 18830.5 19023.7 19044.6 19071.5 57548.6 58136.1 58939.4 59068.0 59130.1 79302.6 79395.0 81879.6 82459.0 82790.2 10954.0 11068.2 11293.5 11364.1 11454.7 12164.3 12188.3 12409.4 12505.1 12608.3 9879.0 10033.6 10158.0 10196.1 10243.8 8596.4 9899.0 9912.6 10376.7 10381.7 10696.8 10852.0 10858.5 10911.9 10942.5 11126.7 11247.9 11403.9 11472.1 11568.7 9122.2 10530.7 10690.5 10908.5 10918.7 15:42:08 INFO - PROCESS | 1642 | 15:42:08 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:6696.09 Median:2408.17 stddev:4178.48 (173.5%) stddev-sans-first:4172.07 15:42:08 INFO - PROCESS | 1642 | Values: 1514.3 1544.0 1563.7 1573.9 1589.2 10508.4 11066.8 11091.8 11104.7 11116.8 10617.0 11045.3 11165.7 11190.6 11203.9 2402.8 2433.3 2436.3 2437.6 2445.4 2323.1 2390.5 2395.2 2408.5 2423.9 2337.9 2400.2 2407.8 2419.5 2422.1 2315.0 2381.6 2388.9 2389.5 2402.0 2363.4 2384.4 2393.1 2397.0 2398.2 10867.5 11109.0 11120.3 11125.2 11126.9 5658.6 5818.5 5825.7 5827.7 5832.1 3036.8 3433.9 3581.5 3597.0 3660.1 1156.6 1174.5 1179.7 1207.5 1224.2 13600.7 13970.5 14216.6 15361.5 15606.8 4512.6 4659.6 4673.7 4680.6 4696.0 11675.5 11688.7 11695.2 11706.9 11761.4 4799.8 4976.2 5446.3 5638.5 5660.9 5117.1 5226.9 5270.1 5294.7 5306.5 3781.6 3801.3 3902.7 3905.8 3906.8 1000.3 1001.3 1017.6 1021.3 1022.7 795.2 801.6 808.6 822.3 831.6 6442.6 6511.1 6705.9 6733.5 6758.3 998.9 1029.9 1034.8 1035.5 1035.5 4206.9 4232.5 4247.4 4367.2 4370.3 13356.9 13882.7 13885.8 13900.0 13921.7 12625.0 12993.0 13654.3 13791.9 13825.5 10016.3 10086.3 10263.8 10296.2 10303.3 5638.8 6739.3 6807.1 6853.9 6936.7 12022.3 12410.6 12410.7 12416.2 12441.7 10057.4 12425.3 12470.7 12491.9 12613.4 9249.2 9265.7 9355.2 9447.1 9468.9 10093.6 10121.7 10308.5 10316.9 10348.5 8579.7 8761.5 8777.4 8796.4 8809.7 8566.5 8614.7 8666.3 8668.0 8671.4 7800.6 7900.3 7906.1 7913.6 7921.8 5775.6 5811.2 5861.0 6006.5 6037.5 5671.1 5740.4 5814.3 5984.0 5995.9 15:42:08 INFO - PROCESS | 1642 | 15:42:08 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4380.08 Median:4753.49 stddev:1226.00 (25.8%) stddev-sans-first:1192.12 15:42:08 INFO - PROCESS | 1642 | Values: 381.0 384.1 389.7 391.2 396.8 4602.6 4619.8 4665.4 4934.5 5044.3 4421.3 4452.8 4473.6 4808.4 4886.5 3222.4 3504.6 3884.5 3908.7 3932.6 3508.4 3638.6 3928.2 3969.7 3993.5 3364.7 3697.5 3776.5 3804.9 3965.3 3262.6 3512.1 3532.2 3890.4 3919.9 3137.1 3289.9 3429.5 3450.4 3524.9 4073.0 4101.9 4510.9 4880.6 4921.0 3660.6 3755.9 3963.2 4402.2 4599.0 4607.3 4658.3 4733.3 5193.6 5213.1 3818.7 3828.0 3835.8 3847.6 3911.3 4208.1 5130.5 5526.4 5561.4 5563.6 4265.9 4428.8 4615.7 4692.7 4822.9 4893.9 4894.4 4917.4 4956.7 4973.5 4430.6 4954.6 4979.7 5027.0 5110.2 5033.8 5258.8 5340.8 5366.2 5476.9 1529.2 1565.3 1644.7 1666.7 1694.0 1175.6 1215.8 1358.9 1360.0 1366.9 4291.9 4498.5 4689.5 4797.9 4948.6 5080.9 5422.6 5459.5 5515.0 5524.7 4218.2 4728.7 4929.3 4957.0 4963.6 2753.0 2772.5 2825.9 2849.8 2872.3 5225.0 5636.9 5680.9 5780.9 5840.0 4759.9 4894.8 5375.8 5394.0 5428.3 4750.8 5029.7 5469.3 5810.8 5873.3 5226.9 5272.3 5429.8 5453.6 5598.5 4673.9 4710.5 4758.5 4834.7 5459.0 4920.5 5552.1 5634.5 5681.9 5695.3 4652.2 4701.7 4748.7 4794.6 4799.6 5144.9 5250.3 5538.9 5657.1 5668.4 4455.2 5151.9 5160.0 5284.4 5351.3 4298.2 4333.9 5302.4 5398.6 5400.3 4283.4 4449.5 5221.5 5289.7 5308.3 4480.7 4830.7 4906.5 4975.0 5213.9 4520.4 4756.2 5069.4 5287.6 5353.4 15:42:08 INFO - PROCESS | 1642 | 15:42:08 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20868.18 Median:3823.63 stddev:30826.21 (806.2%) stddev-sans-first:30875.56 15:42:08 INFO - PROCESS | 1642 | Values: 719.8 745.6 772.9 829.1 838.6 14925.9 15738.5 15746.6 15763.8 15841.9 12200.7 14383.2 14483.1 14506.3 14586.7 919.4 929.7 932.5 939.5 959.1 836.7 857.5 919.9 923.1 924.0 963.2 964.0 964.0 966.2 966.3 960.2 960.8 961.1 961.9 962.3 720.8 721.5 721.8 721.9 722.0 15706.0 15957.4 16017.7 16281.8 16502.3 14146.0 14214.4 14257.5 14280.9 14388.4 11379.8 11661.4 11797.5 11850.8 12362.6 3650.2 3745.8 3810.9 3836.4 4026.0 107668.9 112694.0 113879.4 114725.4 118081.0 8899.5 10173.9 10663.3 10935.9 11112.6 21261.0 21615.8 22021.7 22069.8 22088.6 6734.7 7513.7 7608.9 7630.7 7905.2 4231.2 4295.6 4296.4 4406.4 4556.0 3441.2 3509.1 3537.3 3542.6 3549.4 645.2 674.7 675.6 676.0 679.2 674.4 683.4 688.8 721.1 722.7 9744.7 10406.4 10445.3 10483.6 10709.4 706.2 713.3 719.6 720.7 720.8 4476.6 4778.0 5008.9 5014.8 5015.9 107082.9 108432.0 110387.0 111435.0 113468.0 91385.0 91984.0 94075.1 94235.5 94929.0 48947.5 84623.6 87055.6 87058.0 87318.6 10595.6 10964.3 11063.3 11141.4 11309.7 45220.5 46481.0 46804.0 46870.8 49511.8 42529.0 68075.0 69005.0 69139.5 70365.3 12381.9 12584.7 12624.6 12664.4 13132.9 14404.8 14542.8 14608.8 14622.8 15402.8 13332.3 13475.2 13496.4 13576.9 13717.2 11375.3 13030.7 13131.6 13521.4 13538.9 12777.5 13131.5 13310.4 13336.3 13445.3 9125.8 9391.0 9930.3 9970.2 10103.0 9990.0 11235.2 11431.9 11464.4 11479.8 15:42:08 INFO - PROCESS | 1642 | -------- Summary: end -------- 15:42:08 INFO - PROCESS | 1642 | 15:42:09 INFO - TEST-INFO | 1642: exit 0 15:42:09 INFO - TEST-OK | dromaeo_css | took 1120868ms 15:42:09 INFO - TEST-START | kraken 15:42:09 INFO - operating with platform_type : mac_ 15:42:09 INFO - Initialising browser for kraken test... 15:42:09 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmp1N9gUD/profile http://localhost:49243/getInfo.html) 15:42:12 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 15:42:12 INFO - PROCESS | 1653 | colorDepth:24 15:42:12 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 15:42:12 INFO - PROCESS | 1653 | __metrics 15:42:12 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:42:12 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:42:12 INFO - TEST-INFO | 1653: exit 0 15:42:12 INFO - Browser initialized. 15:42:12 INFO - Running cycle 1/1 for kraken test... 15:42:12 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmp1N9gUD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:42:25 INFO - PROCESS | 1654 | RSS: Main: 215023616 15:42:25 INFO - PROCESS | 1654 | 15:42:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 15:42:28 INFO - PROCESS | 1654 | RSS: Main: 267825152 15:42:28 INFO - PROCESS | 1654 | 15:42:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 15:42:31 INFO - PROCESS | 1654 | RSS: Main: 278646784 15:42:31 INFO - PROCESS | 1654 | 15:42:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 15:42:35 INFO - PROCESS | 1654 | RSS: Main: 375341056 15:42:35 INFO - PROCESS | 1654 | 15:42:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 15:42:38 INFO - PROCESS | 1654 | RSS: Main: 277716992 15:42:38 INFO - PROCESS | 1654 | 15:42:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:42:40 INFO - PROCESS | 1654 | RSS: Main: 279236608 15:42:40 INFO - PROCESS | 1654 | 15:42:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 15:42:44 INFO - PROCESS | 1654 | RSS: Main: 319623168 15:42:44 INFO - PROCESS | 1654 | 15:42:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 15:42:48 INFO - PROCESS | 1654 | RSS: Main: 320872448 15:42:48 INFO - PROCESS | 1654 | 15:42:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 15:42:51 INFO - PROCESS | 1654 | RSS: Main: 317296640 15:42:51 INFO - PROCESS | 1654 | 15:42:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:42:53 INFO - PROCESS | 1654 | RSS: Main: 295043072 15:42:53 INFO - PROCESS | 1654 | 15:42:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:42:56 INFO - PROCESS | 1654 | RSS: Main: 297283584 15:42:56 INFO - PROCESS | 1654 | 15:42:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:42:59 INFO - PROCESS | 1654 | RSS: Main: 301109248 15:42:59 INFO - PROCESS | 1654 | 15:43:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:43:02 INFO - PROCESS | 1654 | RSS: Main: 320135168 15:43:02 INFO - PROCESS | 1654 | 15:43:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:43:06 INFO - PROCESS | 1654 | RSS: Main: 312721408 15:43:06 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 15:43:08 INFO - PROCESS | 1654 | RSS: Main: 357527552 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | __start_tp_report 15:43:08 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 15:43:08 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 15:43:08 INFO - PROCESS | 1654 | |i|pagename|runs| 15:43:08 INFO - PROCESS | 1654 | |0;ai-astar;118;116;114;115;113;114;115;115;115;116 15:43:08 INFO - PROCESS | 1654 | |1;audio-beat-detection;143;110;109;144;110;110;108;112;127;110 15:43:08 INFO - PROCESS | 1654 | |2;audio-dft;188;184;185;184;184;184;185;181;183;185 15:43:08 INFO - PROCESS | 1654 | |3;audio-fft;81;61;63;93;61;61;65;81;97;82 15:43:08 INFO - PROCESS | 1654 | |4;audio-oscillator;88;69;89;70;70;70;82;69;72;69 15:43:08 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;86;86;86;88;86;86;86;85;87;86 15:43:08 INFO - PROCESS | 1654 | |6;imaging-darkroom;93;94;94;94;93;93;94;93;94;93 15:43:08 INFO - PROCESS | 1654 | |7;imaging-desaturate;83;85;85;85;83;85;87;84;86;83 15:43:08 INFO - PROCESS | 1654 | |8;json-parse-financial;65;63;61;61;64;67;65;64;65;64 15:43:08 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;55;64;56;64;62;58;62;81;58;59 15:43:08 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;83;82;84;81;73;79;92;99;102;98 15:43:08 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;125;180;149;154;141;153;136;145;198;167 15:43:08 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;167;183;217;210;170;165;165;164;164;166 15:43:08 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;66;65;67;65;64;66;64;64;64;63 15:43:08 INFO - PROCESS | 1654 | __end_tp_report 15:43:08 INFO - PROCESS | 1654 | __start_cc_report 15:43:08 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2126 15:43:08 INFO - PROCESS | 1654 | __end_cc_report 15:43:08 INFO - PROCESS | 1654 | __startTimestamp1491604988523__endTimestamp 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | ------- Summary: start ------- 15:43:08 INFO - PROCESS | 1654 | Number of tests: 14 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.00 stddev:1.37 (1.2%) stddev-sans-first:0.97 15:43:08 INFO - PROCESS | 1654 | Values: 118.0 116.0 114.0 115.0 113.0 114.0 115.0 115.0 115.0 116.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:118.30 Median:111.00 stddev:14.35 (12.9%) stddev-sans-first:12.13 15:43:08 INFO - PROCESS | 1654 | Values: 143.0 110.0 109.0 144.0 110.0 110.0 108.0 112.0 127.0 110.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:184.30 Median:184.50 stddev:1.77 (1.0%) stddev-sans-first:1.27 15:43:08 INFO - PROCESS | 1654 | Values: 188.0 184.0 185.0 184.0 184.0 184.0 185.0 181.0 183.0 185.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:74.50 Median:81.00 stddev:13.98 (17.3%) stddev-sans-first:14.63 15:43:08 INFO - PROCESS | 1654 | Values: 81.0 61.0 63.0 93.0 61.0 61.0 65.0 81.0 97.0 82.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:74.80 Median:71.00 stddev:8.20 (11.6%) stddev-sans-first:7.18 15:43:08 INFO - PROCESS | 1654 | Values: 88.0 69.0 89.0 70.0 70.0 70.0 82.0 69.0 72.0 69.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:86.00 stddev:0.79 (0.9%) stddev-sans-first:0.83 15:43:08 INFO - PROCESS | 1654 | Values: 86.0 86.0 86.0 88.0 86.0 86.0 86.0 85.0 87.0 86.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 15:43:08 INFO - PROCESS | 1654 | Values: 93.0 94.0 94.0 94.0 93.0 93.0 94.0 93.0 94.0 93.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.35 (1.6%) stddev-sans-first:1.30 15:43:08 INFO - PROCESS | 1654 | Values: 83.0 85.0 85.0 85.0 83.0 85.0 87.0 84.0 86.0 83.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:64.50 stddev:1.85 (2.9%) stddev-sans-first:1.92 15:43:08 INFO - PROCESS | 1654 | Values: 65.0 63.0 61.0 61.0 64.0 67.0 65.0 64.0 65.0 64.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:62.00 stddev:7.42 (12.0%) stddev-sans-first:7.43 15:43:08 INFO - PROCESS | 1654 | Values: 55.0 64.0 56.0 64.0 62.0 58.0 62.0 81.0 58.0 59.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:83.50 stddev:9.78 (11.7%) stddev-sans-first:10.24 15:43:08 INFO - PROCESS | 1654 | Values: 83.0 82.0 84.0 81.0 73.0 79.0 92.0 99.0 102.0 98.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:153.50 stddev:21.64 (14.1%) stddev-sans-first:20.09 15:43:08 INFO - PROCESS | 1654 | Values: 125.0 180.0 149.0 154.0 141.0 153.0 136.0 145.0 198.0 167.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.10 Median:168.50 stddev:20.06 (11.9%) stddev-sans-first:20.94 15:43:08 INFO - PROCESS | 1654 | Values: 167.0 183.0 217.0 210.0 170.0 165.0 165.0 164.0 164.0 166.0 15:43:08 INFO - PROCESS | 1654 | 15:43:08 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:1.23 (1.9%) stddev-sans-first:1.22 15:43:08 INFO - PROCESS | 1654 | Values: 66.0 65.0 67.0 65.0 64.0 66.0 64.0 64.0 64.0 63.0 15:43:08 INFO - PROCESS | 1654 | -------- Summary: end -------- 15:43:08 INFO - PROCESS | 1654 | 15:43:09 INFO - TEST-INFO | 1654: exit 0 15:43:09 INFO - TEST-OK | kraken | took 59838ms 15:43:09 INFO - SUITE-END | took 1180s 15:43:09 INFO - Completed test suite (00:19:40) 15:43:09 INFO - javascript benchmark 15:43:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7593.234705067079, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2850.2731803390134, 2940.985360050783, 3435.089701229175, 3440.879569215078, 3454.2918701666163, 5963.522918166536, 5981.820545383634, 6014.0, 6035.818925432233, 6055.333913269536, 5989.9101513477335, 6045.576809623317, 6051.606645568035, 6058.0, 6064.605800622957, 5384.515393614564, 5403.189521571757, 5446.128619420893, 5471.699056551888, 5506.119020956648, 5300.178472336794, 5313.73431328434, 5525.972370138144, 5527.060399732055, 5551.9722401387935, 5354.571634269249, 5384.273123128386, 5396.055690254209, 5422.783088676449, 5427.674339539641, 5382.219578161144, 5406.162044883049, 5406.756695948672, 5419.376771671229, 5425.077736784737, 5255.973720131413, 5345.786168553253, 5346.91979620306, 5362.892742145174, 5368.543673787733, 5931.822045338647, 5944.583879128462, 5945.970270148665, 5947.6431414115295, 5950.9107363389585, 6336.46140078094, 6345.0, 6353.5870168438905, 6369.617822930639, 6381.0, 6475.028745688137, 6479.8056058318325, 6483.9027414588345, 6495.253045899734, 6505.772297969548, 5028.723420211887, 6096.878062438771, 6120.357362476965, 6128.325884152784, 6158.1686472326555, 6324.810255692337, 6356.586821856565, 6363.490920726331, 6545.14913061299, 6661.233958094785, 4888.462269150391, 6354.968225158845, 6359.0, 6372.7132279047555, 6376.011718183689, 6463.935360646427, 6472.708728107246, 6473.643949582724, 6475.870482590321, 6486.091947127406, 6369.904451433232, 6405.423511883952, 6431.5819471734185, 6433.5496515243485, 6435.549511534241, 6261.060840873905, 6328.905066424008, 6332.968335158294, 6333.36666333363, 6380.393862583047, 5064.645474816801, 5192.065428222956, 5193.480651934777, 5198.9740051299505, 5217.608679349064, 4497.865064048084, 4547.15877562652, 4563.133004729091, 4573.839915602938, 4589.541045895384, 5820.534357251409, 5858.824235272863, 5980.401959804072, 6055.091736239511, 6139.293981192131, 6482.67586620667, 6485.059666348353, 6489.513286503531, 6489.740410383626, 6512.902306465407, 6113.419225173601, 6113.755449781959, 6115.541334399938, 6116.174316467309, 6123.020316749299, 5500.064988951888, 5525.696086715189, 5542.251796007568, 5670.829875103754, 5677.602567820296, 6566.343365663491, 6583.835404114935, 6611.768588099393, 6652.168478940132, 6668.299828517936, 6444.45222156122, 6460.547761656639, 6468.126802881739, 6469.902951455826, 6470.6441145737435, 6580.1116849226655, 6658.700358483783, 6659.4006539411685, 6676.298988606321, 6682.398584127257, 6215.160953271251, 6282.905756413566, 6302.936970630235, 6314.747410103544, 6321.336259692666, 6354.3963323484195, 6374.298827129105, 6378.2346118466085, 6405.775797847053, 6452.645104519296, 6296.905546416716, 6308.842278943063, 6315.5894866833505, 6361.522885783492, 6375.362463753588, 6359.268684101387, 6359.745610175726, 6361.0, 6368.4586810121655, 6380.872382552416, 5907.3797251288, 5976.103584462366, 6129.358790882597, 6297.905531417124, 6299.590526615755, 6136.243373036893, 6174.660393678211, 6224.782132625336, 6277.591956522811, 6318.0, 6131.682521312785, 6140.565599444434, 6208.782692605737, 6215.160953271431, 6235.563510554399], "value": 5899.079806196468, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.9040119984999, 772.1352085664057, 808.0586117173493, 878.4158534574508, 887.3344991256557, 38455.615443845556, 39232.01919952003, 39323.41014884772, 39438.80280598593, 39462.8026859866, 40490.39264411029, 40549.986250343754, 40655.39016914749, 40663.186736265256, 40694.0, 7202.963985180067, 9017.278617710585, 9169.495677737721, 9181.770455738626, 9187.724368268962, 7251.818704532376, 10817.783644327108, 11015.669529914116, 11180.944095279512, 11189.44052797361, 11040.834387484194, 11047.668569942914, 11060.668179954615, 11074.0, 11108.277961932448, 11015.339079655207, 11020.283681560713, 11033.889661103367, 11047.226694131414, 11054.336739795557, 7106.751263705745, 7277.0, 10619.20355973305, 10810.891891081068, 10859.18556108295, 38915.416268755995, 38931.0, 39027.0, 39058.21883562341, 39149.60850391488, 3822.3310920589065, 3827.6555110039953, 3852.081655380874, 3866.072142685763, 3900.278448487011, 27195.86402067997, 27230.0, 27592.862035689894, 28780.568291475643, 28807.0, 3178.1577881861326, 3196.6164060312913, 3200.0, 3272.2637406583567, 3284.2446237365302, 300521.0, 351150.0, 356818.0, 357027.0, 359403.0, 41594.0, 42467.57532424698, 44528.55471445244, 51855.0, 51985.48014519807, 42506.57493425088, 53143.0, 55778.0, 55813.72093139508, 55870.720646396505, 14295.92852035754, 14357.425702971974, 14373.06575072617, 14375.496857609933, 14383.496577619733, 12526.81209781854, 12565.685857853445, 12570.560030398892, 12571.811422828665, 12612.81080783789, 11873.703157420961, 11920.821187682192, 11930.88069119315, 11943.581974630846, 11978.281303121841, 5639.210510528529, 5655.151727240865, 5656.575756818255, 5661.754414028907, 5664.54683625309, 1126.9943650281696, 1147.9770404591861, 1151.476078384333, 1160.2052593972987, 1180.7447774750185, 26127.346816329362, 26455.0, 26657.600135997975, 26771.46457070848, 26787.598186027226, 1175.9299037875492, 1178.4873579992732, 1226.7055906582357, 1242.9067819913362, 1287.2533930320392, 13562.93218533901, 13799.89600831931, 13887.861121388858, 13912.234827084405, 13929.58211253664, 434399.82800085796, 434502.0, 435046.0, 435200.0, 453310.0, 55290.72354638201, 55385.0, 55395.723021384634, 55482.89034219293, 55521.88956220934, 1340.8468716903456, 1360.5578187089236, 1477.697072100217, 1478.3273610507194, 1479.3786609624199, 25093.498130037664, 25886.611700824127, 25923.481530369663, 25947.0, 26059.609105863805, 44883.55116448794, 44926.55073449355, 44979.32531011972, 45032.324515132954, 45033.32450013187, 408307.0, 408333.0, 409240.0, 409256.0, 409570.0, 28318.734501240262, 28896.98860539871, 30021.099367019026, 30195.849020754755, 33319.0, 36219.09452263609, 36819.0, 37280.0, 37490.62509374871, 37589.872303830925, 25771.484570308865, 26409.0, 26594.468110637292, 26710.332241694112, 26830.463390731686, 26800.32999175036, 26927.730722692522, 27112.593311100743, 27734.722652773213, 27949.0, 30530.542041869834, 31192.688073118978, 31344.52983205208, 31465.37069258648, 31494.37011259716, 24420.0, 25372.619410708485, 27694.169174924784, 27895.721042790123, 28003.719962800926, 21272.574548509252, 22416.0, 22493.21273755476, 22493.66259506076, 22534.985925633715], "value": 18218.310732338563, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.09118480386303, 406.6990427083958, 414.2646801926578, 424.74727537115416, 453.71189294797813, 13840.0, 20865.897779288014, 21878.671819922674, 22024.339269821892, 22186.667199991974, 21272.787272127272, 22032.669509957323, 22158.335249942487, 22366.66450003251, 22799.658005129895, 5311.760970756325, 6433.260175079868, 6590.769323073693, 6626.536142470029, 7384.261573842599, 7490.325870671666, 7595.658195381194, 7600.6959721611065, 7602.809929751746, 7610.010698609173, 7320.194778574352, 7454.142773581027, 7475.551466911976, 7488.962555187217, 7554.660040298199, 6717.832054198635, 7229.060222171084, 7288.052553168107, 7483.476156669035, 7531.9246807532045, 6630.403263706289, 7245.818854528627, 7294.562326260441, 7300.525465844703, 7315.451341149382, 25384.0, 25604.35989100269, 25659.486810263876, 26021.479570408675, 26144.084957026604, 2483.1060818105466, 2661.2814540074073, 2880.0783749200264, 2923.0353983185496, 2925.444165608528, 15746.370145194178, 16067.678646427006, 16123.274452649659, 16292.674146517003, 16749.66500669992, 2021.8989050547561, 2062.422521693928, 2098.213170061227, 2107.557412943284, 2122.1935664447565, 159893.40106598902, 160860.0, 165074.0, 168023.0, 168319.3168068316, 14553.492659813819, 19827.20691172337, 19833.900830495757, 19855.205791768458, 20017.996400719916, 23095.88452057729, 28765.712342876723, 29874.954376596714, 29972.0, 30012.699873001427, 8803.031666516677, 8972.955135224282, 9875.851862222073, 10178.796424071477, 10427.113695335982, 8448.197421244971, 8500.0, 8635.870461943075, 8710.520921349387, 8733.91266087331, 9218.492982886006, 9332.95333523328, 9338.299627527962, 9406.482643454676, 9410.38832475887, 3296.297036430104, 3404.7276217903004, 3633.091727068233, 3932.528096628423, 3932.6853851691794, 876.6274333408276, 882.9690960816469, 887.2281115429618, 919.2599957034652, 921.0421161991587, 20498.897505512676, 21608.135674573154, 21630.56738865214, 21705.0, 21705.17832681741, 912.1653686876434, 923.3075193604797, 928.9410072517336, 941.3880977364768, 944.0653752784693, 10712.303700259457, 11115.722106947229, 11184.44077796107, 11255.831162532568, 11393.373364465035, 700755.0, 718694.0, 723827.0, 733800.0, 740533.0, 151137.73293400608, 152847.0, 155449.0, 158135.0, 161556.0, 1093.4095588382343, 1142.1719253541116, 1168.5617893290016, 1171.3733152763232, 1173.143605168249, 18371.816281837546, 18830.52923676919, 19023.71464428007, 19044.619107617495, 19071.52321191926, 57548.561285968186, 58136.127958079815, 58939.41060589511, 59068.0, 59130.11304830517, 79302.60348698219, 79395.0, 81879.590602049, 82459.0, 82790.17209828066, 10954.028274910592, 11068.225224234226, 11293.491792869176, 11364.090872729996, 11454.713632159262, 12164.330961797186, 12188.329641869783, 12409.379531023593, 12505.124641275024, 12608.306543140216, 9878.950605246928, 10033.648822291185, 10158.0, 10196.133328667147, 10243.795124097625, 8596.398252122292, 9898.950505247427, 9912.553935073083, 10376.740581485523, 10381.688549343531, 10696.786064278827, 10852.0, 10858.511366988347, 10911.890881091087, 10942.45287735626, 11126.666200014013, 11247.881544911352, 11403.885961140615, 11472.082233421474, 11568.710782230512, 9122.224610908148, 10530.736731581772, 10690.518926648165, 10908.454577271263, 10918.72703182395], "value": 11321.177052318568, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.3261248744313, 1543.9614009649758, 1563.7419825728753, 1573.8662213711834, 1589.1736297125494, 10508.422036787973, 11066.778664426707, 11091.778164436706, 11104.722381940455, 11116.833247501283, 10617.0, 11045.33727976322, 11165.72085697858, 11190.608328708497, 11203.943980280088, 2402.8197885158597, 2433.294344640061, 2436.3421876093535, 2437.609982402816, 2445.3764290105996, 2323.0939933425893, 2390.521895620874, 2395.197608801054, 2408.542376948374, 2423.8909249083745, 2337.91817286395, 2400.195934361982, 2407.7953373963232, 2419.5402873454077, 2422.103821586016, 2315.0, 2381.642753586967, 2388.856668599872, 2389.5340408620327, 2402.039184326266, 2363.409147713072, 2384.403899025244, 2393.0786647140803, 2396.9640455393182, 2398.2445529658103, 10867.456627168689, 11109.0, 11120.332780033143, 11125.165612579089, 11126.888731112665, 5658.632188907708, 5818.4763371296785, 5825.708714564297, 5827.679477628729, 5832.066869300891, 3036.75705943524, 3433.8798142064907, 3581.5164952731307, 3596.9677132420097, 3660.084978755311, 1156.554726430322, 1174.4597485156837, 1179.7168679516853, 1207.468713765949, 1224.2470880408573, 13600.659983500393, 13970.51103211393, 14216.573502794932, 15361.46234881785, 15606.765898511532, 4512.616427603625, 4659.58063774262, 4673.7429441381055, 4680.648951328664, 4696.0, 11675.532978680927, 11688.70778230551, 11695.239809412362, 11706.88293117058, 11761.411929403495, 4799.808007679654, 4976.2038073908725, 5446.319210098738, 5638.520725738358, 5660.8867822643315, 5117.078925793318, 5226.869328266824, 5270.1040823060175, 5294.708791016531, 5306.469353064663, 3781.6029316921877, 3801.2967600993907, 3902.687784977195, 3905.7851818149707, 3906.7655940643654, 1000.3247807729754, 1001.2990906365428, 1017.5624481473037, 1021.2902033087082, 1022.7340891368298, 795.1611050341829, 801.6032064128294, 808.5552945879742, 822.3051521464353, 831.5509624802539, 6442.645654489048, 6511.088447617338, 6705.899411508832, 6733.494987875929, 6758.256591774901, 998.8501724741346, 1029.9443070852287, 1034.818906691326, 1035.4615599888016, 1035.53400969564, 4206.915861682749, 4232.534421213664, 4247.362895565628, 4367.235733746582, 4370.300751879684, 13356.866431335562, 13882.652933676738, 13885.791713124312, 13900.0, 13921.721565568834, 12625.0, 12993.0, 13654.317284135754, 13791.862081379259, 13825.51610693621, 10016.298859079794, 10086.344387614781, 10263.794724105475, 10296.227782916161, 10303.278770486144, 5638.830835074954, 6739.292374300824, 6807.115075040213, 6853.897191542031, 6936.653167341714, 12022.278663279882, 12410.627681169579, 12410.689732756753, 12416.192947458387, 12441.688957776129, 10057.446840423847, 12425.31660758667, 12470.688232794253, 12491.875081249435, 12613.369331533204, 9249.213816825646, 9265.72202833916, 9355.158035776813, 9447.149756521945, 9468.857967130361, 10093.596256149965, 10121.69634910954, 10308.536115874953, 10316.896831031594, 10348.482575871327, 8579.742607721779, 8761.518116503654, 8777.385583009129, 8796.428232164888, 8809.735707928772, 8566.528840913561, 8614.65541378338, 8666.263367613572, 8668.0, 8671.393002489765, 7800.6099695016155, 7900.2889739923685, 7906.051273847175, 7913.643886025257, 7921.841563168819, 5775.595708300379, 5811.157382179645, 5860.970695146668, 6006.489448396936, 6037.45662890342, 5671.120976248688, 5740.368559458373, 5814.30228372598, 5983.982722937024, 5995.940040599538], "value": 5096.893292462894, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.9961900380996, 384.0590553144796, 389.7349802134551, 391.2488022995848, 396.81349765610173, 4602.585767280944, 4619.838305659303, 4665.393498845146, 4934.481879402665, 5044.344235249421, 4421.2809122389735, 4452.755098469583, 4473.642108631309, 4808.422989241287, 4886.459041298523, 3222.3716375306826, 3504.649535046488, 3884.475595794574, 3908.736205268604, 3932.58707835676, 3508.350955073307, 3638.6179451157645, 3928.2143571285715, 3969.6625786808154, 3993.5407428145845, 3364.7308215342837, 3697.482352470656, 3776.5090538229992, 3804.858542437272, 3965.266425711253, 3262.5758651375168, 3512.0693016350688, 3532.1699400640828, 3890.435886796399, 3919.9216015679526, 3137.074563003919, 3289.901302960915, 3429.53701250333, 3450.3955660617935, 3524.8766293179615, 4072.9796351018354, 4101.876943691694, 4510.887227819298, 4880.560749532559, 4920.950790492085, 3660.584014550384, 3755.868544600926, 3963.2271707017144, 4402.204600078973, 4599.022420359278, 4607.285870690049, 4658.254679251338, 4733.313669517901, 5193.5845132389695, 5213.061648903196, 3818.694504439638, 3827.956774364387, 3835.8082095895093, 3847.5763967331986, 3911.2959667259884, 4208.117439553885, 5130.51260130287, 5526.447355264481, 5561.360443549003, 5563.552037103662, 4265.87202383929, 4428.800703968329, 4615.730674064659, 4692.741899195509, 4822.855314340575, 4893.877653058702, 4894.388201474816, 4917.434495033045, 4956.677815941952, 4973.477784832613, 4430.629613156945, 4954.578860796801, 4979.676321039121, 5026.974865125724, 5110.192906284841, 5033.833553896521, 5258.763355649005, 5340.839774806762, 5366.248725178478, 5476.863078422992, 1529.1589625705817, 1565.3190861975077, 1644.7368421052813, 1666.7332827051528, 1693.992618746789, 1175.629676651869, 1215.750771091924, 1358.932053397346, 1359.9732202187483, 1366.938487768053, 4291.892702682396, 4498.489350545556, 4689.499360204702, 4797.856064318006, 4948.579370753454, 5080.872978175576, 5422.61723260566, 5459.481349271892, 5514.972425137848, 5524.723763811793, 4218.152746895833, 4728.71627702339, 4929.260610908321, 4957.025784529325, 4963.57513315811, 2752.9862350688118, 2772.5167035635836, 2825.9152225433268, 2849.7577705894814, 2872.296287409598, 5225.0, 5636.887262254732, 5680.943190568124, 5780.855478613068, 5839.992403911989, 4759.906061060338, 4894.828680996148, 5375.81184658535, 5393.9730301348245, 5428.294321738149, 4750.762461876961, 5029.673071250357, 5469.343678758495, 5810.767569297265, 5873.2658417697785, 5226.865328826201, 5272.288241087557, 5429.782808687608, 5453.618246722692, 5598.5086636446395, 4673.883152921204, 4710.481846996896, 4758.547937945889, 4834.734089625104, 5458.972705136449, 4920.483349248278, 5552.0567801269235, 5634.478690753555, 5681.857953551194, 5695.288088988877, 4652.231922006401, 4701.670883038291, 4748.738819364969, 4794.568488836021, 4799.640026997919, 5144.948550514597, 5250.317458730341, 5538.867535998589, 5657.066584013592, 5668.404817494269, 4455.220336441174, 5151.871203219949, 5160.045391602565, 5284.418713941386, 5351.3043304370185, 4298.161858437575, 4333.891652708708, 5302.416734159162, 5398.62209645321, 5400.271912987963, 4283.357496375568, 4449.51055383914, 5221.451747566451, 5289.709066001406, 5308.309919710413, 4480.686351955332, 4830.686005409637, 4906.546726636681, 4974.950250497449, 5213.869653258699, 4520.434945631796, 4756.215224488059, 5069.366329208849, 5287.57699384056, 5353.357597088374], "value": 4045.958042475594, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.8056524738317, 745.5973774161953, 772.9227077292271, 829.100426037749, 838.5849004742652, 14925.850741492583, 15738.527844164664, 15746.606334841636, 15763.84236157638, 15841.920790396061, 12200.694982625417, 14383.208923509203, 14483.058601190914, 14506.274686265697, 14586.708265834677, 919.4345477531303, 929.6513807322237, 932.4545141092435, 939.4973689076346, 959.0601210813408, 836.6987884361625, 857.5378978841061, 919.9080091990814, 923.0769230769231, 923.9861402078974, 963.1524258652377, 963.9614415423374, 964.0, 966.1690945786618, 966.2608104799799, 960.2414092866627, 960.8414611589079, 961.0773657289011, 961.9182369498601, 962.3311798300219, 720.7693538067821, 721.4877437019722, 721.761818599866, 721.9205887352387, 722.000029958506, 15706.0, 15957.44148954781, 16017.679646407007, 16281.755773663404, 16502.33990640373, 14146.009779315453, 14214.360353784105, 14257.500987465493, 14280.92859535706, 14388.424463021467, 11379.829302560469, 11661.358625275607, 11797.528098876035, 11850.762984740257, 12362.567310144192, 3650.2152037312107, 3745.812709364548, 3810.8856734298015, 3836.3669994450884, 4025.9597404025876, 107668.92331076667, 112694.0, 113879.43060284728, 114725.42637286843, 118081.0, 8899.510526921082, 10173.94913025445, 10663.253572249869, 10935.945320273348, 11112.55549778016, 21261.0, 21615.783842161378, 22021.66967495489, 22069.779302207095, 22088.558228835333, 6734.730610775514, 7513.699452021858, 7608.923910760932, 7630.656620452093, 7905.169957154416, 4231.174920890397, 4295.613394794484, 4296.37702533131, 4406.405135306757, 4556.0, 3441.174118211611, 3509.052555809917, 3537.3102245061964, 3542.6103128655827, 3549.432090865501, 645.1838424393146, 674.7098747538507, 675.5676367125044, 675.9628220447922, 679.1714108787272, 674.4402146218627, 683.4156795939499, 688.7980474072675, 721.1418412089597, 722.6928555364054, 9744.707658770249, 10406.375617462976, 10445.321054131457, 10483.633072842415, 10709.357438553556, 706.2478460439718, 713.3116542536477, 719.5610677486729, 720.6792977125234, 720.8089856188114, 4476.6418686505, 4778.042013476034, 5008.924866127011, 5014.799408023711, 5015.871428928534, 107082.92917070886, 108432.0, 110387.0, 111435.0, 113468.0, 91385.0, 91984.0, 94075.11849763103, 94235.52882235545, 94929.0, 48947.51052489572, 84623.5768821152, 87055.56472217725, 87058.0, 87318.56340718256, 10595.576176952836, 10964.28732132409, 11063.336199828036, 11141.442927853413, 11309.71725706864, 45220.54779452295, 46481.0, 46804.0, 46870.76564617291, 49511.752441237564, 42529.0, 68074.97887531592, 69005.0, 69139.52922601803, 70365.29634703588, 12381.876181238433, 12584.74830503403, 12624.621261362174, 12664.366781660696, 13132.868671313547, 14404.783928240875, 14542.781858272347, 14608.78086828677, 14622.780658289921, 15402.84597154014, 13332.333383330988, 13475.19148851072, 13496.392662330416, 13576.93211533936, 13717.245551494763, 11375.26060806045, 13030.674233144247, 13131.60605181846, 13521.391537380645, 13538.86461135376, 12777.488900443877, 13131.540396086091, 13310.401031953392, 13336.333183340601, 13445.260510672008, 9125.817483650157, 9390.95304523473, 9930.25523085786, 9970.152537034433, 10103.04021118022, 9990.0, 11235.185175990597, 11431.885681143414, 11464.426778660867, 11479.8278025828], "value": 7638.498988312237, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1441.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 114.0, 115.0, 113.0, 114.0, 115.0, 115.0, 115.0, 116.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 109.0, 144.0, 110.0, 110.0, 108.0, 112.0, 127.0, 110.0], "value": 118.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 184.0, 185.0, 184.0, 184.0, 184.0, 185.0, 181.0, 183.0, 185.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 63.0, 93.0, 61.0, 61.0, 65.0, 81.0, 97.0, 82.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 89.0, 70.0, 70.0, 70.0, 82.0, 69.0, 72.0, 69.0], "value": 74.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 88.0, 86.0, 86.0, 86.0, 85.0, 87.0, 86.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 93.0, 94.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 85.0, 85.0, 83.0, 85.0, 87.0, 84.0, 86.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 61.0, 61.0, 64.0, 67.0, 65.0, 64.0, 65.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 56.0, 64.0, 62.0, 58.0, 62.0, 81.0, 58.0, 59.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 84.0, 81.0, 73.0, 79.0, 92.0, 99.0, 102.0, 98.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 180.0, 149.0, 154.0, 141.0, 153.0, 136.0, 145.0, 198.0, 167.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 183.0, 217.0, 210.0, 170.0, 165.0, 165.0, 164.0, 164.0, 166.0], "value": 177.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 65.0, 64.0, 66.0, 64.0, 64.0, 64.0, 63.0], "value": 64.8, "unit": "score"}]}]} 15:43:09 INFO - Return code: 0 15:43:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:43:09 ERROR - # TBPL SUCCESS # 15:43:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:43:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:43:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:43:09 INFO - 2017-04-07 15:43:09.869 screenresolution[1658:9693] starting screenresolution argv=screenresolution get 15:43:09 INFO - 2017-04-07 15:43:09.872 screenresolution[1658:9693] Display 0: 1600x1200x32@60 15:43:09 INFO - 2017-04-07 15:43:09.878 screenresolution[1659:9696] starting screenresolution argv=screenresolution list 15:43:09 INFO - Available Modes on Display 0 15:43:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:43:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:43:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:43:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:43:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:43:10 INFO - Intel Iris: 15:43:10 INFO - Chipset Model: Intel Iris 15:43:10 INFO - Type: GPU 15:43:10 INFO - Bus: Built-In 15:43:10 INFO - VRAM (Dynamic, Max): 1536 MB 15:43:10 INFO - Vendor: Intel (0x8086) 15:43:10 INFO - Device ID: 0x0a2e 15:43:10 INFO - Revision ID: 0x0009 15:43:10 INFO - Displays: 15:43:10 INFO - Display: 15:43:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:43:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:43:10 INFO - Display Serial Number: 1600x1200 60 15:43:10 INFO - Main Display: Yes 15:43:10 INFO - Mirror: Off 15:43:10 INFO - Online: Yes 15:43:10 INFO - Rotation: Supported 15:43:10 INFO - Return code: 0 15:43:10 INFO - Running post-action listener: _resource_record_post_action 15:43:10 INFO - [mozharness: 2017-04-07 22:43:10.113591Z] Finished run-tests step (success) 15:43:10 INFO - Running post-run listener: _resource_record_post_run 15:43:10 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 39064576; Write bytes: 507600896; Read time: 381; Write time: 1766 15:43:10 INFO - TinderboxPrint: CPU usage
53.7% 15:43:10 INFO - TinderboxPrint: I/O read bytes / time
39,064,576 / 381 15:43:10 INFO - TinderboxPrint: I/O write bytes / time
507,600,896 / 1,766 15:43:10 INFO - TinderboxPrint: CPU idle
2,223.8 (46.3%) 15:43:10 INFO - TinderboxPrint: CPU system
952.5 (19.8%) 15:43:10 INFO - TinderboxPrint: CPU user
1,629.4 (33.9%) 15:43:10 INFO - TinderboxPrint: Swap in / out
246,910,976 / 0 15:43:10 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205982208; Write bytes: 200906752; Read time: 15731; Write time: 435 15:43:10 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 34550784; Write bytes: 295503872; Read time: 348; Write time: 1299 15:43:10 INFO - Running post-run listener: _upload_blobber_files 15:43:10 INFO - Blob upload gear active. 15:43:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:43:10 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 15:43:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:43:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:43:10 INFO - (blobuploader) - INFO - Open directory for files ... 15:43:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:43:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:43:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:43:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:43:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:43:11 INFO - (blobuploader) - INFO - Done attempting. 15:43:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:43:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:43:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:43:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:43:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:43:12 INFO - (blobuploader) - INFO - Done attempting. 15:43:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:43:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:43:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:43:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:43:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:43:13 INFO - (blobuploader) - INFO - Done attempting. 15:43:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:43:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:43:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:43:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:43:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:43:15 INFO - (blobuploader) - INFO - Done attempting. 15:43:15 INFO - (blobuploader) - INFO - Iteration through files over. 15:43:15 INFO - Return code: 0 15:43:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:43:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:43:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92bdec8ee5c48dbabc0877127975c3bc0a0e24a3f920dd2e895debeeb4e3c2a751975a918d21b79593873d4b6f2e6128003e40b8102f2fa3f5e951b3f5488f02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25ea9d344361ddb236e258d265977829b239f95f73360805935367df9a6bab26aa626faa062a22795016200edc0f8b825e9d3170060d902832d4ea3291ef6e8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12957fd3696be6d3af40784d46380bf2a5daaff96a655488c71f9e871bcf6820b08e50efcc2619cd104878aa1a66fc7a2a694347f86b595ec19ac1300c1fc0cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1fab1b89a32413aa0ca30661f19f76b08e26e61a064c91f4a99485d389d285402bd759ee2309d05c79c87f7b0361e0f7c4d801d8e3709c1f42d3d22ec849b17"} 15:43:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:43:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:43:15 INFO - Contents: 15:43:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92bdec8ee5c48dbabc0877127975c3bc0a0e24a3f920dd2e895debeeb4e3c2a751975a918d21b79593873d4b6f2e6128003e40b8102f2fa3f5e951b3f5488f02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25ea9d344361ddb236e258d265977829b239f95f73360805935367df9a6bab26aa626faa062a22795016200edc0f8b825e9d3170060d902832d4ea3291ef6e8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12957fd3696be6d3af40784d46380bf2a5daaff96a655488c71f9e871bcf6820b08e50efcc2619cd104878aa1a66fc7a2a694347f86b595ec19ac1300c1fc0cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1fab1b89a32413aa0ca30661f19f76b08e26e61a064c91f4a99485d389d285402bd759ee2309d05c79c87f7b0361e0f7c4d801d8e3709c1f42d3d22ec849b17"} 15:43:15 INFO - Running post-run listener: copy_logs_to_upload_dir 15:43:15 INFO - Copying logs to upload dir... 15:43:15 INFO - mkdir: /builds/slave/test/build/upload/logs 15:43:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.110456 ========= master_lag: 0.40 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-07 15:43:15.747398) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 15:43:15.753342) ========= 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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92bdec8ee5c48dbabc0877127975c3bc0a0e24a3f920dd2e895debeeb4e3c2a751975a918d21b79593873d4b6f2e6128003e40b8102f2fa3f5e951b3f5488f02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25ea9d344361ddb236e258d265977829b239f95f73360805935367df9a6bab26aa626faa062a22795016200edc0f8b825e9d3170060d902832d4ea3291ef6e8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12957fd3696be6d3af40784d46380bf2a5daaff96a655488c71f9e871bcf6820b08e50efcc2619cd104878aa1a66fc7a2a694347f86b595ec19ac1300c1fc0cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1fab1b89a32413aa0ca30661f19f76b08e26e61a064c91f4a99485d389d285402bd759ee2309d05c79c87f7b0361e0f7c4d801d8e3709c1f42d3d22ec849b17"} build_url:https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010441 build_url: 'https://queue.taskcluster.net/v1/task/SYPihhGnTcu3NSvtUOz3Zg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/92bdec8ee5c48dbabc0877127975c3bc0a0e24a3f920dd2e895debeeb4e3c2a751975a918d21b79593873d4b6f2e6128003e40b8102f2fa3f5e951b3f5488f02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25ea9d344361ddb236e258d265977829b239f95f73360805935367df9a6bab26aa626faa062a22795016200edc0f8b825e9d3170060d902832d4ea3291ef6e8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12957fd3696be6d3af40784d46380bf2a5daaff96a655488c71f9e871bcf6820b08e50efcc2619cd104878aa1a66fc7a2a694347f86b595ec19ac1300c1fc0cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1fab1b89a32413aa0ca30661f19f76b08e26e61a064c91f4a99485d389d285402bd759ee2309d05c79c87f7b0361e0f7c4d801d8e3709c1f42d3d22ec849b17"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 15:43:15.788305) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:43:15.788639) ========= 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.DvWSj6k5OB/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.Pjo8BEqDZh/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.005148 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 15:43:15.837916) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 15:43:15.838240) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 15:43:15.838574) ========= ========= Total master_lag: 0.54 =========